multipassd.log

[2020-02-18T17:32:13.786] [info] [sshfs-mounts] mounting /Users/mark/src => src in dev
[2020-02-18T17:32:13.786] [info] [sshfs-mounts] process program '/Library/Application Support/com.canonical.multipass/bin/sshfs_server'
[2020-02-18T17:32:13.786] [info] [sshfs-mounts] process arguments '192.168.64.2, 22, ubuntu, /Users/mark/src, src, 501:-1,, 20:-1,'
[2020-02-18T18:38:59.941] [info] [dev] hyperkit: [INFO] Allocator: 149631 used; 0 junk; 0 erased; 255 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 150458
[2020-02-18T18:38:59.941] [info] [dev] hyperkit: [INFO] Allocator: file contains cluster 0 .. 150713 will enlarge file to 0 .. 151225
[2020-02-18T18:38:59.941] [info] [dev] hyperkit: [INFO] resize: adding available clusters (Node ((x 150714) (y 151225) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2020-02-19T00:32:40.767] [info] [dev] hyperkit: [INFO] Allocator: 150143 used; 0 junk; 0 erased; 255 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 150970
[2020-02-19T00:32:40.774] [info] [dev] hyperkit: [INFO] Allocator: file contains cluster 0 .. 151225 will enlarge file to 0 .. 151737
[2020-02-19T00:32:40.774] [info] [dev] hyperkit: [INFO] resize: adding available clusters (Node ((x 151226) (y 151737) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2020-02-19T00:37:58.087] [info] [dev] hyperkit: [INFO] Allocator: 150655 used; 0 junk; 0 erased; 255 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 151482
[2020-02-19T00:37:58.087] [info] [dev] hyperkit: [INFO] Allocator: file contains cluster 0 .. 151737 will enlarge file to 0 .. 152249
[2020-02-19T00:37:58.087] [info] [dev] hyperkit: [INFO] resize: adding available clusters (Node ((x 151738) (y 152249) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2020-02-19T00:37:58.295] [info] [dev] hyperkit: [INFO] Allocator: 151167 used; 0 junk; 0 erased; 255 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 151994
[2020-02-19T00:37:58.295] [info] [dev] hyperkit: [INFO] Allocator: file contains cluster 0 .. 152249 will enlarge file to 0 .. 152761
[2020-02-19T00:37:58.295] [info] [dev] hyperkit: [INFO] resize: adding available clusters (Node ((x 152250) (y 152761) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2020-02-19T03:52:05.598] [error] [dev] hyperkit: [ERROR] write sector = 6384736 length = 4096: I/O deadline exceeded
E0219 03:52:05.945446000 123145476575232 call.cc:1833]                 assertion failed: grpc_cq_begin_op(call->cq, notify_tag)
[2020-02-19T03:52:06.133] [info] [rpc] gRPC listening on unix:/var/run/multipass_socket, SSL:on
[2020-02-19T03:52:06.137] [info] [daemon] dev needs starting. Starting now...
[2020-02-19T03:52:06.150] [info] [dev] process program '/Library/Application Support/com.canonical.multipass/bin/hyperkit'
[2020-02-19T03:52:06.150] [info] [dev] process arguments '-c, 4, -m, 8192M, -u, -A, -H, -U, e77989ed-2175-3e78-b31b-20e477fc5582, -s, 0:0,hostbridge, -s, 2:0,virtio-net, -s, 5,virtio-rnd, -s, 31,lpc, -l, com1,autopty=/var/root/Library/Application Support/multipassd/vault/instances/dev/pty,log=/Library/Logs/Multipass/dev-hyperkit.log, -s, 1:0,ahci-hd,file:///var/root/Library/Application Support/multipassd/vault/instances/dev/ubuntu-18.04-server-cloudimg-amd64.img?sync=os&buffered=1,format=qcow,qcow-config=discard=true;compact_after_unmaps=262144;keep_erased=262144;runtime_asserts=false, -s, 1:1,ahci-cd,/var/root/Library/Application Support/multipassd/vault/instances/dev/cloud-init-config.iso, -f, kexec,/var/root/Library/Application Support/multipassd/vault/instances/dev/ubuntu-18.04-server-cloudimg-amd64-vmlinuz-generic,/var/root/Library/Application Support/multipassd/vault/instances/dev/ubuntu-18.04-server-cloudimg-amd64-initrd-generic,earlyprintk=serial console=ttyS0 root=/dev/sda1 rw panic=1 no_timer_check'
[2020-02-19T03:52:06.150] [info] [dev] process state changed to Starting
[2020-02-19T03:52:06.153] [info] [dev] process state changed to Running
[2020-02-19T03:52:06.205] [info] [dev] mirage_block_open: block_config = file:///var/root/Library/Application Support/multipassd/vault/instances/dev/ubuntu-18.04-server-cloudimg-amd64.img?sync=os&buffered=1 and qcow_config = discard=true;compact_after_unmaps=262144;keep_erased=262144;runtime_asserts=false and stats_config = None

[2020-02-19T03:52:06.205] [error] [dev] hyperkit: [ERROR] connect /var/root/Library/Application Support/multipassd/vault/instances/dev/ubuntu-18.04-server-cloudimg-amd64.img: failed to open file
[2020-02-19T03:52:06.205] [error] [dev] hyperkit: [ERROR] Mirage block device raised exception: (Failure
[2020-02-19T03:52:06.205] [error] [dev] "connect /var/root/Library/Application Support/multipassd/vault/instances/dev/ubuntu-18.04-server-cloudimg-amd64.img: failed to open file")
[2020-02-19T03:52:06.205] [error] [dev] Mirage-block error: Block device is disconnected
[2020-02-19T03:52:06.205] [error] [dev] Could not open mirage-block device: Invalid argument
[2020-02-19T03:52:06.206] [info] [dev] process state changed to NotRunning
[2020-02-19T03:52:06.206] [info] [dev] process finished with exit code 1
[2020-02-19T03:52:06.212] [info] [sshfs-mounts] mounting /Users/mark/src => src in dev
[2020-02-19T03:52:06.212] [info] [sshfs-mounts] process program '/Library/Application Support/com.canonical.multipass/bin/sshfs_server'
[2020-02-19T03:52:06.212] [info] [sshfs-mounts] process arguments '192.168.64.2, 22, ubuntu, /Users/mark/src, src, 501:-1,, 20:-1,'
[2020-02-19T03:52:07.773] [warning] [Qt] QNetworkDiskCache::prepare() unable to open temporary file