Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

MacOs can't start an instance - unknown state #3914

Open
a2xchip opened this issue Jan 31, 2025 · 2 comments
Open

MacOs can't start an instance - unknown state #3914

a2xchip opened this issue Jan 31, 2025 · 2 comments
Labels
bug

Comments

@a2xchip
Copy link

a2xchip commented Jan 31, 2025

Describe the bug
Can't start instance on MacOs 15.3

To Reproduce

multipass start honeyed-aardwolf -vvvvv      
[2025-01-31T05:08:13.041] [debug] [honeyed-aardwolf] process working dir ''
[2025-01-31T05:08:13.041] [info] [honeyed-aardwolf] process program 'qemu-system-aarch64'
[2025-01-31T05:08:13.041] [info] [honeyed-aardwolf] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso'
[2025-01-31T05:08:13.052] [debug] [qemu-system-aarch64] [4366] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.FAsUer
[2025-01-31T05:08:13.129] [info] [honeyed-aardwolf] process state changed to Starting
[2025-01-31T05:08:13.133] [info] [honeyed-aardwolf] process state changed to Running
[2025-01-31T05:08:13.133] [debug] [qemu-system-aarch64] [4367] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso
[2025-01-31T05:08:13.133] [info] [honeyed-aardwolf] process started
start failed: The following errors occurred:
honeyed-aardwolf: timed out waiting for response

Expected behavior
Starting given instance

Logs
Please provide logs from the daemon, see accessing logs on where to find them on your platform.

[2025-01-31T04:18:29.395] [debug] [blueprint provider] Loading "anbox-cloud-appliance" v1
[2025-01-31T04:18:29.397] [debug] [blueprint provider] Loading "charm-dev" v1
[2025-01-31T04:18:29.398] [debug] [blueprint provider] Loading "docker" v1
[2025-01-31T04:18:29.398] [debug] [blueprint provider] Loading "jellyfin" v1
[2025-01-31T04:18:29.398] [debug] [blueprint provider] Loading "minikube" v1
[2025-01-31T04:18:29.399] [debug] [blueprint provider] Loading "ros2-humble" v1
[2025-01-31T04:18:29.399] [debug] [blueprint provider] Loading "ros2-jazzy" v1
[2025-01-31T04:18:29.425] [info] [rpc] gRPC listening on unix:/var/run/multipass_socket
[2025-01-31T04:18:29.425] [debug] [async task] fetch manifest periodically
[2025-01-31T04:18:29.435] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T04:18:29.460] [debug] [qemu-img] [892] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.656] [debug] [qemu-img] [897] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.667] [debug] [qemu-img] [898] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.673] [debug] [qemu-img] [900] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.680] [debug] [qemu-img] [901] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/docker/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.686] [debug] [qemu-img] [902] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/docker/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.690] [info] [sshfs-mount-handler] initializing mount /Users/alexandrjeliuc/multipass/docker => docker in 'docker'
[2025-01-31T04:18:29.692] [debug] [qemu-img] [904] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/chardway/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.697] [debug] [qemu-img] [905] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/chardway/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T04:18:29.702] [info] [daemon] Starting Multipass 1.15.0+mac
[2025-01-31T04:18:29.702] [info] [daemon] Daemon arguments: /Library/Application Support/com.canonical.multipass/bin/multipassd --verbosity debug
[2025-01-31T04:18:30.093] [warning] [Qt] QNetworkDiskCache::prepare() unable to open temporary file
[2025-01-31T04:18:30.124] [debug] [update] Latest Multipass release available is version 1.15.0
[2025-01-31T04:23:56.949] [debug] [honeyed-aardwolf] process working dir ''
[2025-01-31T04:23:56.950] [info] [honeyed-aardwolf] process program 'qemu-system-aarch64'
[2025-01-31T04:23:56.950] [info] [honeyed-aardwolf] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso'
[2025-01-31T04:23:56.971] [debug] [qemu-system-aarch64] [2782] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.PxyJCr
[2025-01-31T04:23:57.167] [info] [honeyed-aardwolf] process state changed to Starting
[2025-01-31T04:23:57.170] [info] [honeyed-aardwolf] process state changed to Running
[2025-01-31T04:23:57.170] [debug] [qemu-system-aarch64] [2783] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso
[2025-01-31T04:23:57.170] [info] [honeyed-aardwolf] process started
[2025-01-31T04:23:57.171] [debug] [honeyed-aardwolf] Waiting for SSH to be up
[2025-01-31T04:23:57.174] [debug] [arp] [2784] started: arp -an
[2025-01-31T04:23:57.618] [debug] [honeyed-aardwolf] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T04:23:57.681] [debug] [honeyed-aardwolf] QMP: {"return": {}}

[2025-01-31T04:23:59.290] [debug] [arp] [2790] started: arp -an
[2025-01-31T04:24:01.447] [debug] [arp] [2791] started: arp -an

