search

canonical / multipass

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

macos: multipass launch fails with "timed out waiting for response" #2853

Closed chrisdoherty4 closed 1 year ago

chrisdoherty4 commented 1 year ago

Describe the bug After a clean install via brew, Multipass can't launch VMs. The VM never reaches a state the CLI is happy with. Logging indicates some sort of networking issue.

To Reproduce multipass launch --name <name>

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

cli log

~ > multipass launch -vvv --name tinkerbell
[2022-12-10T08:57:55.221] [debug] [hyperkit factory] app path '/Library/Application Support/com.canonical.multipass/bin'
[2022-12-10T08:57:55.222] [debug] [hyperkit factory] qemu-img-hyperkit convert, -p, -O, qcow2, /var/root/Library/Caches/multipassd/vault/images/jammy-20221201/ubuntu-22.04-server-cloudimg-amd64.img, /var/root/Library/Caches/multipassd/vault/images/jammy-20221201/ubuntu-22.04-server-cloudimg-amd64.qcow2
[2022-12-10T08:57:59.584] [debug] [qemu-img] [19768] started: qemu-img info /var/root/Library/Caches/multipassd/vault/images/jammy-20221201/ubuntu-22.04-server-cloudimg-amd64.img
launch failed: The following errors occurred:                                   
tinkerbell: timed out waiting for response

multipassd.log

[2022-12-10T08:57:55.221] [debug] [hyperkit factory] app path '/Library/Application Support/com.canonical.multipass/bin'
[2022-12-10T08:57:55.222] [debug] [hyperkit factory] qemu-img-hyperkit convert, -p, -O, qcow2, /var/root/Library/Caches/multipassd/vault/images/jammy-20221201/ubuntu-22.04-server-cloudimg-amd64.img, /var/root/Library/Caches/multipassd/vault/images/jammy-20221201/ubuntu-22.04-server-cloudimg-amd64.qcow2
[2022-12-10T08:57:59.584] [debug] [qemu-img] [19768] started: qemu-img info /var/root/Library/Caches/multipassd/vault/images/jammy-20221201/ubuntu-22.04-server-cloudimg-amd64.img
[2022-12-10T08:58:00.498] [debug] [tinkerbell] Waiting for SSH to be up
[2022-12-10T08:58:00.498] [debug] [tinkerbell] process working dir ''
[2022-12-10T08:58:00.499] [info] [tinkerbell] process program '/Library/Application Support/com.canonical.multipass/bin/hyperkit'
[2022-12-10T08:58:00.499] [info] [tinkerbell] process arguments '-c, 1, -m, 1024M, -u, -A, -H, -U, 7f9a6871-b86f-30c3-b013-2c4fc42cda59, -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/tinkerbell/pty,log=/Library/Logs/Multipass/tinkerbell-hyperkit.log, -s, 1:0,virtio-blk,file:///var/root/Library/Application Support/multipassd/vault/instances/tinkerbell/ubuntu-22.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/tinkerbell/cloud-init-config.iso, -f, kexec,/var/root/Library/Application Support/multipassd/vault/instances/tinkerbell/ubuntu-22.04-server-cloudimg-amd64-vmlinuz-generic,/var/root/Library/Application Support/multipassd/vault/instances/tinkerbell/ubuntu-22.04-server-cloudimg-amd64-initrd-generic,earlyprintk=serial console=ttyS0 root=/dev/vda1 rw panic=1 no_timer_check'
[2022-12-10T08:58:00.499] [info] [tinkerbell] process state changed to Starting
[2022-12-10T08:58:00.508] [info] [tinkerbell] process state changed to Running
[2022-12-10T08:58:01.013] [error] [tinkerbell] Using fd 5 for I/O notifications
[2022-12-10T08:58:01.015] [info] [tinkerbell] mirage_block_open: block_config = file:///var/root/Library/Application Support/multipassd/vault/instances/tinkerbell/ubuntu-22.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

[2022-12-10T08:58:01.015] [info] [tinkerbell] hyperkit: [INFO] rounding up file to a whole number of clusters (= 3067136 sectors)
[2022-12-10T08:58:01.038] [info] [tinkerbell] hyperkit: [INFO] Resized file to 23962 clusters (3067136 sectors)
[2022-12-10T08:58:01.038] [info] [tinkerbell] hyperkit: [INFO] block recycler starting with keep_erased = 2048
[2022-12-10T08:58:01.038] [info] [tinkerbell] hyperkit: [INFO] discard requested and lazy_refcounts is disabled: erasing refcount table and enabling lazy_refcounts
[2022-12-10T08:58:01.041] [info] [tinkerbell] hyperkit: [INFO] image has 0 free sectors and 23958 used sectors
[2022-12-10T08:58:01.041] [info] [tinkerbell] mirage_block_open: block_config = file:///var/root/Library/Application Support/multipassd/vault/instances/tinkerbell/ubuntu-22.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 returning 0
mirage_block_stat

