0s autopkgtest [14:07:49]: starting date and time: 2025-04-07 14:07:49+0000 0s autopkgtest [14:07:49]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [14:07:49]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.p0lt40y2/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 acpi-call --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-1.secgroup --name adt-focal-arm64-acpi-call-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-920b3d30-75ce-4a03-ad9f-ab4c2f731b96 --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/ 54s autopkgtest [14:08:43]: testbed dpkg architecture: arm64 54s autopkgtest [14:08:43]: testbed apt version: 2.0.10 55s autopkgtest [14:08:44]: @@@@@@@@@@@@@@@@@@@@ test bed setup 55s autopkgtest [14:08:44]: testbed release detected to be: None 56s autopkgtest [14:08:45]: updating testbed package index (apt update) 56s Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 56s Hit:2 http://ftpmaster.internal/ubuntu focal-updates InRelease 56s Hit:3 http://ftpmaster.internal/ubuntu focal-security InRelease 56s Get:4 http://ftpmaster.internal/ubuntu focal-proposed InRelease [277 kB] 57s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/universe Sources [8808 B] 57s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/multiverse Sources [1948 B] 57s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/main Sources [57.8 kB] 57s Get:8 http://ftpmaster.internal/ubuntu focal-proposed/restricted Sources [15.7 kB] 57s Get:9 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 Packages [316 kB] 57s Get:10 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 c-n-f Metadata [1708 B] 57s Get:11 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 Packages [2936 B] 57s Get:12 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 c-n-f Metadata [116 B] 57s Get:13 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 Packages [54.2 kB] 57s Get:14 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 c-n-f Metadata [1844 B] 57s Get:15 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 Packages [8116 B] 57s Get:16 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 c-n-f Metadata [116 B] 59s Fetched 746 kB in 1s (875 kB/s) 60s Reading package lists... 61s Reading package lists... 61s Building dependency tree... 61s Reading state information... 61s The following additional packages will be installed: 61s linux-gcp-5.15-headers-5.15.0-1081 linux-headers-5.15.0-1081-gcp 61s linux-headers-gcp-edge linux-image-5.15.0-1081-gcp linux-image-gcp-edge 61s linux-modules-5.15.0-1081-gcp 61s Suggested packages: 61s fdutils linux-gcp-5.15-doc-5.15.0 | linux-gcp-5.15-source-5.15.0 61s linux-gcp-5.15-tools 61s The following NEW packages will be installed: 61s linux-gcp-5.15-headers-5.15.0-1081 linux-gcp-edge 61s linux-headers-5.15.0-1081-gcp linux-headers-gcp-edge 61s linux-image-5.15.0-1081-gcp linux-image-gcp-edge 61s linux-modules-5.15.0-1081-gcp 61s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 61s Need to get 49.3 MB of archives. 61s After this operation, 266 MB of additional disk space will be used. 61s 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] 62s 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] 62s 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] 62s 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] 62s 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] 62s 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] 62s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-gcp-edge arm64 5.15.0.1081.90~20.04.1 [1928 B] 63s dpkg-preconfigure: unable to re-open stdin: No such file or directory 63s Fetched 49.3 MB in 1s (48.5 MB/s) 63s Selecting previously unselected package linux-gcp-5.15-headers-5.15.0-1081. 63s (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.) 63s Preparing to unpack .../0-linux-gcp-5.15-headers-5.15.0-1081_5.15.0-1081.90~20.04.1_arm64.deb ... 63s Unpacking linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 65s Selecting previously unselected package linux-modules-5.15.0-1081-gcp. 65s Preparing to unpack .../1-linux-modules-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 65s Unpacking linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 66s Selecting previously unselected package linux-image-5.15.0-1081-gcp. 66s Preparing to unpack .../2-linux-image-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 66s Unpacking linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 66s Selecting previously unselected package linux-image-gcp-edge. 66s Preparing to unpack .../3-linux-image-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 66s Unpacking linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 66s Selecting previously unselected package linux-headers-5.15.0-1081-gcp. 66s Preparing to unpack .../4-linux-headers-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 66s Unpacking linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 67s Selecting previously unselected package linux-headers-gcp-edge. 67s Preparing to unpack .../5-linux-headers-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 67s Unpacking linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 67s Selecting previously unselected package linux-gcp-edge. 67s Preparing to unpack .../6-linux-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 67s Unpacking linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 67s Setting up linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 67s Setting up linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 67s Setting up linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 67s Setting up linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 68s I: /boot/vmlinuz is now a symlink to vmlinuz-5.15.0-1081-gcp 68s I: /boot/initrd.img is now a symlink to initrd.img-5.15.0-1081-gcp 68s Setting up linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 68s Setting up linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 69s Setting up linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 69s Processing triggers for linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 69s /etc/kernel/postinst.d/initramfs-tools: 69s update-initramfs: Generating /boot/initrd.img-5.15.0-1081-gcp 75s flash-kernel: deferring update (trigger activated) 75s /etc/kernel/postinst.d/zz-flash-kernel: 75s flash-kernel: deferring update (trigger activated) 75s /etc/kernel/postinst.d/zz-update-grub: 75s Sourcing file `/etc/default/grub' 75s Sourcing file `/etc/default/grub.d/init-select.cfg' 75s Generating grub configuration file ... 75s Found linux image: /boot/vmlinuz-5.15.0-1081-gcp 75s Found initrd image: /boot/initrd.img-5.15.0-1081-gcp 75s Found linux image: /boot/vmlinuz-5.4.0-212-generic 75s Found initrd image: /boot/initrd.img-5.4.0-212-generic 75s Adding boot menu entry for UEFI Firmware Settings 75s done 75s Processing triggers for flash-kernel (3.103ubuntu1~20.04.4) ... 76s Unsupported platform on EFI system, doing nothing. 76s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s E: Unable to locate package ^kernel-testing--linux-gcp-5.15--modules-extra--preferred$ 76s E: Couldn't find any package by glob '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 76s E: Couldn't find any package by regex '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 76s Reading package lists... 77s Building dependency tree... 77s Reading state information... 77s E: Unable to locate package ^linux-modules-extra-gcp-5.15$ 77s E: Couldn't find any package by glob '^linux-modules-extra-gcp-5.15$' 77s E: Couldn't find any package by regex '^linux-modules-extra-gcp-5.15$' 77s autopkgtest [14:09:06]: upgrading testbed (apt dist-upgrade and autopurge) 77s Reading package lists... 77s Building dependency tree... 77s Reading state information... 77s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 77s Starting 2 pkgProblemResolver with broken count: 0 77s Done 77s 77s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 78s Reading package lists... 78s Building dependency tree... 78s Reading state information... 78s Starting pkgProblemResolver with broken count: 0 78s Starting 2 pkgProblemResolver with broken count: 0 78s Done 78s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 78s autopkgtest [14:09:07]: rebooting testbed after setup commands that affected boot 96s autopkgtest-virt-ssh [14:09:25]: copydown destination failed, status 255 100s autopkgtest-virt-ssh [14:09:29]: ------- nova console-log 95c394c2-c0a7-4b3f-a9de-a6e54143847a (adt-focal-arm64-acpi-call-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-920b3d30-75ce-4a03-ad9f-ab4c2f731b96) ------ 100s [2J[01;01H[=3h[2J[01;01HBdsDxe: loading Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 100s BdsDxe: starting Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 100s error: no suitable video mode found. 100s cloudimg-rootfs: clean, 74747/2593280 files, 692245/5217019 blocks 100s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Boot Screen[0m. 100s [[0;32m OK [0m] Started [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 100s plymouth-start.service 100s [[0;32m OK [0m] Found device [0;1;39m/dev/ttyAMA0[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 100s Starting [0;1;39mLoad Kernel Module chromeos_pstore[0m... 100s Starting [0;1;39mLoad Kernel Module pstore_blk[0m... 100s Starting [0;1;39mLoad Kernel Module pstore_zone[0m... 100s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_blk[0m. 100s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module chromeos_pstore[0m. 100s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_zone[0m. 100s [[0;32m OK [0m] Found device [0;1;39m/dev/disk/by-label/UEFI[0m. 100s systemd-udev-settle.service 100s [[0;32m OK [0m] Finished [0;1;39mudev Wait for Complete Device Initialization[0m. 100s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 100s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m. 100s multipathd.service 100s Starting [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m... 100s [[0;32m OK [0m] Started [0;1;39mFile System Check Daemon to report status[0m. 100s systemd-fsckd.service 100s systemd-fsck@dev-disk-by\x2dlabel-UEFI.service 100s [[0;32m OK [0m] Finished [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 100s Mounting [0;1;39m/boot/efi[0m... 100s [[0;32m OK [0m] Mounted [0;1;39m/boot/efi[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 100s boot-efi.mount 100s Starting [0;1;39mLoad AppArmor profiles[0m... 100s Starting [0;1;39mSet console font and keymap[0m... 100s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 100s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 100s Starting [0;1;39mCreate Volatile Files and Directories[0m... 100s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 100s console-setup.service 100s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 100s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 100s systemd-tmpfiles-setup.service 100s Starting [0;1;39mNetwork Time Synchronization[0m... 100s Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 100s [[0;32m OK [0m] Finished [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 100s systemd-update-utmp.service 100s apparmor.service 100s systemd-timesyncd.service 100s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 100s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Synchronized[0m. 100s [ 5.127667] cloud-init[447]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init-local' at Mon, 07 Apr 2025 14:08:23 +0000. Up 3.71 seconds. 100s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mNetwork (Pre)[0m. 100s cloud-init-local.service 100s Starting [0;1;39mNetwork Service[0m... 100s [[0;32m OK [0m] Started [0;1;39mNetwork Service[0m. 100s systemd-networkd.service 100s Starting [0;1;39mWait for Network to be Configured[0m... 100s Starting [0;1;39mNetwork Name Resolution[0m... 100s systemd-resolved.service 100s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 100s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 100s systemd-networkd-wait-online.service 100s Starting [0;1;39mCloud-init: Network Stage[0m... 100s [ 7.548473] cloud-init[507]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init' at Mon, 07 Apr 2025 14:08:27 +0000. Up 7.41 seconds. 100s [ 7.548576] cloud-init[507]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 100s [ 7.548600] cloud-init[507]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 100s [ 7.548627] cloud-init[507]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 100s [ 7.548651] cloud-init[507]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 100s [ 7.548672] cloud-init[507]: ci-info: | enp1s0 | True | 10.151.35.29 | 255.255.255.0 | global | fa:16:3e:53:dc:39 | 100s [ 7.548696] cloud-init[507]: ci-info: | enp1s0 | True | fe80::f816:3eff:fe53:dc39/64 | . | link | fa:16:3e:53:dc:39 | 100s [ 7.548716] cloud-init[507]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 100s [ 7.548736] cloud-init[507]: ci-info: | lo | True | ::1/128 | . | host | . | 100s [ 7.548755] cloud-init[507]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 100s [ 7.548777] cloud-init[507]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 100s [ 7.548806] cloud-init[507]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 100s [ 7.548831] cloud-init[507]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 100s [ 7.548865] cloud-init[507]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 100s [ 7.548897] cloud-init[507]: ci-info: | 0 | 0.0.0.0 | 10.151.35.1 | 0.0.0.0 | enp1s0 | UG | 100s [ 7.548918] cloud-init[507]: ci-info: | 1 | 10.151.35.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | 100s [ 7.548942] cloud-init[507]: ci-info: | 2 | 169.254.169.254 | 10.151.35.2 | 255.255.255.255 | enp1s0 | UGH | 100s [ 7.548967] cloud-init[507]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 100s [ 7.548987] cloud-init[507]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 100s [ 7.549006] cloud-init[507]: ci-info: +-------+-------------+---------+-----------+-------+ 100s [ 7.549029] cloud-init[507]: ci-info: | Route | Destination | Gateway | Interface | Flags | 100s [ 7.549052] cloud-init[507]: ci-info: +-------+-------------+---------+-----------+-------+ 100s [ 7.549081] cloud-init[507]: ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | 100s [ 7.549101] cloud-init[507]: ci-info: | 3 | local | :: | enp1s0 | U | 100s [ 7.549125] cloud-init[507]: ci-info: | 4 | multicast | :: | enp1s0 | U | 100s [ 7.549144] cloud-init[507]: ci-info: +-------+-------------+---------+-----------+-------+ 100s [ 7.549168] cloud-init[507]: 2025-04-07 14:08:27,457 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 100s [ 8.133109] cloud-init[507]: Generating public/private rsa key pair. 100s [ 8.133213] cloud-init[507]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 100s [ 8.133243] cloud-init[507]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 100s [ 8.133263] cloud-init[507]: The key fingerprint is: 100s [ 8.133294] cloud-init[507]: SHA256:35D0Sr7hGw3JyDouNsU/tiZ6l7gQOQQyPzsUsZzZt0A root@autopkgtest 100s [ 8.133323] cloud-init[507]: The key's randomart image is: 100s [ 8.133343] cloud-init[507]: +---[RSA 3072]----+ 100s [ 8.133368] cloud-init[507]: |o +.E | 100s [ 8.133406] cloud-init[507]: | = O | 100s [ 8.133430] cloud-init[507]: | O + . . | 100s [ 8.133477] cloud-init[507]: | . + + o + + | 100s [ 8.133503] cloud-init[507]: | o +.. S B . | 100s [ 8.133531] cloud-init[507]: | . oo. + * | 100s [ 8.133552] cloud-init[507]: | ..oo .* o | 100s [ 8.133577] cloud-init[507]: | +o+.B. + | 100s [ 8.133603] cloud-init[507]: | ..=o*.o+. | 100s [ 8.133629] cloud-init[507]: +----[SHA256]-----+ 100s [ 8.133652] cloud-init[507]: Generating public/private ecdsa key pair. 100s [ 8.133685] cloud-init[507]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 100s [ 8.133711] cloud-init[507]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 100s [ 8.133736] cloud-init[507]: The key fingerprint is: 100s [ 8.133756] cloud-init[507]: SHA256:+cDbd++OuFw6/h5PbVyaVmJsfhAPVzLC2up1qnfZeV0 root@autopkgtest 100s [ 8.133781] cloud-init[507]: The key's randomart image is: 100s [ 8.133800] cloud-init[507]: +---[ECDSA 256]---+ 100s [ 8.133819] cloud-init[507]: | .. o .| 100s [ 8.133838] cloud-init[507]: | .. o.| 100s [ 8.133861] cloud-init[507]: | o o .| 100s [ 8.133894] cloud-init[507]: | . .. .. = | 100s [ 8.133915] cloud-init[507]: | S . * +| 100s [ 8.134361] cloud-init[507]: | =. .+.BE| 100s [ 8.134897] cloud-init[507]: | ..o..oOoX| 100s [ 8.135415] cloud-init[507]: | .o+*oX+| 100s [ 8.135798] cloud-init[507]: | .+**+o*| 100s [ 8.136330] cloud-init[507]: +----[SHA256]-----+ 100s [ 8.136375] cloud-init[507]: Generating public/private ed25519 key pair. 100s [ 8.136719] cloud-init[507]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 100s [ 8.137008] cloud-init[507]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 100s [ 8.137306] cloud-init[507]: The key fingerprint is: 100s [ 8.137613] cloud-init[507]: SHA256:sYOP4xphe+wDyfiOE8dGGav2SHbpJnBCXA9kN0YzSeo root@autopkgtest 100s [ 8.137911] cloud-init[507]: The key's randomart image is: 100s [ 8.138201] cloud-init[507]: +--[ED25519 256]--+ 100s [ 8.138515] cloud-init[507]: | .ooO. | 100s [ 8.138810] cloud-init[507]: | .o=.+ | 100s [ 8.139104] cloud-init[507]: |. ..o+ . | 100s [ 8.139399] cloud-init[507]: | o. +. . o | 100s [ 8.139712] cloud-init[507]: |. Eoo. S | 100s [ 8.144062] cloud-init[507]: |o O.X+ o . | 100s [ 8.144273] cloud-init[507]: | B Xo.= . | 100s [ 8.144564] cloud-init[507]: | +.==.. | 100s [ 8.144865] cloud-init[507]: | .=o.o. | 100s [ 8.145164] cloud-init[507]: +----[SHA256]-----+ 100s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 100s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 100s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 100s [[0;32m OK [0m] Started [0;1;39mRefresh fwupd metadata regularly[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 100s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mPaths[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mTimers[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSockets[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 100s Starting [0;1;39mLSB: automatic crash report generation[0m... 100s Starting [0;1;39mDeferred execution scheduler[0m... 100s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 100s Starting [0;1;39mCloud-init: Config Stage[0m... 100s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 100s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 100s cloud-init.service 100s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 100s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 100s Starting [0;1;39mRecord successful boot for GRUB[0m... 100s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 100s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 100s autopkgtest@ttyS1.service 100s cron.service 100s dbus.service 100s dmesg.service 100s irqbalance.service 100s Starting [0;1;39mAuthorization Manager[0m... 100s Starting [0;1;39mPollinate to seed���udo random number generator[0m... 100s [[0;32m OK [0m] Started [0;1;39mAdd entropy to /de���random 's pool a hardware RNG[0m. 100s Starting [0;1;39mSystem Logging Service[0m... 100s Starting [0;1;39mLogin Service[0m... 100s Starting [0;1;39mPermit User Sessions[0m... 100s rng-tools.service 100s Starting [0;1;39mDisk Manager[0m... 100s [[0;32m OK [0m] Started [0;1;39mDeferred execution scheduler[0m. 100s atd.service 100s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 100s systemd-user-sessions.service 100s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 100s rsyslog.service 100s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 100s apport.service 100s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 100s [[0;32m OK [0m] Started [0;1;39mAuthorization Manager[0m. 100s polkit.service 100s Starting [0;1;39mModem Manager[0m... 100s Starting [0;1;39mDiscard unused bl���filesystems from /etc/fstab[0m... 100s Starting [0;1;39mGRUB failed boot detection[0m... 100s Starting [0;1;39mHold until boot process finishes up[0m... 100s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 100s Starting [0;1;39mRotate log files[0m... 100s Starting [0;1;39mDaily man-db regeneration[0m... 100s [[0;32m OK [0m] Finished [0;1;39mHold until boot process finishes up[0m. 100s [[0;32m OK [0m] Finished [0;1;39mTerminate Plymouth Boot Screen[0m. 100s [[0;32m OK [0m] Finished [0;1;39mRotate log files[0m. 100s [[0;32m OK [0m] Finished [0;1;39mGRUB failed boot detection[0m. 100s [ 9.222710] cloud-init[658]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:config' at Mon, 07 Apr 2025 14:08:28 +0000. Up 8.91 seconds. 100s 100s Ubuntu 20.04.6 LTS autopkgtest ttyAMA0 100s 100s autopkgtest login: [ 19.212516] cloud-init[732]: Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 100s [ 19.243304] cloud-init[732]: Get:2 http://ftpmaster.internal/ubuntu focal-updates InRelease [128 kB] 100s [ 19.285463] cloud-init[732]: Get:3 http://ftpmaster.internal/ubuntu focal-security InRelease [128 kB] 100s [ 19.412291] cloud-init[732]: Get:4 http://ftpmaster.internal/ubuntu focal-updates/universe Sources [418 kB] 100s [ 19.456587] cloud-init[732]: Get:5 http://ftpmaster.internal/ubuntu focal-updates/main Sources [630 kB] 100s [ 19.688673] cloud-init[732]: Get:6 http://ftpmaster.internal/ubuntu focal-updates/main arm64 Packages [3001 kB] 100s [ 19.811300] cloud-init[732]: Get:7 http://ftpmaster.internal/ubuntu focal-updates/universe arm64 Packages [1190 kB] 100s [ 19.858584] cloud-init[732]: Get:8 http://ftpmaster.internal/ubuntu focal-security/universe Sources [258 kB] 100s [ 19.893674] cloud-init[732]: Get:9 http://ftpmaster.internal/ubuntu focal-security/restricted Sources [56.9 kB] 100s [ 19.927073] cloud-init[732]: Get:10 http://ftpmaster.internal/ubuntu focal-security/main Sources [333 kB] 100s [ 19.964159] cloud-init[732]: Get:11 http://ftpmaster.internal/ubuntu focal-security/main arm64 Packages [2687 kB] 100s [ 20.037952] cloud-init[732]: Get:12 http://ftpmaster.internal/ubuntu focal-security/universe arm64 Packages [964 kB] 100s [ 20.868652] cloud-init[732]: Fetched 9794 kB in 2s (5974 kB/s) 100s [ 21.540706] cloud-init[732]: Reading package lists... 100s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 100s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 100s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 100s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 100s 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 | 100s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 100s <14>Apr 7 14:08:41 cloud-init: ############################################################# 100s <14>Apr 7 14:08:41 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 100s <14>Apr 7 14:08:41 cloud-init: 256 SHA256:+cDbd++OuFw6/h5PbVyaVmJsfhAPVzLC2up1qnfZeV0 root@autopkgtest (ECDSA) 100s <14>Apr 7 14:08:41 cloud-init: 256 SHA256:sYOP4xphe+wDyfiOE8dGGav2SHbpJnBCXA9kN0YzSeo root@autopkgtest (ED25519) 100s <14>Apr 7 14:08:41 cloud-init: 3072 SHA256:35D0Sr7hGw3JyDouNsU/tiZ6l7gQOQQyPzsUsZzZt0A root@autopkgtest (RSA) 100s <14>Apr 7 14:08:41 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 100s <14>Apr 7 14:08:41 cloud-init: ############################################################# 100s -----BEGIN SSH HOST KEY KEYS----- 100s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBLv1wI0rbBEIioKSp1LqLgbugoE1UHOEU6d/CRJRWIWtkB8s/1ALPcBbBm19kDcPPfx83Yy8F4kLCXWLt8AmnXs= root@autopkgtest 100s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIB2+tPqqd6VV+M3wvlNsD/x+XoVsR/082O+DMVLkJSXw root@autopkgtest 100s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDGgFHP2g8CBSkbkhWLbxPqHTa8DEhWImo03TsMQS1dbmuXCUX4a4Pz2fpGulgJw4vMfkYhTtZ+0R/HaYRNt3A2ijirBrB8/76OPGGMsD4yohQx6//MqD6zfk4lBI8xBmZLOPSp31ozaYdkMyVUCRkATOkHDSAcXTmmNRiu7yD5VXHSF8CuPVmagsyJJGKhXL08ACCYe5aweWto6ijly+Su8Ihi/1hO0rnE2wKA7hG+9bhOMYv5MxqOyDjgt7/xv+9lcOX+n3VFs2N+KiQb48rgZIoDt1LflY2iIII14TKxdTLENRyhrFsK/j3b5yW1DZPYNaO7CzbdA6a00/Dl8wNrlYV6D8FjXRfiOWiMP4MQ1w9ZnM5Q5Z0fRq9ywozun2MZYIS5IrFME+M8w7DVgJxkd2fSRv5KquSlFI5sj8OTtlaH2IRLuwJ/Lz/rpYQrPn7bSeQLG8NYax4XdrRA2vCpAoS2I4PvFdUMahn2x75EcvUg+gMMkS1q+axYO6wcahs= root@autopkgtest 100s -----END SSH HOST KEY KEYS----- 100s [ 21.625624] cloud-init[732]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:final' at Mon, 07 Apr 2025 14:08:38 +0000. Up 18.89 seconds. 100s [ 21.626662] cloud-init[732]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 finished at Mon, 07 Apr 2025 14:08:41 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 21.61 seconds 100s [[0;32m OK [0m] Stopped [0;1;39mAuthorization Manager[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mUser Manager for UID 1000[0m. 100s Stopping [0;1;39mUser Runtime Directory /run/user/1000[0m... 100s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Reboot Screen[0m. 100s plymouth-reboot.service 100s [[0;32m OK [0m] Unmounted [0;1;39m/run/user/1000[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mUser Runtime Directory /run/user/1000[0m. 100s [[0;32m OK [0m] Removed slice [0;1;39mUser Slice of UID 1000[0m. 100s Stopping [0;1;39mD-Bus System Message Bus[0m... 100s Stopping [0;1;39mPermit User Sessions[0m... 100s [[0;32m OK [0m] Stopped [0;1;39mD-Bus System Message Bus[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mPermit User Sessions[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mNetwork[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mRemote File Systems[0m. 100s Stopping [0;1;39mNetwork Name Resolution[0m... 100s [[0;32m OK [0m] Stopped [0;1;39mNetwork Name Resolution[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mLogin Service[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mBasic System[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mPaths[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mSlices[0m. 100s [[0;32m OK [0m] Removed slice [0;1;39mUser and Session Slice[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mSockets[0m. 100s [[0;32m OK [0m] Closed [0;1;39mD-Bus System Message Bus Socket[0m. 100s [[0;32m OK [0m] Closed [0;1;39mSyslog Socket[0m. 100s [[0;32m OK [0m] Closed [0;1;39mUUID daemon activation socket[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mSystem Initialization[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mLocal Encrypted Volumes[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���uests to Wall Directory Watch[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mSwap[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Network Stage[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mWait for Network to be Configured[0m. 100s Stopping [0;1;39mNetwork Service[0m... 100s Stopping [0;1;39mNetwork Time Synchronization[0m... 100s Stopping [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 100s [[0;32m OK [0m] Stopped [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mNetwork Time Synchronization[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mCreate Volatile Files and Directories[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems[0m. 100s Unmounting [0;1;39m/boot/efi[0m... 100s [[0;32m OK [0m] Unmounted [0;1;39m/boot/efi[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mUnmount All Filesystems[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 100s [[0;32m OK [0m] Removed slice [0;1;39msystem-systemd\x2dfsck.slice[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems (Pre)[0m. 100s Stopping [0;1;39mDevice-Mapper Multipath Device Controller[0m... 100s [[0;32m OK [0m] Stopped [0;1;39mCreate Static Device Nodes in /dev[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mDevice-Mapper Multipath Device Controller[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mNetwork Service[0m. 100s [[0;32m OK [0m] Stopped target [0;1;39mNetwork (Pre)[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Local Stage (pre-network)[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mApply Kernel Variables[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mLoad Kernel Modules[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mCreate System Users[0m. 100s [[0;32m OK [0m] Stopped [0;1;39mRemount Root and Kernel File Systems[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mShutdown[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mFinal Step[0m. 100s [[0;32m OK [0m] Finished [0;1;39mReboot[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mReboot[0m. 100s [ 51.055723] reboot: Restarting system 100s [2J[01;01H[=3h[2J[01;01HBdsDxe: loading Boot0004 "ubuntu" from HD(15,GPT,BDB1E53A-00A8-4290-8006-6ED7FD15C953,0x800,0x31801)/\EFI\ubuntu\shimaa64.efi 100s BdsDxe: starting Boot0004 "ubuntu" from HD(15,GPT,BDB1E53A-00A8-4290-8006-6ED7FD15C953,0x800,0x31801)/\EFI\ubuntu\shimaa64.efi 100s error: no suitable video mode found. 100s cloudimg-rootfs: clean, 105816/2593280 files, 810897/5217019 blocks 100s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Boot Screen[0m. 100s [[0;32m OK [0m] Started [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 100s plymouth-start.service 100s [[0;32m OK [0m] Found device [0;1;39m/dev/ttyAMA0[0m. 100s [[0;32m OK [0m] Found device [0;1;39m/dev/disk/by-label/UEFI[0m. 100s Starting [0;1;39mLoad Kernel Module chromeos_pstore[0m... 100s Starting [0;1;39mLoad Kernel Module pstore_blk[0m... 100s Starting [0;1;39mLoad Kernel Module pstore_zone[0m... 100s Starting [0;1;39mLoad Kernel Module ramoops[0m... 100s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module chromeos_pstore[0m. 100s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_blk[0m. 100s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_zone[0m. 100s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module ramoops[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 100s [[0;32m OK [0m] Finished [0;1;39mudev Wait for Complete Device Initialization[0m. 100s systemd-udev-settle.service 100s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 100s multipathd.service 100s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m. 100s Starting [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m... 100s [[0;32m OK [0m] Started [0;1;39mFile System Check Daemon to report status[0m. 100s systemd-fsckd.service 100s [[0;32m OK [0m] Finished [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 100s systemd-fsck@dev-disk-by\x2dlabel-UEFI.service 100s Mounting [0;1;39m/boot/efi[0m... 100s [[0;32m OK [0m] Mounted [0;1;39m/boot/efi[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 100s boot-efi.mount 100s Starting [0;1;39mLoad AppArmor profiles[0m... 100s Starting [0;1;39mSet console font and keymap[0m... 100s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 100s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 100s Starting [0;1;39mCreate Volatile Files and Directories[0m... 100s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 100s console-setup.service 100s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 100s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 100s Starting [0;1;39mNetwork Time Synchronization[0m... 100s systemd-tmpfiles-setup.service 100s Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 100s [[0;32m OK [0m] Finished [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 100s systemd-update-utmp.service 100s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Synchronized[0m. 100s systemd-timesyncd.service 100s [ 3.369763] cloud-init[447]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init-local' at Mon, 07 Apr 2025 14:09:21 +0000. Up 3.26 seconds. 100s cloud-init-local.service 100s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mNetwork (Pre)[0m. 100s Starting [0;1;39mNetwork Service[0m... 100s [[0;32m OK [0m] Started [0;1;39mNetwork Service[0m. 100s systemd-networkd.service 100s Starting [0;1;39mWait for Network to be Configured[0m... 100s Starting [0;1;39mNetwork Name Resolution[0m... 100s apparmor.service 100s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 100s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 100s systemd-resolved.service 100s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 100s systemd-networkd-wait-online.service 100s Starting [0;1;39mCloud-init: Network Stage[0m... 100s [ 5.679390] cloud-init[458]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init' at Mon, 07 Apr 2025 14:09:23 +0000. Up 5.54 seconds. 100s [ 5.679511] cloud-init[458]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 100s [ 5.679561] cloud-init[458]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 100s [ 5.679592] cloud-init[458]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 100s [ 5.680445] cloud-init[458]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 100s [ 5.681172] cloud-init[458]: ci-info: | enp1s0 | True | 10.151.35.29 | 255.255.255.0 | global | fa:16:3e:53:dc:39 | 100s [ 5.681205] cloud-init[458]: ci-info: | enp1s0 | True | fe80::f816:3eff:fe53:dc39/64 | . | link | fa:16:3e:53:dc:39 | 100s [ 5.681234] cloud-init[458]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 100s [ 5.682004] cloud-init[458]: ci-info: | lo | True | ::1/128 | . | host | . | 100s [ 5.682045] cloud-init[458]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 100s [ 5.682854] cloud-init[458]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 100s [ 5.683527] cloud-init[458]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 100s [ 5.684299] cloud-init[458]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 100s [ 5.684328] cloud-init[458]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 100s [ 5.685074] cloud-init[458]: ci-info: | 0 | 0.0.0.0 | 10.151.35.1 | 0.0.0.0 | enp1s0 | UG | 100s [ 5.685870] cloud-init[458]: ci-info: | 1 | 10.151.35.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | 100s [ 5.686550] cloud-init[458]: ci-info: | 2 | 169.254.169.254 | 10.151.35.2 | 255.255.255.255 | enp1s0 | UGH | 100s [ 5.687323] cloud-init[458]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 100s [ 5.692740] cloud-init[458]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 100s [ 5.692792] cloud-init[458]: ci-info: +-------+-------------+---------+-----------+-------+ 100s [ 5.692821] cloud-init[458]: ci-info: | Route | Destination | Gateway | Interface | Flags | 100s [ 5.692847] cloud-init[458]: ci-info: +-------+-------------+---------+-----------+-------+ 100s [ 5.692885] cloud-init[458]: ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | 100s [ 5.692916] cloud-init[458]: ci-info: | 3 | local | :: | enp1s0 | U | 100s [ 5.692938] cloud-init[458]: ci-info: | 4 | multicast | :: | enp1s0 | U | 100s [ 5.692959] cloud-init[458]: ci-info: +-------+-------------+---------+-----------+-------+ 100s [ 5.692983] cloud-init[458]: 2025-04-07 14:09:23,833 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 100s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 100s cloud-init.service 100s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 100s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 100s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 100s [[0;32m OK [0m] Started [0;1;39mRefresh fwupd metadata regularly[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 100s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 100s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mPaths[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mTimers[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 100s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mSockets[0m. 100s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 100s Starting [0;1;39mLSB: automatic crash report generation[0m... 100s Starting [0;1;39mDeferred execution scheduler[0m... 100s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 100s Starting [0;1;39mCloud-init: Config Stage[0m... 100s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 100s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 100s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 100s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 100s autopkgtest@ttyS1.service 100s Starting [0;1;39mRecord successful boot for GRUB[0m... 100s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 100s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 100s Starting [0;1;39mAuthorization Manager[0m... 100s cron.service 100s Starting [0;1;39mPollinate to seed���udo random number generator[0m... 100s dbus.service 100s dmesg.service 100s irqbalance.service 100s [[0;32m OK [0m] Started [0;1;39mAdd entropy to /de���random 's pool a hardware RNG[0m. 100s Starting [0;1;39mSystem Logging Service[0m... 100s rng-tools.service 100s Starting [0;1;39mLogin Service[0m... 100s Starting [0;1;39mPermit User Sessions[0m... 100s Starting [0;1;39mDisk Manager[0m... 100s [[0;32m OK [0m] Started [0;1;39mDeferred execution scheduler[0m. 100s atd.service 100s [[0;32m OK [0m] Finished [0;1;39mRemove Stale Onli���ext4 Metadata Check Snapshots[0m. 100s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 100s systemd-user-sessions.service 100s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 100s rsyslog.service 100s [[0;32m OK [0m] Started [0;1;39mLogin Service[0m. 100s systemd-logind.service 100s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 100s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 100s apport.service 100s Starting [0;1;39mGRUB failed boot detection[0m... 100s Starting [0;1;39mHold until boot process finishes up[0m... 100s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 100s [[0;32m OK [0m] Started [0;1;39mAuthorization Manager[0m. 100s polkit.service 100s Starting [0;1;39mModem Manager[0m... 100s [[0;32m OK [0m] Finished [0;1;39mGRUB failed boot detection[0m. 100s [ 6.398552] cloud-init[586]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:config' at Mon, 07 Apr 2025 14:09:24 +0000. Up 6.31 seconds. 100s 100s --------------------------------------------------- 100s ------- nova show 95c394c2-c0a7-4b3f-a9de-a6e54143847a (adt-focal-arm64-acpi-call-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-920b3d30-75ce-4a03-ad9f-ab4c2f731b96) ------ 100s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 100s | Property | Value | 100s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 100s | OS-DCF:diskConfig | MANUAL | 100s | OS-EXT-AZ:availability_zone | AZ2 | 100s | OS-EXT-SRV-ATTR:host | ps7-ra4-arm64-n1.ps7.canonical.com | 100s | OS-EXT-SRV-ATTR:hostname | adt-focal-arm64-acpi-call-20250407-134937-juju-7f2275-prod-prop | 100s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps7-ra4-arm64-n1.ps7.canonical.com | 100s | OS-EXT-SRV-ATTR:instance_name | instance-0000c93b | 100s | OS-EXT-SRV-ATTR:kernel_id | | 100s | OS-EXT-SRV-ATTR:launch_index | 0 | 100s | OS-EXT-SRV-ATTR:ramdisk_id | | 100s | OS-EXT-SRV-ATTR:reservation_id | r-i3m05kc8 | 100s | OS-EXT-SRV-ATTR:root_device_name | /dev/vda | 100s | OS-EXT-STS:power_state | 1 | 100s | OS-EXT-STS:task_state | - | 100s | OS-EXT-STS:vm_state | active | 100s | OS-SRV-USG:launched_at | 2025-04-07T14:08:10.000000 | 100s | OS-SRV-USG:terminated_at | - | 100s | accessIPv4 | | 100s | accessIPv6 | | 100s | config_drive | | 100s | created | 2025-04-07T14:08:05Z | 100s | description | - | 100s | flavor:disk | 20 | 100s | flavor:ephemeral | 0 | 100s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "arm64", "hw:cpu_policy": "shared", "hw:mem_page_size": "large", "hw:pci_numa_affinity_policy": "preferred"} | 100s | flavor:original_name | autopkgtest-cpu2-ram4-disk20-arm64 | 100s | flavor:ram | 4096 | 100s | flavor:swap | 0 | 100s | flavor:vcpus | 2 | 100s | hostId | 7aa16efb1024cdcb74bbd0a97e766c13c19acad3f4ed94776bde7536 | 100s | id | 95c394c2-c0a7-4b3f-a9de-a6e54143847a | 100s | image | adt/ubuntu-focal-arm64-server-20250404.img (82a1a68a-d67b-484b-bdb5-49bf84281933) | 100s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-20 | 100s | locked | False | 100s | locked_reason | - | 100s | metadata | {} | 100s | name | adt-focal-arm64-acpi-call-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-920b3d30-75ce-4a03-ad9f-ab4c2f731b96 | 100s | net_prod-autopkgtest-workers-arm64 network | 10.151.35.29 | 100s | os-extended-volumes:volumes_attached | [] | 100s | progress | 0 | 100s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-arm64-1.secgroup | 100s | server_groups | [] | 100s | status | ACTIVE | 100s | tags | [] | 100s | tenant_id | e27006f5eb9946719a589a78f7f099f8 | 100s | trusted_image_certificates | - | 100s | updated | 2025-04-07T14:08:11Z | 100s | user_id | 5676cac36e4942629c54e24741ff7157 | 100s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 100s --------------------------------------------------- 100s 118s nova [W] Using flock in prodstack7-arm64 118s Creating nova instance adt-focal-arm64-acpi-call-20250407-134937-juju-7f2275-prod-proposed-migration-environment-20-920b3d30-75ce-4a03-ad9f-ab4c2f731b96 from image adt/ubuntu-focal-arm64-server-20250404.img (UUID 82a1a68a-d67b-484b-bdb5-49bf84281933)... 118s nova [W] Timed out waiting for 95c394c2-c0a7-4b3f-a9de-a6e54143847a to get deleted. 118s autopkgtest [14:09:47]: ERROR: testbed failure: sent `auxverb_debug_fail', got `copy-failed', expected `ok...'