[2025-01-31T04:33:30.542] [debug] [async task] fetch manifest periodically
[2025-01-31T04:33:30.552] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T04:48:30.523] [debug] [async task] fetch manifest periodically
[2025-01-31T04:48:30.528] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T05:03:31.507] [debug] [async task] fetch manifest periodically
[2025-01-31T05:03:31.512] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T05:07:47.498] [info] [chardway] Ignoring shutdown since instance is already stopped.
[2025-01-31T05:07:56.302] [info] [honeyed-aardwolf] Forcing shutdown
[2025-01-31T05:07:56.302] [info] [honeyed-aardwolf] Killing process
[2025-01-31T05:07:56.324] [info] [honeyed-aardwolf] process state changed to NotRunning
[2025-01-31T05:07:56.324] [info] [honeyed-aardwolf] process error occurred Crashed program: qemu-system-aarch64; error: Process crashed
[2025-01-31T05:07:56.326] [info] [honeyed-aardwolf] error: program: qemu-system-aarch64; error: Process crashed
[2025-01-31T05:07:56.338] [debug] [qemu-img] [4331] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T05:08:13.041] [debug] [honeyed-aardwolf] process working dir ''
[2025-01-31T05:08:13.041] [info] [honeyed-aardwolf] process program 'qemu-system-aarch64'
[2025-01-31T05:08:13.041] [info] [honeyed-aardwolf] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso'
[2025-01-31T05:08:13.052] [debug] [qemu-system-aarch64] [4366] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.FAsUer
[2025-01-31T05:08:13.129] [info] [honeyed-aardwolf] process state changed to Starting
[2025-01-31T05:08:13.133] [info] [honeyed-aardwolf] process state changed to Running
[2025-01-31T05:08:13.133] [debug] [qemu-system-aarch64] [4367] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso
[2025-01-31T05:08:13.133] [info] [honeyed-aardwolf] process started
[2025-01-31T05:08:13.134] [debug] [honeyed-aardwolf] Waiting for SSH to be up
[2025-01-31T05:08:13.136] [debug] [arp] [4368] started: arp -an
[2025-01-31T05:08:13.242] [debug] [honeyed-aardwolf] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T05:08:13.274] [debug] [honeyed-aardwolf] QMP: {"return": {}}
[2025-01-31T05:13:12.782] [debug] [arp] [4561] started: arp -an
[2025-01-31T05:16:18.279] [debug] [daemon] Returning setting local.privileged-mounts=true
[2025-01-31T05:16:18.279] [debug] [daemon] Returning setting local.bridged-network=en4
[2025-01-31T05:16:18.279] [debug] [daemon] Returning setting local.driver=qemu
[2025-01-31T05:16:18.279] [debug] [daemon] Returning setting local.passphrase=
[2025-01-31T05:16:18.310] [debug] [ifconfig] [4814] started: ifconfig 
[2025-01-31T05:16:18.326] [debug] [networksetup] [4815] started: networksetup -listallhardwareports
[2025-01-31T05:18:31.548] [debug] [async task] fetch manifest periodically
[2025-01-31T05:18:31.554] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T05:24:39.816] [warning] [daemon] Instance 'honeyed-aardwolf' is already running, but in an unknown state.
Try to stop it first.
[2025-01-31T05:24:49.643] [info] [honeyed-aardwolf] SSH session disconnected
[2025-01-31T05:24:49.644] [info] [honeyed-aardwolf] Could not broadcast shutdown message in VM: SSH unavailable on instance honeyed-aardwolf: not running
[2025-01-31T05:24:49.645] [debug] [honeyed-aardwolf] QMP: {"timestamp": {"seconds": 1738293889, "microseconds": 645015}, "event": "POWERDOWN"}

[2025-01-31T05:24:49.645] [info] [honeyed-aardwolf] VM powering down
[2025-01-31T05:24:49.645] [debug] [honeyed-aardwolf] QMP: {"return": {}}