[2022-12-10T08:58:01.041] [info] [tinkerbell] /var/root/Library/Application Support/multipassd/vault/instances/tinkerbell/cloud-init-config.iso: fcntl(F_PUNCHHOLE) Operation not permitted: block device will not support TRIM/DISCARD
[2022-12-10T08:58:02.303] [error] [tinkerbell] vmx_set_ctlreg: cap_field: 2 bit: 22 unspecified don't care: bit is 0
[2022-12-10T08:58:02.303] [error] [tinkerbell] vmx_set_ctlreg: cap_field: 4 bit: 12 unspecified don't care: bit is 0
[2022-12-10T08:58:02.303] [error] [tinkerbell] vmx_set_ctlreg: cap_field: 4 bit: 20 unspecified don't care: bit is 0
[2022-12-10T08:58:02.303] [error] [tinkerbell] vmx_set_ctlreg: cap_field: 3 bit: 13 unspecified don't care: bit is 0
[2022-12-10T08:58:02.774] [info] [tinkerbell] rdmsr to register 0x3a on vcpu 0
[2022-12-10T08:58:02.774] [info] [tinkerbell] rdmsr to register 0x140 on vcpu 0
[2022-12-10T08:58:03.484] [info] [tinkerbell] rdmsr to register 0x64e on vcpu 0
[2022-12-10T08:58:03.484] [info] [tinkerbell] rdmsr to register 0x34 on vcpu 0
[2022-12-10T08:58:04.022] [info] [tinkerbell] rdmsr to register 0x48 on vcpu 0
[2022-12-10T08:58:04.022] [info] [tinkerbell] rdmsr to register 0x122 on vcpu 0
[2022-12-10T08:58:04.022] [info] [tinkerbell] rdmsr to register 0x10f on vcpu 0
[2022-12-10T08:58:04.022] [info] [tinkerbell] rdmsr to register 0x123 on vcpu 0
[2022-12-10T08:58:04.022] [info] [tinkerbell] rdmsr to register 0xc0011020 on vcpu 0
[2022-12-10T08:58:06.114] [info] [tinkerbell] hyperkit: [INFO] block recycler: triggering background flush: 23958 used; 0 junk; 0 erased; 0 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 23961
[2022-12-10T08:58:12.167] [info] [tinkerbell] hyperkit: [INFO] Allocator: 23958 used; 0 junk; 0 erased; 0 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 23961
[2022-12-10T08:58:12.167] [info] [tinkerbell] hyperkit: [INFO] Allocator: file contains cluster 0 .. 23961 will enlarge file to 0 .. 24473
[2022-12-10T08:58:12.167] [info] [tinkerbell] hyperkit: [INFO] resize: adding available clusters (Node ((x 23962) (y 24473) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2022-12-10T09:00:15.874] [info] [tinkerbell] hyperkit: [INFO] Allocator: 24215 used; 0 junk; 0 erased; 255 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 24218
[2022-12-10T09:00:15.874] [info] [tinkerbell] hyperkit: [INFO] Allocator: file contains cluster 0 .. 24473 will enlarge file to 0 .. 24985
[2022-12-10T09:00:15.874] [info] [tinkerbell] hyperkit: [INFO] resize: adding available clusters (Node ((x 24474) (y 24985) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2022-12-10T09:00:17.670] [info] [tinkerbell] hyperkit: [INFO] Allocator: 24727 used; 0 junk; 0 erased; 255 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 24730
[2022-12-10T09:00:17.670] [info] [tinkerbell] hyperkit: [INFO] Allocator: file contains cluster 0 .. 24985 will enlarge file to 0 .. 25497
[2022-12-10T09:00:17.670] [info] [tinkerbell] hyperkit: [INFO] resize: adding available clusters (Node ((x 24986) (y 25497) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2022-12-10T09:00:19.465] [info] [tinkerbell] hyperkit: [INFO] Allocator: 25239 used; 0 junk; 0 erased; 255 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 25242
[2022-12-10T09:00:19.465] [info] [tinkerbell] hyperkit: [INFO] Allocator: file contains cluster 0 .. 25497 will enlarge file to 0 .. 26009
[2022-12-10T09:00:19.465] [info] [tinkerbell] hyperkit: [INFO] resize: adding available clusters (Node ((x 25498) (y 26009) (l Empty) (r Empty) (h 1) (cardinal 512)))
[2022-12-10T09:01:04.486] [info] [tinkerbell] hyperkit: [INFO] block recycler: triggering background flush: 25545 used; 0 junk; 35 erased; 426 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 25583
[2022-12-10T09:01:04.487] [info] [tinkerbell] hyperkit: [INFO] block recycler: 0 cluster copies flushed; 0 cluster copies complete; 35 clusters erased
[2022-12-10T09:01:04.488] [info] [tinkerbell] hyperkit: [INFO] block recycler: flush: 25545 used; 0 junk; 0 erased; 461 available; 0 copies; 0 roots; 0 Copying; 0 Copied; 0 Flushed; 0 Referenced; max_cluster = 25583

vm hyperkit log

+++++++++++++++++++++++++++++++++
[  131.088727] cloud-init[553]: ci-info: +--------+------+---------------------------+-----------+-------+-------------------+
[  131.090029] cloud-init[553]: ci-info: | Device |  Up  |          Address          |    Mask   | Scope |     Hw-Address    |
[  131.095386] cloud-init[553]: ci-info: +--------+------+---------------------------+-----------+-------+-------------------+
[  131.096786] cloud-init[553]: ci-info: | enp0s2 | True | fe80::13:f0ff:fef5:de1/64 |     .     |  link | 02:13:f0:f5:0d:e1 |
[  131.099615] cloud-init[553]: ci-info: |   lo   | True |         127.0.0.1         | 255.0.0.0 |  host |         .         |
[  131.103297] cloud-init[553]: ci-info: |   lo   | True |          ::1/128          |     .     |  host |         .         |
[  131.104666] cloud-init[553]: ci-info: +--------+------+---------------------------+-----------+-------+-------------------+
[  131.107280] cloud-init[553]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++
[  131.111346] cloud-init[553]: ci-info: +-------+-------------+---------+-----------+-------+
[  131.112385] cloud-init[553]: ci-info: | Route | Destination | Gateway | Interface | Flags |
[  131.113416] cloud-init[553]: ci-info: +-------+-------------+---------+-----------+-------+
[  131.119412] cloud-init[553]: ci-info: |   1   |  fe80::/64  |    ::   |   enp0s2  |   U   |
[  131.120554] cloud-init[553]: ci-info: |   3   |    local    |    ::   |   enp0s2  |   U   |
[  131.121589] cloud-init[553]: ci-info: |   4   |  multicast  |    ::   |   enp0s2  |   U   |
[  131.122629] cloud-init[553]: ci-info: +-------+-------------+---------+-----------+-------+
[  132.501426] cloud-init[553]: Generating public/private rsa key pair.
[  132.502393] cloud-init[553]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key
[  132.503585] cloud-init[553]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub
[  132.507309] cloud-init[553]: The key fingerprint is:
[  132.508044] cloud-init[553]: SHA256:PfeGxZYBBdoopja5FFq5FDbOgkWMULidePrCFt+pKyM root@tinkerbell
[  132.509126] cloud-init[553]: The key's randomart image is:
[  132.511293] cloud-init[553]: +---[RSA 3072]----+
[  132.511994] cloud-init[553]: |.+.+o +     oo.  |
[  132.515433] cloud-init[553]: |. .o.+ +   + .   |
[  132.516262] cloud-init[553]: | +... B o o . .  |
[  132.516993] cloud-init[553]: |o +  = * o   . o |
[  132.519326] cloud-init[553]: | o  . B S o . =  |
[  132.520086] cloud-init[553]: |..   o o   o =   |
[  132.523327] cloud-init[553]: |..o . o     . o  |
[  132.524085] cloud-init[553]: |E=.. o       .   |
[  132.524819] cloud-init[553]: |o.ooo            |
[  132.527288] cloud-init[553]: +----[SHA256]-----+
[  132.528068] cloud-init[553]: Generating public/private dsa key pair.
[  132.531300] cloud-init[553]: Your identification has been saved in /etc/ssh/ssh_host_dsa_key
[  132.532563] cloud-init[553]: Your public key has been saved in /etc/ssh/ssh_host_dsa_key.pub
[  132.534933] cloud-init[553]: The key fingerprint is:
[  132.539497] cloud-init[553]: SHA256:Pg+hSjmr2AMKK2prfcNSQkRekZU/vAlUWZAS5l3JOVk root@tinkerbell
[  132.540778] cloud-init[553]: The key's randomart image is:
[  132.541627] cloud-init[553]: +---[DSA 1024]----+
[  132.542461] cloud-init[553]: |  .. o+++o*o=E   |
[  132.547349] cloud-init[553]: |  ....o+.o.*     |
[  132.548108] cloud-init[553]: |  ..  ..+.  .    |
[  132.548927] cloud-init[553]: |   .   . +       |
[  132.549909] cloud-init[553]: |  .     S +      |
[  132.550826] cloud-init[553]: |o  . o o +       |
[  132.555548] cloud-init[553]: |oo. B . +        |
[  132.556430] cloud-init[553]: |+=.+ B   +       |
[  132.557190] cloud-init[553]: |B.+o= .   .      |
[  132.557956] cloud-init[553]: +----[SHA256]-----+
[  132.559320] cloud-init[553]: Generating public/private ecdsa key pair.
[  132.563329] cloud-init[553]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key
[  132.564526] cloud-init[553]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub
[  132.567365] cloud-init[553]: The key fingerprint is:
[  132.568231] cloud-init[553]: SHA256:VInbM8mi3In5Qb4aI2+yJabrQVeQUmvV3Z8+78sqZ0k root@tinkerbell
[  132.571329] cloud-init[553]: The key's randomart image is:
[  132.572191] cloud-init[553]: +---[ECDSA 256]---+
[  132.575300] cloud-init[553]: |   .o... o.o     |
[  132.576039] cloud-init[553]: |  . .+  o.o .    |
[  132.576770] cloud-init[553]: |   .o . .+ . . . |
[  132.579320] cloud-init[553]: |   . . .+ *   o  |
[  132.580050] cloud-init[553]: |  . .. BSo o .   |
[  132.583813] cloud-init[553]: | . .  = =    Eo  |
[  132.584860] cloud-init[553]: |  . + +. o  . .o |
[  132.585658] cloud-init[553]: |   +.=.oo  . +. .|
[  132.587306] cloud-init[553]: | .+..+o.    +..+o|
[  132.588801] cloud-init[553]: +----[SHA256]-----+
[  OK  ] Finished Initial cloud-ini…ob (metadata service crawler).
[  OK  ] Reached target Cloud-config availability.
[  OK  ] Reached target Network is Online.
[  132.593632] cloud-init[553]: Generating public/private ed25519 key pair.
[  OK  ] Reached target System Initialization.
[  OK  ] Started Daily apt download activities.
[  OK  ] Started Daily apt upgrade and clean activities.
[  OK  ] Started Daily dpkg database backup timer.
[  OK  ] Started Periodic ext4 Onli…ata Check for All Filesystems.
[  132.595456] cloud-init[553]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key
[  132.601548] cloud-init[553]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub
[  132.602674] cloud-init[553]: The key fingerprint is:
[  132.603512] cloud-init[553]: SHA256:LTAzQ+s1eWO6Xoh01ub7rGOZ0ggc9zjLtXcejOGqjZ8 root@tinkerbell
[  132.604588] cloud-init[553]: The key's randomart image is:
[  132.605318] cloud-init[553]: +--[ED25519 256]--+
[  132.605988] cloud-init[553]: |      .          |
[  132.606686] cloud-init[553]: |     . . .       |
[  OK  ] Started Discard unused blocks once a week.
[  OK  ] Started Refresh fwupd metadata regularly.
[  OK  ] Started Daily rotation of log files.
[  OK  ] Started Daily man-db regeneration.
[  OK  ] Started Message of the Day.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Started Ubuntu Advantage Timer for running repeated jobs.
[  OK  ] Started Download data for …ailed at package install time.
[  132.611462] cloud-init[553]: |      B + +      |
[  132.616465] cloud-init[553]: |     ..B.B .     |
[  132.617204] cloud-init[553]: |     .ooSo+ .    |
[  132.618013] cloud-init[553]: |     .o+o*o. +   |
[  132.618718] cloud-init[553]: |      .oo*o+o o  |
[  132.619780] cloud-init[553]: |       .=+B=. .. |
[  132.620524] cloud-init[553]: |        +=E=oo.  |
[  132.621244] cloud-init[553]: +----[SHA256]-----+
[  OK  ] Started Check to see wheth…w version of Ubuntu available.
[  OK  ] Reached target Path Units.
[  OK  ] Reached target Timer Units.
[  OK  ] Listening on cloud-init hotplug hook socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on Open-iSCSI iscsid Socket.
         Starting Socket activation for snappy daemon...
[  OK  ] Listening on UUID daemon activation socket.
[  OK  ] Reached target Preparation for Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[  OK  ] Finished Availability of block devices.
[  OK  ] Listening on Socket activation for snappy daemon.
[  OK  ] Reached target Socket Units.
[  OK  ] Reached target Basic System.
         Starting LSB: automatic crash report generation...
[  OK  ] Started Regular background program processing daemon.
[  OK  ] Started D-Bus System Message Bus.
[  OK  ] Started Save initial kernel messages after boot.
         Starting Remove Stale Onli…t4 Metadata Check Snapshots...
         Starting Record successful boot for GRUB...
[  OK  ] Started irqbalance daemon.
         Starting Dispatcher daemon for systemd-networkd...
         Starting Authorization Manager...
         Starting Pollinate to seed…udo random number generator...
         Starting System Logging Service...
[  OK  ] Reached target Preparation for Logins.
         Starting Snap Daemon...
         Starting User Login Management...
         Starting Permit User Sessions...
         Starting Disk Manager...
[  OK  ] Finished Remove Stale Onli…ext4 Metadata Check Snapshots.
[  OK  ] Finished Permit User Sessions.
[  OK  ] Finished Record successful boot for GRUB.
[  OK  ] Started System Logging Service.
[  OK  ] Started Authorization Manager.
         Starting Modem Manager...
         Starting GRUB failed boot detection...
         Starting Hold until boot process finishes up...
         Starting Terminate Plymouth Boot Screen...
[  OK  ] Started LSB: automatic crash report generation.
[  OK  ] Finished Hold until boot process finishes up.
[  OK  ] Finished Terminate Plymouth Boot Screen.
[  OK  ] Finished GRUB failed boot detection.
[  OK  ] Started User Login Management.
[  OK  ] Started Serial Getty on ttyS0.
         Starting Set console scheme...
[  OK  ] Started Unattended Upgrades Shutdown.
[  OK  ] Finished Set console scheme.
[  OK  ] Started Disk Manager.
[  OK  ] Created slice Slice /system/getty.
[  OK  ] Started Getty on tty1.
[  OK  ] Reached target Login Prompts.
[  OK  ] Started Modem Manager.
[  OK  ] Started Dispatcher daemon for systemd-networkd.
[  OK  ] Finished Pollinate to seed…seudo random number generator.
         Starting OpenBSD Secure Shell server...
[  OK  ] Started OpenBSD Secure Shell server.
[  OK  ] Started Snap Daemon.
         Starting Wait until snapd is fully seeded...
[  OK  ] Started snap.lxd.hook.inst…-487b-93ce-995b9ee8c8bc.scope.

Ubuntu 22.04.1 LTS tinkerbell ttyS0

tinkerbell login: [  172.241425] cloud-init[1216]: Cloud-init v. 22.3.4-0ubuntu1~22.04.1 running 'modules:config' at Sat, 10 Dec 2022 15:00:53 +0000. Up 172.18 seconds.
[  173.090763] cloud-init[1231]: Cloud-init v. 22.3.4-0ubuntu1~22.04.1 running 'modules:final' at Sat, 10 Dec 2022 15:00:54 +0000. Up 173.02 seconds.
ci-info: ++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu++++++++++++++++++++++++++++++++++
ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+------------------+
ci-info: | Keytype |                                       Fingerprint (sha256)                                      | Options |     Comment      |
ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+------------------+
ci-info: | ssh-rsa | a0:a2:24:4b:d8:f4:76:e6:b6:ee:19:2e:69:c8:a6:20:45:51:4b:ff:39:cd:cf:9d:37:e3:b5:37:05:f6:a6:80 |    -    | ubuntu@localhost |
ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+------------------+
<14>Dec 10 09:00:54 cloud-init: #############################################################
<14>Dec 10 09:00:54 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS-----
<14>Dec 10 09:00:54 cloud-init: 1024 SHA256:Pg+hSjmr2AMKK2prfcNSQkRekZU/vAlUWZAS5l3JOVk root@tinkerbell (DSA)
<14>Dec 10 09:00:54 cloud-init: 256 SHA256:VInbM8mi3In5Qb4aI2+yJabrQVeQUmvV3Z8+78sqZ0k root@tinkerbell (ECDSA)
<14>Dec 10 09:00:54 cloud-init: 256 SHA256:LTAzQ+s1eWO6Xoh01ub7rGOZ0ggc9zjLtXcejOGqjZ8 root@tinkerbell (ED25519)
<14>Dec 10 09:00:54 cloud-init: 3072 SHA256:PfeGxZYBBdoopja5FFq5FDbOgkWMULidePrCFt+pKyM root@tinkerbell (RSA)
<14>Dec 10 09:00:54 cloud-init: -----END SSH HOST KEY FINGERPRINTS-----
<14>Dec 10 09:00:54 cloud-init: #############################################################
-----BEGIN SSH HOST KEY KEYS-----
ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBNoYv0GkOJEHBlhgbtCaTKbsNEaaJ8THy+wbxW5jSTAV5pKOaeJZP/EuV9DSFUF08S5Oo9j8by9N9BZZdHCPouA= root@tinkerbell
ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBxtmJ9CruPnyrJNv4RrWtRf/1sl4L/RzGdRGgioGOvg root@tinkerbell
ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQCu5ReZKTgR247lcsdy+3pKeEn0B0D0dSv8EQ1yDjngN7wVGdvvNz8m5xk9kk42ikDOUjCyg7oZC2pU/Mmr5fGT87F3a5vWaos3nnf5OJZF1uwdLFEjUlFBjHpTz/+GSCEk5ZMqNtD4arbe2aY5N/c9CWnS8Wwc0KjuZe0o+CAc5tmjhnUqio4t5+1c+ugWs31TUzVEt/VRpSEbvvJB05sjitxPxoUHPDjRa7IlCoSeXf+cRCbo3jFV5YvGea8oATJRyGui/XYi6+TgdiBHMRFTflmal4ngakhDXaAKwUe42uaAnhiJS2dJyCfnaLCQ+UzdFUPgv+S6oKmSEEO8ZNmn22RtbyqEhV5BURTg6T3argy+yLLGXlsup07B7cU4IoM6GSgp0rAL+523WGmVDVW+bhRHdd/noMCZZgTbNVzvd6ciO/BurNpfgiLZIQozpZih+j4vXmUYlDbjUQ66LR690BrnneGUbyYghxYKrh+pwpNttTMGkJ3SIudDnCX9YLs= root@tinkerbell
-----END SSH HOST KEY KEYS-----
[  173.246534] cloud-init[1231]: Cloud-init v. 22.3.4-0ubuntu1~22.04.1 finished at Sat, 10 Dec 2022 15:00:54 +0000. Datasource DataSourceNoCloud [seed=/dev/sr0][dsmode=net].  Up 173.24 seconds
x000007ff pref]
[    0.557991] pci 0000:00:1f.0: [8086:7000] type 00 class 0x060100
[    0.566173] pci 0000:00:1f.0: reg 0x30: [mem 0x00000000-0x000007ff pref]
[    0.571619] ACPI: PCI: Interrupt link LNKA configured for IRQ 5
[    0.574103] ACPI: PCI: Interrupt link LNKB configured for IRQ 6
[    0.578102] ACPI: PCI: Interrupt link LNKC configured for IRQ 7
[    0.582152] ACPI: PCI: Interrupt link LNKD configured for IRQ 10
[    0.586120] ACPI: PCI: Interrupt link LNKE configured for IRQ 0
[    0.587014] ACPI: PCI: Interrupt link LNKE disabled
[    0.590106] ACPI: PCI: Interrupt link LNKF configured for IRQ 0
[    0.590979] ACPI: PCI: Interrupt link LNKF disabled
[    0.594084] ACPI: PCI: Interrupt link LNKG configured for IRQ 0
[    0.597988] ACPI: PCI: Interrupt link LNKG disabled
[    0.598806] ACPI: PCI: Interrupt link LNKH configured for IRQ 0
[    0.601988] ACPI: PCI: Interrupt link LNKH disabled
[    0.606049] iommu: Default domain type: Translated 
[    0.606777] iommu: DMA domain TLB invalidation policy: lazy mode 
[    0.610238] SCSI subsystem initialized
[    0.614140] vgaarb: loaded
[    0.618072] ACPI: bus type USB registered
[    0.622026] usbcore: registered new interface driver usbfs
[    0.622839] usbcore: registered new interface driver hub
[    0.625996] usbcore: registered new device driver usb
[    0.626768] pps_core: LinuxPPS API ver. 1 registered
[    0.627774] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.630007] PTP clock support registered
[    0.630699] EDAC MC: Ver: 3.0.0
[    0.631754] NetLabel: Initializing
[    0.632266] NetLabel:  domain hash size = 128
[    0.633988] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.638011] NetLabel:  unlabeled traffic allowed by default
[    0.642053] PCI: Using ACPI for IRQ routing
[    1.843395] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[    1.844312] hpet0: 8 comparators, 32-bit 10.000000 MHz counter
[    1.850025] clocksource: Switched to clocksource tsc-early
[    1.881492] VFS: Disk quotas dquot_6.6.0
[    1.882125] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.883393] AppArmor: AppArmor Filesystem Enabled
[    1.884138] pnp: PnP ACPI init
[    1.885265] system 00:00: [io  0x0220-0x0223] has been reserved
[    1.889706] system 00:00: [io  0x0224-0x0227] has been reserved
[    1.890595] system 00:00: [io  0x04d0-0x04d1] has been reserved
[    1.891464] system 00:00: [io  0x0400-0x0407] has been reserved
[    1.892333] system 00:00: [mem 0xe0000000-0xefffffff] has been reserved
[    1.893400] pnp: PnP ACPI: found 4 devices
[    1.900290] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.901799] NET: Registered PF_INET protocol family
[    1.902857] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.912277] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
[    1.914071] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.915328] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    1.916679] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    1.917993] TCP: Hash tables configured (established 8192 bind 8192)
[    1.919332] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear)
[    1.920479] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.921598] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    1.922683] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.923559] NET: Registered PF_XDP protocol family
[    1.924284] pci 0000:00:01.0: BAR 6: assigned [mem 0x40000000-0x400007ff pref]
[    1.925341] pci 0000:00:01.1: BAR 6: assigned [mem 0x40000800-0x40000fff pref]
[    1.926408] pci 0000:00:02.0: BAR 6: assigned [mem 0x40001000-0x400017ff pref]
[    1.927463] pci 0000:00:05.0: BAR 6: assigned [mem 0x40001800-0x40001fff pref]
[    1.928516] pci 0000:00:1f.0: BAR 6: assigned [mem 0x40002000-0x400027ff pref]
[    1.929590] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.930530] pci_bus 0000:00: resource 5 [io  0x0d00-0x20ff window]
[    1.931436] pci_bus 0000:00: resource 6 [mem 0x00000000-0x7fffffffff window]
[    1.932539] pci 0000:00:1f.0: Activating ISA DMA hang workarounds
[    1.933441] PCI: CLS 0 bytes, default 64
[    1.934150] Trying to unpack rootfs image as initramfs...
[    1.942393] Initialise system trusted keyrings
[    1.943074] Key type blacklist registered
[    1.943735] workingset: timestamp_bits=36 max_order=18 bucket_order=0
[    1.946769] zbud: loaded
[    1.947484] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.954072] fuse: init (API version 7.34)
[    1.954842] integrity: Platform Keyring initialized
[    1.966392] Key type asymmetric registered
[    1.967266] Asymmetric key parser 'x509' registered
[    1.968047] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243)
[    1.969383] io scheduler mq-deadline registered
[    1.970327] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    1.971503] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    1.978108] ACPI: button: Power Button [PWRF]
[    1.979080] virtio-pci 0000:00:01.0: virtio_pci: leaving for legacy driver
[    1.980280] virtio-pci 0000:00:02.0: virtio_pci: leaving for legacy driver
[    1.981513] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver
[    1.986394] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    2.034370] 00:01: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    2.078315] 00:02: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[    2.083958] Linux agpgart interface v0.103
[    2.092378] loop: module loaded
[    2.093180] tun: Universal TUN/TAP device driver, 1.6
[    2.098089] PPP generic driver version 2.4.2
[    2.098787] VFIO - User Level meta-driver version: 0.3
[    2.099637] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.100903] ehci-pci: EHCI PCI platform driver
[    2.101629] ehci-platform: EHCI generic platform driver
[    2.106152] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    2.107066] ohci-pci: OHCI PCI platform driver
[    2.107742] ohci-platform: OHCI generic platform driver
[    2.108505] uhci_hcd: USB Universal Host Controller Interface driver
[    2.109500] i8042: PNP: No PS/2 controller found.
[    2.114071] mousedev: PS/2 mouse device common for all mice
[    2.114984] ACPI Error: Could not enable RealTimeClock event (20210730/evxfevnt-182)
[    2.116127] ACPI Warning: Could not enable fixed event - RealTimeClock (4) (20210730/evxface-618)
[    2.122650] rtc_cmos 00:03: registered as rtc0
[    2.123469] rtc_cmos 00:03: setting system clock to 2022-12-10T14:58:03 UTC (1670684283)
[    2.124694] rtc_cmos 00:03: alarms up to one day, y3k, 114 bytes nvram
[    2.125659] i2c_dev: i2c /dev entries driver
[    2.130061] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    2.131851] device-mapper: uevent: version 1.0.3
[    2.134318] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[    2.135674] platform eisa.0: Probing EISA bus 0
[    2.136311] platform eisa.0: EISA: Cannot allocate resource for mainboard
[    2.137305] platform eisa.0: Cannot allocate resource for EISA slot 1
[    2.142203] platform eisa.0: Cannot allocate resource for EISA slot 2
[    2.143221] platform eisa.0: EISA: Detected 0 cards
[    2.143945] intel_pstate: CPU model not supported
[    2.150117] ledtrig-cpu: registered to indicate activity on CPUs
[    2.151369] intel_pmc_core intel_pmc_core.0:  initialized
[    2.152284] drop_monitor: Initializing network drop monitor service
[    2.153395] NET: Registered PF_INET6 protocol family
[    2.464990] Freeing initrd memory: 30832K
[    2.471557] Segment Routing with IPv6
[    2.472124] In-situ OAM (IOAM) with IPv6
[    2.472729] NET: Registered PF_PACKET protocol family
[    2.473520] Key type dns_resolver registered
[    2.474341] IPI shorthand broadcast: enabled
[    2.474981] sched_clock: Marking stable (2459296490, 14866385)->(1405997068, 1068165807)
[    2.476272] registered taskstats version 1
[    2.476897] Loading compiled-in X.509 certificates
[    2.478223] Loaded X.509 cert 'Build time autogenerated kernel key: e377a8d98910596b8bf176f042050e2efd9d6422'
[    2.480081] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969'
[    2.482026] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19'
[    2.483595] blacklist: Loading compiled-in revocation X.509 certificates
[    2.484735] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0'
[    2.486238] zswap: loaded using pool lzo/zbud
[    2.486999] Key type ._fscrypt registered
[    2.487630] Key type .fscrypt registered
[    2.488213] Key type fscrypt-provisioning registered
[    2.491800] Key type encrypted registered
[    2.492404] AppArmor: AppArmor sha1 policy hashing enabled
[    2.493212] ima: No TPM chip found, activating TPM-bypass!
[    2.494051] Loading compiled-in module X.509 certificates
[    2.495305] Loaded X.509 cert 'Build time autogenerated kernel key: e377a8d98910596b8bf176f042050e2efd9d6422'
[    2.496760] ima: Allocated hash algorithm: sha1
[    2.497433] ima: No architecture policies found
[    2.498250] evm: Initialising EVM extended attributes:
[    2.499069] evm: security.selinux
[    2.499599] evm: security.SMACK64
[    2.500133] evm: security.SMACK64EXEC
[    2.500756] evm: security.SMACK64TRANSMUTE
[    2.501394] evm: security.SMACK64MMAP
[    2.502024] evm: security.apparmor
[    2.502531] evm: security.ima
[    2.503031] evm: security.capability
[    2.503565] evm: HMAC attrs: 0x1
[    2.504286] PM:   Magic number: 2:715:990
[    2.504851] PM:   hash matches drivers/base/power/main.c:907
[    2.505863] RAS: Correctable Errors collector initialized.
[    2.507694] Freeing unused decrypted memory: 2036K
[    2.508672] Freeing unused kernel image (initmem) memory: 3232K
[    2.518041] Write protecting the kernel read-only data: 30720k
[    2.519355] Freeing unused kernel image (text/rodata gap) memory: 2036K
[    2.520675] Freeing unused kernel image (rodata/data gap) memory: 1476K
[    2.567890] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    2.568888] x86/mm: Checking user space page tables
[    2.613219] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    2.614188] Run /init as init process
Loading, please wait...
Starting version 249.11-0ubuntu3.6
[    2.780995] ahci 0000:00:01.1: can't derive routing for PCI INT B
[    2.782133] ahci 0000:00:01.1: PCI INT B: no GSI - using ISA IRQ 6
[    2.783140] cryptd: max_cpu_qlen set to 1000
[    2.788576] AVX2 version of gcm_enc/dec engaged.
[    2.789277] AES CTR mode by8 optimization enabled
[    2.791421] ahci 0000:00:01.1: SSS flag set, parallel bus scan disabled
[    2.792568] ahci 0000:00:01.1: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0x1 impl SATA mode
[    2.793767] ahci 0000:00:01.1: flags: 64bit ncq sntf ilck stag pm led clo pio slum part sxs apst 
[    2.808951] scsi host0: ahci
[    2.814147] scsi host1: ahci
[    2.819808] virtio_blk virtio0: [vda] 10485760 512-byte logical blocks (5.37 GB/5.00 GiB)
[    2.821111] scsi host2: ahci
[    2.826088] scsi host3: ahci
[    2.829661] scsi host4: ahci
[    2.833827] virtio_net virtio1 enp0s2: renamed from eth0
[    2.835648] GPT:Primary header thinks Alt. header is not at the end of the disk.
[    2.836758] GPT:4612095 != 10485759
[    2.837295] GPT:Alternate GPT header not at the end of the disk.
[    2.838206] GPT:4612095 != 10485759
[    2.838733] GPT: Use GNU Parted to correct GPT errors.
[    2.839493]  vda: vda1 vda14 vda15
[    2.840081] scsi host5: ahci
[    2.840622] ata1: SATA max UDMA/133 abar m1024@0xc0002000 port 0xc0002100 irq 27
[    2.841721] ata2: DUMMY
[    2.842124] ata3: DUMMY
[    2.842497] ata4: DUMMY
[    2.842867] ata5: DUMMY
[    2.843237] ata6: DUMMY
[    2.970042] tsc: Refined TSC clocksource calibration: 2590.132 MHz
[    2.970998] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2555d25e148, max_idle_ns: 440795269633 ns
[    2.972384] clocksource: Switched to clocksource tsc
[    3.156404] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[    3.157451] ata1.00: ATAPI: BHYVE SATA DVD ROM, 001, max UDMA/133
[    3.158480] ata1.00: configured for UDMA/133
[    3.159294] scsi 0:0:0:0: CD-ROM            BHYVE    BHYVE DVD-ROM    001  PQ: 0 ANSI: 5
[    3.160967] sr 0:0:0:0: [sr0] scsi3-mmc drive: 0x/0x xa/form2 caddy
[    3.161906] cdrom: Uniform CD-ROM driver Revision: 3.20
[    3.187417] sr 0:0:0:0: Attached scsi generic sg0 type 5
Begin: Loading essential drivers ... [    4.522015] raid6: avx2x4   gen() 36775 MB/s
[    4.590032] raid6: avx2x4   xor() 15875 MB/s
[    4.658049] raid6: avx2x2   gen() 36210 MB/s
[    4.726064] raid6: avx2x2   xor() 21764 MB/s
[    4.794009] raid6: avx2x1   gen() 22523 MB/s
[    4.862029] raid6: avx2x1   xor() 14588 MB/s
[    4.930035] raid6: sse2x4   gen() 14625 MB/s
[    4.998023] raid6: sse2x4   xor()  8772 MB/s
[    5.066030] raid6: sse2x2   gen() 14983 MB/s
[    5.134030] raid6: sse2x2   xor()  9611 MB/s
[    5.202023] raid6: sse2x1   gen() 13252 MB/s
[    5.270017] raid6: sse2x1   xor()  6528 MB/s
[    5.270675] raid6: using algorithm avx2x4 gen() 36775 MB/s
[    5.271421] raid6: .... xor() 15875 MB/s, rmw enabled
[    5.272110] raid6: using avx2x2 recovery algorithm
[    5.272819] clocksource: timekeeping watchdog on CPU0: Marking clocksource 'tsc' as unstable because the skew is too large:
[    5.274396] clocksource:                       'hpet' wd_nsec: 1087216800 wd_now: 26c634a wd_last: 1c67de2 mask: ffffffff
[    5.276011] clocksource:                       'tsc' cs_nsec: 1086741505 cs_now: 69c058aafa54 cs_last: 69bfb0e479a0 mask: ffffffffffffffff
[    5.277830] clocksource:                       'tsc' is current clocksource.
[    5.278874] tsc: Marking TSC unstable due to clocksource watchdog
[    5.279782] TSC found unstable after boot, most likely due to broken BIOS. Use 'tsc=unstable'.
[    5.281056] sched_clock: Marking unstable (5264906881, 14875460)<-(4211616552, 1068165807)
[    5.282525] clocksource: Not enough CPUs to check clocksource 'tsc'.
[    5.283632] clocksource: Switched to clocksource hpet
[    5.283782] xor: automatically using best checksumming function   avx       
[    5.287759] async_tx: api initialized (async)
done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... [    5.370649] Btrfs loaded, crc32c=crc32c-intel, zoned=yes, fsverity=yes
Scanning for Btrfs filesystems
done.
Warning: fsck not present, so skipping root file system
[    5.406152] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[    5.611001] systemd[1]: Inserted module 'autofs4'
[    5.631300] systemd[1]: systemd 249.11-0ubuntu3.6 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT +GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
[    5.635901] systemd[1]: Detected virtualization bhyve.
[    5.636675] systemd[1]: Detected architecture x86-64.

