0s autopkgtest [14:05:04]: starting date and time: 2025-04-07 14:05:04+0000 0s autopkgtest [14:05:04]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [14:05:04]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.am0b9hlk/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 dpdk --timeout-short=300 --timeout-copy=20000 --timeout-test=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-cpu4-ram16-disk100-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-arm64-3.secgroup --name adt-focal-arm64-dpdk-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-adca7e13-7ff1-4277-bc9f-fecf1e34b391 --image adt/ubuntu-focal-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 78s autopkgtest [14:06:22]: testbed dpkg architecture: arm64 78s autopkgtest [14:06:22]: testbed apt version: 2.0.10 79s autopkgtest [14:06:23]: @@@@@@@@@@@@@@@@@@@@ test bed setup 79s autopkgtest [14:06:23]: testbed release detected to be: None 80s autopkgtest [14:06:24]: updating testbed package index (apt update) 80s Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 80s Hit:2 http://ftpmaster.internal/ubuntu focal-updates InRelease 80s Hit:3 http://ftpmaster.internal/ubuntu focal-security InRelease 80s Get:4 http://ftpmaster.internal/ubuntu focal-proposed InRelease [277 kB] 80s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/restricted Sources [15.7 kB] 80s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/universe Sources [8808 B] 80s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/multiverse Sources [1948 B] 80s Get:8 http://ftpmaster.internal/ubuntu focal-proposed/main Sources [57.8 kB] 80s Get:9 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 Packages [316 kB] 80s Get:10 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 c-n-f Metadata [1708 B] 81s Get:11 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 Packages [2936 B] 81s Get:12 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 c-n-f Metadata [116 B] 81s Get:13 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 Packages [54.2 kB] 81s Get:14 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 c-n-f Metadata [1844 B] 81s Get:15 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 Packages [8116 B] 81s Get:16 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 c-n-f Metadata [116 B] 84s Fetched 746 kB in 1s (884 kB/s) 84s Reading package lists... 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s The following additional packages will be installed: 85s linux-gcp-5.15-headers-5.15.0-1081 linux-headers-5.15.0-1081-gcp 85s linux-headers-gcp-edge linux-image-5.15.0-1081-gcp linux-image-gcp-edge 85s linux-modules-5.15.0-1081-gcp 85s Suggested packages: 85s fdutils linux-gcp-5.15-doc-5.15.0 | linux-gcp-5.15-source-5.15.0 85s linux-gcp-5.15-tools 85s The following NEW packages will be installed: 85s linux-gcp-5.15-headers-5.15.0-1081 linux-gcp-edge 85s linux-headers-5.15.0-1081-gcp linux-headers-gcp-edge 85s linux-image-5.15.0-1081-gcp linux-image-gcp-edge 85s linux-modules-5.15.0-1081-gcp 86s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 49.3 MB of archives. 86s After this operation, 266 MB of additional disk space will be used. 86s 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] 86s 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] 86s 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] 86s 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] 87s 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] 87s 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] 87s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-gcp-edge arm64 5.15.0.1081.90~20.04.1 [1928 B] 87s dpkg-preconfigure: unable to re-open stdin: No such file or directory 87s Fetched 49.3 MB in 1s (47.5 MB/s) 87s Selecting previously unselected package linux-gcp-5.15-headers-5.15.0-1081. 87s (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.) 87s Preparing to unpack .../0-linux-gcp-5.15-headers-5.15.0-1081_5.15.0-1081.90~20.04.1_arm64.deb ... 87s Unpacking linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 88s Selecting previously unselected package linux-modules-5.15.0-1081-gcp. 89s Preparing to unpack .../1-linux-modules-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 89s Unpacking linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 90s Selecting previously unselected package linux-image-5.15.0-1081-gcp. 90s Preparing to unpack .../2-linux-image-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 90s Unpacking linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 90s Selecting previously unselected package linux-image-gcp-edge. 90s Preparing to unpack .../3-linux-image-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 90s Unpacking linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 90s Selecting previously unselected package linux-headers-5.15.0-1081-gcp. 90s Preparing to unpack .../4-linux-headers-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 90s Unpacking linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 91s Selecting previously unselected package linux-headers-gcp-edge. 91s Preparing to unpack .../5-linux-headers-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 91s Unpacking linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 91s Selecting previously unselected package linux-gcp-edge. 91s Preparing to unpack .../6-linux-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 91s Unpacking linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 91s Setting up linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 91s Setting up linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 91s Setting up linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 91s Setting up linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 92s I: /boot/vmlinuz is now a symlink to vmlinuz-5.15.0-1081-gcp 92s I: /boot/initrd.img is now a symlink to initrd.img-5.15.0-1081-gcp 92s Setting up linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 92s Setting up linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 93s Setting up linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 93s Processing triggers for linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 93s /etc/kernel/postinst.d/initramfs-tools: 93s update-initramfs: Generating /boot/initrd.img-5.15.0-1081-gcp 99s flash-kernel: deferring update (trigger activated) 99s /etc/kernel/postinst.d/zz-flash-kernel: 99s flash-kernel: deferring update (trigger activated) 99s /etc/kernel/postinst.d/zz-update-grub: 99s Sourcing file `/etc/default/grub' 99s Sourcing file `/etc/default/grub.d/init-select.cfg' 99s Generating grub configuration file ... 99s Found linux image: /boot/vmlinuz-5.15.0-1081-gcp 99s Found initrd image: /boot/initrd.img-5.15.0-1081-gcp 100s Found linux image: /boot/vmlinuz-5.4.0-212-generic 100s Found initrd image: /boot/initrd.img-5.4.0-212-generic 100s Adding boot menu entry for UEFI Firmware Settings 100s done 100s Processing triggers for flash-kernel (3.103ubuntu1~20.04.4) ... 100s Unsupported platform on EFI system, doing nothing. 100s Reading package lists... 100s Building dependency tree... 100s Reading state information... 101s E: Unable to locate package ^kernel-testing--linux-gcp-5.15--modules-extra--preferred$ 101s E: Couldn't find any package by glob '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 101s E: Couldn't find any package by regex '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 101s E: Unable to locate package ^linux-modules-extra-gcp-5.15$ 101s E: Couldn't find any package by glob '^linux-modules-extra-gcp-5.15$' 101s E: Couldn't find any package by regex '^linux-modules-extra-gcp-5.15$' 101s autopkgtest [14:06:45]: upgrading testbed (apt dist-upgrade and autopurge) 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 101s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 101s Starting 2 pkgProblemResolver with broken count: 0 101s Done 101s 102s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 102s Reading package lists... 102s Building dependency tree... 102s Reading state information... 102s Starting pkgProblemResolver with broken count: 0 102s Starting 2 pkgProblemResolver with broken count: 0 102s Done 102s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 102s autopkgtest [14:06:46]: rebooting testbed after setup commands that affected boot 111s autopkgtest-virt-ssh [14:06:55]: ------- nova console-log 97907921-9ca3-40c7-a923-032cc33a1e4e (adt-focal-arm64-dpdk-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-adca7e13-7ff1-4277-bc9f-fecf1e34b391) ------ 111s [2J[01;01H[=3h[2J[01;01HBdsDxe: loading Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 111s BdsDxe: starting Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 111s error: no suitable video mode found. 111s cloudimg-rootfs: clean, 74747/2593280 files, 692245/5217019 blocks 111s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Boot Screen[0m. 111s plymouth-start.service 111s [[0;32m OK [0m] Started [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 111s [[0;32m OK [0m] Found device [0;1;39m/dev/ttyAMA0[0m. 111s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 111s Starting [0;1;39mLoad Kernel Module chromeos_pstore[0m... 111s Starting [0;1;39mLoad Kernel Module pstore_blk[0m... 111s Starting [0;1;39mLoad Kernel Module pstore_zone[0m... 111s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module chromeos_pstore[0m. 111s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_blk[0m. 111s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_zone[0m. 111s [[0;32m OK [0m] Found device [0;1;39m/dev/disk/by-label/UEFI[0m. 111s systemd-udev-settle.service 111s [[0;32m OK [0m] Finished [0;1;39mudev Wait for Complete Device Initialization[0m. 111s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 111s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m. 111s Starting [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m... 111s multipathd.service 111s systemd-fsckd.service 111s [[0;32m OK [0m] Started [0;1;39mFile System Check Daemon to report status[0m. 111s [[0;32m OK [0m] Finished [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 111s Mounting [0;1;39m/boot/efi[0m... 111s systemd-fsck@dev-disk-by\x2dlabel-UEFI.service 111s boot-efi.mount 111s [[0;32m OK [0m] Mounted [0;1;39m/boot/efi[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 111s Starting [0;1;39mLoad AppArmor profiles[0m... 111s Starting [0;1;39mSet console font and keymap[0m... 111s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 111s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 111s Starting [0;1;39mCreate Volatile Files and Directories[0m... 111s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 111s console-setup.service 111s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 111s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 111s Starting [0;1;39mNetwork Time Synchronization[0m... 111s systemd-tmpfiles-setup.service 111s Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 111s [[0;32m OK [0m] Finished [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 111s systemd-update-utmp.service 111s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 111s apparmor.service 111s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Synchronized[0m. 111s systemd-timesyncd.service 111s [ 4.447300] cloud-init[471]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init-local' at Mon, 07 Apr 2025 14:06:03 +0000. Up 3.31 seconds. 111s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mNetwork (Pre)[0m. 111s cloud-init-local.service 111s Starting [0;1;39mNetwork Service[0m... 111s [[0;32m OK [0m] Started [0;1;39mNetwork Service[0m. 111s systemd-networkd.service 111s Starting [0;1;39mWait for Network to be Configured[0m... 111s Starting [0;1;39mNetwork Name Resolution[0m... 111s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 111s systemd-resolved.service 111s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 111s systemd-networkd-wait-online.service 111s Starting [0;1;39mCloud-init: Network Stage[0m... 111s [ 6.338925] cloud-init[535]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init' at Mon, 07 Apr 2025 14:06:06 +0000. Up 6.20 seconds. 111s [ 6.339014] cloud-init[535]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 111s [ 6.339037] cloud-init[535]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 111s [ 6.339068] cloud-init[535]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 111s [ 6.339089] cloud-init[535]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 111s [ 6.339110] cloud-init[535]: ci-info: | enp1s0 | True | 10.151.35.161 | 255.255.255.0 | global | fa:16:3e:01:71:e5 | 111s [ 6.339138] cloud-init[535]: ci-info: | enp1s0 | True | fe80::f816:3eff:fe01:71e5/64 | . | link | fa:16:3e:01:71:e5 | 111s [ 6.339164] cloud-init[535]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 111s [ 6.339188] cloud-init[535]: ci-info: | lo | True | ::1/128 | . | host | . | 111s [ 6.339208] cloud-init[535]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 111s [ 6.339227] cloud-init[535]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 111s [ 6.339254] cloud-init[535]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 111s [ 6.339279] cloud-init[535]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 111s [ 6.339303] cloud-init[535]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 111s [ 6.339327] cloud-init[535]: ci-info: | 0 | 0.0.0.0 | 10.151.35.1 | 0.0.0.0 | enp1s0 | UG | 111s [ 6.339351] cloud-init[535]: ci-info: | 1 | 10.151.35.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | 111s [ 6.339374] cloud-init[535]: ci-info: | 2 | 169.254.169.254 | 10.151.35.2 | 255.255.255.255 | enp1s0 | UGH | 111s [ 6.339398] cloud-init[535]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 111s [ 6.339417] cloud-init[535]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 111s [ 6.339441] cloud-init[535]: ci-info: +-------+-------------+---------+-----------+-------+ 111s [ 6.339490] cloud-init[535]: ci-info: | Route | Destination | Gateway | Interface | Flags | 111s [ 6.339521] cloud-init[535]: ci-info: +-------+-------------+---------+-----------+-------+ 111s [ 6.339553] cloud-init[535]: ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | 111s [ 6.339575] cloud-init[535]: ci-info: | 3 | local | :: | enp1s0 | U | 111s [ 6.339600] cloud-init[535]: ci-info: | 4 | multicast | :: | enp1s0 | U | 111s [ 6.339620] cloud-init[535]: ci-info: +-------+-------------+---------+-----------+-------+ 111s [ 6.339639] cloud-init[535]: 2025-04-07 14:06:06,153 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 111s [ 7.171681] cloud-init[535]: Generating public/private rsa key pair. 111s [ 7.171783] cloud-init[535]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 111s [ 7.171812] cloud-init[535]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 111s [ 7.171839] cloud-init[535]: The key fingerprint is: 111s [ 7.171866] cloud-init[535]: SHA256:7aoaX9GQ3jdDd0Ye+gCBMgvTwzKyzWrtDhp4HCZnB2k root@autopkgtest 111s [ 7.171893] cloud-init[535]: The key's randomart image is: 111s [ 7.171928] cloud-init[535]: +---[RSA 3072]----+ 111s [ 7.171957] cloud-init[535]: | o .o. ..| 111s [ 7.171983] cloud-init[535]: | o = * o . .o.| 111s [ 7.172021] cloud-init[535]: | E = = B .o. +| 111s [ 7.172054] cloud-init[535]: | . o o o = . .oo | 111s [ 7.172084] cloud-init[535]: |. = + S + + . | 111s [ 7.172113] cloud-init[535]: |.* = . o . o | 111s [ 7.172139] cloud-init[535]: |..+.o . . | 111s [ 7.172169] cloud-init[535]: | .o .+ . . | 111s [ 7.172199] cloud-init[535]: | . ooo.. | 111s [ 7.172225] cloud-init[535]: +----[SHA256]-----+ 111s [ 7.172250] cloud-init[535]: Generating public/private ecdsa key pair. 111s [ 7.172279] cloud-init[535]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 111s [ 7.172306] cloud-init[535]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 111s [ 7.172335] cloud-init[535]: The key fingerprint is: 111s [ 7.172355] cloud-init[535]: SHA256:4xuuKg8V8eyAFCBwIYTN/OCKt4dvC/JvleJvalVNBw0 root@autopkgtest 111s [ 7.172382] cloud-init[535]: The key's randomart image is: 111s [ 7.172408] cloud-init[535]: +---[ECDSA 256]---+ 111s [ 7.172434] cloud-init[535]: |O*=o. E+ | 111s [ 7.172491] cloud-init[535]: |o+=. + . o | 111s [ 7.172523] cloud-init[535]: | ..oo o o . | 111s [ 7.172550] cloud-init[535]: | . .+ . . | 111s [ 7.172580] cloud-init[535]: |.. . .oS | 111s [ 7.172605] cloud-init[535]: |o ... +. . | 111s [ 7.172630] cloud-init[535]: |..o+ + o | 111s [ 7.172658] cloud-init[535]: | o+o* .. o | 111s [ 7.172684] cloud-init[535]: | .XB*o.o | 111s [ 7.172712] cloud-init[535]: +----[SHA256]-----+ 111s [ 7.172732] cloud-init[535]: Generating public/private ed25519 key pair. 111s [ 7.172757] cloud-init[535]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 111s [ 7.172783] cloud-init[535]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 111s [ 7.172810] cloud-init[535]: The key fingerprint is: 111s [ 7.172832] cloud-init[535]: SHA256:udh0zj2/OyNt+xgZi3kud56syvDitM03C5st4kwN8Fs root@autopkgtest 111s [ 7.172863] cloud-init[535]: The key's randomart image is: 111s [ 7.172884] cloud-init[535]: +--[ED25519 256]--+ 111s [ 7.172908] cloud-init[535]: | | 111s [ 7.172933] cloud-init[535]: | | 111s [ 7.172953] cloud-init[535]: | . | 111s [ 7.172978] cloud-init[535]: | o. | 111s [ 7.173003] cloud-init[535]: | So.E . | 111s [ 7.173027] cloud-init[535]: | + ==. o + | 111s [ 7.173055] cloud-init[535]: | . o=oo=.= | 111s [ 7.173076] cloud-init[535]: | +oB.BBBoo| 111s [ 7.173100] cloud-init[535]: | o=oO+O@%o| 111s [ 7.173125] cloud-init[535]: +----[SHA256]-----+ 111s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 111s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 111s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 111s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 111s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 111s [[0;32m OK [0m] Started [0;1;39mRefresh fwupd metadata regularly[0m. 111s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 111s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 111s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 111s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mPaths[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mTimers[0m. 111s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 111s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 111s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mSockets[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 111s cloud-init.service 111s Starting [0;1;39mLSB: automatic crash report generation[0m... 111s Starting [0;1;39mDeferred execution scheduler[0m... 111s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 111s Starting [0;1;39mCloud-init: Config Stage[0m... 111s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 111s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 111s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 111s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 111s Starting [0;1;39mRecord successful boot for GRUB[0m... 111s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 111s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 111s Starting [0;1;39mAuthorization Manager[0m... 111s Starting [0;1;39mPollinate to seed���udo random number generator[0m... 111s [[0;32m OK [0m] Started [0;1;39mAdd entropy to /de���random 's pool a hardware RNG[0m. 111s Starting [0;1;39mSystem Logging Service[0m... 111s Starting [0;1;39mLogin Service[0m... 111s Starting [0;1;39mPermit User Sessions[0m... 111s Starting [0;1;39mDisk Manager[0m... 111s [[0;32m OK [0m] Started [0;1;39mDeferred execution scheduler[0m. 111s autopkgtest@ttyS1.service 111s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 111s cron.service 111s dbus.service 111s dmesg.service 111s irqbalance.service 111s rng-tools.service 111s atd.service 111s systemd-user-sessions.service 111s rsyslog.service 111s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 111s Starting [0;1;39mDiscard unused bl���filesystems from /etc/fstab[0m... 111s Starting [0;1;39mHold until boot process finishes up[0m... 111s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 111s Starting [0;1;39mRotate log files[0m... 111s Starting [0;1;39mDaily man-db regeneration[0m... 111s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 111s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 111s [[0;32m OK [0m] Started [0;1;39mLogin Service[0m. 111s apport.service 111s systemd-logind.service 111s Starting [0;1;39mGRUB failed boot detection[0m... 111s [ 8.136734] cloud-init[718]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:config' at Mon, 07 Apr 2025 14:06:07 +0000. Up 7.70 seconds. 111s 111s Ubuntu 20.04.6 LTS autopkgtest ttyAMA0 111s 111s autopkgtest login: [ 18.477218] cloud-init[782]: Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 111s [ 18.505977] cloud-init[782]: Get:2 http://ftpmaster.internal/ubuntu focal-updates InRelease [128 kB] 111s [ 18.540209] cloud-init[782]: Get:3 http://ftpmaster.internal/ubuntu focal-security InRelease [128 kB] 111s [ 18.693111] cloud-init[782]: Get:4 http://ftpmaster.internal/ubuntu focal-updates/main Sources [630 kB] 111s [ 18.911578] cloud-init[782]: Get:5 http://ftpmaster.internal/ubuntu focal-updates/universe Sources [418 kB] 111s [ 18.944572] cloud-init[782]: Get:6 http://ftpmaster.internal/ubuntu focal-updates/main arm64 Packages [3001 kB] 111s [ 19.158120] cloud-init[782]: Get:7 http://ftpmaster.internal/ubuntu focal-updates/universe arm64 Packages [1190 kB] 111s [ 19.233771] cloud-init[782]: Get:8 http://ftpmaster.internal/ubuntu focal-security/main Sources [333 kB] 111s [ 19.268310] cloud-init[782]: Get:9 http://ftpmaster.internal/ubuntu focal-security/universe Sources [258 kB] 111s [ 19.302870] cloud-init[782]: Get:10 http://ftpmaster.internal/ubuntu focal-security/restricted Sources [56.9 kB] 111s [ 19.335621] cloud-init[782]: Get:11 http://ftpmaster.internal/ubuntu focal-security/main arm64 Packages [2687 kB] 111s [ 19.485584] cloud-init[782]: Get:12 http://ftpmaster.internal/ubuntu focal-security/universe arm64 Packages [964 kB] 111s [ 20.066980] cloud-init[782]: Fetched 9794 kB in 2s (5073 kB/s) 111s [ 20.794693] cloud-init[782]: Reading package lists... 111s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 111s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 111s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 111s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 111s 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 | 111s ci-info: | ssh-rsa | 87:eb:11:e6:d9:4d:64:37:d3:15:bc:fb:70:01:fb:c2:54:4a:7c:f0:03:f0:ca:42:74:a2:5a:a0:67:8b:da:f8 | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-2 | 111s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 111s <14>Apr 7 14:06:20 cloud-init: ############################################################# 111s <14>Apr 7 14:06:20 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 111s <14>Apr 7 14:06:20 cloud-init: 256 SHA256:4xuuKg8V8eyAFCBwIYTN/OCKt4dvC/JvleJvalVNBw0 root@autopkgtest (ECDSA) 111s <14>Apr 7 14:06:20 cloud-init: 256 SHA256:udh0zj2/OyNt+xgZi3kud56syvDitM03C5st4kwN8Fs root@autopkgtest (ED25519) 111s <14>Apr 7 14:06:20 cloud-init: 3072 SHA256:7aoaX9GQ3jdDd0Ye+gCBMgvTwzKyzWrtDhp4HCZnB2k root@autopkgtest (RSA) 111s <14>Apr 7 14:06:20 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 111s <14>Apr 7 14:06:20 cloud-init: ############################################################# 111s -----BEGIN SSH HOST KEY KEYS----- 111s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBDWEc6rl+pQjjeAbGYpfC+UsRd21sgbsBszSmpm3r4F8pTOaLS/0svgoiQ2izubzaWZMnTjqsQ1nMdMSPmNdDf4= root@autopkgtest 111s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOlGeJzUTu1zug2z081EgIVRQRVxPVvhi+a38vysWOlw root@autopkgtest 111s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQCi/aNloeDhfAQlLMTXNW3hPfp0JXbGZQcSF/BynhmAT71x2nclmC1dKJShm/he9pdepwBJL3lpevTFF9C4SGk4iU37VetT1SY4Hitsfk4qNFOonLXchfyryA1QODGgQ/FP0f7Tm43NE4mGjc9o0gWeyI4YCkhWGPq2GKmqVghEhkcJ1cnyjKBtRgItbamA2FI12R2wSycgwWOYZEhN75iO80QHcBN/SzX1NLsHYtsdaldWIIgO8w+OQ2j16zSSQgRfUatSaDd3mW3olsMHMtwbKX9ROzrcPlrXx56zLTNps+bOvry8Vs0eoMekQK5ebbcEBHGbQEn9xjBnefbOysv/weqJxZ0kdbwcAUpWlGyawZaI2+rKKYH7CMQaiudF1wVFrwUszNK6bcWEt2e5Nur0DWoWtGTFgZP6MeV86j8HQrxPAHbq7K/2sDzAQSFkt6n4RsqYr10BSgtVF5x5/z3GCs03RZ27H+DjHO3QdIb8lRdvzULbTiNUG7QiShOhzKE= root@autopkgtest 111s -----END SSH HOST KEY KEYS----- 111s [ 20.893927] cloud-init[782]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:final' at Mon, 07 Apr 2025 14:06:17 +0000. Up 17.80 seconds. 111s [ 20.895201] cloud-init[782]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 finished at Mon, 07 Apr 2025 14:06:20 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 20.88 seconds 111s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Reboot Screen[0m. 111s plymouth-reboot.service 111s [[0;32m OK [0m] Stopped [0;1;39mAuthorization Manager[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mUser Manager for UID 1000[0m. 111s Stopping [0;1;39mUser Runtime Directory /run/user/1000[0m... 111s [[0;32m OK [0m] Unmounted [0;1;39m/run/user/1000[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mUser Runtime Directory /run/user/1000[0m. 111s [[0;32m OK [0m] Removed slice [0;1;39mUser Slice of UID 1000[0m. 111s Stopping [0;1;39mD-Bus System Message Bus[0m... 111s Stopping [0;1;39mPermit User Sessions[0m... 111s [[0;32m OK [0m] Stopped [0;1;39mD-Bus System Message Bus[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mPermit User Sessions[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mNetwork[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mRemote File Systems[0m. 111s Stopping [0;1;39mNetwork Name Resolution[0m... 111s [[0;32m OK [0m] Stopped [0;1;39mNetwork Name Resolution[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mLogin Service[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mBasic System[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mPaths[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mSlices[0m. 111s [[0;32m OK [0m] Removed slice [0;1;39mUser and Session Slice[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mSockets[0m. 111s [[0;32m OK [0m] Closed [0;1;39mD-Bus System Message Bus Socket[0m. 111s [[0;32m OK [0m] Closed [0;1;39mSyslog Socket[0m. 111s [[0;32m OK [0m] Closed [0;1;39mUUID daemon activation socket[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mSystem Initialization[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mLocal Encrypted Volumes[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���uests to Wall Directory Watch[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mSwap[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Network Stage[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mWait for Network to be Configured[0m. 111s Stopping [0;1;39mNetwork Service[0m... 111s Stopping [0;1;39mNetwork Time Synchronization[0m... 111s Stopping [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 111s [[0;32m OK [0m] Stopped [0;1;39mNetwork Time Synchronization[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mCreate Volatile Files and Directories[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems[0m. 111s Unmounting [0;1;39m/boot/efi[0m... 111s [[0;32m OK [0m] Stopped [0;1;39mNetwork Service[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mNetwork (Pre)[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Local Stage (pre-network)[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mApply Kernel Variables[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mLoad Kernel Modules[0m. 111s [[0;32m OK [0m] Unmounted [0;1;39m/boot/efi[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mUnmount All Filesystems[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 111s [[0;32m OK [0m] Removed slice [0;1;39msystem-systemd\x2dfsck.slice[0m. 111s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems (Pre)[0m. 111s Stopping [0;1;39mDevice-Mapper Multipath Device Controller[0m... 111s [[0;32m OK [0m] Stopped [0;1;39mCreate Static Device Nodes in /dev[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mCreate System Users[0m. 111s [[0;32m OK [0m] Stopped [0;1;39mRemount Root and Kernel File Systems[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mShutdown[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mFinal Step[0m. 111s [[0;32m OK [0m] Finished [0;1;39mReboot[0m. 111s [[0;32m OK [0m] Reached target [0;1;39mReboot[0m. 111s [ 50.961648] reboot: Restarting system 111s [2J[01;01H[=3h[2J[01;01H 111s --------------------------------------------------- 111s ------- nova show 97907921-9ca3-40c7-a923-032cc33a1e4e (adt-focal-arm64-dpdk-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-adca7e13-7ff1-4277-bc9f-fecf1e34b391) ------ 111s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 111s | Property | Value | 111s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 111s | OS-DCF:diskConfig | MANUAL | 111s | OS-EXT-AZ:availability_zone | AZ3 | 111s | OS-EXT-SRV-ATTR:host | ps7-ra5-arm64-n1.ps7.canonical.com | 111s | OS-EXT-SRV-ATTR:hostname | adt-focal-arm64-dpdk-20250407-134938-juju-7f2275-prod-proposed | 111s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps7-ra5-arm64-n1.ps7.canonical.com | 111s | OS-EXT-SRV-ATTR:instance_name | instance-0000c92f | 111s | OS-EXT-SRV-ATTR:kernel_id | | 111s | OS-EXT-SRV-ATTR:launch_index | 0 | 111s | OS-EXT-SRV-ATTR:ramdisk_id | | 111s | OS-EXT-SRV-ATTR:reservation_id | r-mpm7b0xj | 111s | OS-EXT-SRV-ATTR:root_device_name | /dev/vda | 111s | OS-EXT-STS:power_state | 1 | 111s | OS-EXT-STS:task_state | - | 111s | OS-EXT-STS:vm_state | active | 111s | OS-SRV-USG:launched_at | 2025-04-07T14:05:49.000000 | 111s | OS-SRV-USG:terminated_at | - | 111s | accessIPv4 | | 111s | accessIPv6 | | 111s | config_drive | | 111s | created | 2025-04-07T14:05:45Z | 111s | description | - | 111s | flavor:disk | 100 | 111s | flavor:ephemeral | 0 | 111s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "arm64", "hw:cpu_policy": "shared", "hw:mem_page_size": "large", "hw:pci_numa_affinity_policy": "preferred"} | 111s | flavor:original_name | autopkgtest-cpu4-ram16-disk100-arm64 | 111s | flavor:ram | 16384 | 111s | flavor:swap | 0 | 111s | flavor:vcpus | 4 | 111s | hostId | eac1ca1ea0296ff2b2b0f182f7dc63f9b5ad0d6a8657cc857051837e | 111s | id | 97907921-9ca3-40c7-a923-032cc33a1e4e | 111s | image | adt/ubuntu-focal-arm64-server-20250404.img (82a1a68a-d67b-484b-bdb5-49bf84281933) | 111s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-2 | 111s | locked | False | 111s | locked_reason | - | 111s | metadata | {} | 111s | name | adt-focal-arm64-dpdk-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-adca7e13-7ff1-4277-bc9f-fecf1e34b391 | 111s | net_prod-autopkgtest-workers-arm64 network | 10.151.35.161 | 111s | os-extended-volumes:volumes_attached | [] | 111s | progress | 0 | 111s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-arm64-3.secgroup | 111s | server_groups | [] | 111s | status | ACTIVE | 111s | tags | [] | 111s | tenant_id | e27006f5eb9946719a589a78f7f099f8 | 111s | trusted_image_certificates | - | 111s | updated | 2025-04-07T14:05:50Z | 111s | user_id | 5676cac36e4942629c54e24741ff7157 | 111s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 111s --------------------------------------------------- 111s 137s nova [W] Using flock in prodstack7-arm64 137s Creating nova instance adt-focal-arm64-dpdk-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-adca7e13-7ff1-4277-bc9f-fecf1e34b391 from image adt/ubuntu-focal-arm64-server-20250404.img (UUID 82a1a68a-d67b-484b-bdb5-49bf84281933)... 137s nova [W] Timed out waiting for 97907921-9ca3-40c7-a923-032cc33a1e4e to get deleted. 137s autopkgtest [14:07:21]: ERROR: testbed failure: testbed auxverb failed with exit code 255