[2025-01-31T05:29:28.504] [debug] [blueprint provider] Loading "anbox-cloud-appliance" v1
[2025-01-31T05:29:28.504] [debug] [blueprint provider] Loading "charm-dev" v1
[2025-01-31T05:29:28.505] [debug] [blueprint provider] Loading "docker" v1
[2025-01-31T05:29:28.505] [debug] [blueprint provider] Loading "jellyfin" v1
[2025-01-31T05:29:28.506] [debug] [blueprint provider] Loading "minikube" v1
[2025-01-31T05:29:28.506] [debug] [blueprint provider] Loading "ros2-humble" v1
[2025-01-31T05:29:28.506] [debug] [blueprint provider] Loading "ros2-jazzy" v1
[2025-01-31T05:29:28.513] [info] [rpc] gRPC listening on unix:/var/run/multipass_socket
[2025-01-31T05:29:28.513] [debug] [async task] fetch manifest periodically
[2025-01-31T05:29:28.519] [debug] [qemu-img] [5821] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.519] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T05:29:28.532] [debug] [qemu-img] [5822] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.544] [debug] [qemu-img] [5823] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.552] [debug] [qemu-img] [5824] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.561] [debug] [qemu-img] [5825] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/docker/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.568] [debug] [qemu-img] [5826] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/docker/ubuntu-22.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.574] [info] [sshfs-mount-handler] initializing mount /Users/alexandrjeliuc/multipass/docker => docker in 'docker'
[2025-01-31T05:29:28.576] [debug] [qemu-img] [5827] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/chardway/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.582] [debug] [qemu-img] [5828] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/chardway/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T05:29:28.588] [info] [daemon] Starting Multipass 1.15.0+mac
[2025-01-31T05:29:28.588] [info] [daemon] Daemon arguments: /Library/Application Support/com.canonical.multipass/bin/multipassd --verbosity debug
[2025-01-31T05:29:29.088] [warning] [Qt] QNetworkDiskCache::prepare() unable to open temporary file
[2025-01-31T05:29:29.089] [debug] [update] Latest Multipass release available is version 1.15.0
[2025-01-31T05:29:30.052] [debug] [daemon] Returning setting local.passphrase=
[2025-01-31T05:29:30.052] [debug] [daemon] Returning setting local.driver=qemu
[2025-01-31T05:29:30.053] [debug] [daemon] Returning setting local.privileged-mounts=true
[2025-01-31T05:29:30.053] [debug] [daemon] Returning setting local.bridged-network=en4
[2025-01-31T05:29:30.066] [debug] [ifconfig] [5829] started: ifconfig 
[2025-01-31T05:29:30.075] [debug] [networksetup] [5830] started: networksetup -listallhardwareports
[2025-01-31T05:30:05.822] [debug] [honeyed-aardwolf] process working dir ''
[2025-01-31T05:30:05.822] [info] [honeyed-aardwolf] process program 'qemu-system-aarch64'
[2025-01-31T05:30:05.822] [info] [honeyed-aardwolf] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso'
[2025-01-31T05:30:05.826] [debug] [qemu-system-aarch64] [5920] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.PFrVlW
[2025-01-31T05:30:05.883] [info] [honeyed-aardwolf] process state changed to Starting
[2025-01-31T05:30:05.886] [info] [honeyed-aardwolf] process state changed to Running
[2025-01-31T05:30:05.886] [debug] [qemu-system-aarch64] [5921] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso
[2025-01-31T05:30:05.886] [info] [honeyed-aardwolf] process started
[2025-01-31T05:30:05.887] [debug] [honeyed-aardwolf] Waiting for SSH to be up
[2025-01-31T05:30:05.889] [debug] [arp] [5922] started: arp -an
[2025-01-31T05:30:05.922] [debug] [honeyed-aardwolf] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T05:30:05.941] [debug] [honeyed-aardwolf] QMP: {"return": {}}

[2025-01-31T05:34:35.636] [debug] [arp] [6169] started: arp -an
[2025-01-31T05:34:36.898] [debug] [primary] process working dir ''
[2025-01-31T05:34:36.898] [info] [primary] process program 'qemu-system-aarch64'
[2025-01-31T05:34:36.898] [info] [primary] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:ed:09:46, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/cloud-init-config.iso'
[2025-01-31T05:34:36.904] [debug] [qemu-system-aarch64] [6178] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.eBqihz
[2025-01-31T05:34:36.984] [info] [primary] process state changed to Starting
[2025-01-31T05:34:36.987] [info] [primary] process state changed to Running
[2025-01-31T05:34:36.987] [debug] [qemu-system-aarch64] [6179] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:ed:09:46 -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/cloud-init-config.iso
[2025-01-31T05:34:36.987] [info] [primary] process started
[2025-01-31T05:34:36.988] [debug] [primary] Waiting for SSH to be up
[2025-01-31T05:34:36.990] [debug] [arp] [6180] started: arp -an
[2025-01-31T05:34:37.015] [debug] [primary] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T05:34:37.034] [debug] [primary] QMP: {"return": {}}

[2025-01-31T05:34:37.796] [debug] [arp] [6181] started: arp -an
[2025-01-31T05:34:39.115] [debug] [arp] [6182] started: arp -an
[2025-01-31T05:34:39.914] [debug] [arp] [6183] started: arp -an
[2025-01-31T05:34:41.229] [debug] [arp] [6184] started: arp -an
[2025-01-31T05:34:42.077] [debug] [arp] [6185] started: arp -an
[2025-01-31T05:34:43.349] [debug] [arp] [6186] started: arp -an
[2025-01-31T05:34:44.219] [debug] [arp] [6187] started: arp -an
[2025-01-31T05:34:45.501] [debug] [arp] [6188] started: arp -an
[2025-01-31T05:34:46.307] [debug] [arp] [6190] started: arp -an
[2025-01-31T05:34:47.585] [debug] [arp] [6191] started: arp -an
[2025-01-31T05:34:48.038] [debug] [primary] QMP: {"timestamp": {"seconds": 1738294488, "microseconds": 38179}, "event": "NIC_RX_FILTER_CHANGED", "data": {"path": "/machine/unattached/device[6]/virtio-backend"}}

