search

canonical / multipass

Multipass orchestrates virtual Ubuntu instances
https://multipass.run
GNU General Public License v3.0
7.79k stars 643 forks source link

launch: timed out waiting for response, Web ui: Unknown state and just hanging #3635

Closed JCzz closed 2 months ago

JCzz commented 2 months ago

Unable to create new instances from terminal or web ui

multipass launch

Additional info

multipass version multipass 1.14.0+mac multipassd 1.14.0+mac

Logs cat /Library/Logs/Multipass/multipassd.log [2024-08-13T12:48:13.515] [debug] [blueprint provider] Loading "anbox-cloud-appliance" v1 [2024-08-13T12:48:13.516] [debug] [blueprint provider] Loading "charm-dev" v1 [2024-08-13T12:48:13.517] [debug] [blueprint provider] Loading "docker" v1 [2024-08-13T12:48:13.517] [debug] [blueprint provider] Loading "jellyfin" v1 [2024-08-13T12:48:13.521] [debug] [blueprint provider] Loading "minikube" v1 [2024-08-13T12:48:13.521] [debug] [blueprint provider] Loading "ros-noetic" v1 [2024-08-13T12:48:13.521] [debug] [blueprint provider] Loading "ros2-humble" v1 [2024-08-13T12:48:13.529] [info] [rpc] gRPC listening on unix:/var/run/multipass_socket [2024-08-13T12:48:13.529] [debug] [async task] fetch manifest periodically [2024-08-13T12:48:13.536] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T12:48:13.539] [debug] [qemu-img] [19557] started: qemu-img snapshot -l /var/root/Library/Application Support/multipassd/qemu/vault/instances/microk8s/ubuntu-24.04-server-cloudimg-arm64.img [2024-08-13T12:48:13.552] [debug] [update] Latest Multipass release available is version 1.14.0 [2024-08-13T12:48:14.515] [debug] [qemu-img] [19567] started: qemu-img amend -o compat=1.1 /var/root/Library/Application Support/multipassd/qemu/vault/instances/microk8s/ubuntu-24.04-server-cloudimg-arm64.img [2024-08-13T12:48:14.527] [info] [sshfs-mount-handler] initializing mount [2024-08-13T12:48:14.529] [info] [daemon] microk8s needs starting. Starting now... [2024-08-13T12:48:14.529] [debug] [microk8s] process working dir '' [2024-08-13T12:48:14.529] [info] [microk8s] process program 'qemu-system-aarch64' [2024-08-13T12:48:14.529] [info] [microk8s] 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:d3:a1:34, -nic, vmnet-bridged,ifname=en0,model=virtio-net-pci,mac=52:54:00:ae:ea:2b, -device, virtio-scsi-pci,id=scsi0, -drive, file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/microk8s/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda, -device, scsi-hd,drive=hda,bus=scsi0.0, -smp, 4, -m, 4096M, -qmp, stdio, -chardev, null,id=char0, -serial, chardev:char0, -nographic, -cdrom, /var/root/Library/Application Support/multipassd/qemu/vault/instances/microk8s/cloud-init-config.iso, -loadvm, suspend, -machine, virt-8.2' [2024-08-13T12:48:14.529] [info] [microk8s] Resuming from a suspended state [2024-08-13T12:48:14.529] [info] [microk8s] process state changed to Starting [2024-08-13T12:48:14.540] [info] [microk8s] process state changed to Running [2024-08-13T12:48:14.540] [debug] [qemu-system-aarch64] [19568] 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:d3:a1:34 -nic vmnet-bridged,ifname=en0,model=virtio-net-pci,mac=52:54:00:ae:ea:2b -device virtio-scsi-pci,id=scsi0 -drive file=/var/root/Library/Application Support/multipassd/qemu/vault/instances/microk8s/ubuntu-24.04-server-cloudimg-arm64.img,if=none,format=qcow2,discard=unmap,id=hda -device scsi-hd,drive=hda,bus=scsi0.0 -smp 4 -m 4096M -qmp stdio -chardev null,id=char0 -serial chardev:char0 -nographic -cdrom /var/root/Library/Application Support/multipassd/qemu/vault/instances/microk8s/cloud-init-config.iso -loadvm suspend -machine virt-8.2 [2024-08-13T12:48:14.540] [info] [microk8s] process started [2024-08-13T12:48:14.541] [debug] [microk8s] Waiting for SSH to be up [2024-08-13T12:48:14.542] [info] [daemon] Starting Multipass 1.14.0+mac [2024-08-13T12:48:14.542] [info] [daemon] Daemon arguments: /Library/Application Support/com.canonical.multipass/bin/multipassd --verbosity debug [2024-08-13T12:48:16.043] [debug] [microk8s] QMP: {"QMP": {"version": {"qemu": {"micro": 1, "minor": 2, "major": 8}, "package": ""}, "capabilities": ["oob"]}}

