--------- beginning of system 10-21 12:16:27.323 3575 3575 I VirtualizationService: virtualizationservice::maintenance: Failed to find a Secretkeeper instance; skipping secret management 10-21 12:16:27.326 3575 3575 I VirtualizationService: virtualizationservice: Registered Binder service android.system.virtualizationservice. 10-21 12:16:27.327 3575 3575 I VirtualizationService: virtualizationservice: Registered Binder service android.hardware.security.keymint.IRemotelyProvisionedComponent/avf. 10-21 12:16:27.328 3575 3575 I VirtualizationService: virtualizationservice: Registered Binder service android.system.virtualizationmaintenance. 10-21 12:16:27.329 3575 3585 E VirtualizationService: avflog: Status(-7, EX_UNSUPPORTED_OPERATION): 'Protected VM support is missing for this operation' --------- beginning of main 10-21 12:17:16.668 10153 10153 W virtmgr : type=1400 audit(0.0:273): avc: denied { use } for path="socket:[85182]" dev="sockfs" ino=85182 scontext=u:r:virtualizationmanager:s0:c252,c256,c522,c768 tcontext=u:r:surfaceflinger:s0 tclass=fd permissive=0 10-21 12:17:16.668 10153 10153 W virtmgr : type=1400 audit(0.0:274): avc: denied { use } for path="socket:[85183]" dev="sockfs" ino=85183 scontext=u:r:virtualizationmanager:s0:c252,c256,c522,c768 tcontext=u:r:surfaceflinger:s0 tclass=fd permissive=0 10-21 12:17:16.668 10153 10153 W virtmgr : type=1400 audit(0.0:275): avc: denied { use } for path="socket:[75444]" dev="sockfs" ino=75444 scontext=u:r:virtualizationmanager:s0:c252,c256,c522,c768 tcontext=u:r:surfaceflinger:s0 tclass=fd permissive=0 10-21 12:17:16.668 10153 10153 W virtmgr : type=1400 audit(0.0:276): avc: denied { use } for path="socket:[75445]" dev="sockfs" ino=75445 scontext=u:r:virtualizationmanager:s0:c252,c256,c522,c768 tcontext=u:r:surfaceflinger:s0 tclass=fd permissive=0 10-21 12:17:16.668 10153 10153 W virtmgr : type=1400 audit(0.0:277): avc: denied { append } for path=2F646174612F757365722F31302F636F6D2E616E64726F69642E7669727475616C697A6174696F6E2E7465726D696E616C2F6170705F776562766965772F44656661756C742F4C6F63616C2053746F726167652F6C6576656C64622F4D414E49464553542D303030303031 dev="dm-64" ino=97519 scontext=u:r:virtualizationmanager:s0:c252,c256,c522,c768 tcontext=u:object_r:privapp_data_file:s0:c252,c256,c522,c768 tclass=file permissive=0 10-21 12:17:16.668 10153 10153 W virtmgr : type=1400 audit(0.0:278): avc: denied { append } for path=2F646174612F757365722F31302F636F6D2E616E64726F69642E7669727475616C697A6174696F6E2E7465726D696E616C2F6170705F776562766965772F44656661756C742F4C6F63616C2053746F726167652F6C6576656C64622F3030303030332E6C6F67 dev="dm-64" ino=98268 scontext=u:r:virtualizationmanager:s0:c252,c256,c522,c768 tcontext=u:object_r:privapp_data_file:s0:c252,c256,c522,c768 tclass=file permissive=0 10-21 12:17:16.668 10153 10153 W virtmgr : type=1400 audit(0.0:279): avc: denied { append } for path="/data/user/10/com.android.virtualization.terminal/app_webview/Default/shared_proto_db/metadata/MANIFEST-000001" dev="dm-64" ino=90401 scontext=u:r:virtualizationmanager:s0:c252,c256,c522,c768 tcontext=u:object_r:privapp_data_file:s0:c252,c256,c522,c768 tclass=file permissive=0 10-21 12:17:16.782 10158 10158 I VirtualizationService: virtualizationservice::maintenance: Failed to find a Secretkeeper instance; skipping secret management 10-21 12:17:16.783 10158 10158 I VirtualizationService: virtualizationservice: Registered Binder service android.system.virtualizationservice. 10-21 12:17:16.784 10153 10153 I virtmgr : virtualizationmanager: Started VirtualizationService RpcServer. Ready to accept connections 10-21 12:17:16.784 10158 10158 I VirtualizationService: virtualizationservice: Registered Binder service android.hardware.security.keymint.IRemotelyProvisionedComponent/avf. 10-21 12:17:16.785 10158 10158 I VirtualizationService: virtualizationservice: Registered Binder service android.system.virtualizationmaintenance. 10-21 12:17:16.804 10158 10158 I VirtualizationService: virtualizationservice::aidl: ignoring removeVmInstance() as no ISecretkeeper 10-21 12:17:16.807 10158 10158 I VirtualizationService: virtualizationservice::aidl: Allocated a VM's instance_id: "77777777"..., for uid: 1010252 10-21 12:17:16.807 10158 10158 I VirtualizationService: virtualizationservice::aidl: ignoring update of Secretkeeper entry as no ISecretkeeper 10-21 12:17:16.938 10172 10172 W crosvm : --socket is deprecated; please use --socket-path instead 10-21 12:17:17.015 10153 10165 W virtmgr : virtualizationmanager::virtualmachine: can't read_link '/proc/9654/exe': Os { code: 2, kind: NotFound, message: "No such file or directory" }; regarding as system 10-21 12:17:17.016 10153 10165 I virtmgr : virtualizationmanager::virtualmachine: callers secontext: u:r:vmlauncher_app:s0:c252,c256,c522,c768 10-21 12:17:17.018 10153 10165 W virtmgr : virtualizationmanager::virtualmachine: VM reference DT doesn't exist in host DT 10-21 12:17:17.020 10153 10165 I virtmgr : virtualizationmanager::debug_config: Debug policy ignored for non-Microdroid VM 10-21 12:17:17.020 10153 10165 I virtmgr : virtualizationmanager::debug_config: Debug policy is disabled 10-21 12:17:17.020 10153 10165 W virtmgr : virtualizationmanager::virtualmachine: unknown partition for '/data/user/10/com.android.virtualization.terminal/files/linux/root_part' 10-21 12:17:17.020 10153 10165 W virtmgr : virtualizationmanager::virtualmachine: unknown partition for '/data/user/10/com.android.virtualization.terminal/files/linux/cidata.iso' 10-21 12:17:17.020 10153 10165 W virtmgr : virtualizationmanager::virtualmachine: unknown partition for '/data/user/10/com.android.virtualization.terminal/files/linux/vmlinuz' 10-21 12:17:17.020 10153 10165 W virtmgr : virtualizationmanager::virtualmachine: unknown partition for '/data/user/10/com.android.virtualization.terminal/files/linux/initrd.img' 10-21 12:17:17.022 10153 10165 W virtmgr : virtualizationmanager::crosvm: Memory balloon not enabled: 10-21 12:17:17.022 10153 10165 W virtmgr : config.balloon=true,hypervisor.memory_reclaim.supported=false 10-21 12:17:17.058 10153 10165 I virtmgr : virtualizationmanager::crosvm: Non-protected virtual machine "debian" (owner: u10_a252, cid: 2048) created 10-21 12:17:17.059 10158 10160 I VirtualizationService: virtualizationservice::aidl: Virtual machine with CID 2048 registered 10-21 12:17:17.060 10153 10165 I virtmgr : virtualizationmanager::atom: Writing VmCreationRequested atom into statsd. 10-21 12:17:17.070 10153 10165 I virtmgr : virtualizationmanager::crosvm: Running crosvm with args: ["device", "fs", "--socket=/data/misc/virtualizationservice/2048/android", "--tag=android", "--shared-dir=/storage/emulated/10", "--cfg", "ugid_map='1000 100 1010252 1010252 7 /; 0 0 0 0 511 /Android'", "--disable-sandbox", "--skip-pivot-root=true"] 10-21 12:17:17.072 10153 10165 I virtmgr : virtualizationmanager::crosvm: Spawned virtiofs crosvm(10185) 10-21 12:17:17.089 10185 10185 W crosvm : --socket is deprecated; please use --socket-path instead 10-21 12:17:17.175 10153 10165 I virtmgr : virtualizationmanager::crosvm: Running crosvm with args: ["--extended-status", "--log-level", "debug,disk=warn", "run", "--disable-sandbox", "--cid", "2048", "--name", "crosvm_debian", "/proc/self/fd/39 (/data/user/10/com.android.virtualization.terminal/files/linux/vmlinuz)", "--params", "root=/dev/vda1 ds=nocloud arm64.nompam 8250.nr_uarts=4 console=ttyS0", "--params", "hostname=debian", "--initrd", "/proc/self/fd/40 (/data/user/10/com.android.virtualization.terminal/files/linux/initrd.img)", "--host-cpu-topology", "--virt-cpufreq", "--cpus", "sve=[auto=true]", "--pci", "mem=[start=0x2c000000,size=0x2000000],cam=[start=0x2e000000,size=0x1000000]", "--no-pmu", "--mem", "4096", "--params", "coherent_pool=4096", "--no-balloon", "--serial=type=file,path=/proc/self/fd/41 (pipe:[81862]),hardware=serial,num=1", "--serial=type=file,path=/proc/self/fd/41 (pipe:[81862]),hardware=virtio-console,num=1,max-queue-sizes=[2,32]", "--serial=type=file,path=/proc/self/fd/42 (pipe:[81863]),hardware=virtio-console,num=3,max-queue-sizes=[2,32]", "--serial=type=file,path=/proc/self/fd/44 (pipe:[88594]),hardware=serial,num=2", "--serial=type=sink,hardware=virtio-console,num=2,max-queue-sizes=[2,32]", "--block", "path=/proc/self/fd/50 (/data/misc/virtualizationservice/2048/composite-0.img),ro=false,lock=false", "--block", "path=/proc/self/fd/46 (/data/user/10/com.android.virtualization.terminal/files/linux/cidata.iso),ro=true,lock=false", "--android-display-service=debian", "--gpu=backend=2d,displays=[[mode=windowed[1280,720],dpi=[320,320],refresh-rate=120]]", "--input", "multi-touch[path=/proc/self/fd/51 (socket:[81870]),width=1280,height=720]", "--input", "keyboard[path=/proc/self/fd/52 (socket:[81872])]", "--input", "mouse[path=/proc/self/fd/53 (socket:[81874])]", "--input", "multi-touch-trackpad[path=/proc/self/fd/54 (socket:[81876]),width=2380,height=1369]", "--virtio-snd", "backend=aaudio,num_input_devices=1,num_output_devices=1", "--no-usb", "--net", "tap-fd=55", "--socket", "/proc/self/fd/13 (socket:[84918])", "--device-tree-overlay", "/proc/self/fd/35 (/data/misc/virtualizationservice/2048/vm_dt_overlay.dtbo)", "--vhost-user", "fs,socket=/data/misc/virtualizationservice/2048/android", "--vhost-user", "fs,socket=/proc/self/fd/14 (socket:[81868])"] 10-21 12:17:17.180 10153 10165 I virtmgr : virtualizationmanager::crosvm: Non-protected virtual machine "debian" (owner: u10_a252, cid: 2048) started 10-21 12:17:17.219 10189 10189 D crosvm : creating hypervisor: Geniezone { device: Some("/dev/gzvm") } 10-21 12:17:17.224 10189 10189 I crosvm : Trying to attach block device: /proc/self/fd/50 10-21 12:17:17.224 10189 10189 I crosvm : Trying to attach block device: /proc/self/fd/46 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:17:17.274 10189 10189 W crosvm : Unable to get cpufreq governor, using 100% default util factor. Err: Error(22) 10-21 12:18:17.218 10153 10165 W virtmgr : virtualizationmanager::crosvm: Failed to get guest CPU time: zero value is measured on elapsed CPU guest_time 10-21 12:18:17.218 10153 10165 W virtmgr : virtualizationmanager::crosvm: Killing VM (name: debian, cid: 2048) forcibly. Data might be corrupted!!! 10-21 12:18:17.226 10185 10185 I crosvm : exiting with success 10-21 12:18:17.229 10158 10160 W VirtualizationService: virtualizationservice::aidl: Display service died. Clearing it. 10-21 12:18:17.248 10153 10190 W virtmgr : virtualizationmanager::crosvm: Failed to get guest CPU time: zero value is measured on elapsed CPU guest_time 10-21 12:18:17.248 10153 10190 W virtmgr : virtualizationmanager::crosvm: Failed to get guest RSS: can't find VmHWM in the status file 10-21 12:18:17.250 10153 10190 I virtmgr : virtualizationmanager::crosvm: crosvm(10189) exited with status signal: 9 (SIGKILL) 10-21 12:18:17.361 10153 10190 I virtmgr : virtualizationmanager::crosvm: Vhost user device(10185) exited with status exit status: 0 10-21 12:18:17.361 10153 10190 I virtmgr : virtualizationmanager::crosvm: Non-protected virtual machine "debian" (owner: u10_a252, cid: 2048) exited 10-21 12:18:17.362 10153 10190 I virtmgr : virtualizationmanager::atom: Writing VmExited atom into statsd. 10-21 12:18:17.363 10158 10160 I VirtualizationService: virtualizationservice::aidl: Virtual machine with CID 2048 unregistered