[2025-01-31T05:34:48.455] [debug] [arp] [6193] started: arp -an
[2025-01-31T05:34:49.724] [debug] [arp] [6194] started: arp -an
[2025-01-31T05:34:50.596] [debug] [arp] [6195] started: arp -an
[2025-01-31T05:34:51.821] [debug] [arp] [6196] started: arp -an
[2025-01-31T05:34:51.860] [debug] [primary] Caching initial SSH session
[2025-01-31T05:34:52.750] [debug] [arp] [6215] started: arp -an
[2025-01-31T05:34:54.881] [debug] [arp] [6216] started: arp -an
[2025-01-31T05:34:57.027] [debug] [arp] [6217] started: arp -an
[2025-01-31T05:34:59.155] [debug] [arp] [6219] started: arp -an
[2025-01-31T05:35:01.210] [debug] [arp] [6220] started: arp -an
[2025-01-31T05:35:03.376] [debug] [arp] [6221] started: arp -an
[2025-01-31T05:35:05.486] [debug] [arp] [6222] started: arp -an
[2025-01-31T05:35:25.910] [debug] [primary] QMP: {"timestamp": {"seconds": 1738294525, "microseconds": 909969}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[4]"}}

[2025-01-31T05:36:37.942] [debug] [daemon] Returning setting local.driver=qemu
[2025-01-31T05:36:37.945] [debug] [ifconfig] [6242] started: ifconfig 
[2025-01-31T05:36:37.950] [debug] [networksetup] [6243] started: networksetup -listallhardwareports
[2025-01-31T05:36:37.964] [debug] [daemon] Returning setting local.primary.bridged=false
[2025-01-31T05:36:37.965] [debug] [daemon] Returning setting local.primary.cpus=1
[2025-01-31T05:36:37.965] [debug] [daemon] Returning setting local.primary.memory=1.0GiB
[2025-01-31T05:36:37.965] [debug] [daemon] Returning setting local.bridged-network=en4
[2025-01-31T05:36:37.965] [debug] [daemon] Returning setting local.primary.disk=5.0GiB
[2025-01-31T05:36:37.968] [debug] [ifconfig] [6245] started: ifconfig 
[2025-01-31T05:36:37.972] [debug] [networksetup] [6246] started: networksetup -listallhardwareports
[2025-01-31T05:36:53.944] [debug] [daemon] Returning setting local.primary.disk=5.0GiB
[2025-01-31T05:36:53.944] [debug] [daemon] Returning setting local.primary.cpus=1
[2025-01-31T05:36:53.944] [debug] [daemon] Returning setting local.primary.memory=1.0GiB
[2025-01-31T05:36:53.944] [debug] [daemon] Returning setting local.driver=qemu
[2025-01-31T05:36:53.947] [debug] [ifconfig] [6249] started: ifconfig 
[2025-01-31T05:36:53.952] [debug] [networksetup] [6250] started: networksetup -listallhardwareports
[2025-01-31T05:36:53.965] [debug] [daemon] Returning setting local.primary.bridged=false
[2025-01-31T05:36:53.966] [debug] [daemon] Returning setting local.bridged-network=en4
[2025-01-31T05:36:53.968] [debug] [ifconfig] [6252] started: ifconfig 
[2025-01-31T05:36:53.971] [debug] [networksetup] [6253] started: networksetup -listallhardwareports
[2025-01-31T05:37:14.105] [debug] [daemon] Deleting instance: chardway
[2025-01-31T05:37:14.106] [info] [chardway] Ignoring shutdown since instance is already stopped.
[2025-01-31T05:37:14.128] [debug] [daemon] Instance purged: chardway
[2025-01-31T05:37:29.069] [debug] [ifconfig] [6261] started: ifconfig 
[2025-01-31T05:37:29.083] [debug] [networksetup] [6262] started: networksetup -listallhardwareports
[2025-01-31T05:37:29.125] [debug] [daemon] Returning setting local.honeyed-aardwolf.bridged=false
[2025-01-31T05:37:29.125] [debug] [daemon] Returning setting local.honeyed-aardwolf.cpus=1
[2025-01-31T05:37:29.125] [debug] [daemon] Returning setting local.driver=qemu
[2025-01-31T05:37:29.126] [debug] [daemon] Returning setting local.bridged-network=en4
[2025-01-31T05:37:29.126] [debug] [daemon] Returning setting local.honeyed-aardwolf.memory=1.0GiB
[2025-01-31T05:37:29.126] [debug] [daemon] Returning setting local.honeyed-aardwolf.disk=5.0GiB
[2025-01-31T05:37:29.138] [debug] [ifconfig] [6264] started: ifconfig 
[2025-01-31T05:37:29.146] [debug] [networksetup] [6265] started: networksetup -listallhardwareports
[2025-01-31T05:40:19.275] [info] [primary] SSH session disconnected: [ssh proc] failed to open session channel: 'Socket error: disconnected'
[2025-01-31T05:40:19.275] [debug] [primary] Renewing cached SSH session
[2025-01-31T05:40:21.284] [info] [primary] SSH session disconnected
[2025-01-31T05:40:21.284] [debug] [primary] Caching new SSH session
[2025-01-31T05:40:22.593] [debug] [primary] QMP: {"timestamp": {"seconds": 1738294822, "microseconds": 593494}, "event": "RESET", "data": {"guest": true, "reason": "guest-reset"}}

[2025-01-31T05:40:22.593] [info] [primary] VM restarting
[2025-01-31T05:40:22.594] [debug] [primary] Waiting for SSH to be up
[2025-01-31T05:40:22.597] [debug] [arp] [6401] started: arp -an
[2025-01-31T05:40:37.347] [debug] [primary] Caching initial SSH session
[2025-01-31T05:41:12.052] [debug] [ssh session] Executing 'wall The system is going down for poweroff now'
[2025-01-31T05:41:12.063] [debug] [primary] Dropping cached SSH session
[2025-01-31T05:41:12.064] [debug] [primary] QMP: {"timestamp": {"seconds": 1738294872, "microseconds": 64596}, "event": "POWERDOWN"}

[2025-01-31T05:41:12.064] [info] [primary] VM powering down
[2025-01-31T05:41:12.064] [debug] [primary] QMP: {"return": {}}

[2025-01-31T05:41:15.621] [debug] [primary] QMP: {"timestamp": {"seconds": 1738294875, "microseconds": 621777}, "event": "SHUTDOWN", "data": {"guest": true, "reason": "guest-shutdown"}}

[2025-01-31T05:41:15.621] [info] [primary] VM shut down
[2025-01-31T05:41:15.637] [info] [primary] process state changed to NotRunning
[2025-01-31T05:41:15.637] [info] [primary] process finished with exit code 0
[2025-01-31T05:41:40.432] [info] [honeyed-aardwolf] Forcing shutdown
[2025-01-31T05:41:40.432] [info] [honeyed-aardwolf] Killing process
[2025-01-31T05:41:40.451] [info] [honeyed-aardwolf] process state changed to NotRunning
[2025-01-31T05:41:40.451] [info] [honeyed-aardwolf] process error occurred Crashed program: qemu-system-aarch64; error: Process crashed
[2025-01-31T05:41:40.453] [info] [honeyed-aardwolf] error: program: qemu-system-aarch64; error: Process crashed
[2025-01-31T05:41:40.458] [debug] [qemu-img] [6449] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T05:41:53.038] [info] [honeyed-aardwolf] Ignoring shutdown since instance is already stopped.
[2025-01-31T05:42:12.842] [debug] [honeyed-aardwolf] process working dir ''
[2025-01-31T05:42:12.842] [info] [honeyed-aardwolf] process program 'qemu-system-aarch64'
[2025-01-31T05:42:12.842] [info] [honeyed-aardwolf] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso'
[2025-01-31T05:42:12.846] [debug] [qemu-system-aarch64] [6531] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.NUSGas
[2025-01-31T05:42:12.923] [info] [honeyed-aardwolf] process state changed to Starting
[2025-01-31T05:42:12.927] [info] [honeyed-aardwolf] process state changed to Running
[2025-01-31T05:42:12.927] [debug] [qemu-system-aarch64] [6532] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso
[2025-01-31T05:42:12.927] [info] [honeyed-aardwolf] process started
[2025-01-31T05:42:12.929] [debug] [honeyed-aardwolf] Waiting for SSH to be up
[2025-01-31T05:42:12.931] [debug] [arp] [6533] started: arp -an
[2025-01-31T05:42:12.961] [debug] [honeyed-aardwolf] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T05:42:12.980] [debug] [honeyed-aardwolf] QMP: {"return": {}}