[2024-08-13T12:48:20.669] [debug] [microk8s] QMP: {"return": {}}

[2024-08-13T12:48:20.729] [debug] [microk8s] Caching initial SSH session [2024-08-13T12:48:20.729] [debug] [microk8s] Deleted memory snapshot [2024-08-13T12:48:20.729] [debug] [microk8s] Syncing RTC clock [2024-08-13T12:48:20.729] [debug] [ssh session] Executing 'sudo timedatectl set-local-rtc 0 --adjust-system-clock' [2024-08-13T12:48:20.790] [debug] [ssh session] Executing 'which snap' [2024-08-13T12:48:20.850] [debug] [ssh session] Executing 'sudo snap list multipass-sshfs' [2024-08-13T12:48:20.888] [debug] [sshfs-mount-handler] The multipass-sshfs snap is already installed on 'microk8s' [2024-08-13T12:48:20.888] [info] [sshfs-mount-handler] process program '/Library/Application Support/com.canonical.multipass/bin/sshfs_server' [2024-08-13T12:48:20.888] [info] [sshfs-mount-handler] process arguments '192.168.105.114, 22, [2024-08-13T12:48:20.896] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [19728] started: /Library/Application Support/com.canonical.multipass/bin/sshfs_server 192.168.105.114 22 ubuntu [2024-08-13T12:48:21.016] [debug] [microk8s] QMP: {"return": ""}