Welcome to Ubuntu 22.04.1 LTS!

[    5.639395] systemd[1]: Hostname set to <ubuntu>.
[    5.644678] systemd[1]: Initializing machine ID from random generator.
[    5.956897] systemd[1]: Queued start job for default target Graphical Interface.
[    5.959308] systemd[1]: Created slice Slice /system/modprobe.
[  OK  ] Created slice Slice /system/modprobe.
[    5.961330] systemd[1]: Created slice Slice /system/serial-getty.
[  OK  ] Created slice Slice /system/serial-getty.
[    5.963438] systemd[1]: Created slice Slice /system/systemd-fsck.
[  OK  ] Created slice Slice /system/systemd-fsck.
[    5.965335] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    5.967302] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password R…uests to Wall Directory Watch.
[    5.969718] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[  OK  ] Set up automount Arbitrary…s File System Automount Point.
[    5.972519] systemd[1]: Reached target Slice Units.
[  OK  ] Reached target Slice Units.
[    5.974149] systemd[1]: Reached target Swaps.
[  OK  ] Reached target Swaps.
[    5.975489] systemd[1]: Reached target Local Verity Protected Volumes.
[  OK  ] Reached target Local Verity Protected Volumes.
[    5.977491] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[  OK  ] Listening on Device-mapper event daemon FIFOs.
[    5.979574] systemd[1]: Listening on LVM2 poll daemon socket.
[  OK  ] Listening on LVM2 poll daemon socket.
[    5.981388] systemd[1]: Listening on multipathd control socket.
[  OK  ] Listening on multipathd control socket.
[    5.983465] systemd[1]: Listening on Syslog Socket.
[  OK  ] Listening on Syslog Socket.
[    5.985294] systemd[1]: Listening on fsck to fsckd communication Socket.
[  OK  ] Listening on fsck to fsckd communication Socket.
[    5.987404] systemd[1]: Listening on initctl Compatibility Named Pipe.
[  OK  ] Listening on initctl Compatibility Named Pipe.
[    5.989558] systemd[1]: Listening on Journal Audit Socket.
[  OK  ] Listening on Journal Audit Socket.
[    5.991403] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    5.993250] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    5.994944] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[    5.996866] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    5.998704] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    6.001681] systemd[1]: Mounting Huge Pages File System...
         Mounting Huge Pages File System...