[2025-01-31T05:43:08.053] [debug] [arp] [6598] started: arp -an
[2025-01-31T05:43:09.556] [info] [honeyed-aardwolf] Forcing shutdown
[2025-01-31T05:43:09.557] [info] [honeyed-aardwolf] Killing process
[2025-01-31T05:43:09.569] [info] [honeyed-aardwolf] process state changed to NotRunning
[2025-01-31T05:43:09.569] [info] [honeyed-aardwolf] process error occurred Crashed program: qemu-system-aarch64; error: Process crashed
[2025-01-31T05:43:09.570] [info] [honeyed-aardwolf] error: program: qemu-system-aarch64; error: Process crashed
[2025-01-31T05:43:09.576] [debug] [qemu-img] [6600] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T05:43:11.642] [info] [honeyed-aardwolf] Ignoring shutdown since instance is already stopped.
[2025-01-31T05:43:24.683] [debug] [primary] process working dir ''
[2025-01-31T05:43:24.683] [info] [primary] process program 'qemu-system-aarch64'
[2025-01-31T05:43:24.684] [info] [primary] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:ed:09:46, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/cloud-init-config.iso'
[2025-01-31T05:43:24.688] [debug] [qemu-system-aarch64] [6661] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.OUfSTS
[2025-01-31T05:43:24.746] [info] [primary] process state changed to Starting
[2025-01-31T05:43:24.750] [info] [primary] process state changed to Running
[2025-01-31T05:43:24.750] [debug] [qemu-system-aarch64] [6662] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:ed:09:46 -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/ubuntu-22.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/primary/cloud-init-config.iso
[2025-01-31T05:43:24.750] [info] [primary] process started
[2025-01-31T05:43:24.751] [debug] [primary] Waiting for SSH to be up
[2025-01-31T05:43:24.753] [debug] [arp] [6663] started: arp -an
[2025-01-31T05:43:24.779] [debug] [primary] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T05:43:24.798] [debug] [primary] QMP: {"return": {}}

[2025-01-31T05:43:35.388] [debug] [primary] QMP: {"timestamp": {"seconds": 1738295015, "microseconds": 388030}, "event": "NIC_RX_FILTER_CHANGED", "data": {"path": "/machine/unattached/device[6]/virtio-backend"}}

