0s autopkgtest [14:08:33]: starting date and time: 2025-04-07 14:08:33+0000 0s autopkgtest [14:08:33]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [14:08:33]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.gejytpma/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:linux-meta-gcp-5.15,src:linux-gcp-5.15,src:linux-signed-gcp-5.15 --apt-upgrade bbswitch --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=linux-meta-gcp-5.15/5.15.0.1081.90~20.04.1 linux-gcp-5.15/5.15.0-1081.90~20.04.1 linux-signed-gcp-5.15/5.15.0-1081.90~20.04.1' --setup-commands 'apt-get install -y ^kernel-testing--linux\-gcp\-5\.15--full--preferred$ || apt-get install -y ^linux-image\-gcp\-5\.15$ ^linux-headers\-gcp\-5\.15$ || apt-get install -y ^linux-image-generic\-gcp\-5\.15$ ^linux-headers-generic\-gcp\-5\.15$' --setup-commands 'apt-get install -y ^kernel-testing--linux\-gcp\-5\.15--modules-extra--preferred$ || apt-get install -y ^linux-modules-extra\-gcp\-5\.15$ || :' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-arm64-3.secgroup --name adt-focal-arm64-bbswitch-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-be05046e-ed49-4fa8-8eb0-4eb5644b3c71 --image adt/ubuntu-focal-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 59s autopkgtest [14:09:32]: testbed dpkg architecture: arm64 59s autopkgtest [14:09:32]: testbed apt version: 2.0.10 59s autopkgtest [14:09:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 59s autopkgtest [14:09:32]: testbed release detected to be: None 60s autopkgtest [14:09:33]: updating testbed package index (apt update) 60s Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 60s Hit:2 http://ftpmaster.internal/ubuntu focal-updates InRelease 60s Hit:3 http://ftpmaster.internal/ubuntu focal-security InRelease 60s Get:4 http://ftpmaster.internal/ubuntu focal-proposed InRelease [277 kB] 60s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/restricted Sources [15.7 kB] 60s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/multiverse Sources [1948 B] 61s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/universe Sources [8808 B] 61s Get:8 http://ftpmaster.internal/ubuntu focal-proposed/main Sources [57.8 kB] 61s Get:9 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 Packages [316 kB] 61s Get:10 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 c-n-f Metadata [1708 B] 61s Get:11 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 Packages [2936 B] 61s Get:12 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 c-n-f Metadata [116 B] 61s Get:13 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 Packages [54.2 kB] 61s Get:14 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 c-n-f Metadata [1844 B] 61s Get:15 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 Packages [8116 B] 61s Get:16 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 c-n-f Metadata [116 B] 64s Fetched 746 kB in 1s (884 kB/s) 64s Reading package lists... 65s Reading package lists... 65s Building dependency tree... 65s Reading state information... 66s The following additional packages will be installed: 66s linux-gcp-5.15-headers-5.15.0-1081 linux-headers-5.15.0-1081-gcp 66s linux-headers-gcp-edge linux-image-5.15.0-1081-gcp linux-image-gcp-edge 66s linux-modules-5.15.0-1081-gcp 66s Suggested packages: 66s fdutils linux-gcp-5.15-doc-5.15.0 | linux-gcp-5.15-source-5.15.0 66s linux-gcp-5.15-tools 66s The following NEW packages will be installed: 66s linux-gcp-5.15-headers-5.15.0-1081 linux-gcp-edge 66s linux-headers-5.15.0-1081-gcp linux-headers-gcp-edge 66s linux-image-5.15.0-1081-gcp linux-image-gcp-edge 66s linux-modules-5.15.0-1081-gcp 66s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 66s Need to get 49.3 MB of archives. 66s After this operation, 266 MB of additional disk space will be used. 66s Get:1 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-gcp-5.15-headers-5.15.0-1081 arm64 5.15.0-1081.90~20.04.1 [12.0 MB] 66s Get:2 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-modules-5.15.0-1081-gcp arm64 5.15.0-1081.90~20.04.1 [19.9 MB] 66s Get:3 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-image-5.15.0-1081-gcp arm64 5.15.0-1081.90~20.04.1 [15.2 MB] 67s Get:4 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-image-gcp-edge arm64 5.15.0.1081.90~20.04.1 [2612 B] 67s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-headers-5.15.0-1081-gcp arm64 5.15.0-1081.90~20.04.1 [2194 kB] 67s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-headers-gcp-edge arm64 5.15.0.1081.90~20.04.1 [2544 B] 67s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-gcp-edge arm64 5.15.0.1081.90~20.04.1 [1928 B] 67s dpkg-preconfigure: unable to re-open stdin: No such file or directory 67s Fetched 49.3 MB in 1s (46.3 MB/s) 67s Selecting previously unselected package linux-gcp-5.15-headers-5.15.0-1081. 67s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 67857 files and directories currently installed.) 67s Preparing to unpack .../0-linux-gcp-5.15-headers-5.15.0-1081_5.15.0-1081.90~20.04.1_arm64.deb ... 67s Unpacking linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 69s Selecting previously unselected package linux-modules-5.15.0-1081-gcp. 69s Preparing to unpack .../1-linux-modules-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 69s Unpacking linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 70s Selecting previously unselected package linux-image-5.15.0-1081-gcp. 70s Preparing to unpack .../2-linux-image-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 70s Unpacking linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 71s Selecting previously unselected package linux-image-gcp-edge. 71s Preparing to unpack .../3-linux-image-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 71s Unpacking linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 71s Selecting previously unselected package linux-headers-5.15.0-1081-gcp. 71s Preparing to unpack .../4-linux-headers-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 71s Unpacking linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 71s Selecting previously unselected package linux-headers-gcp-edge. 71s Preparing to unpack .../5-linux-headers-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 71s Unpacking linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 71s Selecting previously unselected package linux-gcp-edge. 71s Preparing to unpack .../6-linux-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 71s Unpacking linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 71s Setting up linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 71s Setting up linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 71s Setting up linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 71s Setting up linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 72s I: /boot/vmlinuz is now a symlink to vmlinuz-5.15.0-1081-gcp 72s I: /boot/initrd.img is now a symlink to initrd.img-5.15.0-1081-gcp 72s Setting up linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 72s Setting up linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 73s Setting up linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 73s Processing triggers for linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 73s /etc/kernel/postinst.d/initramfs-tools: 73s update-initramfs: Generating /boot/initrd.img-5.15.0-1081-gcp 79s flash-kernel: deferring update (trigger activated) 79s /etc/kernel/postinst.d/zz-flash-kernel: 79s flash-kernel: deferring update (trigger activated) 79s /etc/kernel/postinst.d/zz-update-grub: 79s Sourcing file `/etc/default/grub' 79s Sourcing file `/etc/default/grub.d/init-select.cfg' 79s Generating grub configuration file ... 80s Found linux image: /boot/vmlinuz-5.15.0-1081-gcp 80s Found initrd image: /boot/initrd.img-5.15.0-1081-gcp 80s Found linux image: /boot/vmlinuz-5.4.0-212-generic 80s Found initrd image: /boot/initrd.img-5.4.0-212-generic 80s Adding boot menu entry for UEFI Firmware Settings 80s done 80s Processing triggers for flash-kernel (3.103ubuntu1~20.04.4) ... 80s Unsupported platform on EFI system, doing nothing. 81s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s E: Unable to locate package ^kernel-testing--linux-gcp-5.15--modules-extra--preferred$ 81s E: Couldn't find any package by glob '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 81s E: Couldn't find any package by regex '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 81s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s E: Unable to locate package ^linux-modules-extra-gcp-5.15$ 81s E: Couldn't find any package by glob '^linux-modules-extra-gcp-5.15$' 81s E: Couldn't find any package by regex '^linux-modules-extra-gcp-5.15$' 81s autopkgtest [14:09:54]: upgrading testbed (apt dist-upgrade and autopurge) 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 82s Starting 2 pkgProblemResolver with broken count: 0 82s Done 82s 82s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 82s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s Starting pkgProblemResolver with broken count: 0 83s Starting 2 pkgProblemResolver with broken count: 0 83s Done 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s autopkgtest [14:09:56]: rebooting testbed after setup commands that affected boot 91s autopkgtest-virt-ssh [14:10:04]: ------- nova console-log aeda72f2-74a7-46fb-a231-c419f2870b6e (adt-focal-arm64-bbswitch-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-be05046e-ed49-4fa8-8eb0-4eb5644b3c71) ------ 91s [2J[01;01H[=3h[2J[01;01HBdsDxe: loading Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 91s BdsDxe: starting Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 91s error: no suitable video mode found. 91s cloudimg-rootfs: clean, 74747/2593280 files, 692245/5217019 blocks 91s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Boot Screen[0m. 91s [[0;32m OK [0m] Started [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 91s plymouth-start.service 91s [[0;32m OK [0m] Found device [0;1;39m/dev/ttyAMA0[0m. 91s Starting [0;1;39mLoad Kernel Module chromeos_pstore[0m... 91s Starting [0;1;39mLoad Kernel Module pstore_blk[0m... 91s Starting [0;1;39mLoad Kernel Module pstore_zone[0m... 91s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module chromeos_pstore[0m. 91s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_blk[0m. 91s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_zone[0m. 91s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 91s [[0;32m OK [0m] Found device [0;1;39m/dev/disk/by-label/UEFI[0m. 91s systemd-udev-settle.service 91s [[0;32m OK [0m] Finished [0;1;39mudev Wait for Complete Device Initialization[0m. 91s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 91s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m. 91s Starting [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m... 91s multipathd.service 91s [[0;32m OK [0m] Started [0;1;39mFile System Check Daemon to report status[0m. 91s systemd-fsckd.service 91s [[0;32m OK [0m] Finished [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 91s systemd-fsck@dev-disk-by\x2dlabel-UEFI.service 91s Mounting [0;1;39m/boot/efi[0m... 91s [[0;32m OK [0m] Mounted [0;1;39m/boot/efi[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 91s boot-efi.mount 91s Starting [0;1;39mLoad AppArmor profiles[0m... 91s Starting [0;1;39mSet console font and keymap[0m... 91s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 91s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 91s Starting [0;1;39mCreate Volatile Files and Directories[0m... 91s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 91s console-setup.service 91s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 91s systemd-tmpfiles-setup.service 91s Starting [0;1;39mNetwork Time Synchronization[0m... 91s Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 91s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 91s [[0;32m OK [0m] Finished [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 91s systemd-update-utmp.service 91s systemd-timesyncd.service 91s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Synchronized[0m. 91s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 91s apparmor.service 91s [ 5.113002] cloud-init[446]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init-local' at Mon, 07 Apr 2025 14:09:11 +0000. Up 3.56 seconds. 91s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mNetwork (Pre)[0m. 91s Starting [0;1;39mNetwork Service[0m... 91s cloud-init-local.service 91s [[0;32m OK [0m] Started [0;1;39mNetwork Service[0m. 91s Starting [0;1;39mWait for Network to be Configured[0m... 91s Starting [0;1;39mNetwork Name Resolution[0m... 91s systemd-networkd.service 91s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 91s systemd-resolved.service 91s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 91s systemd-networkd-wait-online.service 91s Starting [0;1;39mCloud-init: Network Stage[0m... 91s [ 6.775367] cloud-init[509]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init' at Mon, 07 Apr 2025 14:09:14 +0000. Up 6.64 seconds. 91s [ 6.775457] cloud-init[509]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 91s [ 6.775481] cloud-init[509]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 91s [ 6.775501] cloud-init[509]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 91s [ 6.775533] cloud-init[509]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 91s [ 6.775557] cloud-init[509]: ci-info: | enp1s0 | True | 10.151.35.64 | 255.255.255.0 | global | fa:16:3e:ac:28:90 | 91s [ 6.775581] cloud-init[509]: ci-info: | enp1s0 | True | fe80::f816:3eff:feac:2890/64 | . | link | fa:16:3e:ac:28:90 | 91s [ 6.775600] cloud-init[509]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 91s [ 6.775620] cloud-init[509]: ci-info: | lo | True | ::1/128 | . | host | . | 91s [ 6.775638] cloud-init[509]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 91s [ 6.775657] cloud-init[509]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 91s [ 6.775681] cloud-init[509]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 91s [ 6.775700] cloud-init[509]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 91s [ 6.775728] cloud-init[509]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 91s [ 6.775754] cloud-init[509]: ci-info: | 0 | 0.0.0.0 | 10.151.35.1 | 0.0.0.0 | enp1s0 | UG | 91s [ 6.775774] cloud-init[509]: ci-info: | 1 | 10.151.35.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | 91s [ 6.775793] cloud-init[509]: ci-info: | 2 | 169.254.169.254 | 10.151.35.2 | 255.255.255.255 | enp1s0 | UGH | 91s [ 6.775812] cloud-init[509]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 91s [ 6.775831] cloud-init[509]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 91s [ 6.775854] cloud-init[509]: ci-info: +-------+-------------+---------+-----------+-------+ 91s [ 6.775873] cloud-init[509]: ci-info: | Route | Destination | Gateway | Interface | Flags | 91s [ 6.775894] cloud-init[509]: ci-info: +-------+-------------+---------+-----------+-------+ 91s [ 6.775923] cloud-init[509]: ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | 91s [ 6.775942] cloud-init[509]: ci-info: | 3 | local | :: | enp1s0 | U | 91s [ 6.775965] cloud-init[509]: ci-info: | 4 | multicast | :: | enp1s0 | U | 91s [ 6.775984] cloud-init[509]: ci-info: +-------+-------------+---------+-----------+-------+ 91s [ 6.776025] cloud-init[509]: 2025-04-07 14:09:14,679 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 91s [ 7.171434] cloud-init[509]: Generating public/private rsa key pair. 91s [ 7.171525] cloud-init[509]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 91s [ 7.171548] cloud-init[509]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 91s [ 7.171581] cloud-init[509]: The key fingerprint is: 91s [ 7.171610] cloud-init[509]: SHA256:DWcC4fGk4GkB9zbaGFe6P8V8xoRsnOfNvRsYPxuk+Rk root@autopkgtest 91s [ 7.171636] cloud-init[509]: The key's randomart image is: 91s [ 7.171660] cloud-init[509]: +---[RSA 3072]----+ 91s [ 7.171680] cloud-init[509]: | ..+ +.o | 91s [ 7.171705] cloud-init[509]: | o * O o o | 91s [ 7.171725] cloud-init[509]: | = O + O o | 91s [ 7.171744] cloud-init[509]: | . B o X = o . | 91s [ 7.171768] cloud-init[509]: | o o S = =.o..| 91s [ 7.171796] cloud-init[509]: | . . o B .| 91s [ 7.171816] cloud-init[509]: | o + E | 91s [ 7.171836] cloud-init[509]: | . . O| 91s [ 7.171859] cloud-init[509]: | = | 91s [ 7.171884] cloud-init[509]: +----[SHA256]-----+ 91s [ 7.171904] cloud-init[509]: Generating public/private ecdsa key pair. 91s [ 7.171924] cloud-init[509]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 91s [ 7.171946] cloud-init[509]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 91s [ 7.171970] cloud-init[509]: The key fingerprint is: 91s [ 7.171989] cloud-init[509]: SHA256:gjL3r6XHesQF2OwVFwZyqpsTXXG9Y/EYoF5SRMZ7ggA root@autopkgtest 91s [ 7.172032] cloud-init[509]: The key's randomart image is: 91s [ 7.172058] cloud-init[509]: +---[ECDSA 256]---+ 91s [ 7.172081] cloud-init[509]: | E=. =*@+ | 91s [ 7.172101] cloud-init[509]: | . =+.O. + | 91s [ 7.172121] cloud-init[509]: | ..++... * | 91s [ 7.172172] cloud-init[509]: | . o.oooo * .| 91s [ 7.172212] cloud-init[509]: | o o +.S.. + . | 91s [ 7.172233] cloud-init[509]: | + . =o | 91s [ 7.172252] cloud-init[509]: | =o. | 91s [ 7.172271] cloud-init[509]: | =+ | 91s [ 7.172291] cloud-init[509]: | +=. | 91s [ 7.172318] cloud-init[509]: +----[SHA256]-----+ 91s [ 7.172339] cloud-init[509]: Generating public/private ed25519 key pair. 91s [ 7.172360] cloud-init[509]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 91s [ 7.172383] cloud-init[509]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 91s [ 7.172404] cloud-init[509]: The key fingerprint is: 91s [ 7.172424] cloud-init[509]: SHA256:QLhpYkkc2gWiFKHOoC8d7S1rnDxzTL/gQGzavys2eUI root@autopkgtest 91s [ 7.172443] cloud-init[509]: The key's randomart image is: 91s [ 7.172465] cloud-init[509]: +--[ED25519 256]--+ 91s [ 7.172485] cloud-init[509]: |.==o... | 91s [ 7.172510] cloud-init[509]: |++o... | 91s [ 7.172534] cloud-init[509]: |=... o. | 91s [ 7.172554] cloud-init[509]: |= +o+ . | 91s [ 7.172574] cloud-init[509]: |.+.o= S | 91s [ 7.172594] cloud-init[509]: | o BE.. | 91s [ 7.172613] cloud-init[509]: |. ++==o. | 91s [ 7.172633] cloud-init[509]: | . &*+.. | 91s [ 7.172657] cloud-init[509]: | o.O=o.. | 91s [ 7.172677] cloud-init[509]: +----[SHA256]-----+ 91s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 91s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 91s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 91s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 91s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 91s [[0;32m OK [0m] Started [0;1;39mRefresh fwupd metadata regularly[0m. 91s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 91s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 91s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 91s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mPaths[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mTimers[0m. 91s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 91s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 91s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mSockets[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 91s cloud-init.service 91s Starting [0;1;39mLSB: automatic crash report generation[0m... 91s Starting [0;1;39mDeferred execution scheduler[0m... 91s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 91s Starting [0;1;39mCloud-init: Config Stage[0m... 91s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 91s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 91s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 91s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 91s Starting [0;1;39mRecord successful boot for GRUB[0m... 91s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 91s autopkgtest@ttyS1.service 91s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 91s Starting [0;1;39mAuthorization Manager[0m... 91s cron.service 91s dbus.service 91s dmesg.service 91s irqbalance.service 91s Starting [0;1;39mPollinate to seed���udo random number generator[0m... 91s [[0;32m OK [0m] Started [0;1;39mAdd entropy to /de���random 's pool a hardware RNG[0m. 91s rng-tools.service 91s Starting [0;1;39mSystem Logging Service[0m... 91s Starting [0;1;39mLogin Service[0m... 91s Starting [0;1;39mPermit User Sessions[0m... 91s Starting [0;1;39mDisk Manager[0m... 91s [[0;32m OK [0m] Started [0;1;39mDeferred execution scheduler[0m. 91s atd.service 91s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 91s systemd-user-sessions.service 91s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 91s rsyslog.service 91s [[0;32m OK [0m] Started [0;1;39mLogin Service[0m. 91s systemd-logind.service 91s Starting [0;1;39mDiscard unused bl���filesystems from /etc/fstab[0m... 91s Starting [0;1;39mHold until boot process finishes up[0m... 91s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 91s Starting [0;1;39mRotate log files[0m... 91s Starting [0;1;39mDaily man-db regeneration[0m... 91s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 91s apport.service 91s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 91s [[0;32m OK [0m] Finished [0;1;39mHold until boot process finishes up[0m. 91s [[0;32m OK [0m] Finished [0;1;39mTerminate Plymouth Boot Screen[0m. 91s [[0;32m OK [0m] Finished [0;1;39mRotate log files[0m. 91s [ 8.247937] cloud-init[656]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:config' at Mon, 07 Apr 2025 14:09:15 +0000. Up 7.93 seconds. 91s 91s Ubuntu 20.04.6 LTS autopkgtest ttyAMA0 91s 91s autopkgtest login: [ 18.220168] cloud-init[729]: Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 91s [ 18.249228] cloud-init[729]: Get:2 http://ftpmaster.internal/ubuntu focal-updates InRelease [128 kB] 91s [ 18.284127] cloud-init[729]: Get:3 http://ftpmaster.internal/ubuntu focal-security InRelease [128 kB] 91s [ 18.424145] cloud-init[729]: Get:4 http://ftpmaster.internal/ubuntu focal-updates/main Sources [630 kB] 91s [ 18.663641] cloud-init[729]: Get:5 http://ftpmaster.internal/ubuntu focal-updates/universe Sources [418 kB] 91s [ 18.695807] cloud-init[729]: Get:6 http://ftpmaster.internal/ubuntu focal-updates/main arm64 Packages [3001 kB] 91s [ 18.811149] cloud-init[729]: Get:7 http://ftpmaster.internal/ubuntu focal-updates/universe arm64 Packages [1190 kB] 91s [ 18.855193] cloud-init[729]: Get:8 http://ftpmaster.internal/ubuntu focal-security/universe Sources [258 kB] 91s [ 18.888704] cloud-init[729]: Get:9 http://ftpmaster.internal/ubuntu focal-security/restricted Sources [56.9 kB] 91s [ 18.921646] cloud-init[729]: Get:10 http://ftpmaster.internal/ubuntu focal-security/main Sources [333 kB] 91s [ 18.956659] cloud-init[729]: Get:11 http://ftpmaster.internal/ubuntu focal-security/main arm64 Packages [2687 kB] 91s [ 19.020454] cloud-init[729]: Get:12 http://ftpmaster.internal/ubuntu focal-security/universe arm64 Packages [964 kB] 91s [ 19.763858] cloud-init[729]: Fetched 9794 kB in 2s (6485 kB/s) 91s [ 20.454588] cloud-init[729]: Reading package lists... 91s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 91s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 91s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 91s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 91s ci-info: | ssh-rsa | 2a:d9:03:84:6a:0e:d7:a1:c3:3d:2c:94:92:f9:7f:cd:87:dd:2b:3e:c8:1b:1c:8c:69:cc:32:78:8b:3a:64:69 | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-20 | 91s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 91s <14>Apr 7 14:09:28 cloud-init: ############################################################# 91s <14>Apr 7 14:09:28 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 91s <14>Apr 7 14:09:28 cloud-init: 256 SHA256:gjL3r6XHesQF2OwVFwZyqpsTXXG9Y/EYoF5SRMZ7ggA root@autopkgtest (ECDSA) 91s <14>Apr 7 14:09:28 cloud-init: 256 SHA256:QLhpYkkc2gWiFKHOoC8d7S1rnDxzTL/gQGzavys2eUI root@autopkgtest (ED25519) 91s <14>Apr 7 14:09:28 cloud-init: 3072 SHA256:DWcC4fGk4GkB9zbaGFe6P8V8xoRsnOfNvRsYPxuk+Rk root@autopkgtest (RSA) 91s <14>Apr 7 14:09:28 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 91s <14>Apr 7 14:09:28 cloud-init: ############################################################# 91s -----BEGIN SSH HOST KEY KEYS----- 91s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBOGDc27iBV7ZQMajoJHzAnkYdoc6vczS5eIvzuejUu/+Xc2tuzPIc2PbNOi8ibVvE9wJ57Y8dBJHUdrcHLr1Fig= root@autopkgtest 91s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHKtZxbVOqUvx0L0Ukka2mwsUMABhHai0lQR3J/8PATg root@autopkgtest 91s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDDDtvJHtlDtEBMpMxt5Eca/rpMlmLyVs2Hlh0GwfiyxMm2pG/R1sW3WUw1mlpUz37La34Lwz16s5znm6V25RytNmEQEe5MlZWqdUXTUD+RbO2s81yWtSNstwtQAmTy1LQKXzzOray/Wr4YJ2mvOi2BplXXq70+bb1L4uNmBH6bHEpT7Dw25rboN0gKG86GvEev1xgm/PX80isZOmlBZ89CC2csyYS9M/y3Z8t8DgYEyjwcfWuQO9XhzpJnxZUjVnISWBOs/hcTwZstBASxwT2QU9HEVXGJUKYouzWUAL/OnYqin58R6VglCOWzKCFPixAsUhAwOBHdPicEaNXhqFfQh0fuM+FjGBKDi10mnwmJee8Yus3M3WRBO6TTv1GiRArxRdDpABXfXn0ZEjZJaus89c5YnlhZPZjLpjIWYXb1iICEgRhr233Z2BqOfFGRPK9Zwgt7wo3zNIBoG3IrRc3CDlLPCn79CnmN+W99xCxhnshFGQrwbrNuLjOFWI1LwkE= root@autopkgtest 91s -----END SSH HOST KEY KEYS----- 91s [ 20.544905] cloud-init[729]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:final' at Mon, 07 Apr 2025 14:09:25 +0000. Up 17.91 seconds. 91s [ 20.546182] cloud-init[729]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 finished at Mon, 07 Apr 2025 14:09:28 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 20.53 seconds 91s [[0;32m OK [0m] Stopped [0;1;39mLSB: automatic crash report generation[0m. 91s plymouth-reboot.service 91s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Reboot Screen[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mAuthorization Manager[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mUser Manager for UID 1000[0m. 91s Stopping [0;1;39mUser Runtime Directory /run/user/1000[0m... 91s [[0;32m OK [0m] Unmounted [0;1;39m/run/user/1000[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mUser Runtime Directory /run/user/1000[0m. 91s [[0;32m OK [0m] Removed slice [0;1;39mUser Slice of UID 1000[0m. 91s Stopping [0;1;39mD-Bus System Message Bus[0m... 91s Stopping [0;1;39mPermit User Sessions[0m... 91s [[0;32m OK [0m] Stopped [0;1;39mPermit User Sessions[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mNetwork[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mRemote File Systems[0m. 91s Stopping [0;1;39mNetwork Name Resolution[0m... 91s [[0;32m OK [0m] Stopped [0;1;39mD-Bus System Message Bus[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mLogin Service[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mBasic System[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mPaths[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mSlices[0m. 91s [[0;32m OK [0m] Removed slice [0;1;39mUser and Session Slice[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mSockets[0m. 91s [[0;32m OK [0m] Closed [0;1;39mD-Bus System Message Bus Socket[0m. 91s [[0;32m OK [0m] Closed [0;1;39mSyslog Socket[0m. 91s [[0;32m OK [0m] Closed [0;1;39mUUID daemon activation socket[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mSystem Initialization[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mLocal Encrypted Volumes[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���uests to Wall Directory Watch[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mSwap[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Network Stage[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mWait for Network to be Configured[0m. 91s Stopping [0;1;39mNetwork Time Synchronization[0m... 91s Stopping [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 91s [[0;32m OK [0m] Stopped [0;1;39mNetwork Name Resolution[0m. 91s Stopping [0;1;39mNetwork Service[0m... 91s [[0;32m OK [0m] Stopped [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mNetwork Service[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mNetwork (Pre)[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Local Stage (pre-network)[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mApply Kernel Variables[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mLoad Kernel Modules[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mNetwork Time Synchronization[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mCreate Volatile Files and Directories[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems[0m. 91s Unmounting [0;1;39m/boot/efi[0m... 91s [[0;32m OK [0m] Unmounted [0;1;39m/boot/efi[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mUnmount All Filesystems[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 91s [[0;32m OK [0m] Removed slice [0;1;39msystem-systemd\x2dfsck.slice[0m. 91s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems (Pre)[0m. 91s Stopping [0;1;39mDevice-Mapper Multipath Device Controller[0m... 91s [[0;32m OK [0m] Stopped [0;1;39mCreate Static Device Nodes in /dev[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mCreate System Users[0m. 91s [[0;32m OK [0m] Stopped [0;1;39mRemount Root and Kernel File Systems[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mShutdown[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mFinal Step[0m. 91s [[0;32m OK [0m] Finished [0;1;39mReboot[0m. 91s [[0;32m OK [0m] Reached target [0;1;39mReboot[0m. 91s [ 51.894035] reboot: Restarting system 91s [2J[01;01H[=3h[2J[01;01H 91s --------------------------------------------------- 91s ------- nova show aeda72f2-74a7-46fb-a231-c419f2870b6e (adt-focal-arm64-bbswitch-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-be05046e-ed49-4fa8-8eb0-4eb5644b3c71) ------ 91s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 91s | Property | Value | 91s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 91s | OS-DCF:diskConfig | MANUAL | 91s | OS-EXT-AZ:availability_zone | AZ1 | 91s | OS-EXT-SRV-ATTR:host | ps7-ra2-arm64-n1.ps7.canonical.com | 91s | OS-EXT-SRV-ATTR:hostname | adt-focal-arm64-bbswitch-20250407-134937-juju-7f2275-prod-propo | 91s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps7-ra2-arm64-n1.ps7.canonical.com | 91s | OS-EXT-SRV-ATTR:instance_name | instance-0000c93e | 91s | OS-EXT-SRV-ATTR:kernel_id | | 91s | OS-EXT-SRV-ATTR:launch_index | 0 | 91s | OS-EXT-SRV-ATTR:ramdisk_id | | 91s | OS-EXT-SRV-ATTR:reservation_id | r-tro0bnyp | 91s | OS-EXT-SRV-ATTR:root_device_name | /dev/vda | 91s | OS-EXT-STS:power_state | 1 | 91s | OS-EXT-STS:task_state | - | 91s | OS-EXT-STS:vm_state | active | 91s | OS-SRV-USG:launched_at | 2025-04-07T14:08:58.000000 | 91s | OS-SRV-USG:terminated_at | - | 91s | accessIPv4 | | 91s | accessIPv6 | | 91s | config_drive | | 91s | created | 2025-04-07T14:08:52Z | 91s | description | - | 91s | flavor:disk | 20 | 91s | flavor:ephemeral | 0 | 91s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "arm64", "hw:cpu_policy": "shared", "hw:mem_page_size": "large", "hw:pci_numa_affinity_policy": "preferred"} | 91s | flavor:original_name | autopkgtest-cpu2-ram4-disk20-arm64 | 91s | flavor:ram | 4096 | 91s | flavor:swap | 0 | 91s | flavor:vcpus | 2 | 91s | hostId | 514bcd46ddda01b3c63e985a72cd86dd0a2b6711d44b167131382610 | 91s | id | aeda72f2-74a7-46fb-a231-c419f2870b6e | 91s | image | adt/ubuntu-focal-arm64-server-20250404.img (82a1a68a-d67b-484b-bdb5-49bf84281933) | 91s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-20 | 91s | locked | False | 91s | locked_reason | - | 91s | metadata | {} | 91s | name | adt-focal-arm64-bbswitch-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-be05046e-ed49-4fa8-8eb0-4eb5644b3c71 | 91s | net_prod-autopkgtest-workers-arm64 network | 10.151.35.64 | 91s | os-extended-volumes:volumes_attached | [] | 91s | progress | 0 | 91s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-arm64-3.secgroup | 91s | server_groups | [] | 91s | status | ACTIVE | 91s | tags | [] | 91s | tenant_id | e27006f5eb9946719a589a78f7f099f8 | 91s | trusted_image_certificates | - | 91s | updated | 2025-04-07T14:08:59Z | 91s | user_id | 5676cac36e4942629c54e24741ff7157 | 91s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 91s --------------------------------------------------- 91s 117s nova [W] Using flock in prodstack7-arm64 117s Creating nova instance adt-focal-arm64-bbswitch-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-be05046e-ed49-4fa8-8eb0-4eb5644b3c71 from image adt/ubuntu-focal-arm64-server-20250404.img (UUID 82a1a68a-d67b-484b-bdb5-49bf84281933)... 117s nova [W] Timed out waiting for aeda72f2-74a7-46fb-a231-c419f2870b6e to get deleted. 117s autopkgtest [14:10:30]: ERROR: testbed failure: testbed auxverb failed with exit code 255