[    6.004314] systemd[1]: Mounting POSIX Message Queue File System...
         Mounting POSIX Message Queue File System...
[    6.012091] systemd[1]: Mounting Kernel Debug File System...
         Mounting Kernel Debug File System...
[    6.017338] systemd[1]: Mounting Kernel Trace File System...
         Mounting Kernel Trace File System...
[    6.024416] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    6.033786] systemd[1]: Starting Set the console keyboard layout...
         Starting Set the console keyboard layout...
[    6.039337] systemd[1]: Starting Create List of Static Device Nodes...
         Starting Create List of Static Device Nodes...
[    6.055458] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
         Starting Monitoring of LVM…meventd or progress polling...
[    6.066428] systemd[1]: Condition check resulted in LXD - agent being skipped.
[    6.083285] systemd[1]: Starting Load Kernel Module chromeos_pstore...
         Starting Load Kernel Module chromeos_pstore...
[    6.094259] systemd[1]: Starting Load Kernel Module configfs...
         Starting Load Kernel Module configfs...
[    6.110276] systemd[1]: Starting Load Kernel Module drm...
         Starting Load Kernel Module drm...
[    6.120337] systemd[1]: Starting Load Kernel Module efi_pstore...
         Starting Load Kernel Module efi_pstore...
[    6.143713] systemd[1]: Starting Load Kernel Module fuse...
         Starting Load Kernel Module fuse...