[2025-01-31T05:43:38.788] [debug] [primary] Caching initial SSH session
[2025-01-31T05:44:14.917] [debug] [primary] QMP: {"timestamp": {"seconds": 1738295054, "microseconds": 917723}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[4]"}}

[2025-01-31T05:44:29.518] [debug] [async task] fetch manifest periodically
[2025-01-31T05:44:29.526] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T05:49:40.652] [debug] [honeyed-aardwolf] process working dir ''
[2025-01-31T05:49:40.652] [info] [honeyed-aardwolf] process program 'qemu-system-aarch64'
[2025-01-31T05:49:40.652] [info] [honeyed-aardwolf] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 1, -m, 1024M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso'
[2025-01-31T05:49:40.661] [debug] [qemu-system-aarch64] [6772] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.zpawXp
[2025-01-31T05:49:40.713] [info] [honeyed-aardwolf] process state changed to Starting
[2025-01-31T05:49:40.716] [info] [honeyed-aardwolf] process state changed to Running
[2025-01-31T05:49:40.716] [debug] [qemu-system-aarch64] [6773] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 1 -m 1024M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso
[2025-01-31T05:49:40.716] [info] [honeyed-aardwolf] process started
[2025-01-31T05:49:40.716] [debug] [honeyed-aardwolf] Waiting for SSH to be up
[2025-01-31T05:49:40.718] [debug] [arp] [6774] started: arp -an
[2025-01-31T05:49:40.747] [debug] [honeyed-aardwolf] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T05:49:40.765] [debug] [honeyed-aardwolf] QMP: {"return": {}}

[2025-01-31T05:49:42.875] [debug] [arp] [6775] started: arp -an
[2025-01-31T05:49:44.992] [debug] [arp] [6776] started: arp -an
[2025-01-31T05:49:47.099] [debug] [arp] [6778] started: arp -an
[2025-01-31T05:49:49.259] [debug] [arp] [6779] started: arp -an
[2025-01-31T05:49:51.321] [debug] [arp] [6780] started: arp -an
[2025-01-31T05:49:53.457] [debug] [arp] [6781] started: arp -an
[2025-01-31T05:49:55.582] [debug] [arp] [6782] started: arp -an
[2025-01-31T05:49:57.732] [debug] [arp] [6784] started: arp -an
[2025-01-31T05:49:59.832] [debug] [arp] [6785] started: arp -an
[2025-01-31T05:50:01.935] [debug] [arp] [6786] started: arp -an
[2025-01-31T05:50:04.013] [debug] [arp] [6788] started: arp -an
[2025-01-31T05:50:06.164] [debug] [arp] [6799] started: arp -an
[2025-01-31T05:50:08.301] [debug] [arp] [6800] started: arp -an
[2025-01-31T05:50:10.464] [debug] [arp] [6802] started: arp -an
[2025-01-31T05:50:12.599] [debug] [arp] [6804] started: arp -an
[2025-01-31T05:50:14.717] [debug] [arp] [6805] started: arp -an
[2025-01-31T05:50:16.860] [debug] [arp] [6806] started: arp -an
[2025-01-31T05:50:18.977] [debug] [arp] [6807] started: arp -an
[2025-01-31T05:50:21.048] [debug] [arp] [6808] started: arp -an
[2025-01-31T05:50:23.139] [debug] [arp] [6810] started: arp -an
[2025-01-31T05:50:25.185] [debug] [arp] [6811] started: arp -an
[2025-01-31T05:50:27.292] [debug] [arp] [6813] started: arp -an
[2025-01-31T05:50:29.426] [debug] [arp] [6814] started: arp -an
[2025-01-31T05:50:31.580] [debug] [arp] [6815] started: arp -an
[2025-01-31T05:50:33.704] [debug] [arp] [6817] started: arp -an
[2025-01-31T05:50:35.864] [debug] [arp] [6818] started: arp -an
[2025-01-31T05:50:37.963] [debug] [arp] [6819] started: arp -an
[2025-01-31T05:55:16.379] [debug] [blueprint provider] Loading "anbox-cloud-appliance" v1
[2025-01-31T05:55:16.380] [debug] [blueprint provider] Loading "charm-dev" v1
[2025-01-31T05:55:16.381] [debug] [blueprint provider] Loading "docker" v1
[2025-01-31T05:55:16.381] [debug] [blueprint provider] Loading "jellyfin" v1
[2025-01-31T05:55:16.382] [debug] [blueprint provider] Loading "minikube" v1
[2025-01-31T05:55:16.382] [debug] [blueprint provider] Loading "ros2-humble" v1
[2025-01-31T05:55:16.383] [debug] [blueprint provider] Loading "ros2-jazzy" v1
[2025-01-31T05:55:17.927] [debug] [primary] QMP: {"timestamp": {"seconds": 1738295717, "microseconds": 927326}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[4]"}}

[2025-01-31T05:59:29.531] [debug] [async task] fetch manifest periodically
[2025-01-31T05:59:29.537] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T06:02:07.336] [debug] [ssh session] Executing 'wall The system is going down for poweroff now'
[2025-01-31T06:02:07.350] [debug] [primary] Dropping cached SSH session
[2025-01-31T06:02:07.351] [debug] [primary] QMP: {"timestamp": {"seconds": 1738296127, "microseconds": 351525}, "event": "POWERDOWN"}

[2025-01-31T06:02:07.351] [info] [primary] VM powering down
[2025-01-31T06:02:07.351] [debug] [primary] QMP: {"return": {}}

[2025-01-31T06:02:10.965] [debug] [primary] QMP: {"timestamp": {"seconds": 1738296130, "microseconds": 965193}, "event": "SHUTDOWN", "data": {"guest": true, "reason": "guest-shutdown"}}

