0s autopkgtest [14:04:30]: starting date and time: 2025-04-07 14:04:30+0000 0s autopkgtest [14:04:30]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [14:04:30]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4flrznpj/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 evdi --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-2@sto01-arm64-2.secgroup --name adt-focal-arm64-evdi-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-4784db53-eaf5-4d35-8977-c3fe63fb8e8d --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/ 93s autopkgtest [14:06:03]: testbed dpkg architecture: arm64 94s autopkgtest [14:06:04]: testbed apt version: 2.0.10 94s autopkgtest [14:06:04]: @@@@@@@@@@@@@@@@@@@@ test bed setup 94s autopkgtest [14:06:04]: testbed release detected to be: None 95s autopkgtest [14:06:05]: updating testbed package index (apt update) 95s Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 95s Hit:2 http://ftpmaster.internal/ubuntu focal-updates InRelease 95s Hit:3 http://ftpmaster.internal/ubuntu focal-security InRelease 95s Get:4 http://ftpmaster.internal/ubuntu focal-proposed InRelease [277 kB] 95s Get:5 http://ftpmaster.internal/ubuntu focal-proposed/multiverse Sources [1948 B] 95s Get:6 http://ftpmaster.internal/ubuntu focal-proposed/restricted Sources [15.7 kB] 95s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/main Sources [57.8 kB] 95s Get:8 http://ftpmaster.internal/ubuntu focal-proposed/universe Sources [8808 B] 95s Get:9 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 Packages [316 kB] 95s Get:10 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 c-n-f Metadata [1708 B] 95s Get:11 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 Packages [2936 B] 95s Get:12 http://ftpmaster.internal/ubuntu focal-proposed/restricted arm64 c-n-f Metadata [116 B] 95s Get:13 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 Packages [54.2 kB] 95s Get:14 http://ftpmaster.internal/ubuntu focal-proposed/universe arm64 c-n-f Metadata [1844 B] 96s Get:15 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 Packages [8116 B] 96s Get:16 http://ftpmaster.internal/ubuntu focal-proposed/multiverse arm64 c-n-f Metadata [116 B] 98s Fetched 746 kB in 1s (909 kB/s) 99s Reading package lists... 100s Reading package lists... 100s Building dependency tree... 100s Reading state information... 100s The following additional packages will be installed: 100s linux-gcp-5.15-headers-5.15.0-1081 linux-headers-5.15.0-1081-gcp 100s linux-headers-gcp-edge linux-image-5.15.0-1081-gcp linux-image-gcp-edge 100s linux-modules-5.15.0-1081-gcp 100s Suggested packages: 100s fdutils linux-gcp-5.15-doc-5.15.0 | linux-gcp-5.15-source-5.15.0 100s linux-gcp-5.15-tools 100s The following NEW packages will be installed: 100s linux-gcp-5.15-headers-5.15.0-1081 linux-gcp-edge 100s linux-headers-5.15.0-1081-gcp linux-headers-gcp-edge 100s linux-image-5.15.0-1081-gcp linux-image-gcp-edge 100s linux-modules-5.15.0-1081-gcp 100s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 100s Need to get 49.3 MB of archives. 100s After this operation, 266 MB of additional disk space will be used. 100s 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] 101s 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] 101s 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] 101s 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] 101s 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] 102s 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] 102s Get:7 http://ftpmaster.internal/ubuntu focal-proposed/main arm64 linux-gcp-edge arm64 5.15.0.1081.90~20.04.1 [1928 B] 102s dpkg-preconfigure: unable to re-open stdin: No such file or directory 102s Fetched 49.3 MB in 1s (42.7 MB/s) 102s Selecting previously unselected package linux-gcp-5.15-headers-5.15.0-1081. 102s (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.) 102s Preparing to unpack .../0-linux-gcp-5.15-headers-5.15.0-1081_5.15.0-1081.90~20.04.1_arm64.deb ... 102s Unpacking linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 103s Selecting previously unselected package linux-modules-5.15.0-1081-gcp. 103s Preparing to unpack .../1-linux-modules-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 103s Unpacking linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 105s Selecting previously unselected package linux-image-5.15.0-1081-gcp. 105s Preparing to unpack .../2-linux-image-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 105s Unpacking linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 105s Selecting previously unselected package linux-image-gcp-edge. 105s Preparing to unpack .../3-linux-image-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 105s Unpacking linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 105s Selecting previously unselected package linux-headers-5.15.0-1081-gcp. 105s Preparing to unpack .../4-linux-headers-5.15.0-1081-gcp_5.15.0-1081.90~20.04.1_arm64.deb ... 105s Unpacking linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 106s Selecting previously unselected package linux-headers-gcp-edge. 106s Preparing to unpack .../5-linux-headers-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 106s Unpacking linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 106s Selecting previously unselected package linux-gcp-edge. 106s Preparing to unpack .../6-linux-gcp-edge_5.15.0.1081.90~20.04.1_arm64.deb ... 106s Unpacking linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 106s Setting up linux-gcp-5.15-headers-5.15.0-1081 (5.15.0-1081.90~20.04.1) ... 106s Setting up linux-headers-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 106s Setting up linux-headers-gcp-edge (5.15.0.1081.90~20.04.1) ... 106s Setting up linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 107s I: /boot/vmlinuz is now a symlink to vmlinuz-5.15.0-1081-gcp 107s I: /boot/initrd.img is now a symlink to initrd.img-5.15.0-1081-gcp 107s Setting up linux-image-gcp-edge (5.15.0.1081.90~20.04.1) ... 107s Setting up linux-modules-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 108s Setting up linux-gcp-edge (5.15.0.1081.90~20.04.1) ... 108s Processing triggers for linux-image-5.15.0-1081-gcp (5.15.0-1081.90~20.04.1) ... 108s /etc/kernel/postinst.d/initramfs-tools: 108s update-initramfs: Generating /boot/initrd.img-5.15.0-1081-gcp 114s flash-kernel: deferring update (trigger activated) 114s /etc/kernel/postinst.d/zz-flash-kernel: 114s flash-kernel: deferring update (trigger activated) 114s /etc/kernel/postinst.d/zz-update-grub: 114s Sourcing file `/etc/default/grub' 114s Sourcing file `/etc/default/grub.d/init-select.cfg' 114s Generating grub configuration file ... 114s Found linux image: /boot/vmlinuz-5.15.0-1081-gcp 114s Found initrd image: /boot/initrd.img-5.15.0-1081-gcp 114s Found linux image: /boot/vmlinuz-5.4.0-212-generic 114s Found initrd image: /boot/initrd.img-5.4.0-212-generic 114s Adding boot menu entry for UEFI Firmware Settings 114s done 114s Processing triggers for flash-kernel (3.103ubuntu1~20.04.4) ... 115s Unsupported platform on EFI system, doing nothing. 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s E: Unable to locate package ^kernel-testing--linux-gcp-5.15--modules-extra--preferred$ 115s E: Couldn't find any package by glob '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 115s E: Couldn't find any package by regex '^kernel-testing--linux-gcp-5.15--modules-extra--preferred$' 115s Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s E: Unable to locate package ^linux-modules-extra-gcp-5.15$ 116s E: Couldn't find any package by glob '^linux-modules-extra-gcp-5.15$' 116s E: Couldn't find any package by regex '^linux-modules-extra-gcp-5.15$' 116s autopkgtest [14:06:26]: upgrading testbed (apt dist-upgrade and autopurge) 116s Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 116s Starting 2 pkgProblemResolver with broken count: 0 116s Done 116s 116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s Starting pkgProblemResolver with broken count: 0 117s Starting 2 pkgProblemResolver with broken count: 0 117s Done 117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s autopkgtest [14:06:27]: rebooting testbed after setup commands that affected boot 135s autopkgtest-virt-ssh [14:06:45]: copydown destination failed, status 255 143s autopkgtest-virt-ssh [14:06:53]: ------- nova console-log 8cb05562-f607-462f-978b-b67a89c28260 (adt-focal-arm64-evdi-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-4784db53-eaf5-4d35-8977-c3fe63fb8e8d) ------ 143s [2J[01;01H[=3h[2J[01;01HBdsDxe: loading Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 143s BdsDxe: starting Boot0002 "UEFI Misc Device 2" from PciRoot(0x0)/Pci(0x1,0x3)/Pci(0x0,0x0) 143s error: no suitable video mode found. 143s cloudimg-rootfs: clean, 74747/2593280 files, 692245/5217019 blocks 143s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Boot Screen[0m. 143s [[0;32m OK [0m] Started [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 143s plymouth-start.service 143s [[0;32m OK [0m] Found device [0;1;39m/dev/ttyAMA0[0m. 143s [[0;32m OK [0m] Found device [0;1;39m/dev/disk/by-label/UEFI[0m. 143s Starting [0;1;39mLoad Kernel Module chromeos_pstore[0m... 143s Starting [0;1;39mLoad Kernel Module pstore_blk[0m... 143s Starting [0;1;39mLoad Kernel Module pstore_zone[0m... 143s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module chromeos_pstore[0m. 143s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_blk[0m. 143s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_zone[0m. 143s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 143s systemd-udev-settle.service 143s [[0;32m OK [0m] Finished [0;1;39mudev Wait for Complete Device Initialization[0m. 143s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 143s multipathd.service 143s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m. 143s Starting [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m... 143s [[0;32m OK [0m] Started [0;1;39mFile System Check Daemon to report status[0m. 143s systemd-fsckd.service 143s systemd-fsck@dev-disk-by\x2dlabel-UEFI.service 143s [[0;32m OK [0m] Finished [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 143s Mounting [0;1;39m/boot/efi[0m... 143s [[0;32m OK [0m] Mounted [0;1;39m/boot/efi[0m. 143s boot-efi.mount 143s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 143s Starting [0;1;39mLoad AppArmor profiles[0m... 143s Starting [0;1;39mSet console font and keymap[0m... 143s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 143s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 143s Starting [0;1;39mCreate Volatile Files and Directories[0m... 143s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 143s console-setup.service 143s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 143s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 143s systemd-tmpfiles-setup.service 143s Starting [0;1;39mNetwork Time Synchronization[0m... 143s Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 143s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 143s [[0;32m OK [0m] Finished [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 143s apparmor.service 143s systemd-update-utmp.service 143s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Synchronized[0m. 143s systemd-timesyncd.service 143s [ 5.406559] cloud-init[448]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init-local' at Mon, 07 Apr 2025 14:05:43 +0000. Up 3.82 seconds. 143s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mNetwork (Pre)[0m. 143s cloud-init-local.service 143s Starting [0;1;39mNetwork Service[0m... 143s [[0;32m OK [0m] Started [0;1;39mNetwork Service[0m. 143s systemd-networkd.service 143s Starting [0;1;39mWait for Network to be Configured[0m... 143s Starting [0;1;39mNetwork Name Resolution[0m... 143s systemd-resolved.service 143s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 143s systemd-networkd-wait-online.service 143s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 143s Starting [0;1;39mCloud-init: Network Stage[0m... 143s [ 7.289460] cloud-init[511]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init' at Mon, 07 Apr 2025 14:05:47 +0000. Up 7.15 seconds. 143s [ 7.289567] cloud-init[511]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 143s [ 7.289591] cloud-init[511]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 143s [ 7.289618] cloud-init[511]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 143s [ 7.289644] cloud-init[511]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 143s [ 7.289675] cloud-init[511]: ci-info: | enp1s0 | True | 10.151.35.106 | 255.255.255.0 | global | fa:16:3e:6e:29:01 | 143s [ 7.289702] cloud-init[511]: ci-info: | enp1s0 | True | fe80::f816:3eff:fe6e:2901/64 | . | link | fa:16:3e:6e:29:01 | 143s [ 7.289722] cloud-init[511]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 143s [ 7.289741] cloud-init[511]: ci-info: | lo | True | ::1/128 | . | host | . | 143s [ 7.289760] cloud-init[511]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 143s [ 7.289783] cloud-init[511]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 143s [ 7.289808] cloud-init[511]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 143s [ 7.289833] cloud-init[511]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 143s [ 7.289897] cloud-init[511]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 143s [ 7.289924] cloud-init[511]: ci-info: | 0 | 0.0.0.0 | 10.151.35.1 | 0.0.0.0 | enp1s0 | UG | 143s [ 7.289950] cloud-init[511]: ci-info: | 1 | 10.151.35.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | 143s [ 7.289974] cloud-init[511]: ci-info: | 2 | 169.254.169.254 | 10.151.35.2 | 255.255.255.255 | enp1s0 | UGH | 143s [ 7.289994] cloud-init[511]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 143s [ 7.290013] cloud-init[511]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 143s [ 7.290036] cloud-init[511]: ci-info: +-------+-------------+---------+-----------+-------+ 143s [ 7.290061] cloud-init[511]: ci-info: | Route | Destination | Gateway | Interface | Flags | 143s [ 7.290089] cloud-init[511]: ci-info: +-------+-------------+---------+-----------+-------+ 143s [ 7.290119] cloud-init[511]: ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | 143s [ 7.290140] cloud-init[511]: ci-info: | 3 | local | :: | enp1s0 | U | 143s [ 7.290164] cloud-init[511]: ci-info: | 4 | multicast | :: | enp1s0 | U | 143s [ 7.290184] cloud-init[511]: ci-info: +-------+-------------+---------+-----------+-------+ 143s [ 7.290203] cloud-init[511]: 2025-04-07 14:05:47,182 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 143s [ 7.837542] cloud-init[511]: Generating public/private rsa key pair. 143s [ 7.837626] cloud-init[511]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 143s [ 7.837669] cloud-init[511]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 143s [ 7.837691] cloud-init[511]: The key fingerprint is: 143s [ 7.837712] cloud-init[511]: SHA256:hCOEP40VnhUrLFT7lt/ywpEqjtuOfm8o6dH6tv2T9Ek root@autopkgtest 143s [ 7.837732] cloud-init[511]: The key's randomart image is: 143s [ 7.837761] cloud-init[511]: +---[RSA 3072]----+ 143s [ 7.837789] cloud-init[511]: | .o.o.o. | 143s [ 7.837814] cloud-init[511]: | .o o.= . | 143s [ 7.837842] cloud-init[511]: | .o+O o | 143s [ 7.837866] cloud-init[511]: | +o.= . | 143s [ 7.837890] cloud-init[511]: | . S . | 143s [ 7.837924] cloud-init[511]: | . . .+.E | 143s [ 7.837945] cloud-init[511]: | .... +o=.. | 143s [ 7.837969] cloud-init[511]: | o*=.o =oo | 143s [ 7.837996] cloud-init[511]: | oBO*=o..o. | 143s [ 7.838016] cloud-init[511]: +----[SHA256]-----+ 143s [ 7.838036] cloud-init[511]: Generating public/private ecdsa key pair. 143s [ 7.838060] cloud-init[511]: Your identification has been saved in /etc/ssh/ssh_host_ecdsa_key 143s [ 7.838085] cloud-init[511]: Your public key has been saved in /etc/ssh/ssh_host_ecdsa_key.pub 143s [ 7.838113] cloud-init[511]: The key fingerprint is: 143s [ 7.838133] cloud-init[511]: SHA256:ahNHhvs7vIzU4QgkASrhScrUE3qY5XjcdU9n+XNBCLo root@autopkgtest 143s [ 7.838157] cloud-init[511]: The key's randomart image is: 143s [ 7.838176] cloud-init[511]: +---[ECDSA 256]---+ 143s [ 7.838198] cloud-init[511]: |o+oo. . . o.o+. | 143s [ 7.838228] cloud-init[511]: |B.X+. ... + oo . | 143s [ 7.838253] cloud-init[511]: |+O.=o.. o. . . .| 143s [ 7.838277] cloud-init[511]: |. oo + . o.| 143s [ 7.838302] cloud-init[511]: | . o SE o| 143s [ 7.838326] cloud-init[511]: | . O . | 143s [ 7.838347] cloud-init[511]: | *.+ | 143s [ 7.838371] cloud-init[511]: | o +o. | 143s [ 7.838414] cloud-init[511]: | . +o | 143s [ 7.838457] cloud-init[511]: +----[SHA256]-----+ 143s [ 7.838478] cloud-init[511]: Generating public/private ed25519 key pair. 143s [ 7.838503] cloud-init[511]: Your identification has been saved in /etc/ssh/ssh_host_ed25519_key 143s [ 7.838524] cloud-init[511]: Your public key has been saved in /etc/ssh/ssh_host_ed25519_key.pub 143s [ 7.838549] cloud-init[511]: The key fingerprint is: 143s [ 7.838570] cloud-init[511]: SHA256:hYsH8/k//c14OxfvbrDos1vU+ZMDwutYsGkRcYvtLns root@autopkgtest 143s [ 7.838595] cloud-init[511]: The key's randomart image is: 143s [ 7.838621] cloud-init[511]: +--[ED25519 256]--+ 143s [ 7.838642] cloud-init[511]: | . . | 143s [ 7.838667] cloud-init[511]: | .= . | 143s [ 7.838687] cloud-init[511]: | o .o.o | 143s [ 7.838707] cloud-init[511]: | = ++ ..| 143s [ 7.838726] cloud-init[511]: | . So + ....| 143s [ 7.838746] cloud-init[511]: | . .* o.o.o| 143s [ 7.838770] cloud-init[511]: | =.+ o.*+| 143s [ 7.838794] cloud-init[511]: | . *E+.o+O| 143s [ 7.838819] cloud-init[511]: | o.o==.BX| 143s [ 7.838839] cloud-init[511]: +----[SHA256]-----+ 143s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 143s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 143s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 143s [[0;32m OK [0m] Started [0;1;39mRefresh fwupd metadata regularly[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 143s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mPaths[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mTimers[0m. 143s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 143s cloud-init.service 143s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 143s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSockets[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 143s Starting [0;1;39mLSB: automatic crash report generation[0m... 143s Starting [0;1;39mDeferred execution scheduler[0m... 143s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 143s Starting [0;1;39mCloud-init: Config Stage[0m... 143s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 143s autopkgtest@ttyS1.service 143s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 143s cron.service 143s dbus.service 143s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 143s dmesg.service 143s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 143s Starting [0;1;39mRecord successful boot for GRUB[0m... 143s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 143s irqbalance.service 143s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 143s Starting [0;1;39mAuthorization Manager[0m... 143s Starting [0;1;39mPollinate to seed���udo random number generator[0m... 143s [[0;32m OK [0m] Started [0;1;39mAdd entropy to /de���random 's pool a hardware RNG[0m. 143s rng-tools.service 143s Starting [0;1;39mSystem Logging Service[0m... 143s Starting [0;1;39mLogin Service[0m... 143s Starting [0;1;39mPermit User Sessions[0m... 143s Starting [0;1;39mDisk Manager[0m... 143s [[0;32m OK [0m] Started [0;1;39mDeferred execution scheduler[0m. 143s atd.service 143s [[0;32m OK [0m] Finished [0;1;39mRemove Stale Onli���ext4 Metadata Check Snapshots[0m. 143s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 143s systemd-user-sessions.service 143s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 143s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 143s rsyslog.service 143s [[0;32m OK [0m] Started [0;1;39mAuthorization Manager[0m. 143s polkit.service 143s [[0;32m OK [0m] Started [0;1;39mLogin Service[0m. 143s systemd-logind.service 143s Starting [0;1;39mModem Manager[0m... 143s Starting [0;1;39mDiscard unused bl���filesystems from /etc/fstab[0m... 143s Starting [0;1;39mGRUB failed boot detection[0m... 143s Starting [0;1;39mHold until boot process finishes up[0m... 143s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 143s Starting [0;1;39mRotate log files[0m... 143s Starting [0;1;39mDaily man-db regeneration[0m... 143s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 143s [[0;32m OK [0m] Started [0;1;39mDispatcher daemon for systemd-networkd[0m. 143s [[0;32m OK [0m] Finished [0;1;39mHold until boot process finishes up[0m. 143s [[0;32m OK [0m] Finished [0;1;39mTerminate Plymouth Boot Screen[0m. 143s [[0;32m OK [0m] Finished [0;1;39mRotate log files[0m. 143s [[0;32m OK [0m] Finished [0;1;39mGRUB failed boot detection[0m. 143s [ 9.064244] cloud-init[660]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:config' at Mon, 07 Apr 2025 14:05:48 +0000. Up 8.71 seconds. 143s 143s Ubuntu 20.04.6 LTS autopkgtest ttyAMA0 143s 143s autopkgtest login: [ 18.984275] cloud-init[714]: Hit:1 http://ftpmaster.internal/ubuntu focal InRelease 143s [ 19.012160] cloud-init[714]: Get:2 http://ftpmaster.internal/ubuntu focal-updates InRelease [128 kB] 143s [ 19.046130] cloud-init[714]: Get:3 http://ftpmaster.internal/ubuntu focal-security InRelease [128 kB] 143s [ 19.180518] cloud-init[714]: Get:4 http://ftpmaster.internal/ubuntu focal-updates/main Sources [630 kB] 143s [ 19.419762] cloud-init[714]: Get:5 http://ftpmaster.internal/ubuntu focal-updates/universe Sources [418 kB] 143s [ 19.453520] cloud-init[714]: Get:6 http://ftpmaster.internal/ubuntu focal-updates/main arm64 Packages [3001 kB] 143s [ 19.572139] cloud-init[714]: Get:7 http://ftpmaster.internal/ubuntu focal-updates/universe arm64 Packages [1190 kB] 143s [ 19.615546] cloud-init[714]: Get:8 http://ftpmaster.internal/ubuntu focal-security/restricted Sources [56.9 kB] 143s [ 19.648318] cloud-init[714]: Get:9 http://ftpmaster.internal/ubuntu focal-security/universe Sources [258 kB] 143s [ 19.681651] cloud-init[714]: Get:10 http://ftpmaster.internal/ubuntu focal-security/main Sources [333 kB] 143s [ 19.736216] cloud-init[714]: Get:11 http://ftpmaster.internal/ubuntu focal-security/main arm64 Packages [2687 kB] 143s [ 19.796899] cloud-init[714]: Get:12 http://ftpmaster.internal/ubuntu focal-security/universe arm64 Packages [964 kB] 143s [ 20.876612] cloud-init[714]: Fetched 9794 kB in 2s (5274 kB/s) 143s [ 21.598840] cloud-init[714]: Reading package lists... 143s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 143s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 143s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 143s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 143s 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 | 143s 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 | 143s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 143s <14>Apr 7 14:06:01 cloud-init: ############################################################# 143s <14>Apr 7 14:06:01 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 143s <14>Apr 7 14:06:01 cloud-init: 256 SHA256:ahNHhvs7vIzU4QgkASrhScrUE3qY5XjcdU9n+XNBCLo root@autopkgtest (ECDSA) 143s <14>Apr 7 14:06:01 cloud-init: 256 SHA256:hYsH8/k//c14OxfvbrDos1vU+ZMDwutYsGkRcYvtLns root@autopkgtest (ED25519) 143s <14>Apr 7 14:06:01 cloud-init: 3072 SHA256:hCOEP40VnhUrLFT7lt/ywpEqjtuOfm8o6dH6tv2T9Ek root@autopkgtest (RSA) 143s <14>Apr 7 14:06:01 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 143s <14>Apr 7 14:06:01 cloud-init: ############################################################# 143s -----BEGIN SSH HOST KEY KEYS----- 143s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBN8un/p8Z/1KkVtE4nfw7T68ZfQt6gE8x4GzYdA5TXt1MdKH2wppWywdH5lhLgxm0Xj5/Dp8W5frqcbEGMrLZdE= root@autopkgtest 143s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKVtwra4PePzc6YEeJjp28loghMajx3Umg5o/gdBsr1t root@autopkgtest 143s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC5tTPHpSIQe327GjoSuoHC4VZI0PNu0hRclklMXlshnclzlmgSNNrZNaNM3QsdWOJps5TVzNcR+xiteb434CK9zL8gVxBNryAVALFI7F4iVltSds9yFqy69i35y4xe2z68qMlIgHv19idgkxT37sgF3QDK8+DXd5Zf3xEUqpOBkGSK4utL1fy/LdQoiMzpJImfvki6VRFMs7cr2JkNH4/AcE6EoHHc+JpAN2s4ChrY82wjfm4EhYwvuMyrlZsvxLsgs0JaZdyRC22rJj5XAAKa9b9HvfNZGuG0k+P5nrsW6cyNsabsU5guP6QSDAOxixW6L+Hh8mJGB3nFKggPY0uG9TOIOtpswvK16MSnKFV44aclwHyEPRccUhJJS9KqWS/i6WmYtN29hZvY2BL5DKcJ1okbjGwtVzdTLZ89aoWLrSB7kdS00v8EQ5mhm4t1pk2pb/YMT3rsOmQfb+Z5rfOlM1LkxGkZ/TmrRx6oVV0dR1XED/WUWnPu0I/7BIaIrVs= root@autopkgtest 143s -----END SSH HOST KEY KEYS----- 143s [ 21.690293] cloud-init[714]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:final' at Mon, 07 Apr 2025 14:05:58 +0000. Up 18.67 seconds. 143s [ 21.691245] cloud-init[714]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 finished at Mon, 07 Apr 2025 14:06:01 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 21.68 seconds 143s [[0;32m OK [0m] Stopped [0;1;39mAuthorization Manager[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mUser Manager for UID 1000[0m. 143s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Reboot Screen[0m. 143s plymouth-reboot.service 143s Stopping [0;1;39mUser Runtime Directory /run/user/1000[0m... 143s [[0;32m OK [0m] Unmounted [0;1;39m/run/user/1000[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mUser Runtime Directory /run/user/1000[0m. 143s [[0;32m OK [0m] Removed slice [0;1;39mUser Slice of UID 1000[0m. 143s Stopping [0;1;39mD-Bus System Message Bus[0m... 143s Stopping [0;1;39mPermit User Sessions[0m... 143s [[0;32m OK [0m] Stopped [0;1;39mD-Bus System Message Bus[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mPermit User Sessions[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mNetwork[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mRemote File Systems[0m. 143s Stopping [0;1;39mNetwork Name Resolution[0m... 143s [[0;32m OK [0m] Stopped [0;1;39mLogin Service[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mBasic System[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mPaths[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mSlices[0m. 143s [[0;32m OK [0m] Removed slice [0;1;39mUser and Session Slice[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mSockets[0m. 143s [[0;32m OK [0m] Closed [0;1;39mD-Bus System Message Bus Socket[0m. 143s [[0;32m OK [0m] Closed [0;1;39mSyslog Socket[0m. 143s [[0;32m OK [0m] Closed [0;1;39mUUID daemon activation socket[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mSystem Initialization[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mLocal Encrypted Volumes[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mForward Password R���uests to Wall Directory Watch[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mSwap[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Network Stage[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mWait for Network to be Configured[0m. 143s Stopping [0;1;39mNetwork Time Synchronization[0m... 143s Stopping [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 143s [[0;32m OK [0m] Stopped [0;1;39mNetwork Name Resolution[0m. 143s Stopping [0;1;39mNetwork Service[0m... 143s [[0;32m OK [0m] Stopped [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mNetwork Service[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mNetwork (Pre)[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mCloud-init: Local Stage (pre-network)[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mApply Kernel Variables[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mLoad Kernel Modules[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mNetwork Time Synchronization[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mCreate Volatile Files and Directories[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems[0m. 143s Unmounting [0;1;39m/boot/efi[0m... 143s [[0;32m OK [0m] Unmounted [0;1;39m/boot/efi[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mUnmount All Filesystems[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 143s [[0;32m OK [0m] Removed slice [0;1;39msystem-systemd\x2dfsck.slice[0m. 143s [[0;32m OK [0m] Stopped target [0;1;39mLocal File Systems (Pre)[0m. 143s Stopping [0;1;39mDevice-Mapper Multipath Device Controller[0m... 143s [[0;32m OK [0m] Stopped [0;1;39mCreate Static Device Nodes in /dev[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mCreate System Users[0m. 143s [[0;32m OK [0m] Stopped [0;1;39mRemount Root and Kernel File Systems[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mShutdown[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mFinal Step[0m. 143s [[0;32m OK [0m] Finished [0;1;39mReboot[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mReboot[0m. 143s [ 51.779414] reboot: Restarting system 143s [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 143s BdsDxe: starting Boot0004 "ubuntu" from HD(15,GPT,BDB1E53A-00A8-4290-8006-6ED7FD15C953,0x800,0x31801)/\EFI\ubuntu\shimaa64.efi 143s error: no suitable video mode found. 143s cloudimg-rootfs: clean, 105816/2593280 files, 810897/5217019 blocks 143s [[0;32m OK [0m] Started [0;1;39mShow Plymouth Boot Screen[0m. 143s [[0;32m OK [0m] Started [0;1;39mForward Password R���s to Plymouth Directory Watch[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mLocal Encrypted Volumes[0m. 143s plymouth-start.service 143s [[0;32m OK [0m] Found device [0;1;39m/dev/ttyAMA0[0m. 143s [[0;32m OK [0m] Found device [0;1;39m/dev/disk/by-label/UEFI[0m. 143s Starting [0;1;39mLoad Kernel Module chromeos_pstore[0m... 143s Starting [0;1;39mLoad Kernel Module pstore_blk[0m... 143s Starting [0;1;39mLoad Kernel Module pstore_zone[0m... 143s Starting [0;1;39mLoad Kernel Module ramoops[0m... 143s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module chromeos_pstore[0m. 143s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_blk[0m. 143s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module pstore_zone[0m. 143s [[0;32m OK [0m] Finished [0;1;39mLoad Kernel Module ramoops[0m. 143s [[0;32m OK [0m] Listening on [0;1;39mLoad/Save RF ���itch Status /dev/rfkill Watch[0m. 143s systemd-udev-settle.service 143s [[0;32m OK [0m] Finished [0;1;39mudev Wait for Complete Device Initialization[0m. 143s Starting [0;1;39mDevice-Mapper Multipath Device Controller[0m... 143s multipathd.service 143s [[0;32m OK [0m] Started [0;1;39mDevice-Mapper Multipath Device Controller[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems (Pre)[0m. 143s Starting [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m... 143s [[0;32m OK [0m] Started [0;1;39mFile System Check Daemon to report status[0m. 143s systemd-fsckd.service 143s [[0;32m OK [0m] Finished [0;1;39mFile System Check on /dev/disk/by-label/UEFI[0m. 143s systemd-fsck@dev-disk-by\x2dlabel-UEFI.service 143s Mounting [0;1;39m/boot/efi[0m... 143s [[0;32m OK [0m] Mounted [0;1;39m/boot/efi[0m. 143s boot-efi.mount 143s [[0;32m OK [0m] Reached target [0;1;39mLocal File Systems[0m. 143s Starting [0;1;39mLoad AppArmor profiles[0m... 143s Starting [0;1;39mSet console font and keymap[0m... 143s Starting [0;1;39mCloud-init: Local Stage (pre-network)[0m... 143s Starting [0;1;39mTell Plymouth To Write Out Runtime Data[0m... 143s Starting [0;1;39mCreate Volatile Files and Directories[0m... 143s [[0;32m OK [0m] Finished [0;1;39mSet console font and keymap[0m. 143s console-setup.service 143s [[0;32m OK [0m] Finished [0;1;39mTell Plymouth To Write Out Runtime Data[0m. 143s [[0;32m OK [0m] Finished [0;1;39mCreate Volatile Files and Directories[0m. 143s systemd-tmpfiles-setup.service 143s Starting [0;1;39mNetwork Time Synchronization[0m... 143s Starting [0;1;39mUpdate UTMP about System Boot/Shutdown[0m... 143s [[0;32m OK [0m] Finished [0;1;39mUpdate UTMP about System Boot/Shutdown[0m. 143s systemd-update-utmp.service 143s [[0;32m OK [0m] Started [0;1;39mNetwork Time Synchronization[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Set[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSystem Time Synchronized[0m. 143s systemd-timesyncd.service 143s [ 3.437733] cloud-init[447]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init-local' at Mon, 07 Apr 2025 14:06:42 +0000. Up 3.33 seconds. 143s cloud-init-local.service 143s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Local Stage (pre-network)[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mNetwork (Pre)[0m. 143s Starting [0;1;39mNetwork Service[0m... 143s [[0;32m OK [0m] Started [0;1;39mNetwork Service[0m. 143s systemd-networkd.service 143s Starting [0;1;39mWait for Network to be Configured[0m... 143s Starting [0;1;39mNetwork Name Resolution[0m... 143s [[0;32m OK [0m] Finished [0;1;39mLoad AppArmor profiles[0m. 143s apparmor.service 143s [[0;32m OK [0m] Started [0;1;39mNetwork Name Resolution[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mNetwork[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mHost and Network Name Lookups[0m. 143s systemd-resolved.service 143s systemd-networkd-wait-online.service 143s [[0;32m OK [0m] Finished [0;1;39mWait for Network to be Configured[0m. 143s Starting [0;1;39mCloud-init: Network Stage[0m... 143s [ 5.662620] cloud-init[458]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'init' at Mon, 07 Apr 2025 14:06:44 +0000. Up 5.52 seconds. 143s [ 5.662722] cloud-init[458]: ci-info: +++++++++++++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++++++++++++ 143s [ 5.662757] cloud-init[458]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 143s [ 5.662786] cloud-init[458]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 143s [ 5.662809] cloud-init[458]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 143s [ 5.662830] cloud-init[458]: ci-info: | enp1s0 | True | 10.151.35.106 | 255.255.255.0 | global | fa:16:3e:6e:29:01 | 143s [ 5.662852] cloud-init[458]: ci-info: | enp1s0 | True | fe80::f816:3eff:fe6e:2901/64 | . | link | fa:16:3e:6e:29:01 | 143s [ 5.662874] cloud-init[458]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 143s [ 5.662897] cloud-init[458]: ci-info: | lo | True | ::1/128 | . | host | . | 143s [ 5.662926] cloud-init[458]: ci-info: +--------+------+------------------------------+---------------+--------+-------------------+ 143s [ 5.662948] cloud-init[458]: ci-info: ++++++++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++++++ 143s [ 5.662972] cloud-init[458]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 143s [ 5.662996] cloud-init[458]: ci-info: | Route | Destination | Gateway | Genmask | Interface | Flags | 143s [ 5.663018] cloud-init[458]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 143s [ 5.663041] cloud-init[458]: ci-info: | 0 | 0.0.0.0 | 10.151.35.1 | 0.0.0.0 | enp1s0 | UG | 143s [ 5.663062] cloud-init[458]: ci-info: | 1 | 10.151.35.0 | 0.0.0.0 | 255.255.255.0 | enp1s0 | U | 143s [ 5.663086] cloud-init[458]: ci-info: | 2 | 169.254.169.254 | 10.151.35.2 | 255.255.255.255 | enp1s0 | UGH | 143s [ 5.663107] cloud-init[458]: ci-info: +-------+-----------------+-------------+-----------------+-----------+-------+ 143s [ 5.663128] cloud-init[458]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 143s [ 5.663150] cloud-init[458]: ci-info: +-------+-------------+---------+-----------+-------+ 143s [ 5.663175] cloud-init[458]: ci-info: | Route | Destination | Gateway | Interface | Flags | 143s [ 5.663200] cloud-init[458]: ci-info: +-------+-------------+---------+-----------+-------+ 143s [ 5.663236] cloud-init[458]: ci-info: | 1 | fe80::/64 | :: | enp1s0 | U | 143s [ 5.663260] cloud-init[458]: ci-info: | 3 | local | :: | enp1s0 | U | 143s [ 5.663283] cloud-init[458]: ci-info: | 4 | multicast | :: | enp1s0 | U | 143s [ 5.663304] cloud-init[458]: ci-info: +-------+-------------+---------+-----------+-------+ 143s [ 5.663326] cloud-init[458]: 2025-04-07 14:06:44,789 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 143s cloud-init.service 143s [[0;32m OK [0m] Finished [0;1;39mCloud-init: Network Stage[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mCloud-config availability[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mNetwork is Online[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSystem Initialization[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily apt download activities[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily apt upgrade and clean activities[0m. 143s [[0;32m OK [0m] Started [0;1;39mPeriodic ext4 Onli���ata Check for All Filesystems[0m. 143s [[0;32m OK [0m] Started [0;1;39mDiscard unused blocks once a week[0m. 143s [[0;32m OK [0m] Started [0;1;39mRefresh fwupd metadata regularly[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily rotation of log files[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily man-db regeneration[0m. 143s [[0;32m OK [0m] Started [0;1;39mMessage of the Day[0m. 143s [[0;32m OK [0m] Started [0;1;39mDaily Cleanup of Temporary Directories[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mPaths[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mTimers[0m. 143s [[0;32m OK [0m] Listening on [0;1;39mcloud-init hotplug hook socket[0m. 143s [[0;32m OK [0m] Listening on [0;1;39mD-Bus System Message Bus Socket[0m. 143s [[0;32m OK [0m] Listening on [0;1;39mUUID daemon activation socket[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mSockets[0m. 143s [[0;32m OK [0m] Reached target [0;1;39mBasic System[0m. 143s Starting [0;1;39mLSB: automatic crash report generation[0m... 143s Starting [0;1;39mDeferred execution scheduler[0m... 143s [[0;32m OK [0m] Started [0;1;39mautopkgtest root shell on ttyS1[0m. 143s Starting [0;1;39mCloud-init: Config Stage[0m... 143s autopkgtest@ttyS1.service 143s [[0;32m OK [0m] Started [0;1;39mRegular background program processing daemon[0m. 143s [[0;32m OK [0m] Started [0;1;39mD-Bus System Message Bus[0m. 143s cron.service 143s [[0;32m OK [0m] Started [0;1;39mSave initial kernel messages after boot[0m. 143s dbus.service 143s Starting [0;1;39mRemove Stale Onli���t4 Metadata Check Snapshots[0m... 143s dmesg.service 143s Starting [0;1;39mRecord successful boot for GRUB[0m... 143s [[0;32m OK [0m] Started [0;1;39mirqbalance daemon[0m. 143s irqbalance.service 143s Starting [0;1;39mDispatcher daemon for systemd-networkd[0m... 143s Starting [0;1;39mAuthorization Manager[0m... 143s Starting [0;1;39mPollinate to seed���udo random number generator[0m... 143s [[0;32m OK [0m] Started [0;1;39mAdd entropy to /de���random 's pool a hardware RNG[0m. 143s rng-tools.service 143s Starting [0;1;39mSystem Logging Service[0m... 143s Starting [0;1;39mLogin Service[0m... 143s Starting [0;1;39mPermit User Sessions[0m... 143s Starting [0;1;39mDisk Manager[0m... 143s [[0;32m OK [0m] Started [0;1;39mDeferred execution scheduler[0m. 143s atd.service 143s [[0;32m OK [0m] Finished [0;1;39mRemove Stale Onli���ext4 Metadata Check Snapshots[0m. 143s [[0;32m OK [0m] Finished [0;1;39mPermit User Sessions[0m. 143s systemd-user-sessions.service 143s [[0;32m OK [0m] Started [0;1;39mSystem Logging Service[0m. 143s rsyslog.service 143s [[0;32m OK [0m] Started [0;1;39mAuthorization Manager[0m. 143s polkit.service 143s [[0;32m OK [0m] Started [0;1;39mLSB: automatic crash report generation[0m. 143s apport.service 143s Starting [0;1;39mModem Manager[0m... 143s Starting [0;1;39mHold until boot process finishes up[0m... 143s Starting [0;1;39mTerminate Plymouth Boot Screen[0m... 143s [[0;32m OK [0m] Finished [0;1;39mRecord successful boot for GRUB[0m. 143s Starting [0;1;39mGRUB failed boot detection[0m... 143s systemd-logind.service 143s [ 6.436130] cloud-init[586]: Cloud-init v. 24.4.1-0ubuntu0~20.04.2 running 'modules:config' at Mon, 07 Apr 2025 14:06:45 +0000. Up 6.35 seconds. 143s 143s --------------------------------------------------- 143s ------- nova show 8cb05562-f607-462f-978b-b67a89c28260 (adt-focal-arm64-evdi-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-4784db53-eaf5-4d35-8977-c3fe63fb8e8d) ------ 143s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 143s | Property | Value | 143s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 143s | OS-DCF:diskConfig | MANUAL | 143s | OS-EXT-AZ:availability_zone | AZ1 | 143s | OS-EXT-SRV-ATTR:host | ps7-ra2-arm64-n1.ps7.canonical.com | 143s | OS-EXT-SRV-ATTR:hostname | adt-focal-arm64-evdi-20250407-134938-juju-7f2275-prod-proposed | 143s | OS-EXT-SRV-ATTR:hypervisor_hostname | ps7-ra2-arm64-n1.ps7.canonical.com | 143s | OS-EXT-SRV-ATTR:instance_name | instance-0000c92d | 143s | OS-EXT-SRV-ATTR:kernel_id | | 143s | OS-EXT-SRV-ATTR:launch_index | 0 | 143s | OS-EXT-SRV-ATTR:ramdisk_id | | 143s | OS-EXT-SRV-ATTR:reservation_id | r-hikwdrij | 143s | OS-EXT-SRV-ATTR:root_device_name | /dev/vda | 143s | OS-EXT-STS:power_state | 1 | 143s | OS-EXT-STS:task_state | - | 143s | OS-EXT-STS:vm_state | active | 143s | OS-SRV-USG:launched_at | 2025-04-07T14:05:30.000000 | 143s | OS-SRV-USG:terminated_at | - | 143s | accessIPv4 | | 143s | accessIPv6 | | 143s | config_drive | | 143s | created | 2025-04-07T14:05:24Z | 143s | description | - | 143s | flavor:disk | 20 | 143s | flavor:ephemeral | 0 | 143s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "arm64", "hw:cpu_policy": "shared", "hw:mem_page_size": "large", "hw:pci_numa_affinity_policy": "preferred"} | 143s | flavor:original_name | autopkgtest-cpu2-ram4-disk20-arm64 | 143s | flavor:ram | 4096 | 143s | flavor:swap | 0 | 143s | flavor:vcpus | 2 | 143s | hostId | 514bcd46ddda01b3c63e985a72cd86dd0a2b6711d44b167131382610 | 143s | id | 8cb05562-f607-462f-978b-b67a89c28260 | 143s | image | adt/ubuntu-focal-arm64-server-20250404.img (82a1a68a-d67b-484b-bdb5-49bf84281933) | 143s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-2 | 143s | locked | False | 143s | locked_reason | - | 143s | metadata | {} | 143s | name | adt-focal-arm64-evdi-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-4784db53-eaf5-4d35-8977-c3fe63fb8e8d | 143s | net_prod-autopkgtest-workers-arm64 network | 10.151.35.106 | 143s | os-extended-volumes:volumes_attached | [] | 143s | progress | 0 | 143s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-arm64-2.secgroup | 143s | server_groups | [] | 143s | status | ACTIVE | 143s | tags | [] | 143s | tenant_id | e27006f5eb9946719a589a78f7f099f8 | 143s | trusted_image_certificates | - | 143s | updated | 2025-04-07T14:05:30Z | 143s | user_id | 5676cac36e4942629c54e24741ff7157 | 143s +--------------------------------------------+--------------------------------------------------------------------------------------------------------------------------------------------------------+ 143s --------------------------------------------------- 143s 150s nova [W] Using flock in prodstack7-arm64 150s Creating nova instance adt-focal-arm64-evdi-20250407-134938-juju-7f2275-prod-proposed-migration-environment-2-4784db53-eaf5-4d35-8977-c3fe63fb8e8d from image adt/ubuntu-focal-arm64-server-20250404.img (UUID 82a1a68a-d67b-484b-bdb5-49bf84281933)... 150s nova [W] Timed out waiting for 8cb05562-f607-462f-978b-b67a89c28260 to get deleted. 150s autopkgtest [14:07:00]: ERROR: testbed failure: sent `auxverb_debug_fail', got `copy-failed', expected `ok...'