[    6.158275] systemd[1]: Starting Load Kernel Module pstore_blk...
         Starting Load Kernel Module pstore_blk...
[    6.166263] systemd[1]: Starting Load Kernel Module pstore_zone...
         Starting Load Kernel Module pstore_zone...
[    6.178278] systemd[1]: Starting Load Kernel Module ramoops...
         Starting Load Kernel Module ramoops...
[    6.184876] systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped.
[    6.194296] systemd[1]: Condition check resulted in File System Check on Root Device being skipped.
[    6.197792] systemd[1]: Starting Load Kernel Modules...
         Starting Load Kernel Modules...
[    6.211601] systemd[1]: Starting Remount Root and Kernel File Systems...
         Starting Remount Root and Kernel File Systems...
[    6.227236] EXT4-fs (vda1): re-mounted. Opts: discard,errors=remount-ro. Quota mode: none.
[    6.228780] systemd[1]: Starting Coldplug All udev Devices...
         Starting Coldplug All udev Devices...
[    6.242554] systemd[1]: Mounted Huge Pages File System.
[  OK  ] Mounted Huge Pages File System.
[    6.258307] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Kernel Trace File System.
[  OK  ] Finished Create List of Static Device Nodes.
[  OK  ] Finished Monitoring of LVM… dmeventd or progress polling.
[  OK  ] Finished Load Kernel Module chromeos_pstore.
[  OK  ] Finished Load Kernel Module configfs.
[  OK  ] Finished Load Kernel Module drm.
[  OK  ] Finished Load Kernel Module efi_pstore.
[  OK  ] Finished Load Kernel Module fuse.
[  OK  ] Finished Load Kernel Module pstore_blk.
[  OK  ] Finished Load Kernel Module pstore_zone.
[  OK  ] Finished Load Kernel Module ramoops.
[  OK  ] Finished Load Kernel Modules.
[  OK  ] Finished Remount Root and Kernel File Systems.
[  OK  ] Finished Set the console keyboard layout.
         Mounting FUSE Control File System...
         Mounting Kernel Configuration File System...
         Starting Device-Mapper Multipath Device Controller...
         Starting Flush Journal to Persistent Storage...
