0s autopkgtest [15:49:42]: starting date and time: 2025-01-20 15:49:42+0000 0s autopkgtest [15:49:42]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [15:49:42]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ebuvcg68/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:libtool,src:openipmi,src:python3-defaults --apt-upgrade cluster-glue --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=libtool/2.5.4-2 openipmi/2.0.33-2 python3-defaults/3.13.1-1~exp2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-29.secgroup --name adt-plucky-amd64-cluster-glue-20250120-144233-juju-7f2275-prod-proposed-migration-environment-20-28e6ca24-afda-49ee-be84-edfe0dd42e6a --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 58s autopkgtest [15:50:40]: testbed dpkg architecture: amd64 59s autopkgtest [15:50:41]: testbed apt version: 2.9.18 59s autopkgtest [15:50:41]: @@@@@@@@@@@@@@@@@@@@ test bed setup 59s autopkgtest [15:50:41]: testbed release detected to be: None 60s autopkgtest [15:50:42]: updating testbed package index (apt update) 60s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 60s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 61s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 61s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 61s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 61s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [797 kB] 61s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [142 kB] 61s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [16.1 kB] 61s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [187 kB] 61s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [273 kB] 61s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 61s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 61s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [372 kB] 61s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [911 kB] 61s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [27.7 kB] 61s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [7584 B] 61s Fetched 2860 kB in 1s (3004 kB/s) 62s Reading package lists... 62s Reading package lists... 63s Building dependency tree... 63s Reading state information... 63s Calculating upgrade... 63s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 63s Reading package lists... 63s Building dependency tree... 63s Reading state information... 63s 0 upgraded, 0 newly installed, 0 to remove and 3 not upgraded. 63s autopkgtest [15:50:45]: upgrading testbed (apt dist-upgrade and autopurge) 64s Reading package lists... 64s Building dependency tree... 64s Reading state information... 64s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 64s Starting 2 pkgProblemResolver with broken count: 0 64s Done 65s Entering ResolveByKeep 65s 65s The following packages were automatically installed and are no longer required: 65s python3.12 python3.12-minimal 65s Use 'sudo apt autoremove' to remove them. 65s The following NEW packages will be installed: 65s libpython3.13-minimal libpython3.13-stdlib python3.13 python3.13-minimal 65s The following packages will be upgraded: 65s libpython3-stdlib python3 python3-minimal 65s 3 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 65s Need to get 6117 kB of archives. 65s After this operation, 22.7 MB of additional disk space will be used. 65s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 libpython3.13-minimal amd64 3.13.1-3 [881 kB] 66s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13-minimal amd64 3.13.1-3 [2358 kB] 66s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3-minimal amd64 3.13.1-1~exp2 [27.6 kB] 66s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3 amd64 3.13.1-1~exp2 [23.9 kB] 66s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libpython3.13-stdlib amd64 3.13.1-3 [2087 kB] 66s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13 amd64 3.13.1-3 [729 kB] 66s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libpython3-stdlib amd64 3.13.1-1~exp2 [10.2 kB] 66s Fetched 6117 kB in 1s (7439 kB/s) 66s Selecting previously unselected package libpython3.13-minimal:amd64. 66s (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 ... 75821 files and directories currently installed.) 66s Preparing to unpack .../libpython3.13-minimal_3.13.1-3_amd64.deb ... 66s Unpacking libpython3.13-minimal:amd64 (3.13.1-3) ... 66s Selecting previously unselected package python3.13-minimal. 66s Preparing to unpack .../python3.13-minimal_3.13.1-3_amd64.deb ... 66s Unpacking python3.13-minimal (3.13.1-3) ... 66s Setting up libpython3.13-minimal:amd64 (3.13.1-3) ... 66s Setting up python3.13-minimal (3.13.1-3) ... 67s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 76142 files and directories currently installed.) 67s Preparing to unpack .../python3-minimal_3.13.1-1~exp2_amd64.deb ... 67s Unpacking python3-minimal (3.13.1-1~exp2) over (3.12.8-1) ... 67s Setting up python3-minimal (3.13.1-1~exp2) ... 67s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 76142 files and directories currently installed.) 67s Preparing to unpack .../python3_3.13.1-1~exp2_amd64.deb ... 67s Unpacking python3 (3.13.1-1~exp2) over (3.12.8-1) ... 67s Selecting previously unselected package libpython3.13-stdlib:amd64. 67s Preparing to unpack .../libpython3.13-stdlib_3.13.1-3_amd64.deb ... 67s Unpacking libpython3.13-stdlib:amd64 (3.13.1-3) ... 67s Selecting previously unselected package python3.13. 67s Preparing to unpack .../python3.13_3.13.1-3_amd64.deb ... 67s Unpacking python3.13 (3.13.1-3) ... 67s Preparing to unpack .../libpython3-stdlib_3.13.1-1~exp2_amd64.deb ... 67s Unpacking libpython3-stdlib:amd64 (3.13.1-1~exp2) over (3.12.8-1) ... 67s Setting up libpython3.13-stdlib:amd64 (3.13.1-3) ... 67s Setting up libpython3-stdlib:amd64 (3.13.1-1~exp2) ... 67s Setting up python3.13 (3.13.1-3) ... 68s Setting up python3 (3.13.1-1~exp2) ... 68s /usr/bin/py3clean:101: DeprecationWarning: glob.glob1 is deprecated and will be removed in Python 3.15. Use glob.glob and pass a directory to its root_dir argument instead. 68s for fn in glob1(directory, "%s.*" % fname): 68s Processing triggers for man-db (2.13.0-1) ... 69s Processing triggers for systemd (257-2ubuntu1) ... 69s Reading package lists... 70s Building dependency tree... 70s Reading state information... 70s Starting pkgProblemResolver with broken count: 0 70s Starting 2 pkgProblemResolver with broken count: 0 70s Done 70s The following packages will be REMOVED: 70s python3.12* python3.12-minimal* 70s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 70s After this operation, 8926 kB disk space will be freed. 70s (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 ... 76558 files and directories currently installed.) 70s Removing python3.12 (3.12.8-3) ... 70s Removing python3.12-minimal (3.12.8-3) ... 71s /usr/bin/py3clean:125: DeprecationWarning: glob.glob1 is deprecated and will be removed in Python 3.15. Use glob.glob and pass a directory to its root_dir argument instead. 71s for fn in glob1(directory, "%s.%s.py[co]" % (fname, magic_tag)): 71s Processing triggers for man-db (2.13.0-1) ... 71s Processing triggers for systemd (257-2ubuntu1) ... 71s (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 ... 76530 files and directories currently installed.) 71s Purging configuration files for python3.12-minimal (3.12.8-3) ... 74s autopkgtest [15:50:56]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 74s autopkgtest [15:50:56]: @@@@@@@@@@@@@@@@@@@@ apt-source cluster-glue 81s Get:1 http://ftpmaster.internal/ubuntu plucky/main cluster-glue 1.0.12-24ubuntu1 (dsc) [3367 B] 81s Get:2 http://ftpmaster.internal/ubuntu plucky/main cluster-glue 1.0.12-24ubuntu1 (tar) [491 kB] 81s Get:3 http://ftpmaster.internal/ubuntu plucky/main cluster-glue 1.0.12-24ubuntu1 (diff) [55.5 kB] 81s gpgv: Signature made Sun Dec 1 22:33:42 2024 UTC 81s gpgv: using RSA key 439884E6862A429C290DF63B033C4CA276024834 81s gpgv: issuer "athos.ribeiro@canonical.com" 81s gpgv: Can't check signature: No public key 81s dpkg-source: warning: cannot verify inline signature for ./cluster-glue_1.0.12-24ubuntu1.dsc: no acceptable signature found 81s dpkg-source: warning: diff 'src/debian/patches/ubuntu/lp1895355-b7bed186-Dev-update-permissions-for-ec2.patch' doesn't contain any patch 82s autopkgtest [15:51:04]: testing package cluster-glue version 1.0.12-24ubuntu1 84s autopkgtest [15:51:06]: build not needed 87s autopkgtest [15:51:09]: test user: preparing testbed 87s Reading package lists... 88s Building dependency tree... 88s Reading state information... 88s Starting pkgProblemResolver with broken count: 0 88s Starting 2 pkgProblemResolver with broken count: 0 88s Done 88s The following NEW packages will be installed: 88s cluster-glue liblrm2t64 libltdl7 libopenhpi3 libopenipmi0t64 libpils2t64 88s libplumb2t64 libplumbgpl2t64 libsnmp-base libsnmp40t64 libstonith1t64 88s libtimedate-perl python3.12 python3.12-minimal 88s 0 upgraded, 14 newly installed, 0 to remove and 0 not upgraded. 88s Need to get 5458 kB of archives. 88s After this operation, 18.5 MB of additional disk space will be used. 88s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.12-minimal amd64 3.12.8-3 [2349 kB] 89s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.12 amd64 3.12.8-3 [667 kB] 89s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libltdl7 amd64 2.5.4-2 [46.0 kB] 89s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 libpils2t64 amd64 1.0.12-24ubuntu1 [22.0 kB] 89s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libplumb2t64 amd64 1.0.12-24ubuntu1 [83.5 kB] 89s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 liblrm2t64 amd64 1.0.12-24ubuntu1 [17.6 kB] 89s Get:7 http://ftpmaster.internal/ubuntu plucky/universe amd64 libopenhpi3 amd64 3.8.0-2.2ubuntu1 [140 kB] 89s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libopenipmi0t64 amd64 2.0.33-2 [531 kB] 89s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 libplumbgpl2t64 amd64 1.0.12-24ubuntu1 [10.3 kB] 89s Get:10 http://ftpmaster.internal/ubuntu plucky/main amd64 libsnmp-base all 5.9.4+dfsg-1.1ubuntu5 [206 kB] 89s Get:11 http://ftpmaster.internal/ubuntu plucky/main amd64 libsnmp40t64 amd64 5.9.4+dfsg-1.1ubuntu5 [1065 kB] 89s Get:12 http://ftpmaster.internal/ubuntu plucky/main amd64 libstonith1t64 amd64 1.0.12-24ubuntu1 [13.8 kB] 89s Get:13 http://ftpmaster.internal/ubuntu plucky/main amd64 libtimedate-perl all 2.3300-2 [34.0 kB] 89s Get:14 http://ftpmaster.internal/ubuntu plucky/universe amd64 cluster-glue amd64 1.0.12-24ubuntu1 [274 kB] 89s Fetched 5458 kB in 1s (6387 kB/s) 89s Selecting previously unselected package python3.12-minimal. 89s (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 ... 76530 files and directories currently installed.) 89s Preparing to unpack .../00-python3.12-minimal_3.12.8-3_amd64.deb ... 89s Unpacking python3.12-minimal (3.12.8-3) ... 89s Selecting previously unselected package python3.12. 89s Preparing to unpack .../01-python3.12_3.12.8-3_amd64.deb ... 89s Unpacking python3.12 (3.12.8-3) ... 89s Selecting previously unselected package libltdl7:amd64. 89s Preparing to unpack .../02-libltdl7_2.5.4-2_amd64.deb ... 89s Unpacking libltdl7:amd64 (2.5.4-2) ... 89s Selecting previously unselected package libpils2t64. 89s Preparing to unpack .../03-libpils2t64_1.0.12-24ubuntu1_amd64.deb ... 89s Unpacking libpils2t64 (1.0.12-24ubuntu1) ... 89s Selecting previously unselected package libplumb2t64. 89s Preparing to unpack .../04-libplumb2t64_1.0.12-24ubuntu1_amd64.deb ... 89s Unpacking libplumb2t64 (1.0.12-24ubuntu1) ... 89s Selecting previously unselected package liblrm2t64. 89s Preparing to unpack .../05-liblrm2t64_1.0.12-24ubuntu1_amd64.deb ... 89s Unpacking liblrm2t64 (1.0.12-24ubuntu1) ... 89s Selecting previously unselected package libopenhpi3. 89s Preparing to unpack .../06-libopenhpi3_3.8.0-2.2ubuntu1_amd64.deb ... 89s Unpacking libopenhpi3 (3.8.0-2.2ubuntu1) ... 90s Selecting previously unselected package libopenipmi0t64:amd64. 90s Preparing to unpack .../07-libopenipmi0t64_2.0.33-2_amd64.deb ... 90s Unpacking libopenipmi0t64:amd64 (2.0.33-2) ... 90s Selecting previously unselected package libplumbgpl2t64. 90s Preparing to unpack .../08-libplumbgpl2t64_1.0.12-24ubuntu1_amd64.deb ... 90s Unpacking libplumbgpl2t64 (1.0.12-24ubuntu1) ... 90s Selecting previously unselected package libsnmp-base. 90s Preparing to unpack .../09-libsnmp-base_5.9.4+dfsg-1.1ubuntu5_all.deb ... 90s Unpacking libsnmp-base (5.9.4+dfsg-1.1ubuntu5) ... 90s Selecting previously unselected package libsnmp40t64:amd64. 90s Preparing to unpack .../10-libsnmp40t64_5.9.4+dfsg-1.1ubuntu5_amd64.deb ... 90s Unpacking libsnmp40t64:amd64 (5.9.4+dfsg-1.1ubuntu5) ... 90s Selecting previously unselected package libstonith1t64. 90s Preparing to unpack .../11-libstonith1t64_1.0.12-24ubuntu1_amd64.deb ... 90s Unpacking libstonith1t64 (1.0.12-24ubuntu1) ... 90s Selecting previously unselected package libtimedate-perl. 90s Preparing to unpack .../12-libtimedate-perl_2.3300-2_all.deb ... 90s Unpacking libtimedate-perl (2.3300-2) ... 90s Selecting previously unselected package cluster-glue. 90s Preparing to unpack .../13-cluster-glue_1.0.12-24ubuntu1_amd64.deb ... 90s Unpacking cluster-glue (1.0.12-24ubuntu1) ... 90s Setting up python3.12-minimal (3.12.8-3) ... 91s Setting up libsnmp-base (5.9.4+dfsg-1.1ubuntu5) ... 91s Setting up libopenipmi0t64:amd64 (2.0.33-2) ... 91s Setting up python3.12 (3.12.8-3) ... 91s Setting up libplumbgpl2t64 (1.0.12-24ubuntu1) ... 91s Setting up libltdl7:amd64 (2.5.4-2) ... 91s Setting up libtimedate-perl (2.3300-2) ... 92s Setting up libopenhpi3 (3.8.0-2.2ubuntu1) ... 92s Setting up libpils2t64 (1.0.12-24ubuntu1) ... 92s Setting up libplumb2t64 (1.0.12-24ubuntu1) ... 92s Setting up libsnmp40t64:amd64 (5.9.4+dfsg-1.1ubuntu5) ... 92s Setting up liblrm2t64 (1.0.12-24ubuntu1) ... 92s Setting up libstonith1t64 (1.0.12-24ubuntu1) ... 92s Setting up cluster-glue (1.0.12-24ubuntu1) ... 92s info: Selecting GID from range 100 to 999 ... 92s info: Adding group `haclient' (GID 109) ... 92s info: The home dir /var/lib/pacemaker you specified can't be accessed: No such file or directory 92s 92s info: Selecting UID from range 100 to 999 ... 92s 92s info: Adding system user `hacluster' (UID 108) ... 92s info: Adding new user `hacluster' (UID 108) with group `haclient' ... 92s info: Not creating home directory `/var/lib/pacemaker'. 92s Created symlink '/etc/systemd/system/multi-user.target.wants/logd.service' → '/usr/lib/systemd/system/logd.service'. 92s Processing triggers for systemd (257-2ubuntu1) ... 92s Processing triggers for man-db (2.13.0-1) ... 93s Processing triggers for libc-bin (2.40-4ubuntu1) ... 94s autopkgtest [15:51:16]: test user: [----------------------- 95s uid=108(hacluster) gid=109(haclient) groups=109(haclient) 95s autopkgtest [15:51:17]: test user: -----------------------] 95s autopkgtest [15:51:17]: test user: - - - - - - - - - - results - - - - - - - - - - 95s user PASS 96s autopkgtest [15:51:18]: test ipctest: preparing testbed 96s Reading package lists... 96s Building dependency tree... 96s Reading state information... 96s Starting pkgProblemResolver with broken count: 0 96s Starting 2 pkgProblemResolver with broken count: 0 96s Done 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 97s autopkgtest [15:51:19]: test ipctest: [----------------------- 98s Jan 20 15:51:20 autopkgtest ipctest: [2645]: info: Echo server: 10000 reps pid 2645. 98s Jan 20 15:51:20 autopkgtest ipctest: [2646]: info: Echo client: 10000 reps pid 2646. 98s Jan 20 15:51:20 autopkgtest ipctest: [2646]: info: echoclient: 0 errors 99s Jan 20 15:51:21 autopkgtest ipctest: [2645]: info: echoserver: 0 errors 99s Jan 20 15:51:21 autopkgtest ipctest: [2650]: info: Asyn echo server: 10000 reps pid 2650. 99s Jan 20 15:51:21 autopkgtest ipctest: [2651]: info: Async Echo client: 10000 reps pid 2651. 99s Jan 20 15:51:21 autopkgtest ipctest: [2651]: info: Async echoclient: 0 errors, 10000 reads, 10000 writes 99s Jan 20 15:51:21 autopkgtest ipctest: [2650]: info: asyn_echoserver: 0 errors 99s Jan 20 15:51:21 autopkgtest ipctest: [2652]: info: Mainloop echo server: 10000 reps pid 2652. 99s Jan 20 15:51:21 autopkgtest ipctest: [2653]: info: Mainloop echo client: 10000 reps pid 2653. 99s Jan 20 15:51:21 autopkgtest ipctest: [2653]: info: Mainloop echo client: 0 errors, 10000 read 10000 written 99s 99s (process:2652): GLib-CRITICAL **: 15:51:21.937: Source ID 1 was not found when attempting to remove it 99s Jan 20 15:51:21 autopkgtest ipctest: [2652]: info: Mainloop echo server: 0 errors 99s Jan 20 15:51:21 autopkgtest ipctest: [2652]: info: TOTAL errors: 0 100s autopkgtest [15:51:22]: test ipctest: -----------------------] 100s autopkgtest [15:51:22]: test ipctest: - - - - - - - - - - results - - - - - - - - - - 100s ipctest PASS 101s autopkgtest [15:51:23]: test logd: preparing testbed 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 102s Starting pkgProblemResolver with broken count: 0 102s Starting 2 pkgProblemResolver with broken count: 0 102s Done 102s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 103s autopkgtest [15:51:25]: test logd: [----------------------- 103s === service === 103s ● logd.service - ha_logd logging daemon 103s Loaded: loaded (/usr/lib/systemd/system/logd.service; enabled; preset: enabled) 103s Active: active (running) since Mon 2025-01-20 15:51:25 UTC; 10ms ago 103s Invocation: 25822b783f27475db11b333157b8fcdc 103s Docs: man:ha_logd(8) 103s Process: 2805 ExecStartPre=/bin/rm -f /run/logd.pid (code=exited, status=0/SUCCESS) 103s Main PID: 2807 ((ha_logd)) 103s Tasks: 1 (limit: 4616) 103s Memory: 1.5M (peak: 1.6M) 103s CPU: 11ms 103s CGroup: /system.slice/logd.service 103s └─2807 /usr/lib/heartbeat/ha_logd -c /etc/logd.cf 103s 103s Jan 20 15:51:25 autopkgtest systemd[1]: Starting logd.service - ha_logd logging daemon... 103s Jan 20 15:51:25 autopkgtest systemd[1]: Started logd.service - ha_logd logging daemon. 103s Jan 20 15:51:25 autopkgtest logd[2807]: [2807]: info: logd started with /etc/logd.cf. 103s === ha_logger === 103s 2025-01-20T15:51:25.553239+00:00 autopkgtest cluster: [2812]: autopkgtest-message 103s === logtest === 103s sending Message 0 103s sending Message 1 103s sending Message 2 103s sending Message 3 103s sending Message 4 103s sending Message 5 103s sending Message 6 103s sending Message 7 103s sending Message 8 103s sending Message 9 104s 2025-01-20T15:51:25.560377+00:00 autopkgtest logtest: [2814]: info: total message dropped: 0 104s autopkgtest [15:51:26]: test logd: -----------------------] 105s logd PASS 105s autopkgtest [15:51:27]: test logd: - - - - - - - - - - results - - - - - - - - - - 105s autopkgtest [15:51:27]: test stonith-null: preparing testbed 174s autopkgtest [15:52:36]: testbed dpkg architecture: amd64 174s autopkgtest [15:52:36]: testbed apt version: 2.9.18 175s autopkgtest [15:52:37]: @@@@@@@@@@@@@@@@@@@@ test bed setup 175s autopkgtest [15:52:37]: testbed release detected to be: plucky 176s autopkgtest [15:52:38]: updating testbed package index (apt update) 176s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 176s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 176s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 176s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 176s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 176s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [16.1 kB] 176s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [142 kB] 176s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [797 kB] 177s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [273 kB] 177s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [187 kB] 177s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted i386 Packages [2408 B] 177s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [40.1 kB] 177s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [372 kB] 177s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [911 kB] 177s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [27.7 kB] 177s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [7584 B] 177s Fetched 2860 kB in 1s (2836 kB/s) 178s Reading package lists... 178s Reading package lists... 178s Building dependency tree... 178s Reading state information... 179s Calculating upgrade... 179s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 179s Reading package lists... 179s Building dependency tree... 179s Reading state information... 179s 0 upgraded, 0 newly installed, 0 to remove and 3 not upgraded. 179s autopkgtest [15:52:41]: upgrading testbed (apt dist-upgrade and autopurge) 179s Reading package lists... 180s Building dependency tree... 180s Reading state information... 180s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 180s Starting 2 pkgProblemResolver with broken count: 0 180s Done 180s Entering ResolveByKeep 181s 181s The following packages were automatically installed and are no longer required: 181s python3.12 python3.12-minimal 181s Use 'sudo apt autoremove' to remove them. 181s The following NEW packages will be installed: 181s libpython3.13-minimal libpython3.13-stdlib python3.13 python3.13-minimal 181s The following packages will be upgraded: 181s libpython3-stdlib python3 python3-minimal 181s 3 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 181s Need to get 6117 kB of archives. 181s After this operation, 22.7 MB of additional disk space will be used. 181s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 libpython3.13-minimal amd64 3.13.1-3 [881 kB] 181s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13-minimal amd64 3.13.1-3 [2358 kB] 182s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3-minimal amd64 3.13.1-1~exp2 [27.6 kB] 182s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3 amd64 3.13.1-1~exp2 [23.9 kB] 182s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libpython3.13-stdlib amd64 3.13.1-3 [2087 kB] 182s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13 amd64 3.13.1-3 [729 kB] 182s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libpython3-stdlib amd64 3.13.1-1~exp2 [10.2 kB] 182s Fetched 6117 kB in 1s (6342 kB/s) 182s Selecting previously unselected package libpython3.13-minimal:amd64. 182s (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 ... 75821 files and directories currently installed.) 182s Preparing to unpack .../libpython3.13-minimal_3.13.1-3_amd64.deb ... 182s Unpacking libpython3.13-minimal:amd64 (3.13.1-3) ... 182s Selecting previously unselected package python3.13-minimal. 182s Preparing to unpack .../python3.13-minimal_3.13.1-3_amd64.deb ... 182s Unpacking python3.13-minimal (3.13.1-3) ... 182s Setting up libpython3.13-minimal:amd64 (3.13.1-3) ... 182s Setting up python3.13-minimal (3.13.1-3) ... 183s (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 ... 76142 files and directories currently installed.) 183s Preparing to unpack .../python3-minimal_3.13.1-1~exp2_amd64.deb ... 183s Unpacking python3-minimal (3.13.1-1~exp2) over (3.12.8-1) ... 183s Setting up python3-minimal (3.13.1-1~exp2) ... 183s (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 ... 76142 files and directories currently installed.) 183s Preparing to unpack .../python3_3.13.1-1~exp2_amd64.deb ... 183s Unpacking python3 (3.13.1-1~exp2) over (3.12.8-1) ... 183s Selecting previously unselected package libpython3.13-stdlib:amd64. 183s Preparing to unpack .../libpython3.13-stdlib_3.13.1-3_amd64.deb ... 183s Unpacking libpython3.13-stdlib:amd64 (3.13.1-3) ... 183s Selecting previously unselected package python3.13. 183s Preparing to unpack .../python3.13_3.13.1-3_amd64.deb ... 183s Unpacking python3.13 (3.13.1-3) ... 183s Preparing to unpack .../libpython3-stdlib_3.13.1-1~exp2_amd64.deb ... 183s Unpacking libpython3-stdlib:amd64 (3.13.1-1~exp2) over (3.12.8-1) ... 183s Setting up libpython3.13-stdlib:amd64 (3.13.1-3) ... 183s Setting up libpython3-stdlib:amd64 (3.13.1-1~exp2) ... 183s Setting up python3.13 (3.13.1-3) ... 184s Setting up python3 (3.13.1-1~exp2) ... 184s /usr/bin/py3clean:101: DeprecationWarning: glob.glob1 is deprecated and will be removed in Python 3.15. Use glob.glob and pass a directory to its root_dir argument instead. 184s for fn in glob1(directory, "%s.*" % fname): 184s Processing triggers for man-db (2.13.0-1) ... 185s Processing triggers for systemd (257-2ubuntu1) ... 185s Reading package lists... 185s Building dependency tree... 185s Reading state information... 186s Starting pkgProblemResolver with broken count: 0 186s Starting 2 pkgProblemResolver with broken count: 0 186s Done 186s The following packages will be REMOVED: 186s python3.12* python3.12-minimal* 186s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 186s After this operation, 8926 kB disk space will be freed. 186s (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 ... 76558 files and directories currently installed.) 186s Removing python3.12 (3.12.8-3) ... 186s Removing python3.12-minimal (3.12.8-3) ... 186s /usr/bin/py3clean:125: DeprecationWarning: glob.glob1 is deprecated and will be removed in Python 3.15. Use glob.glob and pass a directory to its root_dir argument instead. 186s for fn in glob1(directory, "%s.%s.py[co]" % (fname, magic_tag)): 187s Processing triggers for man-db (2.13.0-1) ... 187s Processing triggers for systemd (257-2ubuntu1) ... 187s (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 ... 76530 files and directories currently installed.) 187s Purging configuration files for python3.12-minimal (3.12.8-3) ... 190s Reading package lists... 190s Building dependency tree... 190s Reading state information... 190s Starting pkgProblemResolver with broken count: 0 190s Starting 2 pkgProblemResolver with broken count: 0 190s Done 191s The following NEW packages will be installed: 191s cluster-glue liblrm2t64 libltdl7 libopenhpi3 libopenipmi0t64 libpils2t64 191s libplumb2t64 libplumbgpl2t64 libsnmp-base libsnmp40t64 libstonith1t64 191s libtimedate-perl python3.12 python3.12-minimal 191s 0 upgraded, 14 newly installed, 0 to remove and 0 not upgraded. 191s Need to get 5458 kB of archives. 191s After this operation, 18.5 MB of additional disk space will be used. 191s Get:1 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.12-minimal amd64 3.12.8-3 [2349 kB] 191s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.12 amd64 3.12.8-3 [667 kB] 191s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libltdl7 amd64 2.5.4-2 [46.0 kB] 191s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 libpils2t64 amd64 1.0.12-24ubuntu1 [22.0 kB] 191s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libplumb2t64 amd64 1.0.12-24ubuntu1 [83.5 kB] 191s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 liblrm2t64 amd64 1.0.12-24ubuntu1 [17.6 kB] 191s Get:7 http://ftpmaster.internal/ubuntu plucky/universe amd64 libopenhpi3 amd64 3.8.0-2.2ubuntu1 [140 kB] 191s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libopenipmi0t64 amd64 2.0.33-2 [531 kB] 191s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 libplumbgpl2t64 amd64 1.0.12-24ubuntu1 [10.3 kB] 191s Get:10 http://ftpmaster.internal/ubuntu plucky/main amd64 libsnmp-base all 5.9.4+dfsg-1.1ubuntu5 [206 kB] 191s Get:11 http://ftpmaster.internal/ubuntu plucky/main amd64 libsnmp40t64 amd64 5.9.4+dfsg-1.1ubuntu5 [1065 kB] 191s Get:12 http://ftpmaster.internal/ubuntu plucky/main amd64 libstonith1t64 amd64 1.0.12-24ubuntu1 [13.8 kB] 191s Get:13 http://ftpmaster.internal/ubuntu plucky/main amd64 libtimedate-perl all 2.3300-2 [34.0 kB] 191s Get:14 http://ftpmaster.internal/ubuntu plucky/universe amd64 cluster-glue amd64 1.0.12-24ubuntu1 [274 kB] 192s Fetched 5458 kB in 1s (6428 kB/s) 192s Selecting previously unselected package python3.12-minimal. 192s (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 ... 76530 files and directories currently installed.) 192s Preparing to unpack .../00-python3.12-minimal_3.12.8-3_amd64.deb ... 192s Unpacking python3.12-minimal (3.12.8-3) ... 192s Selecting previously unselected package python3.12. 192s Preparing to unpack .../01-python3.12_3.12.8-3_amd64.deb ... 192s Unpacking python3.12 (3.12.8-3) ... 192s Selecting previously unselected package libltdl7:amd64. 192s Preparing to unpack .../02-libltdl7_2.5.4-2_amd64.deb ... 192s Unpacking libltdl7:amd64 (2.5.4-2) ... 192s Selecting previously unselected package libpils2t64. 192s Preparing to unpack .../03-libpils2t64_1.0.12-24ubuntu1_amd64.deb ... 192s Unpacking libpils2t64 (1.0.12-24ubuntu1) ... 192s Selecting previously unselected package libplumb2t64. 192s Preparing to unpack .../04-libplumb2t64_1.0.12-24ubuntu1_amd64.deb ... 192s Unpacking libplumb2t64 (1.0.12-24ubuntu1) ... 192s Selecting previously unselected package liblrm2t64. 192s Preparing to unpack .../05-liblrm2t64_1.0.12-24ubuntu1_amd64.deb ... 192s Unpacking liblrm2t64 (1.0.12-24ubuntu1) ... 192s Selecting previously unselected package libopenhpi3. 192s Preparing to unpack .../06-libopenhpi3_3.8.0-2.2ubuntu1_amd64.deb ... 192s Unpacking libopenhpi3 (3.8.0-2.2ubuntu1) ... 192s Selecting previously unselected package libopenipmi0t64:amd64. 192s Preparing to unpack .../07-libopenipmi0t64_2.0.33-2_amd64.deb ... 192s Unpacking libopenipmi0t64:amd64 (2.0.33-2) ... 192s Selecting previously unselected package libplumbgpl2t64. 192s Preparing to unpack .../08-libplumbgpl2t64_1.0.12-24ubuntu1_amd64.deb ... 192s Unpacking libplumbgpl2t64 (1.0.12-24ubuntu1) ... 192s Selecting previously unselected package libsnmp-base. 192s Preparing to unpack .../09-libsnmp-base_5.9.4+dfsg-1.1ubuntu5_all.deb ... 192s Unpacking libsnmp-base (5.9.4+dfsg-1.1ubuntu5) ... 192s Selecting previously unselected package libsnmp40t64:amd64. 192s Preparing to unpack .../10-libsnmp40t64_5.9.4+dfsg-1.1ubuntu5_amd64.deb ... 192s Unpacking libsnmp40t64:amd64 (5.9.4+dfsg-1.1ubuntu5) ... 192s Selecting previously unselected package libstonith1t64. 192s Preparing to unpack .../11-libstonith1t64_1.0.12-24ubuntu1_amd64.deb ... 192s Unpacking libstonith1t64 (1.0.12-24ubuntu1) ... 192s Selecting previously unselected package libtimedate-perl. 192s Preparing to unpack .../12-libtimedate-perl_2.3300-2_all.deb ... 192s Unpacking libtimedate-perl (2.3300-2) ... 192s Selecting previously unselected package cluster-glue. 192s Preparing to unpack .../13-cluster-glue_1.0.12-24ubuntu1_amd64.deb ... 192s Unpacking cluster-glue (1.0.12-24ubuntu1) ... 192s Setting up python3.12-minimal (3.12.8-3) ... 193s Setting up libsnmp-base (5.9.4+dfsg-1.1ubuntu5) ... 193s Setting up libopenipmi0t64:amd64 (2.0.33-2) ... 193s Setting up python3.12 (3.12.8-3) ... 194s Setting up libplumbgpl2t64 (1.0.12-24ubuntu1) ... 194s Setting up libltdl7:amd64 (2.5.4-2) ... 194s Setting up libtimedate-perl (2.3300-2) ... 194s Setting up libopenhpi3 (3.8.0-2.2ubuntu1) ... 194s Setting up libpils2t64 (1.0.12-24ubuntu1) ... 194s Setting up libplumb2t64 (1.0.12-24ubuntu1) ... 194s Setting up libsnmp40t64:amd64 (5.9.4+dfsg-1.1ubuntu5) ... 194s Setting up liblrm2t64 (1.0.12-24ubuntu1) ... 194s Setting up libstonith1t64 (1.0.12-24ubuntu1) ... 194s Setting up cluster-glue (1.0.12-24ubuntu1) ... 194s info: Selecting GID from range 100 to 999 ... 194s info: Adding group `haclient' (GID 109) ... 194s info: The home dir /var/lib/pacemaker you specified can't be accessed: No such file or directory 194s 194s info: Selecting UID from range 100 to 999 ... 194s 194s info: Adding system user `hacluster' (UID 108) ... 194s info: Adding new user `hacluster' (UID 108) with group `haclient' ... 194s info: Not creating home directory `/var/lib/pacemaker'. 194s Created symlink '/etc/systemd/system/multi-user.target.wants/logd.service' → '/usr/lib/systemd/system/logd.service'. 195s Processing triggers for systemd (257-2ubuntu1) ... 195s Processing triggers for man-db (2.13.0-1) ... 196s Processing triggers for libc-bin (2.40-4ubuntu1) ... 198s autopkgtest [15:53:00]: test stonith-null: [----------------------- 198s === list === 198s apcmaster 198s apcmastersnmp 198s apcsmart 198s baytech 198s bladehpi 198s cyclades 198s drac3 198s external/drac5 198s external/dracmc-telnet 198s external/ec2 198s external/hetzner 198s external/hmchttp 198s external/ibmrsa 198s external/ibmrsa-telnet 198s external/ipmi 198s external/ippower9258 198s external/kdumpcheck 198s external/libvirt 198s external/nut 198s external/rackpdu 198s external/riloe 198s external/ssh 198s external/vcenter 198s external/vmware 198s external/xen0 198s external/xen0-ha 198s ibmhmc 198s ipmilan 198s meatware 198s null 198s nw_rpc100s 198s rcd_serial 198s rps10 198s ssh 198s suicide 198s wti_mpc 198s wti_nps 198s 198s === metadata === 198s 198s 198s 198s 1.0 198s 198s Dummy (do-nothing) STONITH device 198s FOR TESTING ONLY! 198s 198s NULL STONITH device 198s 198s 198s Hostlist 198s 198s The list of hosts that the STONITH device controls 198s 198s 198s 198s 198s 198s 198s 198s 198s 198s 198s 2.0 198s 198s 198s 198s === status === 198s Jan 20 15:53:00 autopkgtest stonith: [2095]: debug: null device OK. 198s autopkgtest 198s 198s === reset === 198s Jan 20 15:53:00 autopkgtest stonith: [2096]: info: Host null-reset: autopkgtest 198s autopkgtest [15:53:00]: test stonith-null: -----------------------] 199s stonith-null PASS 199s autopkgtest [15:53:01]: test stonith-null: - - - - - - - - - - results - - - - - - - - - - 199s autopkgtest [15:53:01]: test stonith-suicide: preparing testbed 199s Reading package lists... 200s Building dependency tree... 200s Reading state information... 200s Starting pkgProblemResolver with broken count: 0 200s Starting 2 pkgProblemResolver with broken count: 0 200s Done 200s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 201s autopkgtest [15:53:03]: test stonith-suicide: [----------------------- 201s === list === 201s apcmaster 201s apcmastersnmp 201s apcsmart 201s baytech 201s bladehpi 201s cyclades 201s drac3 201s external/drac5 201s external/dracmc-telnet 201s external/ec2 201s external/hetzner 201s external/hmchttp 201s external/ibmrsa 201s external/ibmrsa-telnet 201s external/ipmi 201s external/ippower9258 201s external/kdumpcheck 201s external/libvirt 201s external/nut 201s external/rackpdu 201s external/riloe 201s external/ssh 201s external/vcenter 201s external/vmware 201s external/xen0 201s external/xen0-ha 201s ibmhmc 201s ipmilan 201s meatware 201s null 201s nw_rpc100s 201s rcd_serial 201s rps10 201s ssh 201s suicide 201s wti_mpc 201s wti_nps 201s 201s === metadata === 201s 201s 201s 201s 1.0 201s 201s Virtual device to reboot/powerdown itself. 201s 201s 201s Suicide STONITH device 201s 201s 201s 201s 201s 201s 201s 201s 201s 201s 2.0 201s 201s 201s 201s === status === 201s Jan 20 15:53:03 autopkgtest stonith: [2244]: debug: suicide device OK. 201s autopkgtest 201s 201s === reset === 201s Killed 201s autopkgtest [15:53:03]: test process requested preparation for reboot with marker fenced 202s autopkgtest-virt-ssh [15:53:04]: state saved, waiting for testbed to reboot... 507s nova [W] Skipping flock for amd64 507s Creating nova instance adt-plucky-amd64-cluster-glue-20250120-144233-juju-7f2275-prod-proposed-migration-environment-20-28e6ca24-afda-49ee-be84-edfe0dd42e6a from image adt/ubuntu-plucky-amd64-server-20250120.img (UUID 218cebe1-b495-4d19-8e47-186cc3f3e583)... 507s nova [W] Timed out waiting for 6246e6eb-913b-4f63-9e6c-5cec3285a7c6 to get deleted. 507s nova [W] Skipping flock for amd64 507s Creating nova instance adt-plucky-amd64-cluster-glue-20250120-144233-juju-7f2275-prod-proposed-migration-environment-20-28e6ca24-afda-49ee-be84-edfe0dd42e6a from image adt/ubuntu-plucky-amd64-server-20250120.img (UUID 218cebe1-b495-4d19-8e47-186cc3f3e583)... 507s ------- nova console-log 0dc76d64-753f-45b5-b71f-da9601581d1a (adt-plucky-amd64-cluster-glue-20250120-144233-juju-7f2275-prod-proposed-migration-environment-20-28e6ca24-afda-49ee-be84-edfe0dd42e6a) ------ 507s [ 0.000000] Linux version 6.11.0-8-generic (buildd@lcy02-amd64-042) (x86_64-linux-gnu-gcc-14 (Ubuntu 14.2.0-4ubuntu2) 14.2.0, GNU ld (GNU Binutils for Ubuntu) 2.43.1) #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 (Ubuntu 6.11.0-8.8-generic 6.11.0) 507s [ 0.000000] Command line: BOOT_IMAGE=/vmlinuz-6.11.0-8-generic root=UUID=c1d91cd9-c36e-400f-a528-e35795b2f626 ro console=tty0 console=hvc0 console=ttyS0 507s [ 0.000000] KERNEL supported cpus: 507s [ 0.000000] Intel GenuineIntel 507s [ 0.000000] AMD AuthenticAMD 507s [ 0.000000] Hygon HygonGenuine 507s [ 0.000000] Centaur CentaurHauls 507s [ 0.000000] zhaoxin Shanghai 507s [ 0.000000] BIOS-provided physical RAM map: 507s [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable 507s [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved 507s [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved 507s [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffdcfff] usable 507s [ 0.000000] BIOS-e820: [mem 0x00000000bffdd000-0x00000000bfffffff] reserved 507s [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved 507s [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved 507s [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000013fffffff] usable 507s [ 0.000000] BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved 507s [ 0.000000] NX (Execute Disable) protection: active 507s [ 0.000000] APIC: Static calls initialized 507s [ 0.000000] SMBIOS 3.0.0 present. 507s [ 0.000000] DMI: OpenStack Foundation OpenStack Nova, BIOS 1.16.3-debian-1.16.3-2~backport22.04.202412060920~ubuntu22.04.1 04/01/2014 507s [ 0.000000] DMI: Memory slots populated: 1/1 507s [ 0.000000] Hypervisor detected: KVM 507s [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 507s [ 0.000000] kvm-clock: using sched offset of 2275043608 cycles 507s [ 0.000002] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns 507s [ 0.000004] tsc: Detected 2295.686 MHz processor 507s [ 0.000967] last_pfn = 0x140000 max_arch_pfn = 0x400000000 507s [ 0.001006] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs 507s [ 0.001008] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT 507s [ 0.001061] last_pfn = 0xbffdd max_arch_pfn = 0x400000000 507s [ 0.003133] found SMP MP-table at [mem 0x000f53c0-0x000f53cf] 507s [ 0.003143] Using GB pages for direct mapping 507s [ 0.003462] RAMDISK: [mem 0x3096b000-0x344acfff] 507s [ 0.010130] ACPI: Early table checksum verification disabled 507s [ 0.010139] ACPI: RSDP 0x00000000000F5130 000014 (v00 BOCHS ) 507s [ 0.010145] ACPI: RSDT 0x00000000BFFE1B6B 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) 507s [ 0.010150] ACPI: FACP 0x00000000BFFE1A4F 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) 507s [ 0.010156] ACPI: DSDT 0x00000000BFFE0040 001A0F (v01 BOCHS BXPC 00000001 BXPC 00000001) 507s [ 0.010159] ACPI: FACS 0x00000000BFFE0000 000040 507s [ 0.010161] ACPI: APIC 0x00000000BFFE1AC3 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) 507s [ 0.010163] ACPI: WAET 0x00000000BFFE1B43 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) 507s [ 0.010165] ACPI: Reserving FACP table memory at [mem 0xbffe1a4f-0xbffe1ac2] 507s [ 0.010167] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a4e] 507s [ 0.010168] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] 507s [ 0.010169] ACPI: Reserving APIC table memory at [mem 0xbffe1ac3-0xbffe1b42] 507s [ 0.010170] ACPI: Reserving WAET table memory at [mem 0xbffe1b43-0xbffe1b6a] 507s [ 0.010765] No NUMA configuration found 507s [ 0.010767] Faking a node at [mem 0x0000000000000000-0x000000013fffffff] 507s [ 0.010781] NODE_DATA(0) allocated [mem 0x13ffd3000-0x13fffdfff] 507s [ 0.010994] Zone ranges: 507s [ 0.010994] DMA [mem 0x0000000000001000-0x0000000000ffffff] 507s [ 0.010996] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] 507s [ 0.010998] Normal [mem 0x0000000100000000-0x000000013fffffff] 507s [ 0.010999] Device empty 507s [ 0.011000] Movable zone start for each node 507s [ 0.011002] Early memory node ranges 507s [ 0.011003] node 0: [mem 0x0000000000001000-0x000000000009efff] 507s [ 0.011004] node 0: [mem 0x0000000000100000-0x00000000bffdcfff] 507s [ 0.011005] node 0: [mem 0x0000000100000000-0x000000013fffffff] 507s [ 0.011007] Initmem setup node 0 [mem 0x0000000000001000-0x000000013fffffff] 507s [ 0.011182] On node 0, zone DMA: 1 pages in unavailable ranges 507s [ 0.011200] On node 0, zone DMA: 97 pages in unavailable ranges 507s [ 0.020750] On node 0, zone Normal: 35 pages in unavailable ranges 507s [ 0.021180] ACPI: PM-Timer IO Port: 0x608 507s [ 0.021195] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) 507s [ 0.021232] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 507s [ 0.021235] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) 507s [ 0.021237] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) 507s [ 0.021238] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) 507s [ 0.021239] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) 507s [ 0.021240] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) 507s [ 0.021243] ACPI: Using ACPI (MADT) for SMP configuration information 507s [ 0.021249] CPU topo: Max. logical packages: 2 507s [ 0.021250] CPU topo: Max. logical dies: 2 507s [ 0.021250] CPU topo: Max. dies per package: 1 507s [ 0.021254] CPU topo: Max. threads per core: 1 507s [ 0.021255] CPU topo: Num. cores per package: 1 507s [ 0.021256] CPU topo: Num. threads per package: 1 507s [ 0.021256] CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs 507s [ 0.021278] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() 507s [ 0.021307] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] 507s [ 0.021308] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] 507s [ 0.021309] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff] 507s [ 0.021310] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff] 507s [ 0.021311] PM: hibernation: Registered nosave memory: [mem 0xbffdd000-0xbfffffff] 507s [ 0.021312] PM: hibernation: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] 507s [ 0.021313] PM: hibernation: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] 507s [ 0.021313] PM: hibernation: Registered nosave memory: [mem 0xff000000-0xfffbffff] 507s [ 0.021314] PM: hibernation: Registered nosave memory: [mem 0xfffc0000-0xffffffff] 507s [ 0.021316] [mem 0xc0000000-0xfeffbfff] available for PCI devices 507s [ 0.021317] Booting paravirtualized kernel on KVM 507s [ 0.021319] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns 507s [ 0.021658] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 507s [ 0.021907] percpu: Embedded 88 pages/cpu s237568 r8192 d114688 u1048576 507s [ 0.021938] kvm-guest: PV spinlocks disabled, no host support 507s [ 0.021939] Kernel command line: BOOT_IMAGE=/vmlinuz-6.11.0-8-generic root=UUID=c1d91cd9-c36e-400f-a528-e35795b2f626 ro console=tty0 console=hvc0 console=ttyS0 507s [ 0.021995] Unknown kernel command line parameters "BOOT_IMAGE=/vmlinuz-6.11.0-8-generic", will be passed to user space. 507s [ 0.022013] random: crng init done 507s [ 0.022466] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) 507s [ 0.022692] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) 507s [ 0.022719] Fallback order for Node 0: 0 507s [ 0.022722] Built 1 zonelists, mobility grouping on. Total pages: 1048443 507s [ 0.022723] Policy zone: Normal 507s [ 0.022729] mem auto-init: stack:all(zero), heap alloc:on, heap free:off 507s [ 0.022734] software IO TLB: area num 2. 507s [ 0.040929] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 507s [ 0.040959] ftrace: allocating 59297 entries in 232 pages 507s [ 0.053143] ftrace: allocated 232 pages with 4 groups 507s [ 0.054008] Dynamic Preempt: voluntary 507s [ 0.054227] rcu: Preemptible hierarchical RCU implementation. 507s [ 0.054228] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2. 507s [ 0.054230] Trampoline variant of Tasks RCU enabled. 507s [ 0.054230] Rude variant of Tasks RCU enabled. 507s [ 0.054230] Tracing variant of Tasks RCU enabled. 507s [ 0.054231] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. 507s [ 0.054232] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 507s [ 0.054237] RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. 507s [ 0.054239] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. 507s [ 0.054240] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. 507s [ 0.058340] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16 507s [ 0.058559] rcu: srcu_init: Setting srcu_struct sizes based on contention. 507s [ 0.068034] Console: colour VGA+ 80x25 507s [ 0.068037] printk: legacy console [tty0] enabled 507s [ 0.107251] printk: legacy console [ttyS0] enabled 507s [ 0.211556] ACPI: Core revision 20240322 507s [ 0.212407] APIC: Switch to symmetric I/O mode setup 507s [ 0.213502] x2apic enabled 507s [ 0.214341] APIC: Switched APIC routing to: physical x2apic 507s [ 0.216471] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 507s [ 0.217543] tsc: Marking TSC unstable due to TSCs unsynchronized 507s [ 0.218590] Calibrating delay loop (skipped) preset value.. 4591.37 BogoMIPS (lpj=2295686) 507s [ 0.220058] x86/cpu: User Mode Instruction Prevention (UMIP) activated 507s [ 0.220952] Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 507s [ 0.221856] Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 507s [ 0.223592] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization 507s [ 0.224981] Spectre V2 : Mitigation: Retpolines 507s [ 0.226588] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch 507s [ 0.227976] Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT 507s [ 0.228863] Spectre V2 : Enabling Speculation Barrier for firmware calls 507s [ 0.230588] RETBleed: Mitigation: untrained return thunk 507s [ 0.231522] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier 507s [ 0.232588] Speculative Store Bypass: Vulnerable 507s [ 0.233817] Speculative Return Stack Overflow: IBPB-extending microcode not applied! 507s [ 0.234959] Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. 507s [ 0.234961] Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode 507s [ 0.238603] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' 507s [ 0.239972] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' 507s [ 0.240864] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' 507s [ 0.241865] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 507s [ 0.242873] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. 507s [ 0.278845] Freeing SMP alternatives memory: 48K 507s [ 0.279591] pid_max: default: 32768 minimum: 301 507s [ 0.283646] LSM: initializing lsm=lockdown,capability,landlock,yama,apparmor,ima,evm 507s [ 0.285005] landlock: Up and running. 507s [ 0.286590] Yama: becoming mindful. 507s [ 0.287630] AppArmor: AppArmor initialized 507s [ 0.288614] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 507s [ 0.289971] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 507s [ 0.393834] smpboot: CPU0: AMD EPYC-Rome Processor (family: 0x17, model: 0x31, stepping: 0x0) 507s [ 0.395286] Performance Events: Fam17h+ core perfctr, AMD PMU driver. 507s [ 0.396593] ... version: 0 507s [ 0.397365] ... bit width: 48 507s [ 0.397816] ... generic registers: 6 507s [ 0.398814] ... value mask: 0000ffffffffffff 507s [ 0.399847] ... max period: 00007fffffffffff 507s [ 0.400848] ... fixed-purpose events: 0 507s [ 0.401589] ... event mask: 000000000000003f 507s [ 0.402688] signal: max sigframe size: 1776 507s [ 0.403618] rcu: Hierarchical SRCU implementation. 507s [ 0.404590] rcu: Max phase no-delay instances is 400. 507s [ 0.405640] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level 507s [ 0.411454] smp: Bringing up secondary CPUs ... 507s [ 0.411987] smpboot: x86: Booting SMP configuration: 507s [ 0.412857] .... node #0, CPUs: #1 507s [ 0.413704] smp: Brought up 1 node, 2 CPUs 507s [ 0.415593] smpboot: Total of 2 processors activated (9182.74 BogoMIPS) 507s [ 0.417036] Memory: 3933356K/4193772K available (22528K kernel code, 4521K rwdata, 14936K rodata, 4956K init, 4664K bss, 253512K reserved, 0K cma-reserved) 507s [ 0.418730] devtmpfs: initialized 507s [ 0.419646] x86/mm: Memory block size: 128MB 507s [ 0.421377] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns 507s [ 0.422035] futex hash table entries: 512 (order: 3, 32768 bytes, linear) 507s [ 0.422971] pinctrl core: initialized pinctrl subsystem 507s [ 0.424589] PM: RTC time: 15:52:07, date: 2025-01-20 507s [ 0.426449] NET: Registered PF_NETLINK/PF_ROUTE protocol family 507s [ 0.427705] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations 507s [ 0.428949] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations 507s [ 0.430024] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations 507s [ 0.431601] audit: initializing netlink subsys (disabled) 507s [ 0.432870] thermal_sys: Registered thermal governor 'fair_share' 507s [ 0.432870] thermal_sys: Registered thermal governor 'bang_bang' 507s [ 0.434594] audit: type=2000 audit(1737388327.792:1): state=initialized audit_enabled=0 res=1 507s [ 0.435602] thermal_sys: Registered thermal governor 'step_wise' 507s [ 0.437022] thermal_sys: Registered thermal governor 'user_space' 507s [ 0.437861] thermal_sys: Registered thermal governor 'power_allocator' 507s [ 0.438915] EISA bus registered 507s [ 0.441611] cpuidle: using governor ladder 507s [ 0.442605] cpuidle: using governor menu 507s [ 0.444007] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 507s [ 0.445920] PCI: Using configuration type 1 for base access 507s [ 0.446864] PCI: Using configuration type 1 for extended access 507s [ 0.448665] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. 507s [ 0.459438] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages 507s [ 0.460589] HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page 507s [ 0.461590] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages 507s [ 0.462879] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page 507s [ 0.464878] ACPI: Added _OSI(Module Device) 507s [ 0.465592] ACPI: Added _OSI(Processor Device) 507s [ 0.466590] ACPI: Added _OSI(3.0 _SCP Extensions) 507s [ 0.467463] ACPI: Added _OSI(Processor Aggregator Device) 507s [ 0.470354] ACPI: 1 ACPI AML tables successfully acquired and loaded 507s [ 0.479866] ACPI: Interpreter enabled 507s [ 0.480838] ACPI: PM: (supports S0 S3 S4 S5) 507s [ 0.481591] ACPI: Using IOAPIC for interrupt routing 507s [ 0.482819] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug 507s [ 0.484019] PCI: Using E820 reservations for host bridge windows 507s [ 0.485751] ACPI: Enabled 2 GPEs in block 00 to 0F 507s [ 0.490807] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) 507s [ 0.491877] acpi PNP0A03:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI EDR HPX-Type3] 507s [ 0.494160] acpiphp: Slot [3] registered 507s [ 0.495618] acpiphp: Slot [4] registered 507s [ 0.496604] acpiphp: Slot [5] registered 507s [ 0.497389] acpiphp: Slot [6] registered 507s [ 0.497834] acpiphp: Slot [7] registered 507s [ 0.498835] acpiphp: Slot [8] registered 507s [ 0.499616] acpiphp: Slot [9] registered 507s [ 0.500397] acpiphp: Slot [10] registered 507s [ 0.500844] acpiphp: Slot [11] registered 507s [ 0.501839] acpiphp: Slot [12] registered 507s [ 0.502839] acpiphp: Slot [13] registered 507s [ 0.503611] acpiphp: Slot [14] registered 507s [ 0.504392] acpiphp: Slot [15] registered 507s [ 0.504839] acpiphp: Slot [16] registered 507s [ 0.505841] acpiphp: Slot [17] registered 507s [ 0.506836] acpiphp: Slot [18] registered 507s [ 0.507611] acpiphp: Slot [19] registered 507s [ 0.508400] acpiphp: Slot [20] registered 507s [ 0.508836] acpiphp: Slot [21] registered 507s [ 0.509839] acpiphp: Slot [22] registered 507s [ 0.510612] acpiphp: Slot [23] registered 507s [ 0.511606] acpiphp: Slot [24] registered 507s [ 0.512393] acpiphp: Slot [25] registered 507s [ 0.512836] acpiphp: Slot [26] registered 507s [ 0.513840] acpiphp: Slot [27] registered 507s [ 0.514614] acpiphp: Slot [28] registered 507s [ 0.515601] acpiphp: Slot [29] registered 507s [ 0.516388] acpiphp: Slot [30] registered 507s [ 0.516838] acpiphp: Slot [31] registered 507s [ 0.517828] PCI host bridge to bus 0000:00 507s [ 0.518594] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] 507s [ 0.519883] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] 507s [ 0.520890] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] 507s [ 0.521984] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] 507s [ 0.523590] pci_bus 0000:00: root bus resource [mem 0xc000000000-0xc07fffffff window] 507s [ 0.524992] pci_bus 0000:00: root bus resource [bus 00-ff] 507s [ 0.526025] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint 507s [ 0.528043] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint 507s [ 0.530145] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint 507s [ 0.534525] pci 0000:00:01.1: BAR 4 [io 0xc180-0xc18f] 507s [ 0.536613] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk 507s [ 0.537894] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk 507s [ 0.538871] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk 507s [ 0.539882] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk 507s [ 0.541071] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint 507s [ 0.542993] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI 507s [ 0.543981] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB 507s [ 0.545814] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000 conventional PCI endpoint 507s [ 0.548610] pci 0000:00:02.0: BAR 0 [mem 0xf4000000-0xf7ffffff] 507s [ 0.551600] pci 0000:00:02.0: BAR 1 [mem 0xf8000000-0xfbffffff] 507s [ 0.554610] pci 0000:00:02.0: BAR 2 [mem 0xfc090000-0xfc091fff] 507s [ 0.558621] pci 0000:00:02.0: BAR 3 [io 0xc140-0xc15f] 507s [ 0.565600] pci 0000:00:02.0: ROM [mem 0xfc080000-0xfc08ffff pref] 507s [ 0.567674] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] 507s [ 0.569783] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint 507s [ 0.571974] pci 0000:00:03.0: BAR 0 [io 0xc080-0xc0bf] 507s [ 0.573593] pci 0000:00:03.0: BAR 1 [mem 0xfc092000-0xfc092fff] 507s [ 0.577952] pci 0000:00:03.0: BAR 4 [mem 0xc000000000-0xc000003fff 64bit pref] 507s [ 0.579999] pci 0000:00:03.0: ROM [mem 0xfc000000-0xfc07ffff pref] 507s [ 0.583058] pci 0000:00:04.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint 507s [ 0.585375] pci 0000:00:04.0: BAR 0 [io 0xc0c0-0xc0ff] 507s [ 0.587351] pci 0000:00:04.0: BAR 1 [mem 0xfc093000-0xfc093fff] 507s [ 0.590231] pci 0000:00:04.0: BAR 4 [mem 0xc000004000-0xc000007fff 64bit pref] 507s [ 0.594427] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint 507s [ 0.597606] pci 0000:00:05.0: BAR 0 [io 0xc000-0xc07f] 507s [ 0.600603] pci 0000:00:05.0: BAR 1 [mem 0xfc094000-0xfc094fff] 507s [ 0.606222] pci 0000:00:05.0: BAR 4 [mem 0xc000008000-0xc00000bfff 64bit pref] 507s [ 0.610574] pci 0000:00:06.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint 507s [ 0.611871] pci 0000:00:06.0: BAR 0 [io 0xc100-0xc13f] 507s [ 0.615173] pci 0000:00:06.0: BAR 4 [mem 0xc00000c000-0xc00000ffff 64bit pref] 507s [ 0.619999] pci 0000:00:07.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint 507s [ 0.622449] pci 0000:00:07.0: BAR 0 [io 0xc160-0xc17f] 507s [ 0.623959] pci 0000:00:07.0: BAR 1 [mem 0xfc095000-0xfc095fff] 507s [ 0.627592] pci 0000:00:07.0: BAR 4 [mem 0xc000010000-0xc000013fff 64bit pref] 507s [ 0.639689] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 507s [ 0.640782] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 507s [ 0.642059] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 507s [ 0.643625] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 507s [ 0.644680] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 507s [ 0.646398] iommu: Default domain type: Translated 507s [ 0.646865] iommu: DMA domain TLB invalidation policy: lazy mode 507s [ 0.648870] SCSI subsystem initialized 507s [ 0.649650] ACPI: bus type USB registered 507s [ 0.650615] usbcore: registered new interface driver usbfs 507s [ 0.651601] usbcore: registered new interface driver hub 507s [ 0.652601] usbcore: registered new device driver usb 507s [ 0.653634] pps_core: LinuxPPS API ver. 1 registered 507s [ 0.654869] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 507s [ 0.656053] PTP clock support registered 507s [ 0.656906] EDAC MC: Ver: 3.0.0 507s [ 0.658652] NetLabel: Initializing 507s [ 0.659354] NetLabel: domain hash size = 128 507s [ 0.659826] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO 507s [ 0.660887] NetLabel: unlabeled traffic allowed by default 507s [ 0.661914] mctp: management component transport protocol core 507s [ 0.662887] NET: Registered PF_MCTP protocol family 507s [ 0.663888] PCI: Using ACPI for IRQ routing 507s [ 0.665623] pci 0000:00:02.0: vgaarb: setting as boot VGA device 507s [ 0.666587] pci 0000:00:02.0: vgaarb: bridge control possible 507s [ 0.666587] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none 507s [ 0.666597] vgaarb: loaded 507s [ 0.667887] clocksource: Switched to clocksource kvm-clock 507s [ 0.670170] VFS: Disk quotas dquot_6.6.0 507s [ 0.670990] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) 507s [ 0.672704] AppArmor: AppArmor Filesystem Enabled 507s [ 0.673685] pnp: PnP ACPI init 507s [ 0.674937] pnp: PnP ACPI: found 5 devices 507s [ 0.684349] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns 507s [ 0.686162] NET: Registered PF_INET protocol family 507s [ 0.687191] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) 507s [ 0.705242] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) 507s [ 0.706996] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) 507s [ 0.708453] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) 507s [ 0.710001] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) 507s [ 0.712164] TCP: Hash tables configured (established 32768 bind 32768) 507s [ 0.713427] MPTCP token hash table entries: 4096 (order: 4, 98304 bytes, linear) 507s [ 0.714941] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) 507s [ 0.716189] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) 507s [ 0.717608] NET: Registered PF_UNIX/PF_LOCAL protocol family 507s [ 0.718704] NET: Registered PF_XDP protocol family 507s [ 0.719642] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] 507s [ 0.720785] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] 507s [ 0.721932] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] 507s [ 0.723292] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] 507s [ 0.724575] pci_bus 0000:00: resource 8 [mem 0xc000000000-0xc07fffffff window] 507s [ 0.726020] pci 0000:00:01.0: PIIX3: Enabling Passive Release 507s [ 0.727107] pci 0000:00:00.0: Limiting direct PCI/PCI transfers 507s [ 0.728302] PCI: CLS 0 bytes, default 64 507s [ 0.729228] Trying to unpack rootfs image as initramfs... 507s [ 0.732052] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) 507s [ 0.733369] software IO TLB: mapped [mem 0x00000000bbfdd000-0x00000000bffdd000] (64MB) 507s [ 0.736430] Initialise system trusted keyrings 507s [ 0.737346] Key type blacklist registered 507s [ 0.738227] workingset: timestamp_bits=36 max_order=20 bucket_order=0 507s [ 0.739425] zbud: loaded 507s [ 0.740325] squashfs: version 4.0 (2009/01/31) Phillip Lougher 507s [ 0.742335] fuse: init (API version 7.40) 507s [ 0.743329] integrity: Platform Keyring initialized 507s [ 0.744277] integrity: Machine keyring initialized 507s [ 0.756720] Key type asymmetric registered 507s [ 0.757523] Asymmetric key parser 'x509' registered 507s [ 0.758490] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) 507s [ 0.759965] io scheduler mq-deadline registered 507s [ 0.761224] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 507s [ 0.762610] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 507s [ 0.764071] ACPI: button: Power Button [PWRF] 507s [ 0.810653] ACPI: \_SB_.LNKC: Enabled at IRQ 11 507s [ 0.823665] Freeing initrd memory: 60680K 507s [ 0.858842] ACPI: \_SB_.LNKD: Enabled at IRQ 10 507s [ 0.907642] ACPI: \_SB_.LNKA: Enabled at IRQ 10 507s [ 0.954213] ACPI: \_SB_.LNKB: Enabled at IRQ 11 507s [ 1.002140] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled 507s [ 1.026817] 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A 507s [ 1.041329] Linux agpgart interface v0.103 507s [ 1.044248] loop: module loaded 507s [ 1.045178] virtio_blk virtio2: 2/0/0 default/read/poll queues 507s [ 1.047116] virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB) 507s [ 1.050650] vda: vda1 vda13 vda14 vda15 507s [ 1.052839] scsi host0: ata_piix 507s [ 1.053742] scsi host1: ata_piix 507s [ 1.054460] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc180 irq 14 lpm-pol 0 507s [ 1.055906] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc188 irq 15 lpm-pol 0 507s [ 1.057394] ACPI: bus type drm_connector registered 507s [ 1.058621] tun: Universal TUN/TAP device driver, 1.6 507s [ 1.061661] PPP generic driver version 2.4.2 507s [ 1.062701] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 507s [ 1.064930] serio: i8042 KBD port at 0x60,0x64 irq 1 507s [ 1.065917] serio: i8042 AUX port at 0x60,0x64 irq 12 507s [ 1.067044] mousedev: PS/2 mouse device common for all mice 507s [ 1.068462] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 507s [ 1.070204] rtc_cmos 00:04: RTC can wake from S4 507s [ 1.071622] rtc_cmos 00:04: registered as rtc0 507s [ 1.072722] rtc_cmos 00:04: setting system clock to 2025-01-20T15:52:08 UTC (1737388328) 507s [ 1.075418] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram 507s [ 1.077093] i2c_dev: i2c /dev entries driver 507s [ 1.078054] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. 507s [ 1.080292] device-mapper: uevent: version 1.0.3 507s [ 1.081321] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev 507s [ 1.083147] platform eisa.0: Probing EISA bus 0 507s [ 1.084040] platform eisa.0: EISA: Cannot allocate resource for mainboard 507s [ 1.085313] platform eisa.0: Cannot allocate resource for EISA slot 1 507s [ 1.086515] platform eisa.0: Cannot allocate resource for EISA slot 2 507s [ 1.087739] platform eisa.0: Cannot allocate resource for EISA slot 3 507s [ 1.088950] platform eisa.0: Cannot allocate resource for EISA slot 4 507s [ 1.090158] platform eisa.0: Cannot allocate resource for EISA slot 5 507s [ 1.091351] platform eisa.0: Cannot allocate resource for EISA slot 6 507s [ 1.092556] platform eisa.0: Cannot allocate resource for EISA slot 7 507s [ 1.093742] platform eisa.0: Cannot allocate resource for EISA slot 8 507s [ 1.094942] platform eisa.0: EISA: Detected 0 cards 507s [ 1.095893] amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled 507s [ 1.097401] ledtrig-cpu: registered to indicate activity on CPUs 507s [ 1.098666] drop_monitor: Initializing network drop monitor service 507s [ 1.099995] NET: Registered PF_INET6 protocol family 507s [ 1.108725] Segment Routing with IPv6 507s [ 1.109606] In-situ OAM (IOAM) with IPv6 507s [ 1.110449] NET: Registered PF_PACKET protocol family 507s [ 1.111516] Key type dns_resolver registered 507s [ 1.112788] IPI shorthand broadcast: enabled 507s [ 1.116364] sched_clock: Marking stable (960006929, 156022136)->(1142772554, -26743489) 507s [ 1.118230] registered taskstats version 1 507s [ 1.119353] Loading compiled-in X.509 certificates 507s [ 1.121002] Loaded X.509 cert 'Build time autogenerated kernel key: 44bb7aee388ba8514143508bda37e4c74d0436e3' 507s [ 1.123476] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969' 507s [ 1.125822] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19' 507s [ 1.127695] blacklist: Loading compiled-in revocation X.509 certificates 507s [ 1.129038] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0' 507s [ 1.130920] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2017): 242ade75ac4a15e50d50c84b0d45ff3eae707a03' 507s [ 1.132912] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (ESM 2018): 365188c1d374d6b07c3c8f240f8ef722433d6a8b' 507s [ 1.135922] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2019): c0746fd6c5da3ae827864651ad66ae47fe24b3e8' 507s [ 1.137868] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v1): a8d54bbb3825cfb94fa13c9f8a594a195c107b8d' 507s [ 1.139898] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v2): 4cf046892d6fd3c9a5b03f98d845f90851dc6a8c' 507s [ 1.141882] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (2021 v3): 100437bb6de6e469b581e61cd66bce3ef4ed53af' 507s [ 1.144092] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing (Ubuntu Core 2019): c1d57b8f6b743f23ee41f4f7ee292f06eecadfb9' 507s [ 1.152810] Demotion targets for Node 0: null 507s [ 1.154300] Key type .fscrypt registered 507s [ 1.155252] Key type fscrypt-provisioning registered 507s [ 1.174021] Key type encrypted registered 507s [ 1.174931] AppArmor: AppArmor sha256 policy hashing enabled 507s [ 1.176012] ima: No TPM chip found, activating TPM-bypass! 507s [ 1.177109] Loading compiled-in module X.509 certificates 507s [ 1.178709] Loaded X.509 cert 'Build time autogenerated kernel key: 44bb7aee388ba8514143508bda37e4c74d0436e3' 507s [ 1.180606] ima: Allocated hash algorithm: sha256 507s [ 1.181537] ima: No architecture policies found 507s [ 1.182463] evm: Initialising EVM extended attributes: 507s [ 1.183499] evm: security.selinux 507s [ 1.187527] evm: security.SMACK64 507s [ 1.188363] evm: security.SMACK64EXEC 507s [ 1.189159] evm: security.SMACK64TRANSMUTE 507s [ 1.189963] evm: security.SMACK64MMAP 507s [ 1.190700] evm: security.apparmor 507s [ 1.191439] evm: security.ima 507s [ 1.192086] evm: security.capability 507s [ 1.192848] evm: HMAC attrs: 0x1 507s [ 1.194250] PM: Magic number: 9:402:892 507s [ 1.200282] RAS: Correctable Errors collector initialized. 507s [ 1.217016] clk: Disabling unused clocks 507s [ 1.217937] PM: genpd: Disabling unused power domains 507s [ 1.221459] Freeing unused decrypted memory: 2028K 507s [ 1.223202] Freeing unused kernel image (initmem) memory: 4956K 507s [ 1.224980] Write protecting the kernel read-only data: 38912k 507s [ 1.226517] Freeing unused kernel image (rodata/data gap) memory: 1448K 507s [ 1.280493] x86/mm: Checked W+X mappings: passed, no W+X pages found. 507s [ 1.281664] Run /init as init process 507s Loading, please wait... 507s Starting systemd-udevd version 257-2ubuntu1 507s [ 1.404918] virtio_net virtio0 ens3: renamed from eth0 507s [ 1.421120] FDC 0 is a S82078B 507s [ 1.424515] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 507s [ 1.427150] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 507s Begin: Loading essential drivers ... done. 507s Begin: Running /scripts/init-premount ... done. 507s Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. 507s Begin: Running /scripts/local-premount ... [ 2.602031] raid6: avx2x4 gen() 29904 MB/s 507s [ 2.619034] raid6: avx2x2 gen() 22435 MB/s 507s [ 2.636032] raid6: avx2x1 gen() 16023 MB/s 507s [ 2.636752] raid6: using algorithm avx2x4 gen() 29904 MB/s 507s [ 2.654032] raid6: .... xor() 3042 MB/s, rmw enabled 507s [ 2.654809] raid6: using avx2x2 recovery algorithm 507s [ 2.656528] xor: automatically using best checksumming function avx 507s [ 2.742602] Btrfs loaded, zoned=yes, fsverity=yes 507s Scanning for Btrfs filesystems 507s done. 507s Begin: Will now check root file system ... fsck from util-linux 2.40.2 507s [/usr/sbin/fsck.ext4 (1) -- /dev/vda1] fsck.ext4 -a -C0 /dev/vda1 507s cloudimg-rootfs: clean, 83587/2383360 files, 774306/4952315 blocks 507s done. 507s [ 2.834794] EXT4-fs (vda1): orphan cleanup on readonly fs 507s [ 2.835849] EXT4-fs (vda1): mounted filesystem c1d91cd9-c36e-400f-a528-e35795b2f626 ro with ordered data mode. Quota mode: none. 507s done. 507s Begin: Running /scripts/local-bottom ... done. 507s Begin: Running /scripts/init-bottom ... done. 507s [ 2.972978] systemd[1]: Inserted module 'autofs4' 507s [6n[32766;32766H[6n[!p]104[?7h[ 3.333282] systemd[1]: systemd 257-2ubuntu1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +IPE +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 +PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD +BPF_FRAMEWORK -BTF -XKBCOMMON -UTMP +SYSVINIT +LIBARCHIVE) 507s [ 3.338222] systemd[1]: Detected virtualization kvm. 507s [ 3.339004] systemd[1]: Detected architecture x86-64. 507s 507s [0;1;39mWelcome to [0m[1mUbuntu Plucky Puffin (development branch)[0m[0;1;39m![0m 507s 507s [ 3.343599] systemd[1]: Hostname set to . 507s [ 3.428820] systemd[1]: bpf-restrict-fs: BPF LSM hook not enabled in the kernel, BPF LSM not supported. 507s [ 3.487923] Guest personality initialized and is inactive 507s [ 3.489170] VMCI host device registered (name=vmci, major=10, minor=122) 507s [ 3.490432] Initialized host personality 507s [ 3.494782] NET: Registered PF_VSOCK protocol family 507s [ 3.643417] systemd[1]: Queued start job for default target graphical.target. 507s [ 3.659773] systemd[1]: Created slice system-autopkgtest.slice - Slice /system/autopkgtest. 507s [[0;32m OK [0m] Created slice [0;1;39msystem-autopkgtest.slice[0m - Slice /system/autopkgtest. 507s [ 3.663170] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. 507s [[0;32m OK [0m] Created slice [0;1;39msystem-modprobe.slice[0m - Slice /system/modprobe. 507s [ 3.666208] systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. 507s [[0;32m OK [0m] Created slice [0;1;39msystem-serial\x2dget���slice[0m - Slice /system/serial-getty. 507s [ 3.669454] systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. 507s [[0;32m OK [0m] Created slice [0;1;39msystem-systemd\x2dfs���slice[0m - Slice /system/systemd-fsck. 507s [ 3.672631] systemd[1]: Created slice user.slice - User and Session Slice. 507s [[0;32m OK [0m] Created slice [0;1;39muser.slice[0m - User and Session Slice. 507s [ 3.674916] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. 507s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-wall.���[0md Requests to Wall Directory Watch. 507s [ 3.678171] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. 507s [[0;32m OK [0m] Set up automount [0;1;39mproc-sys-fs-binfm���[0mormats File System Automount Point. 507s [ 3.681197] systemd[1]: Expecting device dev-disk-by\x2dlabel-BOOT.device - /dev/disk/by-label/BOOT... 507s Expecting device [0;1;39mdev-disk-by\x2dla���device[0m - /dev/disk/by-label/BOOT... 507s [ 3.683639] systemd[1]: Expecting device dev-disk-by\x2dlabel-UEFI.device - /dev/disk/by-label/UEFI... 507s Expecting device [0;1;39mdev-disk-by\x2dla���device[0m - /dev/disk/by-label/UEFI... 507s [ 3.687287] systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... 507s Expecting device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0... 507s [ 3.689274] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. 507s [[0;32m OK [0m] Reached target [0;1;39mintegritysetup.targ���[0m Local Integrity Protected Volumes. 507s [ 3.692064] systemd[1]: Reached target remote-fs.target - Remote File Systems. 507s [[0;32m OK [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems. 507s [ 3.694243] systemd[1]: Reached target slices.target - Slice Units. 507s [[0;32m OK [0m] Reached target [0;1;39mslices.target[0m - Slice Units. 507s [ 3.696069] systemd[1]: Reached target swap.target - Swaps. 507s [[0;32m OK [0m] Reached target [0;1;39mswap.target[0m - Swaps. 507s [ 3.697715] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. 507s [[0;32m OK [0m] Reached target [0;1;39mveritysetup.target[0m - Local Verity Protected Volumes. 507s [ 3.700314] systemd[1]: Listening on syslog.socket - Syslog Socket. 507s [[0;32m OK [0m] Listening on [0;1;39msyslog.socket[0m - Syslog Socket. 507s [ 3.702946] systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-creds.socket[0m - Credential Encryption/Decryption. 507s [ 3.705429] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-fsckd.socket[0m ���fsck to fsckd communication Socket. 507s [ 3.707969] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-initctl.socke���[0m- initctl Compatibility Named Pipe. 507s [ 3.710639] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald-dev-���socket[0m - Journal Socket (/dev/log). 507s [ 3.713494] systemd[1]: Listening on systemd-journald.socket - Journal Sockets. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Sockets. 507s [ 3.715901] systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-networkd.socket[0m - Network Service Netlink Socket. 507s [ 3.719588] systemd[1]: systemd-pcrextend.socket - TPM PCR Measurements was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 507s [ 3.721528] systemd[1]: systemd-pcrlock.socket - Make TPM PCR Policy was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 507s [ 3.723506] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-control.socket[0m - udev Control Socket. 507s [ 3.725875] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd-kernel.socket[0m - udev Kernel Socket. 507s [ 3.729719] systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... 507s Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System... 507s [ 3.734105] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... 507s Mounting [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System... 507s [ 3.740016] systemd[1]: Mounting run-lock.mount - Legacy Locks Directory /run/lock... 507s Mounting [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock... 507s [ 3.745185] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... 507s Mounting [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System... 507s [ 3.747710] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... 507s Mounting [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System... 507s [ 3.758208] systemd[1]: Starting systemd-journald.service - Journal Service... 507s Starting [0;1;39msystemd-journald.service[0m - Journal Service... 507s [ 3.763134] systemd[1]: Starting keyboard-setup.service - Set the console keyboard layout... 507s Starting [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout... 507s [ 3.767488] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... 507s Starting [0;1;39mkmod-static-nodes.service[0m���eate List of Static Device Nodes... 507s [ 3.773137] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... 507s Starting [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs... 507s [ 3.777079] systemd[1]: Starting modprobe@dm_multipath.service - Load Kernel Module dm_multipath... 507s Starting [0;1;39mmodprobe@dm_multipath.ser���[0m Load Kernel Module dm_multipath... 507s [ 3.787795] systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... 507s Starting [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm... 507s [ 3.792020] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... 507s [ 3.793444] systemd-journald[274]: Collecting audit messages is disabled. 507s Starting [0;1;39mmodprobe@efi_pstore.servi���[0m - Load Kernel Module efi_pstore... 507s [ 3.800261] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... 507s Starting [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse... 507s [ 3.804007] systemd[1]: netplan-ovs-cleanup.service - OpenVSwitch configuration for cleanup was skipped because of an unmet condition check (ConditionFileIsExecutable=/usr/bin/ovs-vsctl). 507s [ 3.808131] systemd[1]: systemd-fsck-root.service - File System Check on Root Device was skipped because of an unmet condition check (ConditionPathExists=!/run/initramfs/fsck-root). 507s [ 3.814086] systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). 507s [ 3.823381] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... 507s Starting [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules... 507s [ 3.826128] systemd[1]: systemd-pcrmachine.service - TPM PCR Machine ID Measurement was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 507s [ 3.834267] systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... 507s Starting [0;1;39msystemd-remount-fs.servic���[0munt Root and Kernel File Systems... 507s [ 3.837148] systemd[1]: systemd-tpm2-setup-early.service - Early TPM SRK Setup was skipped because of an unmet condition check (ConditionSecurity=measured-uki). 507s [ 3.843370] systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... 507s Starting [0;1;39msystemd-udev-load-credent���[0mLoad udev Rules from Credentials... 507s [ 3.851866] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... 507s Starting [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices... 507s [ 3.859832] systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. 507s [[0;32m OK [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System. 507s [ 3.864312] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. 507s [[0;32m OK [0m] Mounted [0;1;39mdev-mqueue.mount[0m - POSIX Message Queue File System. 507s [ 3.866717] systemd[1]: Mounted run-lock.mount - Legacy Locks Directory /run/lock. 507s [ 3.867783] EXT4-fs (vda1): re-mounted c1d91cd9-c36e-400f-a528-e35795b2f626 r/w. Quota mode: none. 507s [[0;32m OK [0m] Mounted [0;1;39mrun-lock.mount[0m - Legacy Locks Directory /run/lock. 507s [ 3.872639] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. 507s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-debug.mount[0m - Kernel Debug File System. 507s [ 3.876438] systemd[1]: Started systemd-journald.service - Journal Service. 507s [[0;32m OK [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service. 507s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-tracing.mount[0m - Kernel Trace File System. 507s [[0;32m OK [0m] Finished [0;1;39mkeyboard-setup.service[0m - Set the console keyboard layout. 507s [[0;32m OK [0m] Finished [0;1;39mkmod-static-nodes.service[0m���Create List of Static Device Nodes. 507s [[0;32m OK [0m] Finished [0;1;39mmodprobe@configfs.service[0m - Load Kernel Module configfs. 507s [[0;32m OK [0m] Finished [0;1;39mmodprobe@dm_multipath.ser���[0m - Load Kernel Module dm_multipath. 507s [[0;32m OK [0m] Finished [0;1;39mmodprobe@drm.service[0m - Load Kernel Module drm. 507s [[0;32m OK [0m] Finished [0;1;39mmodprobe@efi_pstore.service[0m - Load Kernel Module efi_pstore. 507s [[0;32m OK [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-modules-load.service[0m - Load Kernel Modules. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-remount-fs.servic���[0mmount Root and Kernel File Systems. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-udev-load-credent���[0m- Load udev Rules from Credentials. 507s Mounting [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System... 507s Mounting [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System... 507s Starting [0;1;39mmultipathd.service[0m - Devi���pper Multipath Device Controller... 507s Starting [0;1;39mcloud-init-main.service[0m - Cloud-init: Single Process... 507s Starting [0;1;39msystemd-journal-flush.ser���[0msh Journal to Persistent Storage... 507s Starting [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed... 507s Starting [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables... 507s Starting [0;1;39msystemd-tmpfiles-setup-de���[0m Device Nodes in /dev gracefully... 507s [[0;32m OK [0m] Mounted [0;1;39msys-fs-fuse-connections.mount[0m - FUSE Control File System. 507s [[0;32m OK [0m] Mounted [0;1;39msys-kernel-config.mount[0m - Kernel Configuration File System. 507s [ 3.969743] systemd-journald[274]: Received client request to flush runtime journal. 507s [[0;32m OK [0m] Started [0;1;39mmultipathd.service[0m - Devic���Mapper Multipath Device Controller. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mic Device Nodes in /dev gracefully. 507s Starting [0;1;39msystemd-tmpfiles-setup-de���[0meate Static Device Nodes in /dev... 507s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup-de���[0mCreate Static Device Nodes in /dev. 507s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs-pre.target[0m���Preparation for Local File Systems. 507s Starting [0;1;39msystemd-udevd.service[0m - R���ager for Device Events and Files... 507s [[0;32m OK [0m] Finished [0;1;39msystemd-random-seed.service[0m - Load/Save OS Random Seed. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-journal-flush.ser���[0mlush Journal to Persistent Storage. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-udev-trigger.service[0m - Coldplug All udev Devices. 507s [[0;32m OK [0m] Started [0;1;39msystemd-udevd.service[0m - Ru���anager for Device Events and Files. 507s [[0;32m OK [0m] Started [0;1;39msystemd-ask-password-conso���[0mequests to Console Directory Watch. 507s [[0;32m OK [0m] Reached target [0;1;39mcryptsetup.target[0m - Local Encrypted Volumes. 507s [[0;32m OK [0m] Found device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0. 507s [[0;32m OK [0m] Found device [0;1;39mdev-disk-by\x2dlabel-���T.device[0m - /dev/disk/by-label/BOOT. 507s Starting [0;1;39msystemd-fsck@dev-disk-by\���[0mCheck on /dev/disk/by-label/BOOT... 507s [[0;32m OK [0m] Started [0;1;39msystemd-fsckd.service[0m - Fi���stem Check Daemon to report status. 507s [[0;32m OK [0m] Found device [0;1;39mdev-disk-by\x2dlabel-���I.device[0m - /dev/disk/by-label/UEFI. 507s Starting [0;1;39msystemd-fsck@dev-disk-by\���[0mCheck on /dev/disk/by-label/UEFI... 507s [[0;32m OK [0m] Finished [0;1;39msystemd-fsck@dev-disk-by\���[0mm Check on /dev/disk/by-label/BOOT. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-fsck@dev-disk-by\���[0mm Check on /dev/disk/by-label/UEFI. 507s [[0;32m OK [0m] Started [0;1;39mcloud-init-main.service[0m - Cloud-init: Single Process. 507s Starting [0;1;39mcloud-init-local.service[0m ���-init: Local Stage (pre-network)... 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-rfkill.socket[0m���ll Switch Status /dev/rfkill Watch. 507s [ 4.631092] cloud-init[426]: Cloud-init v. 25.1~2g4ee65391-0ubuntu1 running 'init-local' at Mon, 20 Jan 2025 15:52:12 +0000. Up 4.59 seconds. 507s Mounting [0;1;39mboot.mount[0m - /boot... 507s [[0;32m OK [0m] Mounted [0;1;39mboot.mount[0m - /boot. 507s Mounting [0;1;39mboot-efi.mount[0m - /boot/efi... 507s [[0;32m OK [0m] Mounted [0;1;39mboot-efi.mount[0m - /boot/efi. 507s [[0;32m OK [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-sysext.socket[0m��� System Extension Image Management. 507s Starting [0;1;39mapparmor.service[0m - Load AppArmor profiles... 507s Starting [0;1;39mconsole-setup.service[0m - Set console font and keymap... 507s Starting [0;1;39mplymouth-read-write.servi���[0mymouth To Write Out Runtime Data... 507s Starting [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats... 507s Starting [0;1;39msystemd-tmpfiles-setup.se���[0mate System Files and Directories... 507s Starting [0;1;39mufw.service[0m - Uncomplicated firewall... 507s [[0;32m OK [0m] Finished [0;1;39mconsole-setup.service[0m - Set console font and keymap. 507s [[0;32m OK [0m] Finished [0;1;39mplymouth-read-write.servi���[0mPlymouth To Write Out Runtime Data. 507s [[0;32m OK [0m] Finished [0;1;39mufw.service[0m - Uncomplicated firewall. 507s Mounting [0;1;39mproc-sys-fs-binfmt_misc.m���[0mcutable File Formats File System... 507s [[0;32m OK [0m] Mounted [0;1;39mproc-sys-fs-binfmt_misc.mo���[0mxecutable File Formats File System. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-setup.se���[0mreate System Files and Directories. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-binfmt.service[0m - Set Up Additional Binary Formats. 507s Starting [0;1;39msystemd-resolved.service[0m - Network Name Resolution... 507s Starting [0;1;39msystemd-timesyncd.service[0m - Network Time Synchronization... 507s [[0;32m OK [0m] Started [0;1;39msystemd-timesyncd.service[0m - Network Time Synchronization. 507s [[0;32m OK [0m] Reached target [0;1;39mtime-set.target[0m - System Time Set. 507s [[0;32m OK [0m] Started [0;1;39msystemd-resolved.service[0m - Network Name Resolution. 507s [[0;32m OK [0m] Reached target [0;1;39mnss-lookup.target[0m - Host and Network Name Lookups. 507s [[0;32m OK [0m] Finished [0;1;39mapparmor.service[0m - Load AppArmor profiles. 507s [ 19.008990] sh[418]: Completed socket interaction for boot stage local 507s [[0;32m OK [0m] Finished [0;1;39mcloud-init-local.service[0m ���ud-init: Local Stage (pre-network). 507s [[0;32m OK [0m] Reached target [0;1;39mnetwork-pre.target[0m - Preparation for Network. 507s Starting [0;1;39mcloud-init-network.service[0m - Cloud-init: Network Stage... 507s Starting [0;1;39msystemd-networkd.service[0m - Network Configuration... 507s [ 19.050380] cloud-init[426]: Cloud-init v. 25.1~2g4ee65391-0ubuntu1 running 'init' at Mon, 20 Jan 2025 15:52:26 +0000. Up 19.03 seconds. 507s [ 19.057063] cloud-init[426]: ci-info: +++++++++++++++++++++++++++Net device info++++++++++++++++++++++++++++ 507s [ 19.058317] cloud-init[426]: ci-info: +--------+-------+-----------+-----------+-------+-------------------+ 507s [ 19.059541] cloud-init[426]: ci-info: | Device | Up | Address | Mask | Scope | Hw-Address | 507s [ 19.060792] cloud-init[426]: ci-info: +--------+-------+-----------+-----------+-------+-------------------+ 507s [ 19.062089] cloud-init[426]: ci-info: | ens3 | False | . | . | . | fa:16:3e:87:72:23 | 507s [ 19.063293] cloud-init[426]: ci-info: | lo | True | 127.0.0.1 | 255.0.0.0 | host | . | 507s [ 19.064617] cloud-init[426]: ci-info: | lo | True | ::1/128 | . | host | . | 507s [ 19.066085] cloud-init[426]: ci-info: +--------+-------+-----------+-----------+-------+-------------------+ 507s [ 19.067287] cloud-init[426]: ci-info: +++++++++++++++++++Route IPv6 info+++++++++++++++++++ 507s [ 19.068374] cloud-init[426]: ci-info: +-------+-------------+---------+-----------+-------+ 507s [ 19.069910] cloud-init[426]: ci-info: | Route | Destination | Gateway | Interface | Flags | 507s [ 19.071020] cloud-init[426]: ci-info: +-------+-------------+---------+-----------+-------+ 507s [ 19.072649] cloud-init[426]: ci-info: +-------+-------------+---------+-----------+-------+ 507s [[0;32m OK [0m] Started [0;1;39msystemd-networkd.service[0m - Network Configuration. 507s [[0;32m OK [0m] Reached target [0;1;39mnetwork.target[0m - Network. 507s Starting [0;1;39msystemd-networkd-persiste���[0mtent Storage in systemd-networkd... 507s Starting [0;1;39msystemd-networkd-wait-onl���[0mait for Network to be Configured... 507s [[0;32m OK [0m] Finished [0;1;39msystemd-networkd-persiste���[0mistent Storage in systemd-networkd. 507s [ 19.131081] cloud-init[426]: 2025-01-20 15:52:26,714 - loggers.py[DEPRECATED]: Deprecated cloud-config provided: apt_update: Deprecated in version 22.2. Use **package_update** instead., apt_upgrade: Deprecated in version 22.2. Use **package_upgrade** instead. 507s [ 19.133768] cloud-init[426]: 2025-01-20 15:52:26,714 - schema.py[WARNING]: cloud-config failed schema validation! You may run 'sudo cloud-init schema --system' to check the details. 507s [ 19.712505] sh[677]: Completed socket interaction for boot stage network 507s [[0;32m OK [0m] Finished [0;1;39mcloud-init-network.service[0m - Cloud-init: Network Stage. 507s [[0;32m OK [0m] Reached target [0;1;39mcloud-config.target[0m - Cloud-config availability. 507s [[0;32m OK [0m] Reached target [0;1;39msysinit.target[0m - System Initialization. 507s [[0;32m OK [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities. 507s [[0;32m OK [0m] Started [0;1;39mapt-daily-upgrade.timer[0m - ���y apt upgrade and clean activities. 507s [[0;32m OK [0m] Started [0;1;39mdpkg-db-backup.timer[0m - Daily dpkg database backup timer. 507s [[0;32m OK [0m] Started [0;1;39me2scrub_all.timer[0m - Period���Metadata Check for All Filesystems. 507s [[0;32m OK [0m] Started [0;1;39mfstrim.timer[0m - Discard unused filesystem blocks once a week. 507s [[0;32m OK [0m] Started [0;1;39mfwupd-refresh.timer[0m - Refresh fwupd metadata regularly. 507s [[0;32m OK [0m] Started [0;1;39mlogrotate.timer[0m - Daily rotation of log files. 507s [[0;32m OK [0m] Started [0;1;39mman-db.timer[0m - Daily man-db regeneration. 507s [[0;32m OK [0m] Started [0;1;39mmotd-news.timer[0m - Message of the Day. 507s [[0;32m OK [0m] Started [0;1;39msysstat-collect.timer[0m - Ru���y accounting tool every 10 minutes. 507s [[0;32m OK [0m] Started [0;1;39msysstat-rotate.timer[0m - Rot���tem activity data file at midnight. 507s [[0;32m OK [0m] Started [0;1;39msysstat-summary.timer[0m - Ge��� of yesterday's process accounting. 507s [[0;32m OK [0m] Started [0;1;39msystemd-tmpfiles-clean.tim���[0my Cleanup of Temporary Directories. 507s [[0;32m OK [0m] Reached target [0;1;39mboot-complete.target[0m - Boot Completion Check. 507s [[0;32m OK [0m] Reached target [0;1;39mpaths.target[0m - Path Units. 507s [[0;32m OK [0m] Reached target [0;1;39mtimers.target[0m - Timer Units. 507s [[0;32m OK [0m] Listening on [0;1;39mcloud-init-hotplugd.s���t[0m - cloud-init hotplug hook socket. 507s [[0;32m OK [0m] Listening on [0;1;39mdbus.socket[0m - D-Bus System Message Bus Socket. 507s Starting [0;1;39mlxd-installer.socket[0m - He���er to install lxd snap on demand... 507s [[0;32m OK [0m] Listening on [0;1;39mssh.socket[0m - OpenBSD Secure Shell server socket. 507s [[0;32m OK [0m] Listening on [0;1;39msshd-unix-local.socke���[0mtemd-ssh-generator, AF_UNIX Local). 507s [[0;32m OK [0m] Listening on [0;1;39msshd-vsock.socket[0m - O��� (systemd-ssh-generator, AF_VSOCK). 507s [[0;32m OK [0m] Reached target [0;1;39mssh-access.target[0m - SSH Access Available. 507s [[0;32m OK [0m] Listening on [0;1;39msystemd-hostnamed.socket[0m - Hostname Service Socket. 507s [[0;32m OK [0m] Listening on [0;1;39muuidd.socket[0m - UUID daemon activation socket. 507s [[0;32m OK [0m] Listening on [0;1;39mlxd-installer.socket[0m ���lper to install lxd snap on demand. 507s [[0;32m OK [0m] Reached target [0;1;39msockets.target[0m - Socket Units. 507s [[0;32m OK [0m] Reached target [0;1;39mbasic.target[0m - Basic System. 507s Starting [0;1;39mapport.service[0m - automatic crash report generation... 507s [[0;32m OK [0m] Started [0;1;39mautopkgtest@ttyS1.service[0m - autopkgtest root shell on ttyS1. 507s [[0;32m OK [0m] Started [0;1;39mcron.service[0m - Regular background program processing daemon. 507s Starting [0;1;39mdbus.service[0m - D-Bus System Message Bus... 507s [[0;32m OK [0m] Started [0;1;39mdmesg.service[0m - Save initial kernel messages after boot. 507s Starting [0;1;39me2scrub_reap.service[0m - Re���ne ext4 Metadata Check Snapshots... 507s Starting [0;1;39mgrub-common.service[0m - Record successful boot for GRUB... 507s Starting [0;1;39mrng-tools-debian.service[0m ���gatherer daemon (Debian variant)... 507s Starting [0;1;39mrsyslog.service[0m - System Logging Service... 507s Starting [0;1;39msysstat.service[0m - Resets System Activity Logs... 507s Starting [0;1;39msystemd-logind.service[0m - User Login Management... 507s Starting [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions... 507s Starting [0;1;39mudisks2.service[0m - Disk Manager... 507s [[0;32m OK [0m] Started [0;1;39mdbus.service[0m - D-Bus System Message Bus. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-user-sessions.service[0m - Permit User Sessions. 507s [[0;32m OK [0m] Finished [0;1;39me2scrub_reap.service[0m - Re���line ext4 Metadata Check Snapshots. 507s [[0;32m OK [0m] Finished [0;1;39msysstat.service[0m - Resets System Activity Logs. 507s [[0;32m OK [0m] Finished [0;1;39mgrub-common.service[0m - Record successful boot for GRUB. 507s Starting [0;1;39mgrub-initrd-fallback.service[0m - GRUB failed boot detection... 507s [[0;32m OK [0m] Started [0;1;39msystemd-logind.service[0m - User Login Management. 507s [[0;32m OK [0m] Finished [0;1;39mgrub-initrd-fallback.service[0m - GRUB failed boot detection. 507s [[0;32m OK [0m] Started [0;1;39mudisks2.service[0m - Disk Manager. 507s [[0;32m OK [0m] Started [0;1;39mrsyslog.service[0m - System Logging Service. 507s [[0;32m OK [0m] Finished [0;1;39mapport.service[0m - automatic crash report generation. 507s [[0;32m OK [0m] Finished [0;1;39msystemd-networkd-wait-onl���[0m Wait for Network to be Configured. 507s [[0;32m OK [0m] Reached target [0;1;39mnetwork-online.target[0m - Network is Online. 507s Starting [0;1;39mcloud-config.service[0m - Cloud-init: Config Stage... 507s Starting [0;1;39mrc-local.service[0m - /etc/rc.local Compatibility... 507s Starting [0;1;39mssh.service[0m - OpenBSD Secure Shell server... 507s [[0;32m OK [0m] Started [0;1;39mrc-local.service[0m - /etc/rc.local Compatibility. 507s Starting [0;1;39mplymouth-quit-wait.servic���[0md until boot process finishes up... 507s Starting [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen... 507s [ 20.595400] cloud-init[426]: Cloud-init v. 25.1~2g4ee65391-0ubuntu1 running 'modules:config' at Mon, 20 Jan 2025 15:52:28 +0000. Up 20.56 seconds. 507s [[0;32m OK [0m] Started [0;1;39mssh.service[0m - OpenBSD Secure Shell server. 507s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit-wait.servic���[0mold until boot process finishes up. 507s [[0;32m OK [0m] Started [0;1;39mserial-getty@ttyS0.service[0m - Serial Getty on ttyS0. 507s Starting [0;1;39msetvtrgb.service[0m - Set console scheme... 507s [[0;32m OK [0m] Finished [0;1;39mplymouth-quit.service[0m - Terminate Plymouth Boot Screen. 507s [ 20.619843] cloud-init[426]: 2025-01-20 15:52:28,203 - lifecycle.py[DEPRECATED]: The following config key(s): ['apt_mirror', 'apt_preserve_sources_list'] is deprecated in 22.1 and scheduled to be removed in 27.1. 507s [ 20.622705] cloud-init[426]: 2025-01-20 15:52:28,205 - lifecycle.py[DEPRECATED]: Support for combined old and new apt module keys is deprecated in 22.1 and scheduled to be removed in 27.1. 507s [[0;32m OK [0m] Finished [0;1;39msetvtrgb.service[0m - Set console scheme. 507s [[0;32m OK [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty. 507s [[0;32m OK [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1. 507s [[0;32m OK [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts. 507s [[0;32m OK [0m] Reached target [0;1;39mmulti-user.target[0m - Multi-User System. 507s [[0;32m OK [0m] Reached target [0;1;39mgraphical.target[0m - Graphical Interface. 507s [ 20.656126] sh[811]: Completed socket interaction for boot stage config 507s [[0;32m OK [0m] Finished [0;1;39mcloud-config.service[0m - Cloud-init: Config Stage. 507s Starting [0;1;39mcloud-final.service[0m - Cloud-init: Final Stage... 507s [ 20.709107] cloud-init[426]: Cloud-init v. 25.1~2g4ee65391-0ubuntu1 running 'modules:final' at Mon, 20 Jan 2025 15:52:28 +0000. Up 20.68 seconds. 507s Starting [0;1;39mapt-news.service[0m - Update APT News... 507s Starting [0;1;39mesm-cache.service[0m - Update the local ESM caches... 507s [[0;32m OK [0m] Finished [0;1;39mesm-cache.service[0m - Update the local ESM caches. 507s [[0;32m OK [0m] Finished [0;1;39mapt-news.service[0m - Update APT News. 507s [ 20.962831] cloud-init[426]: Get:1 http://ftpmaster.internal/ubuntu plucky InRelease [213 kB] 507s [ 21.295148] cloud-init[426]: Hit:2 http://ftpmaster.internal/ubuntu plucky-updates InRelease 507s [ 21.361324] cloud-init[426]: Hit:3 http://ftpmaster.internal/ubuntu plucky-security InRelease 507s [ 21.433127] cloud-init[426]: Get:4 http://ftpmaster.internal/ubuntu plucky/main Sources [1387 kB] 507s [ 21.644648] cloud-init[426]: Get:5 http://ftpmaster.internal/ubuntu plucky/universe Sources [20.9 MB] 507s [ 22.851447] cloud-init[426]: Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 Packages [1451 kB] 507s [ 22.937651] cloud-init[426]: Get:7 http://ftpmaster.internal/ubuntu plucky/main i386 Packages [1078 kB] 507s [ 23.002267] cloud-init[426]: Get:8 http://ftpmaster.internal/ubuntu plucky/universe i386 Packages [8701 kB] 507s [ 23.495934] cloud-init[426]: Get:9 http://ftpmaster.internal/ubuntu plucky/universe amd64 Packages [15.5 MB] 507s [[0;32m OK [0m] Created slice [0;1;39muser-1000.slice[0m - User Slice of UID 1000. 507s Starting [0;1;39muser-runtime-dir@1000.ser���[0mRuntime Directory /run/user/1000... 507s [[0;32m OK [0m] Finished [0;1;39muser-runtime-dir@1000.ser���[0mr Runtime Directory /run/user/1000. 507s Starting [0;1;39muser@1000.service[0m - User Manager for UID 1000... 525s [[0;32m OK [0m] Started [0;1;39muser@1000.service[0m - User Manager for UID 1000. 525s [[0;32m OK [0m] Started [0;1;39msession-1.scope[0m - Session 1 of User ubuntu. 525s [[0;32m OK [0m] Started [0;1;39msession-3.scope[0m - Session 3 of User ubuntu. 525s [!p]104[?7h[6n[32766;32766H[6n[!p]104[?7h[6n[32766;32766H[6n[ 25.819051] cloud-init[426]: Fetched 49.2 MB in 5s (9939 kB/s) 525s [ 26.451928] cloud-init[426]: Reading package lists... 525s ci-info: ++++++++++++++++++++++++++++++++++++++++++++++++++++++Authorized keys from /home/ubuntu/.ssh/authorized_keys for user ubuntu+++++++++++++++++++++++++++++++++++++++++++++++++++++++ 525s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 525s ci-info: | Keytype | Fingerprint (sha256) | Options | Comment | 525s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 525s ci-info: | ssh-rsa | e8:35:e0:40:84:e9:20:46:36:c3:0d:24:8f:f7:02:62:a2:c6:f7:f5:5c:ed:3f:df:2b:54:8b:69:97:eb:98:5e | - | ubuntu@juju-7f2275-prod-proposed-migration-environment-15 | 525s 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 | 525s ci-info: +---------+-------------------------------------------------------------------------------------------------+---------+-----------------------------------------------------------+ 525s 525s Ubuntu Plucky Puffin (development branch) autopkgtest ttyS0 525s 525s autopkgtest login: <14>Jan 20 15:52:34 cloud-init: ############################################################# 525s <14>Jan 20 15:52:34 cloud-init: -----BEGIN SSH HOST KEY FINGERPRINTS----- 525s <14>Jan 20 15:52:34 cloud-init: 256 SHA256:HUtQ9NMMQ2SiPfkcBi/7T7fpufYLsqptm3k5i82c90o root@autopkgtest (ECDSA) 525s <14>Jan 20 15:52:34 cloud-init: 256 SHA256:de22mKgRxObNg06CDKYT98mKPOUEvsnNYVnSfVVvOXg root@autopkgtest (ED25519) 525s <14>Jan 20 15:52:34 cloud-init: 3072 SHA256:vluhNYI0FCEA3ZOZ+AyrncyHOGZgOes+hDNZ1mF2RHg root@autopkgtest (RSA) 525s <14>Jan 20 15:52:34 cloud-init: -----END SSH HOST KEY FINGERPRINTS----- 525s <14>Jan 20 15:52:34 cloud-init: ############################################################# 525s -----BEGIN SSH HOST KEY KEYS----- 525s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBHrx/H8YQVSf7xrPAoewqGmjipGTQ0PnYNn5+UZVVN4h1ZbFDKEs5P5lJrFtbJePKXDFyXtUHW8gW6CH1mK+GHY= root@autopkgtest 525s ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKD4yvd1sxG9AwmCcGun8aRzwK3jiqFHkOs1pGQBmaLy root@autopkgtest 525s ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC76LLSUtJI2/H6ke6B4i3/jIIi7wGLf4W6730nKyijXBkP/NaENFa3mJuq6ok5wSI/HMB+yKZMvtKCnQsF+lgDBmdscSf3kJN8V9AN+EpWOz24B6UUbxYjDmA8w/QKo36eUM9hmMUA8zzn2Tb2p3cOf0Hw1aYhNYQBvDSCB7uVzdncAs45wJBhXv3vzZMkrnAailX6gVMb9AgQLCnI9jk0e4N3/W6xrlMuveTqVp+Ue11nLeQ1/7RC36ose0yXK6SBkEl44hoAYLky0eUi4CfBHV6sYCtNl+Nk52QQrkbVS3JnOyLHfh25E+SC77buBnuHHW7451orcxeou9YepXKBkFePrB5JMyQDUZsOOdCpMwW88ZepAFwlNxIzriqOAepTEQ3Q/KgXlJLQcnELA6LU0XukWVGwF03nfB83TB8Z06BX39RC+L4tSCR6uHbshMzmjHsB4yb7dl2SbfX6QTmDpIRZtkDlx3oy+yNlY9IlIoZVLiSRcXT1LmVBXkEvwDM= root@autopkgtest 525s -----END SSH HOST KEY KEYS----- 525s [ 26.547107] cloud-init[426]: Cloud-init v. 25.1~2g4ee65391-0ubuntu1 finished at Mon, 20 Jan 2025 15:52:34 +0000. Datasource DataSourceOpenStackLocal [net,ver=2]. Up 26.54 seconds 525s [ 26.550973] cloud-init[426]: Generating public/private rsa key pair. 525s [ 26.551095] cloud-init[426]: Your identification has been saved in /etc/ssh/ssh_host_rsa_key 525s [ 26.551387] cloud-init[426]: Your public key has been saved in /etc/ssh/ssh_host_rsa_key.pub 525s [ 26.551706] cloud-init[426]: The key fingerprint is: 525s [ 26.552031] cloud-init[426]: SHA256:vluhNYI0FCEA3ZOZ+AyrncyHOGZgOes+hDNZ1mF2RHg root@autopkgtest 525s [ 26.552326] cloud-init[426]: The key's randomart image is: 525s [ 26.552629] cloud-init[426]: +---[RSA 3072]----+ 525s [ 26.552956] clnova [W] Timed out waiting for 0dc76d64-753f-45b5-b71f-da9601581d1a to get deleted. 525s Unexpected error: 525s Traceback (most recent call last): 525s File "/home/ubuntu/autopkgtest/virt/autopkgtest-virt-ssh", line 481, in hook_wait_reboot 525s wait_port_down(sshconfig['hostname'], port, 300) 525s File "/home/ubuntu/autopkgtest/virt/autopkgtest-virt-ssh", line 456, in wait_port_down 525s time.sleep(0.1) 525s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 75, in alarm_handler 525s raise Timeout(to) 525s VirtSubproc.Timeout: 300 525s 525s During handling of the above exception, another exception occurred: 525s 525s Traceback (most recent call last): 525s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 833, in mainloop 525s command() 525s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 762, in command 525s r = f(c, ce) 525s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 457, in cmd_reboot 525s caller.hook_wait_reboot(**wait_reboot_args) 525s File "/home/ubuntu/autopkgtest/virt/autopkgtest-virt-ssh", line 483, in hook_wait_reboot 525s execute_setup_script('debug-failure', fail_ok=True) 525s File "/home/ubuntu/autopkgtest/virt/autopkgtest-virt-ssh", line 209, in execute_setup_script 525s sys.stderr.write(err) 525s BlockingIOError: [Errno 11] write could not complete without blocking 525s autopkgtest [15:58:27]: ERROR: testbed failure: unexpected eof from the testbed