[2025-01-31T06:02:10.965] [info] [primary] VM shut down
[2025-01-31T06:02:10.978] [info] [primary] process state changed to NotRunning
[2025-01-31T06:02:10.978] [info] [primary] process finished with exit code 0
[2025-01-31T06:02:29.867] [info] [honeyed-aardwolf] Forcing shutdown
[2025-01-31T06:02:29.868] [info] [honeyed-aardwolf] Killing process
[2025-01-31T06:02:29.889] [info] [honeyed-aardwolf] process state changed to NotRunning
[2025-01-31T06:02:29.889] [info] [honeyed-aardwolf] process error occurred Crashed program: qemu-system-aarch64; error: Process crashed
[2025-01-31T06:02:29.890] [info] [honeyed-aardwolf] error: program: qemu-system-aarch64; error: Process crashed
[2025-01-31T06:02:29.898] [debug] [qemu-img] [7137] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img
[2025-01-31T06:11:10.840] [debug] [daemon] Returning setting local.driver=qemu
[2025-01-31T06:11:10.841] [debug] [daemon] Returning setting local.honeyed-aardwolf.disk=5.0GiB
[2025-01-31T06:11:10.841] [debug] [daemon] Returning setting local.honeyed-aardwolf.cpus=1
[2025-01-31T06:11:10.842] [debug] [daemon] Returning setting local.honeyed-aardwolf.memory=1.0GiB
[2025-01-31T06:11:10.842] [debug] [daemon] Returning setting local.bridged-network=en4
[2025-01-31T06:11:10.853] [debug] [ifconfig] [7660] started: ifconfig 
[2025-01-31T06:11:10.869] [debug] [networksetup] [7661] started: networksetup -listallhardwareports
[2025-01-31T06:11:10.902] [debug] [daemon] Returning setting local.honeyed-aardwolf.bridged=false
[2025-01-31T06:11:10.908] [debug] [ifconfig] [7663] started: ifconfig 
[2025-01-31T06:11:10.917] [debug] [networksetup] [7664] started: networksetup -listallhardwareports
[2025-01-31T06:11:50.526] [debug] [daemon] Succeeded setting local.honeyed-aardwolf.cpus=6
[2025-01-31T06:11:50.536] [debug] [qemu-img] [7671] started: qemu-img resize /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img 10737418240
[2025-01-31T06:11:50.554] [debug] [daemon] Succeeded setting local.honeyed-aardwolf.disk=10737418240B
[2025-01-31T06:11:50.556] [debug] [daemon] Succeeded setting local.honeyed-aardwolf.memory=17179869184B
[2025-01-31T06:11:57.421] [debug] [honeyed-aardwolf] process working dir ''
[2025-01-31T06:11:57.421] [info] [honeyed-aardwolf] process program 'qemu-system-aarch64'
[2025-01-31T06:11:57.421] [info] [honeyed-aardwolf] process arguments '-machine, virt,gic-version=3, -accel, hvf, -drive, file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on, -cpu, host, -nic, vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 6, -m, 16384M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso'
[2025-01-31T06:11:57.431] [debug] [qemu-system-aarch64] [7672] started: qemu-system-aarch64 -machine virt,gic-version=3 -nographic -dump-vmstate /private/var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/T/multipassd.LbbHkL
[2025-01-31T06:11:57.480] [info] [honeyed-aardwolf] process state changed to Starting
[2025-01-31T06:11:57.483] [info] [honeyed-aardwolf] process state changed to Running
[2025-01-31T06:11:57.483] [debug] [qemu-system-aarch64] [7673] started: qemu-system-aarch64 -machine virt,gic-version=3 -accel hvf -drive file=/Library/Application Support/com.canonical.multipass/bin/../Resources/qemu/edk2-aarch64-code.fd,if=pflash,format=raw,readonly=on -cpu host -nic vmnet-shared,model=virtio-net-pci,mac=52:54:00:e6:99:de -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 6 -m 16384M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/honeyed-aardwolf/cloud-init-config.iso
[2025-01-31T06:11:57.483] [info] [honeyed-aardwolf] process started
[2025-01-31T06:11:57.483] [debug] [honeyed-aardwolf] Waiting for SSH to be up
[2025-01-31T06:11:57.485] [debug] [arp] [7674] started: arp -an
[2025-01-31T06:11:57.510] [debug] [honeyed-aardwolf] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2025-01-31T06:11:57.529] [debug] [honeyed-aardwolf] QMP: {"return": {}}