[2024-08-13T12:48:21.184] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [2024-08-13T12:48:21.184] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:123 make_sftp_server(source = [2024-08-13T12:48:21.184] [debug] [ssh session] Executing 'snap run multipass-sshfs.env'

[2024-08-13T12:48:21.554] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [2024-08-13T12:48:21.184] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:123 make_sftp_server(source = [2024-08-13T12:48:21.184] [debug] [ssh session] Executing 'snap run multipass-sshfs.env' [2024-08-13T12:48:21.553] [debug] [ssh session] Executing 'sudo env LD_LIBRARY_PATH=/snap/multipass-sshfs/147/lib /snap/multipass-sshfs/147/bin/sshfs -V'

[2024-08-13T12:48:21.561] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [2024-08-13T12:48:21.184] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:123 make_sftp_server(source = [2024-08-13T12:48:21.184] [debug] [ssh session] Executing 'snap run multipass-sshfs.env' [2024-08-13T12:48:21.553] [debug] [ssh session] Executing 'sudo env LD_LIBRARY_PATH=/snap/multipass-sshfs/147/lib /snap/multipass-sshfs/147/bin/sshfs -V' [2024-08-13T12:48:21.561] [debug] [ssh session] Executing 'echo ~/setup'

[2024-08-13T12:48:21.563] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [2024-08-13T12:48:21.184] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:123 make_sftp_server(source = [2024-08-13T12:48:21.184] [debug] [ssh session] Executing 'snap run multipass-sshfs.env' [2024-08-13T12:48:21.553] [debug] [ssh session] Executing 'sudo env LD_LIBRARY_PATH=/snap/multipass-sshfs/147/lib /snap/multipass-sshfs/147/bin/sshfs -V' [2024-08-13T12:48:21.561] [debug] [ssh session] Executing 'echo ~/setup' [2024-08-13T12:48:21.563] [debug] [ssh session] Executing 'sudo /bin/bash -c 'P="/home/ubuntu/setup"; while [ ! -d "$P/" ]; do P="${P%/*}"; done; echo $P/''

[2024-08-13T12:48:21.568] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [2024-08-13T12:48:21.184] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:123 make_sftp_server(source = [2024-08-13T12:48:21.184] [debug] [ssh session] Executing 'snap run multipass-sshfs.env' [2024-08-13T12:48:21.553] [debug] [ssh session] Executing 'sudo env LD_LIBRARY_PATH=/snap/multipass-sshfs/147/lib /snap/multipass-sshfs/147/bin/sshfs -V' [2024-08-13T12:48:21.561] [debug] [ssh session] Executing 'echo ~/setup' [2024-08-13T12:48:21.563] [debug] [ssh session] Executing 'sudo /bin/bash -c 'P="/home/ubuntu/setup"; while [ ! -d "$P/" ]; do P="${P%/*}"; done; echo $P/'' [2024-08-13T12:48:21.568] [debug] [ssh session] Executing 'id -u'

[2024-08-13T12:48:21.571] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [2024-08-13T12:48:21.184] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:123 make_sftp_server(source = [2024-08-13T12:48:21.184] [debug] [ssh session] Executing 'snap run multipass-sshfs.env' [2024-08-13T12:48:21.553] [debug] [ssh session] Executing 'sudo env LD_LIBRARY_PATH=/snap/multipass-sshfs/147/lib /snap/multipass-sshfs/147/bin/sshfs -V' [2024-08-13T12:48:21.561] [debug] [ssh session] Executing 'echo ~/setup' [2024-08-13T12:48:21.563] [debug] [ssh session] Executing 'sudo /bin/bash -c 'P="/home/ubuntu/setup"; while [ ! -d "$P/" ]; do P="${P%/*}"; done; echo $P/'' [2024-08-13T12:48:21.568] [debug] [ssh session] Executing 'id -u' [2024-08-13T12:48:21.571] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:132 make_sftp_server(): id -u = 1000 [2024-08-13T12:48:21.571] [debug] [ssh session] Executing 'id -g'

[2024-08-13T12:48:21.573] [debug] [/Library/Application Support/com.canonical.multipass/bin/sshfs_server] [2024-08-13T12:48:21.184] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:123 make_sftp_server(source = [2024-08-13T12:48:21.184] [debug] [ssh session] Executing 'snap run multipass-sshfs.env' [2024-08-13T12:48:21.553] [debug] [ssh session] Executing 'sudo env LD_LIBRARY_PATH=/snap/multipass-sshfs/147/lib /snap/multipass-sshfs/147/bin/sshfs -V' [2024-08-13T12:48:21.561] [debug] [ssh session] Executing 'echo ~/setup' [2024-08-13T12:48:21.563] [debug] [ssh session] Executing 'sudo /bin/bash -c 'P="/home/ubuntu/setup"; while [ ! -d "$P/" ]; do P="${P%/*}"; done; echo $P/'' [2024-08-13T12:48:21.568] [debug] [ssh session] Executing 'id -u' [2024-08-13T12:48:21.571] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:132 make_sftp_server(): id -u = 1000 [2024-08-13T12:48:21.571] [debug] [ssh session] Executing 'id -g' [2024-08-13T12:48:21.573] [debug] [sshfs mount] /Users/cibot/actions-runner-2/_work/multipass-private/multipass-private/src/sshfs_mount/sshfs_mount.cpp:137 make_sftp_server(): id -g = 1000 [2024-08-13T12:48:21.573] [debug] [ssh session] Executing 'sudo env LD_LIBRARY_PATH=/snap/multipass-sshfs/147/lib /snap/multipass-sshfs/147/bin/sshfs -o slave -o transform_symlinks -o allow_other -o Compression=no -o dir_cache=no

[2024-08-13T13:04:52.394] [debug] [async task] fetch manifest periodically [2024-08-13T13:04:52.400] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T13:12:56.987] [debug] [daemon] Returning setting local.driver=qemu [2024-08-13T13:19:52.393] [debug] [async task] fetch manifest periodically [2024-08-13T13:19:52.397] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T13:34:53.391] [debug] [async task] fetch manifest periodically [2024-08-13T13:34:53.395] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T13:57:14.299] [debug] [async task] fetch manifest periodically [2024-08-13T13:57:14.305] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T14:12:14.297] [debug] [async task] fetch manifest periodically [2024-08-13T14:12:14.301] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T14:27:14.297] [debug] [async task] fetch manifest periodically [2024-08-13T14:27:14.301] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T14:43:22.998] [debug] [async task] fetch manifest periodically [2024-08-13T14:43:23.002] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T14:58:23.997] [debug] [async task] fetch manifest periodically [2024-08-13T14:58:24.002] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T15:13:23.995] [debug] [async task] fetch manifest periodically [2024-08-13T15:13:23.999] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T15:28:23.994] [debug] [async task] fetch manifest periodically [2024-08-13T15:28:23.999] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T15:45:03.010] [debug] [async task] fetch manifest periodically [2024-08-13T15:45:03.014] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T16:00:03.009] [debug] [async task] fetch manifest periodically [2024-08-13T16:00:03.013] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T16:11:27.887] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723558287, "microseconds": 884115}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-13T16:15:03.007] [debug] [async task] fetch manifest periodically [2024-08-13T16:15:03.011] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T16:15:13.006] [warning] [url downloader] Error getting https://cloud-images.ubuntu.com/releases/streams/v1/index.json: Network timeout - trying cache. [2024-08-13T16:15:13.006] [warning] [url downloader] Error getting https://cloud-images.ubuntu.com/buildd/daily/streams/v1/index.json: Network timeout - trying cache. [2024-08-13T16:15:23.006] [warning] [url downloader] Error getting https://cloud-images.ubuntu.com/buildd/daily/streams/v1/com.ubuntu.cloud:daily:download.json: Network timeout - trying cache. [2024-08-13T16:22:27.888] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723558947, "microseconds": 887943}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-13T16:32:31.013] [debug] [async task] fetch manifest periodically [2024-08-13T16:32:31.018] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-13T18:20:38.257] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723566038, "microseconds": 256444}, "event": "RTC_CHANGE", "data": {"offset": 4294960864, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T03:45:17.149] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723599917, "microseconds": 149033}, "event": "RTC_CHANGE", "data": {"offset": 4294927643, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T04:34:19.985] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723602859, "microseconds": 985530}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T04:37:41.764] [debug] [async task] fetch manifest periodically [2024-08-14T04:37:41.768] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T04:53:44.975] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723604024, "microseconds": 975068}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T05:27:53.497] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723606073, "microseconds": 497283}, "event": "RTC_CHANGE", "data": {"offset": 4294966325, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T05:56:28.981] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723607788, "microseconds": 981720}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T06:23:56.652] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723609436, "microseconds": 652411}, "event": "RTC_CHANGE", "data": {"offset": 0, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T07:12:33.962] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723612353, "microseconds": 962803}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T07:45:00.973] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723614300, "microseconds": 973086}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T08:24:48.816] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723616688, "microseconds": 816217}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T08:58:56.921] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723618736, "microseconds": 921295}, "event": "RTC_CHANGE", "data": {"offset": 1, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T09:11:22.329] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723619482, "microseconds": 329061}, "event": "RTC_CHANGE", "data": {"offset": 4294967209, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T09:13:16.750] [debug] [async task] fetch manifest periodically [2024-08-14T09:13:16.756] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T09:44:48.938] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723621488, "microseconds": 937665}, "event": "RTC_CHANGE", "data": {"offset": 4294965863, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T09:52:41.043] [debug] [async task] fetch manifest periodically [2024-08-14T09:52:41.047] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T09:57:43.599] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723622263, "microseconds": 598841}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T10:07:42.053] [debug] [async task] fetch manifest periodically [2024-08-14T10:07:42.074] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T10:08:41.588] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723622921, "microseconds": 588558}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T10:19:40.581] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723623580, "microseconds": 580826}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T10:22:44.061] [debug] [async task] fetch manifest periodically [2024-08-14T10:22:44.065] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T10:30:40.576] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723624240, "microseconds": 576088}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T10:37:46.070] [debug] [async task] fetch manifest periodically [2024-08-14T10:37:46.074] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T10:41:38.565] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723624898, "microseconds": 565188}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T10:52:37.557] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723625557, "microseconds": 557335}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T10:52:47.080] [debug] [async task] fetch manifest periodically [2024-08-14T10:52:47.084] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T11:03:35.554] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723626215, "microseconds": 554326}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T11:07:49.089] [debug] [async task] fetch manifest periodically [2024-08-14T11:07:49.093] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T11:14:36.545] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723626876, "microseconds": 544926}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T11:22:49.098] [debug] [async task] fetch manifest periodically [2024-08-14T11:22:49.102] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T11:25:34.534] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723627534, "microseconds": 534064}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T11:36:32.527] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723628192, "microseconds": 527186}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T11:37:09.114] [debug] [image vault] Checking for images to update… [2024-08-14T11:37:50.108] [debug] [async task] fetch manifest periodically [2024-08-14T11:37:50.113] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T11:47:31.518] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723628851, "microseconds": 518485}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T11:52:51.118] [debug] [async task] fetch manifest periodically [2024-08-14T11:52:51.123] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T11:58:29.510] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723629509, "microseconds": 510526}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T12:07:52.128] [debug] [async task] fetch manifest periodically [2024-08-14T12:07:52.132] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T12:09:28.503] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723630168, "microseconds": 502796}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T12:20:26.496] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723630826, "microseconds": 495182}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T12:22:54.137] [debug] [async task] fetch manifest periodically [2024-08-14T12:22:54.140] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T12:31:24.490] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723631484, "microseconds": 489844}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T12:37:54.145] [debug] [async task] fetch manifest periodically [2024-08-14T12:37:54.150] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T12:42:22.483] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723632142, "microseconds": 483054}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T12:52:56.156] [debug] [async task] fetch manifest periodically [2024-08-14T12:52:56.160] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T12:53:20.471] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723632800, "microseconds": 471717}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T13:04:22.466] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723633462, "microseconds": 466451}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T13:07:58.165] [debug] [async task] fetch manifest periodically [2024-08-14T13:07:58.170] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T13:15:20.461] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723634120, "microseconds": 460861}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T13:22:59.174] [debug] [async task] fetch manifest periodically [2024-08-14T13:22:59.179] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T13:26:18.452] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723634778, "microseconds": 452085}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T13:37:16.440] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723635436, "microseconds": 440512}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T13:38:01.184] [debug] [async task] fetch manifest periodically [2024-08-14T13:38:01.187] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T13:48:15.432] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723636095, "microseconds": 432743}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T13:53:02.192] [debug] [async task] fetch manifest periodically [2024-08-14T13:53:02.196] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T13:59:13.426] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723636753, "microseconds": 426180}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T14:08:04.203] [debug] [async task] fetch manifest periodically [2024-08-14T14:08:04.207] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T14:10:11.417] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723637411, "microseconds": 417408}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T14:21:13.409] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723638073, "microseconds": 409339}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T14:23:05.212] [debug] [async task] fetch manifest periodically [2024-08-14T14:23:05.217] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T14:32:13.405] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723638733, "microseconds": 405157}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T14:38:07.221] [debug] [async task] fetch manifest periodically [2024-08-14T14:38:07.226] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T14:43:11.394] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723639391, "microseconds": 393900}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T14:53:09.230] [debug] [async task] fetch manifest periodically [2024-08-14T14:53:09.234] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T14:54:10.386] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723640050, "microseconds": 386087}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T15:05:09.378] [debug] [microk8s] QMP: {"timestamp": {"seconds": 1723640709, "microseconds": 378420}, "event": "RTC_CHANGE", "data": {"offset": 4294965746, "qom-path": "/machine/unattached/device[7]"}}

