0s autopkgtest [10:15:41]: starting date and time: 2025-05-24 10:15:41+0000 0s autopkgtest [10:15:41]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [10:15:41]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.29eolaxw/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:linux-meta-lowlatency-hwe-5.15,src:linux-lowlatency-hwe-5.15,src:linux-signed-lowlatency-hwe-5.15 --apt-upgrade adv-17v35x --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=linux-meta-lowlatency-hwe-5.15/5.15.0.142.152~20.04.1 linux-lowlatency-hwe-5.15/5.15.0-142.152~20.04.1 linux-signed-lowlatency-hwe-5.15/5.15.0-142.152~20.04.1' --setup-commands 'apt-get install -y ^kernel-testing--linux\-lowlatency\-hwe\-5\.15--full--preferred$ || apt-get install -y ^linux-image\-lowlatency\-hwe\-5\.15$ ^linux-headers\-lowlatency\-hwe\-5\.15$ || apt-get install -y ^linux-image-generic\-lowlatency\-hwe\-5\.15$ ^linux-headers-generic\-lowlatency\-hwe\-5\.15$' --setup-commands 'apt-get install -y ^kernel-testing--linux\-lowlatency\-hwe\-5\.15--modules-extra--preferred$ || apt-get install -y ^linux-modules-extra\-lowlatency\-hwe\-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-15@sto01-arm64-2.secgroup --name adt-focal-arm64-adv-17v35x-20250524-100020-juju-7f2275-prod-proposed-migration-environment-15-109f4d27-7883-4c8d-a67f-bbc495792dbd --image adt/ubuntu-focal-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 70s autopkgtest [10:16:51]: testbed dpkg architecture: arm64 70s autopkgtest [10:16:51]: testbed apt version: 2.0.10 70s autopkgtest [10:16:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 70s autopkgtest [10:16:51]: testbed release detected to be: None 71s autopkgtest [10:16:52]: updating testbed package index (apt update) 71s Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 71s Hit:2 http://ftpmaster.internal/ubuntu focal-updates InRelease 71s Hit:3 http://ftpmaster.internal/ubuntu focal-security InRelease 71s Get:4 http://ftpmaster.internal/ubuntu focal-proposed InRelease [277 kB] 71s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/main Sources [32.7 kB] 72s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/universe Sources [6856 B] 72s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/restricted Sources [9344 B] 72s Get:8 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 Packages [297 kB] 72s Get:9 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 c-n-f Metadata [1708 B] 72s Get:10 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 Packages [2936 B] 72s Get:11 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 c-n-f Metadata [116 B] 72s Get:12 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 Packages [44.5 kB] 72s Get:13 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 c-n-f Metadata [1844 B] 72s Get:14 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 Packages [1020 B] 72s Get:15 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 c-n-f Metadata [116 B] 76s Fetched 675 kB in 1s (851 kB/s) 77s Reading package lists... 77s Reading package lists... 77s Building dependency tree... 77s Reading state information... 78s The following additional packages will be installed: 78s linux-headers-5.15.0-142-lowlatency linux-headers-lowlatency-hwe-20.04 78s linux-image-5.15.0-142-lowlatency linux-image-lowlatency-hwe-20.04 78s linux-lowlatency-hwe-5.15-headers-5.15.0-142 78s linux-modules-5.15.0-142-lowlatency 78s Suggested packages: 78s fdutils linux-doc | linux-lowlatency-hwe-5.15-source-5.15.0 78s linux-lowlatency-hwe-5.15-tools linux-modules-extra-5.15.0-142-lowlatency 78s Recommended packages: 78s thermald 78s The following NEW packages will be installed: 78s linux-headers-5.15.0-142-lowlatency linux-headers-lowlatency-hwe-20.04 78s linux-image-5.15.0-142-lowlatency linux-image-lowlatency-hwe-20.04 78s linux-lowlatency-hwe-20.04 linux-lowlatency-hwe-5.15-headers-5.15.0-142 78s linux-modules-5.15.0-142-lowlatency 78s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 78s Need to get 111 MB of archives. 78s After this operation, 725 MB of additional disk space will be used. 78s Get:1 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-lowlatency-hwe-5.15-headers-5.15.0-142 all 5.15.0-142.152~20.04.1 [12.0 MB] 78s Get:2 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-headers-5.15.0-142-lowlatency arm64 5.15.0-142.152~20.04.1 [2221 kB] 78s Get:3 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-headers-lowlatency-hwe-20.04 arm64 5.15.0.142.152~20.04.1 [2452 B] 78s Get:4 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-modules-5.15.0-142-lowlatency arm64 5.15.0-142.152~20.04.1 [82.2 MB] 79s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-image-5.15.0-142-lowlatency arm64 5.15.0-142.152~20.04.1 [14.7 MB] 80s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-image-lowlatency-hwe-20.04 arm64 5.15.0.142.152~20.04.1 [2536 B] 80s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-lowlatency-hwe-20.04 arm64 5.15.0.142.152~20.04.1 [1928 B] 80s dpkg-preconfigure: unable to re-open stdin: No such file or directory 80s Fetched 111 MB in 2s (63.5 MB/s) 80s Selecting previously unselected package linux-lowlatency-hwe-5.15-headers-5.15.0-142. 80s (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 ... 67873 files and directories currently installed.) 80s Preparing to unpack .../0-linux-lowlatency-hwe-5.15-headers-5.15.0-142_5.15.0-142.152~20.04.1_all.deb ... 80s Unpacking linux-lowlatency-hwe-5.15-headers-5.15.0-142 (5.15.0-142.152~20.04.1) ... 82s Selecting previously unselected package linux-headers-5.15.0-142-lowlatency. 82s Preparing to unpack .../1-linux-headers-5.15.0-142-lowlatency_5.15.0-142.152~20.04.1_arm64.deb ... 82s Unpacking linux-headers-5.15.0-142-lowlatency (5.15.0-142.152~20.04.1) ... 82s Selecting previously unselected package linux-headers-lowlatency-hwe-20.04. 82s Preparing to unpack .../2-linux-headers-lowlatency-hwe-20.04_5.15.0.142.152~20.04.1_arm64.deb ... 82s Unpacking linux-headers-lowlatency-hwe-20.04 (5.15.0.142.152~20.04.1) ... 82s Selecting previously unselected package linux-modules-5.15.0-142-lowlatency. 83s Preparing to unpack .../3-linux-modules-5.15.0-142-lowlatency_5.15.0-142.152~20.04.1_arm64.deb ... 83s Unpacking linux-modules-5.15.0-142-lowlatency (5.15.0-142.152~20.04.1) ... 88s Selecting previously unselected package linux-image-5.15.0-142-lowlatency. 88s Preparing to unpack .../4-linux-image-5.15.0-142-lowlatency_5.15.0-142.152~20.04.1_arm64.deb ... 88s Unpacking linux-image-5.15.0-142-lowlatency (5.15.0-142.152~20.04.1) ... 89s Selecting previously unselected package linux-image-lowlatency-hwe-20.04. 89s Preparing to unpack .../5-linux-image-lowlatency-hwe-20.04_5.15.0.142.152~20.04.1_arm64.deb ... 89s Unpacking linux-image-lowlatency-hwe-20.04 (5.15.0.142.152~20.04.1) ... 89s Selecting previously unselected package linux-lowlatency-hwe-20.04. 89s Preparing to unpack .../6-linux-lowlatency-hwe-20.04_5.15.0.142.152~20.04.1_arm64.deb ... 89s Unpacking linux-lowlatency-hwe-20.04 (5.15.0.142.152~20.04.1) ... 89s Setting up linux-lowlatency-hwe-5.15-headers-5.15.0-142 (5.15.0-142.152~20.04.1) ... 89s Setting up linux-headers-5.15.0-142-lowlatency (5.15.0-142.152~20.04.1) ... 89s Setting up linux-headers-lowlatency-hwe-20.04 (5.15.0.142.152~20.04.1) ... 89s Setting up linux-modules-5.15.0-142-lowlatency (5.15.0-142.152~20.04.1) ... 91s Setting up linux-image-5.15.0-142-lowlatency (5.15.0-142.152~20.04.1) ... 92s I: /boot/vmlinuz is now a symlink to vmlinuz-5.15.0-142-lowlatency 92s I: /boot/initrd.img is now a symlink to initrd.img-5.15.0-142-lowlatency 92s Setting up linux-image-lowlatency-hwe-20.04 (5.15.0.142.152~20.04.1) ... 92s Setting up linux-lowlatency-hwe-20.04 (5.15.0.142.152~20.04.1) ... 92s Processing triggers for linux-image-5.15.0-142-lowlatency (5.15.0-142.152~20.04.1) ... 92s /etc/kernel/postinst.d/initramfs-tools: 92s update-initramfs: Generating /boot/initrd.img-5.15.0-142-lowlatency 112s flash-kernel: deferring update (trigger activated) 112s /etc/kernel/postinst.d/zz-flash-kernel: 112s flash-kernel: deferring update (trigger activated) 112s /etc/kernel/postinst.d/zz-update-grub: 112s Sourcing file `/etc/default/grub' 112s Sourcing file `/etc/default/grub.d/init-select.cfg' 112s Generating grub configuration file ... 112s Found linux image: /boot/vmlinuz-5.15.0-142-lowlatency 112s Found initrd image: /boot/initrd.img-5.15.0-142-lowlatency 112s Found linux image: /boot/vmlinuz-5.4.0-216-generic 112s Found initrd image: /boot/initrd.img-5.4.0-216-generic 112s Adding boot menu entry for UEFI Firmware Settings 112s done 112s Processing triggers for flash-kernel (3.103ubuntu1~20.04.4) ... 113s Unsupported platform on EFI system, doing nothing. 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s E: Unable to locate package ^kernel-testing--linux-lowlatency-hwe-5.15--modules-extra--preferred$ 113s E: Couldn't find any package by glob '^kernel-testing--linux-lowlatency-hwe-5.15--modules-extra--preferred$' 113s E: Couldn't find any package by regex '^kernel-testing--linux-lowlatency-hwe-5.15--modules-extra--preferred$' 113s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s E: Unable to locate package ^linux-modules-extra-lowlatency-hwe-5.15$ 114s E: Couldn't find any package by glob '^linux-modules-extra-lowlatency-hwe-5.15$' 114s E: Couldn't find any package by regex '^linux-modules-extra-lowlatency-hwe-5.15$' 114s autopkgtest [10:17:35]: upgrading testbed (apt dist-upgrade and autopurge) 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 114s Starting 2 pkgProblemResolver with broken count: 0 114s Done 114s 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Starting pkgProblemResolver with broken count: 0 115s Starting 2 pkgProblemResolver with broken count: 0 115s Done 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s autopkgtest [10:17:36]: rebooting testbed after setup commands that affected boot 122s autopkgtest-virt-ssh [10:17:43]: ------- nova console-log 2ab51b44-e2d2-4c13-b4b4-e6a21b5f0c86 (adt-focal-arm64-adv-17v35x-20250524-100020-juju-7f2275-prod-proposed-migration-environment-15-109f4d27-7883-4c8d-a67f-bbc495792dbd) ------ 122s [2J[01;01H[=3h[2J[01;01HBdsDxe: loading Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 122s BdsDxe: starting Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 122s error: no suitable video mode found. 122s cloudimg-rootfs: clean, 74765/2593280 files, 692349/5217019 blocks 122s plymouth-start.service 122s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Boot Screen[0m. 122s [[0;32m OK [0m] Started [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 122s [[0;32m OK [0m] Found device [0;1;39m/dev/ttyAMA0[0m. 122s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 122s Starting [0;1;39mLoad Kernel Module chromeos_pstore[0m... 122s Starting [0;1;39mLoad Kernel Module pstore_blk[0m... 122s Starting [0;1;39mLoad Kernel Module pstore_zone[0m... 122s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module chromeos_pstore[0m. 122s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_zone[0m. 122s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_blk[0m. 122s [[0;32m OK [0m] Found device [0;1;39m/dev/disk/by-label/UEFI[0m. 122s systemd-udev-settle.service 122s [[0;32m OK [0m] Finished [0;1;39mudev Wait for Complete Device Initialization[0m. 122s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 122s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m. 122s Starting [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m... 122s multipathd.service 122s [[0;32m OK [0m] Started [0;1;39mFile System Check Daemon to report status[0m. 122s systemd-fsckd.service 122s [[0;32m OK [0m] Finished [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 122s systemd-fsck@dev-disk-by\x2dlabel-UEFI.service 122s Mounting [0;1;39m/boot/efi[0m... 122s boot-efi.mount 122s [[0;32m OK [0m] Mounted [0;1;39m/boot/efi[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 122s Starting [0;1;39mLoad AppArmor profiles[0m... 122s Starting [0;1;39mSet console font and keymap[0m... 122s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 122s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 122s Starting [0;1;39mCreate Volatile Files and Directories[0m... 122s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 122s console-setup.service 122s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 122s systemd-tmpfiles-setup.service 122s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 122s Starting [0;1;39mNetwork Time Synchronization[0m... 122s Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 122s [[0;32m OK [0m] Finished [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 122s systemd-update-utmp.service 122s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 122s apparmor.service 122s systemd-timesyncd.service 122s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Synchronized[0m. 122s [ 5.052805] cloud-init[447]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init-local' at Sat, 24 May 2025 10:16:31 +0000. Up 3.76 seconds. 122s cloud-init-local.service 122s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mNetwork (Pre)[0m. 122s Starting [0;1;39mNetwork Service[0m... 122s [[0;32m OK [0m] Started [0;1;39mNetwork Service[0m. 122s systemd-networkd.service 122s Starting [0;1;39mWait for Network to be Configured[0m... 122s Starting [0;1;39mNetwork Name Resolution[0m... 122s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 122s systemd-resolved.service 122s systemd-networkd-wait-online.service 122s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 122s Starting [0;1;39mCloud-init: Network Stage[0m... 122s [ 6.772499] cloud-init[510]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init' at Sat, 24 May 2025 10:16:34 +0000. Up 6.64 seconds. 122s [ 6.772631] cloud-init[510]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 122s [ 6.772675] cloud-init[510]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 122s [ 6.772707] cloud-init[510]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 122s [ 6.773626] cloud-init[510]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 122s [ 6.774443] cloud-init[510]: ci-info: | enp1s0 | True | 10.151.35.69 | 255.255.255.0 | global | fa:16:3e:be:c4:28 | 122s [ 6.775341] cloud-init[510]: ci-info: | enp1s0 | True | fe80::f816:3eff:febe:c428/64 | . | link | fa:16:3e:be:c4:28 | 122s [ 6.776195] cloud-init[510]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 122s [ 6.777057] cloud-init[510]: ci-info: | lo | True | ::1/128 | . | host | . | 122s [ 6.777097] cloud-init[510]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 122s [ 6.777996] cloud-init[510]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 122s [ 6.778875] cloud-init[510]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 122s [ 6.779746] cloud-init[510]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 122s [ 6.779785] cloud-init[510]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 122s [ 6.784123] cloud-init[510]: ci-info: | 0 | 0.0.0.0 | 10.151.35.1 | 0.0.0.0 | enp1s0 | UG | 122s [ 6.784161] cloud-init[510]: ci-info: | 1 | 10.151.35.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | 122s [ 6.784192] cloud-init[510]: ci-info: | 2 | 169.254.169.254 | 10.151.35.2 | 255.255.255.255 | enp1s0 | UGH | 122s [ 6.784217] cloud-init[510]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 122s [ 6.784254] cloud-init[510]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 122s [ 6.784276] cloud-init[510]: ci-info: +-------+-------------+---------+-----------+-------+ 122s [ 6.784303] cloud-init[510]: ci-info: | Route | Destination | Gateway | Interface | Flags | 122s [ 6.784329] cloud-init[510]: ci-info: +-------+-------------+---------+-----------+-------+ 122s [ 6.784362] cloud-init[510]: ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | 122s [ 6.784392] cloud-init[510]: ci-info: | 3 | local | :: | enp1s0 | U | 122s [ 6.784422] cloud-init[510]: ci-info: | 4 | multicast | :: | enp1s0 | U | 122s [ 6.784441] cloud-init[510]: ci-info: +-------+-------------+---------+-----------+-------+ 122s [ 6.784461] cloud-init[510]: 2025-05-24 10:16:34,680 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 122s [ 8.847166] cloud-init[510]: Generating public/private rsa key pair. 122s [ 8.847277] cloud-init[510]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 122s [ 8.847328] cloud-init[510]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 122s [ 8.847356] cloud-init[510]: The key fingerprint is: 122s [ 8.847385] cloud-init[510]: SHA256:R+bFAvEllxUAy/E+Lp0kV9hYHCPbwO9wJThi2GfEXgg root@autopkgtest 122s [ 8.847413] cloud-init[510]: The key's randomart image is: 122s [ 8.847444] cloud-init[510]: +---[RSA 3072]----+ 122s [ 8.847471] cloud-init[510]: | o+E*BB*o | 122s [ 8.847502] cloud-init[510]: | .++OXX+..| 122s [ 8.847533] cloud-init[510]: | .B=B++o | 122s [ 8.847563] cloud-init[510]: | + +o.o | 122s [ 8.847588] cloud-init[510]: | S + =+ | 122s [ 8.847620] cloud-init[510]: | . * o. | 122s [ 8.847647] cloud-init[510]: | . + | 122s [ 8.847675] cloud-init[510]: | . | 122s [ 8.847697] cloud-init[510]: | | 122s [ 8.847720] cloud-init[510]: +----[SHA256]-----+ 122s [ 8.847742] cloud-init[510]: Generating public/private ecdsa key pair. 122s [ 8.847772] cloud-init[510]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 122s [ 8.847799] cloud-init[510]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 122s [ 8.847831] cloud-init[510]: The key fingerprint is: 122s [ 8.847860] cloud-init[510]: SHA256:vLnI75+NkcWUrCUxfCfbVGY6VHGBMnUZP1uH02PukhM root@autopkgtest 122s [ 8.847883] cloud-init[510]: The key's randomart image is: 122s [ 8.847903] cloud-init[510]: +---[ECDSA 256]---+ 122s [ 8.847923] cloud-init[510]: | .o ..oB@| 122s [ 8.847943] cloud-init[510]: | .*o++Bo| 122s [ 8.847966] cloud-init[510]: | ..B*=+=| 122s [ 8.847988] cloud-init[510]: | . *. +o*| 122s [ 8.848067] cloud-init[510]: | S . oE o | 122s [ 8.848107] cloud-init[510]: | o o + | 122s [ 8.848129] cloud-init[510]: | o o + . | 122s [ 8.848154] cloud-init[510]: | . . . = o | 122s [ 8.848175] cloud-init[510]: | oo+.+ . | 122s [ 8.848194] cloud-init[510]: +----[SHA256]-----+ 122s [ 8.848235] cloud-init[510]: Generating public/private ed25519 key pair. 122s [ 8.848303] cloud-init[510]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 122s [ 8.848323] cloud-init[510]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 122s [ 8.848380] cloud-init[510]: The key fingerprint is: 122s [ 8.848400] cloud-init[510]: SHA256:vRfhH0i8nToR1bkEScQkEzNDo/44jHKPi7eQZ74NxPs root@autopkgtest 122s [ 8.848426] cloud-init[510]: The key's randomart image is: 122s [ 8.848446] cloud-init[510]: +--[ED25519 256]--+ 122s [ 8.848466] cloud-init[510]: | .X*=+..| 122s [ 8.848488] cloud-init[510]: | ..B+ o.| 122s [ 8.848516] cloud-init[510]: | . = . .| 122s [ 8.848540] cloud-init[510]: | . .. o * o | 122s [ 8.848561] cloud-init[510]: | oS.. * + | 122s [ 8.848588] cloud-init[510]: | o + o. = . | 122s [ 8.848614] cloud-init[510]: | + B +..+ . | 122s [ 8.848634] cloud-init[510]: | O.* .. . | 122s [ 8.848659] cloud-init[510]: | ..*+E | 122s [ 8.848684] cloud-init[510]: +----[SHA256]-----+ 122s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 122s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 122s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 122s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 122s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 122s [[0;32m OK [0m] Started [0;1;39mRefresh fwupd metadata regularly[0m. 122s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 122s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 122s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 122s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mPaths[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mTimers[0m. 122s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 122s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 122s cloud-init.service 122s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mSockets[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 122s Starting [0;1;39mLSB: automatic crash report generation[0m... 122s Starting [0;1;39mDeferred execution scheduler[0m... 122s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 122s Starting [0;1;39mCloud-init: Config Stage[0m... 122s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 122s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 122s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 122s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 122s Starting [0;1;39mRecord successful boot for GRUB[0m... 122s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 122s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 122s Starting [0;1;39mAuthorization Manager[0m... 122s Starting [0;1;39mPollinate to seed���udo random number generator[0m... 122s [autopkgtest@ttyS1.service 122s [0;32m OK [0m] Started [0;1;39mAdd entropy to /de���random 's pool a hardware RNG[0m. 122s Starting [0;1;39mSystem Logging Service[0m... 122s Starting [0;1;39mLogin Service[0m... 122s Starting [0;1;39mPermit User Sessions[0m... 122s Starting [0;1;39mDisk Manager[0m... 122s cron.service 122s [[0;32m OK [0m] Started [0;1;39mDeferred execution scheduler[0m. 122s [[0;32m OK [0m] Finished [0;1;39mRemove Stale Onli���ext4 Metadata Check Snapshots[0m. 122s dbus.service 122s dmesg.service 122s irqbalance.service 122s rng-tools.service 122s atd.service 122s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 122s systemd-user-sessions.service 122s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 122s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 122s rsyslog.service 122s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 122s [apport.service 122s [0;32m OK [0m] Started [0;1;39mAuthorization Manager[0m. 122s polkit.service 122s Starting [0;1;39mModem Manager[0m... 122s Starting [0;1;39mGRUB failed boot detection[0m... 122s Starting [0;1;39mHold until boot process finishes up[0m... 122s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 122s [[0;32m OK [0m] Started [0;1;39mLogin Service[0m. 122s systemd-logind.service 122s [[0;32m OK [0m] Finished [0;1;39mGRUB failed boot detection[0m. 122s [ 9.813969] cloud-init[650]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:config' at Sat, 24 May 2025 10:16:37 +0000. Up 9.49 seconds. 122s 122s Ubuntu 20.04.6 LTS autopkgtest ttyAMA0 122s 122s autopkgtest login: [ 19.906925] cloud-init[723]: Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 122s [ 19.936916] cloud-init[723]: Get:2 http://ftpmaster.internal/ubuntu focal-updates InRelease [128 kB] 122s [ 19.969927] cloud-init[723]: Get:3 http://ftpmaster.internal/ubuntu focal-security InRelease [128 kB] 122s [ 20.426372] cloud-init[723]: Fetched 256 kB in 0s (548 kB/s) 122s [ 21.249862] cloud-init[723]: Reading package lists... 122s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 122s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 122s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 122s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 122s ci-info: | ssh-rsa | 91:43:c5:c0:08:13:e1:4c:d2:84:a3:93:cc:b6:92:94:24:03:c7:0a:60:b3:34:9d:7f:63:f7:38:37:6f:9d:18 | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-23 | 122s ci-info: | ssh-rsa | e8:35:e0:40:84:e9:20:46:36:c3:0d:24:8f:f7:02:62:a2:c6:f7:f5:5c:ed:3f:df:2b:54:8b:69:97:eb:98:5e | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-15 | 122s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 122s <14>May 24 10:16:49 cloud-init: ############################################################# 122s <14>May 24 10:16:49 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 122s <14>May 24 10:16:49 cloud-init: 256 SHA256:vLnI75+NkcWUrCUxfCfbVGY6VHGBMnUZP1uH02PukhM root@autopkgtest (ECDSA) 122s <14>May 24 10:16:49 cloud-init: 256 SHA256:vRfhH0i8nToR1bkEScQkEzNDo/44jHKPi7eQZ74NxPs root@autopkgtest (ED25519) 122s <14>May 24 10:16:49 cloud-init: 3072 SHA256:R+bFAvEllxUAy/E+Lp0kV9hYHCPbwO9wJThi2GfEXgg root@autopkgtest (RSA) 122s <14>May 24 10:16:49 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 122s <14>May 24 10:16:49 cloud-init: ############################################################# 122s -----BEGIN SSH HOST KEY KEYS----- 122s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBAR/XbTXuRYi8Vui51j2P/NdKwpIEDA6W2dsXjFow42lMrjnl/D7xYVz3BtlNO358PUSVplEtZxtPlzqisIKP48= root@autopkgtest 122s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIJziHsLhWporyDaSvU1QjUGkbHjHNQAoWYw4btQTAzH0 root@autopkgtest 122s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQCXSrQ1ppAsLhp5kXVEu2oET2dJY5rD79rSZUmiS/YPVZp1imrob+5l52ireVsVDl5U5qLVI2OedDOBwcr5ikITTXnbjJvMo7jZiYzVvdaxxsMUqRT4c6AlEzZYk37lnCLwXfJBrQ1ZuOB5MIbJsvU9oktAX2Bg+bsv+nP174iWImG34cvgwxORNBkdE/bkJRVNja+6hBBn8dT8pC5fHllU4jykuTQg9ecSAlH76vH5l9AdDY2On9BVavgjLo9O+rtEwr/QcCz8y2JCA22SaUJCDSn+Wn/ngUNERZiFhCuCLdPFuaFM1irv7uumbZxNhtVc7/v3iqn+sQLb0BJWQOknJV45+pX0CoN5OYqNC5pc57pCJzvn3ja0+qvRgde8Vr2WnJflJoUuErRWjYNUaHK5j1MuKRGu4qZWjukcTpjJuudO7yPQHJfQXNF35fdnYqYthS9G1qZMkLPEUUBSLfWog+8UwvEx8pzslt3P+g/jHIcHakhSfzCRhWx+imm+uQc= root@autopkgtest 122s -----END SSH HOST KEY KEYS----- 122s [ 21.342386] cloud-init[723]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:final' at Sat, 24 May 2025 10:16:47 +0000. Up 19.62 seconds. 122s [ 21.343566] cloud-init[723]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 finished at Sat, 24 May 2025 10:16:49 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 21.33 seconds 122s [[0;32m OK [0m] Stopped [0;1;39mAuthorization Manager[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mUser Manager for UID 1000[0m. 122s Stopping [0;1;39mUser Runtime Directory /run/user/1000[0m... 122s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Reboot Screen[0m. 122s plymouth-reboot.service 122s [[0;32m OK [0m] Unmounted [0;1;39m/run/user/1000[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mUser Runtime Directory /run/user/1000[0m. 122s [[0;32m OK [0m] Removed slice [0;1;39mUser Slice of UID 1000[0m. 122s Stopping [0;1;39mD-Bus System Message Bus[0m... 122s Stopping [0;1;39mPermit User Sessions[0m... 122s [[0;32m OK [0m] Stopped [0;1;39mPermit User Sessions[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mNetwork[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mRemote File Systems[0m. 122s Stopping [0;1;39mNetwork Name Resolution[0m... 122s [[0;32m OK [0m] Stopped [0;1;39mLogin Service[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mD-Bus System Message Bus[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mNetwork Name Resolution[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mBasic System[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mPaths[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mSlices[0m. 122s [[0;32m OK [0m] Removed slice [0;1;39mUser and Session Slice[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mSockets[0m. 122s [[0;32m OK [0m] Closed [0;1;39mD-Bus System Message Bus Socket[0m. 122s [[0;32m OK [0m] Closed [0;1;39mSyslog Socket[0m. 122s [[0;32m OK [0m] Closed [0;1;39mUUID daemon activation socket[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mSystem Initialization[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mLocal Encrypted Volumes[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���uests to Wall Directory Watch[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mSwap[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Network Stage[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mWait for Network to be Configured[0m. 122s Stopping [0;1;39mNetwork Service[0m... 122s Stopping [0;1;39mNetwork Time Synchronization[0m... 122s Stopping [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 122s [[0;32m OK [0m] Stopped [0;1;39mNetwork Service[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mNetwork (Pre)[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Local Stage (pre-network)[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mApply Kernel Variables[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mLoad Kernel Modules[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mNetwork Time Synchronization[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mCreate Volatile Files and Directories[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems[0m. 122s Unmounting [0;1;39m/boot/efi[0m... 122s [[0;32m OK [0m] Unmounted [0;1;39m/boot/efi[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mUnmount All Filesystems[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 122s [[0;32m OK [0m] Removed slice [0;1;39msystem-systemd\x2dfsck.slice[0m. 122s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems (Pre)[0m. 122s Stopping [0;1;39mDevice-Mapper Multipath Device Controller[0m... 122s [[0;32m OK [0m] Stopped [0;1;39mCreate Static Device Nodes in /dev[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mCreate System Users[0m. 122s [[0;32m OK [0m] Stopped [0;1;39mRemount Root and Kernel File Systems[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mShutdown[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mFinal Step[0m. 122s [[0;32m OK [0m] Finished [0;1;39mReboot[0m. 122s [[0;32m OK [0m] Reached target [0;1;39mReboot[0m. 122s [ 72.901651] reboot: Restarting system 122s [2J[01;01H[=3h[2J[01;01H 122s --------------------------------------------------- 122s ------- nova show 2ab51b44-e2d2-4c13-b4b4-e6a21b5f0c86 (adt-focal-arm64-adv-17v35x-20250524-100020-juju-7f2275-prod-proposed-migration-environment-15-109f4d27-7883-4c8d-a67f-bbc495792dbd) ------ 122s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 122s | Property | Value | 122s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 122s | OS-DCF:diskConfig | MANUAL | 122s | OS-EXT-AZ:availability_zone | AZ3 | 122s | OS-EXT-SRV-ATTR:host | ps7-ra5-arm64-n1.ps7.canonical.com | 122s | OS-EXT-SRV-ATTR:hostname | adt-focal-arm64-adv-17v35x-20250524-100020-juju-7f2275-prod-pro | 122s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps7-ra5-arm64-n1.ps7.canonical.com | 122s | OS-EXT-SRV-ATTR:instance_name | instance-00060192 | 122s | OS-EXT-SRV-ATTR:kernel_id | | 122s | OS-EXT-SRV-ATTR:launch_index | 0 | 122s | OS-EXT-SRV-ATTR:ramdisk_id | | 122s | OS-EXT-SRV-ATTR:reservation_id | r-prv0oflh | 122s | OS-EXT-SRV-ATTR:root_device_name | /dev/vda | 122s | OS-EXT-STS:power_state | 1 | 122s | OS-EXT-STS:task_state | - | 122s | OS-EXT-STS:vm_state | active | 122s | OS-SRV-USG:launched_at | 2025-05-24T10:16:17.000000 | 122s | OS-SRV-USG:terminated_at | - | 122s | accessIPv4 | | 122s | accessIPv6 | | 122s | config_drive | | 122s | created | 2025-05-24T10:16:14Z | 122s | description | - | 122s | flavor:disk | 20 | 122s | flavor:ephemeral | 0 | 122s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "arm64", "hw:cpu_policy": "shared", "hw:mem_page_size": "large", "hw:pci_numa_affinity_policy": "preferred"} | 122s | flavor:original_name | autopkgtest-cpu2-ram4-disk20-arm64 | 122s | flavor:ram | 4096 | 122s | flavor:swap | 0 | 122s | flavor:vcpus | 2 | 122s | hostId | eac1ca1ea0296ff2b2b0f182f7dc63f9b5ad0d6a8657cc857051837e | 122s | id | 2ab51b44-e2d2-4c13-b4b4-e6a21b5f0c86 | 122s | image | adt/ubuntu-focal-arm64-server-20250524.img (ca51a752-b375-4af4-a744-29856914844b) | 122s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-15 | 122s | locked | False | 122s | locked_reason | - | 122s | metadata | {} | 122s | name | adt-focal-arm64-adv-17v35x-20250524-100020-juju-7f2275-prod-proposed-migration-environment-15-109f4d27-7883-4c8d-a67f-bbc495792dbd | 122s | net_prod-autopkgtest-workers-arm64 network | 10.151.35.69 | 122s | os-extended-volumes:volumes_attached | [] | 122s | progress | 0 | 122s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-arm64-2.secgroup | 122s | server_groups | [] | 122s | status | ACTIVE | 122s | tags | [] | 122s | tenant_id | e27006f5eb9946719a589a78f7f099f8 | 122s | trusted_image_certificates | - | 122s | updated | 2025-05-24T10:16:18Z | 122s | user_id | 5676cac36e4942629c54e24741ff7157 | 122s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 122s --------------------------------------------------- 122s 151s nova [W] Using flock in prodstack7-arm64 151s Creating nova instance adt-focal-arm64-adv-17v35x-20250524-100020-juju-7f2275-prod-proposed-migration-environment-15-109f4d27-7883-4c8d-a67f-bbc495792dbd from image adt/ubuntu-focal-arm64-server-20250524.img (UUID ca51a752-b375-4af4-a744-29856914844b)... 151s nova [W] Timed out waiting for 2ab51b44-e2d2-4c13-b4b4-e6a21b5f0c86 to get deleted. 151s autopkgtest [10:18:12]: ERROR: testbed failure: testbed auxverb failed with exit code 255