[    6.360307] alua: device handler registered
[    6.362125] emc: device handler registered
[    6.368273] rdac: device handler registered
         Starting Load/Save Random Seed...[    6.371525] systemd-journald[352]: Received client request to flush runtime journal.

         Starting Apply Kernel Variables...
         Starting Create System Users...
[  OK  ] Mounted FUSE Control File System.
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Finished Coldplug All udev Devices.
[  OK  ] Finished Load/Save Random Seed.
[  OK  ] Finished Apply Kernel Variables.
[  OK  ] Finished Create System Users.
         Starting Create Static Device Nodes in /dev...
[  OK  ] Finished Flush Journal to Persistent Storage.
[  OK  ] Finished Create Static Device Nodes in /dev.
         Starting Rule-based Manage…for Device Events and Files...
[  OK  ] Started Device-Mapper Multipath Device Controller.
[  OK  ] Reached target Preparation for Local File Systems.
         Mounting Mount unit for core20, revision 1695...
         Mounting Mount unit for lxd, revision 23541...
         Mounting Mount unit for snapd, revision 17883...
[  OK  ] Mounted Mount unit for core20, revision 1695.
[  OK  ] Mounted Mount unit for lxd, revision 23541.
[  OK  ] Mounted Mount unit for snapd, revision 17883.
[  OK  ] Started Rule-based Manager for Device Events and Files.
[  OK  ] Started Dispatch Password …ts to Console Directory Watch.
[  OK  ] Reached target Local Encrypted Volumes.
[  OK  ] Found device /dev/ttyS0.
[  OK  ] Found device /dev/disk/by-label/UEFI.
         Starting File System Check on /dev/disk/by-label/UEFI...