[2024-08-14T15:05:33.140] [info] [microk8s] SSH session disconnected: [ssh proc] failed to open session channel: 'Socket error: Connection reset by peer' [2024-08-14T15:05:33.141] [debug] [microk8s] Renewing cached SSH session [2024-08-14T15:05:40.920] [debug] [microk8s] Error getting extra IP addresses: ssh connection failed: 'Operation timed out' [2024-08-14T15:05:42.830] [info] [microk8s] SSH session disconnected [2024-08-14T15:05:42.831] [debug] [microk8s] Caching new SSH session [2024-08-14T15:05:50.727] [info] [microk8s] SSH session disconnected [2024-08-14T15:05:50.727] [debug] [microk8s] Caching new SSH session [2024-08-14T15:05:58.629] [info] [microk8s] SSH session disconnected [2024-08-14T15:05:58.629] [debug] [microk8s] Caching new SSH session [2024-08-14T15:06:06.520] [info] [microk8s] SSH session disconnected [2024-08-14T15:06:06.521] [debug] [microk8s] Caching new SSH session [2024-08-14T15:06:14.406] [info] [microk8s] SSH session disconnected [2024-08-14T15:06:14.407] [debug] [microk8s] Caching new SSH session [2024-08-14T15:06:22.297] [info] [microk8s] SSH session disconnected [2024-08-14T15:06:22.297] [debug] [microk8s] Caching new SSH session [2024-08-14T15:06:30.185] [info] [microk8s] SSH session disconnected [2024-08-14T15:06:30.185] [debug] [microk8s] Caching new SSH session [2024-08-14T15:06:38.073] [info] [microk8s] SSH session disconnected [2024-08-14T15:06:38.074] [debug] [microk8s] Caching new SSH session [2024-08-14T15:08:09.239] [debug] [async task] fetch manifest periodically [2024-08-14T15:08:09.245] [info] [VMImageHost] Did not find any supported products in "appliance" [2024-08-14T15:10:24.272] [info] [microk8s] SSH session disconnected [2024-08-14T15:10:24.272] [debug] [microk8s] Caching new SSH session [2024-08-14T15:10:44.273] [info] [microk8s] SSH session disconnected [2024-08-14T15:10:44.274] [debug] [microk8s] Caching new SSH session [2024-08-14T15:11:04.276] [debug] [microk8s] Error getting extra IP addresses: ssh connection failed: 'Timeout connecting to 192.168.105.114' [2024-08-14T15:11:04.277] [info] [microk8s] SSH session disconnected [2024-08-14T15:11:04.277] [debug] [microk8s] Caching new SSH session [2024-08-14T15:13:02.434] [info] [microk8s] SSH session disconnected [2024-08-14T15:13:02.434] [debug] [microk8s] Caching new SSH session [2024-08-14T15:13:22.436] [debug] [microk8s] Error getting extra IP addresses: ssh connection failed: 'Timeout connecting to 192.168.105.114' [2024-08-14T15:13:52.211] [info] [microk8s] SSH session disconnected [2024-08-14T15:13:52.211] [debug] [microk8s] Caching new SSH session [2024-08-14T15:14:12.212] [debug] [microk8s] Error getting extra IP addresses: ssh connection failed: 'Timeout connecting to 192.168.105.114'