[2025-01-31T06:11:59.574] [debug] [arp] [7676] started: arp -an
[2025-01-31T06:12:01.672] [debug] [arp] [7677] started: arp -an
[2025-01-31T06:12:43.991] [debug] [arp] [7701] started: arp -an
[2025-01-31T06:12:46.133] [debug] [arp] [7702] started: arp -an
[2025-01-31T06:14:27.879] [debug] [arp] [7763] started: arp -an
[2025-01-31T06:14:29.511] [debug] [async task] fetch manifest periodically
[2025-01-31T06:14:29.517] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T06:15:19.709] [debug] [arp] [7810] started: arp -an
[2025-01-31T06:15:21.946] [debug] [arp] [7811] started: arp -an
[2025-01-31T06:15:24.153] [debug] [arp] [7813] started: arp -an
[2025-01-31T06:15:26.395] [debug] [arp] [7814] started: arp -an
[2025-01-31T06:15:28.690] [debug] [arp] [7815] started: arp -an
[2025-01-31T06:15:31.005] [debug] [arp] [7816] started: arp -an
[2025-01-31T13:36:08.639] [debug] [async task] fetch manifest periodically
[2025-01-31T13:36:08.648] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T13:51:10.587] [debug] [async task] fetch manifest periodically
[2025-01-31T13:51:10.889] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T14:06:11.606] [debug] [async task] fetch manifest periodically
[2025-01-31T14:06:11.613] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T14:21:11.570] [debug] [async task] fetch manifest periodically
[2025-01-31T14:21:11.575] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T14:36:12.567] [debug] [async task] fetch manifest periodically
[2025-01-31T14:36:12.572] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T14:51:12.589] [debug] [async task] fetch manifest periodically
[2025-01-31T14:51:12.593] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T15:06:12.554] [debug] [async task] fetch manifest periodically
[2025-01-31T15:06:12.559] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T15:21:13.581] [debug] [async task] fetch manifest periodically
[2025-01-31T15:21:13.589] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T15:36:13.552] [debug] [async task] fetch manifest periodically
[2025-01-31T15:36:13.558] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T15:51:13.545] [debug] [async task] fetch manifest periodically
[2025-01-31T15:51:13.550] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T16:06:13.557] [debug] [async task] fetch manifest periodically
[2025-01-31T16:06:13.563] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T16:21:14.536] [debug] [async task] fetch manifest periodically
[2025-01-31T16:21:14.541] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T16:36:14.532] [debug] [async task] fetch manifest periodically
[2025-01-31T16:36:14.540] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T16:51:14.527] [debug] [async task] fetch manifest periodically
[2025-01-31T16:51:14.533] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T17:06:16.572] [debug] [async task] fetch manifest periodically
[2025-01-31T17:06:16.578] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T17:21:17.546] [debug] [async task] fetch manifest periodically
[2025-01-31T17:21:17.552] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T17:36:17.529] [debug] [async task] fetch manifest periodically
[2025-01-31T17:36:17.537] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T17:51:17.524] [debug] [async task] fetch manifest periodically
[2025-01-31T17:51:17.533] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T18:06:17.516] [debug] [async task] fetch manifest periodically
[2025-01-31T18:06:17.520] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T18:21:18.507] [debug] [async task] fetch manifest periodically
[2025-01-31T18:21:18.511] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T18:36:06.550] [debug] [image vault] Checking for images to update…
[2025-01-31T18:36:18.541] [debug] [async task] fetch manifest periodically
[2025-01-31T18:36:18.544] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T18:51:18.556] [debug] [async task] fetch manifest periodically
[2025-01-31T18:51:18.560] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T19:06:18.561] [debug] [async task] fetch manifest periodically
[2025-01-31T19:06:18.567] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T19:21:18.593] [debug] [async task] fetch manifest periodically
[2025-01-31T19:21:18.598] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T19:36:19.611] [debug] [async task] fetch manifest periodically
[2025-01-31T19:36:19.615] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T19:51:19.621] [debug] [async task] fetch manifest periodically
[2025-01-31T19:51:19.625] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T21:09:08.157] [debug] [async task] fetch manifest periodically
[2025-01-31T21:09:08.161] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T21:24:09.174] [debug] [async task] fetch manifest periodically
[2025-01-31T21:24:09.179] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T21:39:09.188] [debug] [async task] fetch manifest periodically
[2025-01-31T21:39:09.196] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T21:56:12.731] [debug] [async task] fetch manifest periodically
[2025-01-31T21:56:12.738] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T22:11:12.725] [debug] [async task] fetch manifest periodically
[2025-01-31T22:11:12.734] [info] [VMImageHost] Did not find any supported products in "appliance"
[2025-01-31T22:26:12.749] [debug] [async task] fetch manifest periodically
[2025-01-31T22:26:12.754] [info] [VMImageHost] Did not find any supported products in "appliance"

Additional info

  • OS: [e.g. macOS 10.15]
  • CPU architecture or model: M2-PRO
  • multipass version: 1.15.0+mac
  • multipass info
Name:           honeyed-aardwolf
State:          Unknown
Snapshots:      0
IPv4:           --
Release:        --
Image hash:     6e1f90d3e81b (Ubuntu 24.04 LTS)
CPU(s):         --
Load:           --
Disk usage:     --
Memory usage:   --
Mounts:         --
  • multipass get local.driver

Additional context
Add any other context about the problem here.
@a2xchip a2xchip added bug needs triage Issue needs to be triaged labels Jan 31, 2025
@ricab
Copy link
Collaborator

ricab commented Feb 3, 2025

Hi @a2xchip, do I understand correctly that it is only honeyed-aardwolf that doesn't start and other instances launch/start successfully? That would point toward some sort of corruption with that particular instance.

Have a look through this troubleshooting post. Your logs would indicate situation 4.1 in there: Multipass can't find an IP for the instance. Most likely, the instance is not booting properly to the point where it even requests one.

@ricab ricab removed the needs triage Issue needs to be triaged label Feb 3, 2025
@a2xchip
Copy link
Author

a2xchip commented Feb 3, 2025

Hi, @ricab, thank you for the link. Going to try it.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@a2xchip @ricab