[  OK  ] Started File System Check Daemon to report status.
[  OK  ] Finished File System Check on /dev/disk/by-label/UEFI.
         Mounting /boot/efi...
[  OK  ] Mounted /boot/efi.
[  OK  ] Reached target Local File Systems.
         Starting Load AppArmor profiles...
         Starting Set console font and keymap...
         Starting Create final runt…dir for shutdown pivot root...
         Starting Tell Plymouth To Write Out Runtime Data...
         Starting Create Volatile Files and Directories...
         Starting Uncomplicated firewall...
[  OK  ] Finished Set console font and keymap.
[  OK  ] Finished Create final runt…e dir for shutdown pivot root.
[  OK  ] Finished Tell Plymouth To Write Out Runtime Data.
[  OK  ] Finished Create Volatile Files and Directories.
[  OK  ] Finished Uncomplicated firewall.
[  OK  ] Listening on Load/Save RF …itch Status /dev/rfkill Watch.
         Starting Network Time Synchronization...
         Starting Record System Boot/Shutdown in UTMP...
[  OK  ] Finished Record System Boot/Shutdown in UTMP.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Set.
[  OK  ] Finished Load AppArmor profiles.
         Starting Load AppArmor pro…managed internally by snapd...
         Starting Initial cloud-init job (pre-networking)...
         Mounting Arbitrary Executable File Formats File System...