SprantaSplunk commented 2 months ago

having the same issue

DoChaoing123 commented 2 months ago

Mee too.

RayOei commented 2 months ago

Same here: this started after the upgrade to macOS 14.6.1

georgeliao commented 2 months ago

Interestingly, my MacBook pro is a macOS Sonoma 14.6.1, m2 chip. And I did not encounter this problem on multipass 1.14.0. @JCzz based on the log, it looks like multipass just can not reach the internet. Can you first try turning the firewall off, restart the computer, and check if it makes a difference?

JCzz commented 2 months ago

Hi @georgeliao

The firewall is already turned off, but good suggestion. Also I brew upgraded from the previous version.

I also tried installing with macos installer from github, same problem.

Thanks Christian

SprantaSplunk commented 2 months ago

I can not turn off the firewall, it is not allowed regarding security restrictions....

RayOei commented 2 months ago

Same here: this started after the upgrade to macOS 14.6.1

For some reason my VM became corrupt/unstable/no idea. It seems it is starting but the shell is never able to connect to it. I ultimately deleted it and created a new one. All is fine now. 🤷

georgeliao commented 2 months ago

@JCzz

Then we need more info to diagnose. You mentioned that you can not launch any new vm instance, right? Did this also occur on the old version multipass before you update? Did you have any MacOs update recently?