[  OK  ] Mounted Arbitrary Executable File Formats File System.
[    9.876433] cloud-init[507]: Cloud-init v. 22.3.4-0ubuntu1~22.04.1 running 'init-local' at Sat, 10 Dec 2022 14:58:11 +0000. Up 9.81 seconds.
[  OK  ] Finished Initial cloud-init job (pre-networking).
[  OK  ] Reached target Preparation for Network.
         Starting Network Configuration...
[  OK  ] Started Network Configuration.
         Starting Wait for Network to be Configured...
         Starting Network Name Resolution...
[  OK  ] Started Network Name Resolution.
[  OK  ] Reached target Network.
[  OK  ] Reached target Host and Network Name Lookups.
[  OK  ] Finished Load AppArmor pro…s managed internally by snapd.
[FAILED] Failed to start Wait for Network to be Configured.
See 'systemctl status systemd-networkd-wait-online.service' for details.
         Starting Initial cloud-ini… (metadata service crawler)...
[  131.072298] cloud-init[553]: Cloud-init v. 22.3.4-0ubuntu1~22.04.1 running 'init' at Sat, 10 Dec 2022 15:00:12 +0000. Up 131.04 seconds.
[  131.087312] cloud-init[553]: ci-info: +++++++++++++++++++++++++++++++++++Net device info++

Additional info MacOS 12.6.1

~ > multipass version
multipass   1.10.1+mac
multipassd  1.10.1+mac
~ > multipass info --all
Name:           tinkerbell
State:          Unknown
IPv4:           --
Release:        --
Image hash:     8a814737df48 (Ubuntu 22.04 LTS)
Load:           --
Disk usage:     --
Memory usage:   --
Mounts:         --
~ > multipass get local.driver
hyperkit

Additional context Add any other context about the problem here.

ricab commented 1 year ago

Hi @chrisdoherty4, this usually means that there is some incompatibility between your networking setup and Multipass (e.g. firewall, VPN...). Have you seen our troubleshooting docs? Let us know what you find!

CrazyJazzHands commented 1 year ago

Hi guys I'm not a pro, just wanted to mention that I tend to get this error when I hotspot from my phone to mac.

chrisdoherty4 commented 1 year ago

I couldn't figure out what was causing the issue and just switched to Virtual Box as the backend. Perhaps the maintainers can consider switching to Virtual Box as the default driver as I know of others that gave up with QEMU and switched also.

ananyaaaaww commented 4 months ago

I had previously installed multipass in my system yesterday. It worked well for some minutes, and then again after opening it today, it was not working, When launching the instance this is the error i am getting even after repetitive trails : launch failed: The following errors occurred: primary: timed out waiting for response

Things which I have tried doing are :

uninstalled and reinstalled multipass some 3-4 times using brew as well as package installer. checked with firewall settings tallied the system settings with others whereas all the other peers are able to work in multipass smoothly. did some network troubleshooting which some of the links said. Please can you suggest a proper fixing to this. Thankyou in advance!

IMG20240716190830