JCzz commented 2 months ago

@georgeliao

I had no problem on the previous version, and yes I upgraded the os to see it that would help the multipass problem - but with no luck.

Please keep asking

georgeliao commented 2 months ago

@JCzz

If you were able to run things on the previous version multipass. Can you try to install multipass 1.13.1 again and see if it makes a difference on the current os version?

JCzz commented 2 months ago

I may have solved my issue. I think that stoping and closing "Cisco Secure Client" not just disconnecting, may have been the root problem. I found this here https://discourse.ubuntu.com/t/how-to-troubleshoot-networking-on-macos/12901

Before I did not completely "quit" the VPN client, just disconnected. But I also did the following:

  1. run uninstall.sh sudo sh "/Library/Application Support/com.canonical.multipass/uninstall.sh"

  2. re-installed multipass, in my case via brew

Thanks @georgeliao for keep asking 👍hopefully this is it - I will close this issue

georgeliao commented 2 months ago

@JCzz Good that you have resolved it. What was baffling was that why the VPN was not an issue previously. I could not fathom what mutlipass change during this upgrade could make this difference.

JCzz commented 2 months ago

@georgeliao

Good thinking, and a followup comment is, that I have just recently started to use Cisco Secure Client.

And I totally love multipass, and I am glad that it keeps evolving - good job!