0s autopkgtest [05:07:12]: starting date and time: 2026-02-05 05:07:12+0000 0s autopkgtest [05:07:12]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [05:07:12]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.zxq83ahi/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc,src:chiark-tcl --apt-upgrade poa --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 chiark-tcl/1.3.7build1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-arm64-22.secgroup --name adt-resolute-arm64-poa-20260205-050711-juju-7f2275-prod-proposed-migration-environment-15-fc4f9095-9bac-4d0d-b1da-7eef60cce74c --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-arm64-poa-20260205-050711-juju-7f2275-prod-proposed-migration-environment-15-fc4f9095-9bac-4d0d-b1da-7eef60cce74c from image adt/ubuntu-resolute-arm64-server-20260204.img (UUID f58d981d-b271-4157-b9b1-fd704695563c)... 76s autopkgtest [05:08:28]: testbed dpkg architecture: arm64 77s autopkgtest [05:08:29]: testbed apt version: 3.1.14 80s autopkgtest [05:08:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 80s autopkgtest [05:08:32]: testbed release detected to be: None 84s autopkgtest [05:08:36]: updating testbed package index (apt update) 84s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 84s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 84s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 84s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 85s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 85s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [227 kB] 85s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB] 85s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 85s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [265 kB] 85s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [7328 B] 85s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [52.9 kB] 85s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [328 B] 85s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [1481 kB] 85s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [40.2 kB] 85s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [26.0 kB] 85s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [776 B] 89s Fetched 3984 kB in 1s (2896 kB/s) 90s Reading package lists... 92s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 92s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 92s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 92s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 93s Reading package lists... 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 93s Calculating upgrade... 94s The following packages will be upgraded: 94s libc-bin libc-gconv-modules-extra libc6 locales pollinate python3-linkify-it 94s python3-referencing sed 94s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 94s Need to get 8110 kB of archives. 94s After this operation, 0 B of additional disk space will be used. 94s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 sed arm64 4.9-2build3 [193 kB] 94s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-gconv-modules-extra arm64 2.42-2ubuntu5 [1413 kB] 98s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc6 arm64 2.42-2ubuntu5 [1594 kB] 98s dpkg-preconfigure: unable to re-open stdin: No such file or directory 98s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-bin arm64 2.42-2ubuntu5 [599 kB] 98s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 locales all 2.42-2ubuntu5 [4255 kB] 98s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-linkify-it all 2.0.3-1ubuntu3 [19.4 kB] 98s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 pollinate all 4.33-4ubuntu5 [14.0 kB] 98s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 98s Fetched 8110 kB in 1s (12.0 MB/s) 98s (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 ... 89360 files and directories currently installed.) 98s Preparing to unpack .../sed_4.9-2build3_arm64.deb ... 98s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 98s Setting up sed (4.9-2build3) ... 98s (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 ... 89360 files and directories currently installed.) 98s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_arm64.deb ... 98s Unpacking libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 98s Setting up libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) ... 98s (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 ... 89360 files and directories currently installed.) 98s Preparing to unpack .../libc6_2.42-2ubuntu5_arm64.deb ... 98s Unpacking libc6:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 98s Setting up libc6:arm64 (2.42-2ubuntu5) ... 98s (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 ... 89360 files and directories currently installed.) 98s Preparing to unpack .../libc-bin_2.42-2ubuntu5_arm64.deb ... 98s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 98s Setting up libc-bin (2.42-2ubuntu5) ... 98s (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 ... 89360 files and directories currently installed.) 98s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 98s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 98s Preparing to unpack .../python3-linkify-it_2.0.3-1ubuntu3_all.deb ... 98s Unpacking python3-linkify-it (2.0.3-1ubuntu3) over (2.0.3-1ubuntu2) ... 98s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 98s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 98s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 98s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 98s Setting up locales (2.42-2ubuntu5) ... 98s Generating locales (this might take a while)... 100s en_US.UTF-8... done 100s Generation complete. 100s Setting up pollinate (4.33-4ubuntu5) ... 110s Setting up python3-linkify-it (2.0.3-1ubuntu3) ... 111s Setting up python3-referencing (0.36.2-1ubuntu2) ... 111s Processing triggers for man-db (2.13.1-1) ... 112s Processing triggers for install-info (7.2-5) ... 112s Processing triggers for systemd (259-1ubuntu3) ... 112s autopkgtest [05:09:04]: upgrading testbed (apt dist-upgrade and autopurge) 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s Calculating upgrade... 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Solving dependencies... 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s autopkgtest [05:09:07]: rebooting testbed after setup commands that affected boot 161s autopkgtest [05:09:53]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 165s autopkgtest [05:09:57]: @@@@@@@@@@@@@@@@@@@@ apt-source poa 167s Get:1 http://ftpmaster.internal/ubuntu resolute/universe poa 2.0+20060928-10 (dsc) [2151 B] 167s Get:2 http://ftpmaster.internal/ubuntu resolute/universe poa 2.0+20060928-10 (tar) [62.6 kB] 167s Get:3 http://ftpmaster.internal/ubuntu resolute/universe poa 2.0+20060928-10 (diff) [12.4 kB] 168s gpgv: Signature made Sat Mar 1 15:38:26 2025 UTC 168s gpgv: using RSA key 8F91B227C7D6F2B1948C8236793CF67E8F0D11DA 168s gpgv: issuer "emollier@debian.org" 168s gpgv: Can't check signature: No public key 168s dpkg-source: warning: cannot verify inline signature for ./poa_2.0+20060928-10.dsc: no acceptable signature found 168s autopkgtest [05:10:00]: testing package poa version 2.0+20060928-10 170s autopkgtest [05:10:02]: build not needed 173s autopkgtest [05:10:05]: test run-unit-test: preparing testbed 175s Reading package lists... 175s Building dependency tree... 175s Reading state information... 175s Solving dependencies... 176s The following NEW packages will be installed: 176s poa 176s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 176s Need to get 47.0 kB of archives. 176s After this operation, 121 kB of additional disk space will be used. 176s Get:1 http://ftpmaster.internal/ubuntu resolute/universe arm64 poa arm64 2.0+20060928-10 [47.0 kB] 176s Fetched 47.0 kB in 0s (150 kB/s) 177s Selecting previously unselected package poa. 177s (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 ... 89360 files and directories currently installed.) 177s Preparing to unpack .../poa_2.0+20060928-10_arm64.deb ... 177s Unpacking poa (2.0+20060928-10) ... 177s Setting up poa (2.0+20060928-10) ... 177s Processing triggers for man-db (2.13.1-1) ... 181s autopkgtest [05:10:13]: test run-unit-test: [----------------------- 182s ...Read 2 sequences from sequence file test1.fa... 182s Performing iterative alignment... 182s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00 182s aligned (13 nodes, 12 edges) to (12 nodes, 11 edges): best local score = 26 @ (12 10) 182s ...Wrote 2 sequences to CLUSTAL file clustal.aln... 182s ...Read 2 sequences from MSA file clustal.aln... 182s ...Wrote 2 sequences to FASTA-PIR file pir.aln... 182s ...Read 5 sequences from sequence file test2.fa... 182s Performing iterative alignment... 182s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00 182s aligned (44 nodes, 43 edges) to (35 nodes, 34 edges): best local score = 140 @ (43 34) 182s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score -1.00 182s aligned (50 nodes, 51 edges) to (43 nodes, 42 edges): best local score = 112 @ (49 42) 182s Fusing cluster 3 (seq4, nseq=1) --> 0 (seq1, nseq=3)... score -1.00 182s aligned (71 nodes, 79 edges) to (44 nodes, 43 edges): best local score = 94 @ (68 42) 182s Fusing cluster 4 (seq5, nseq=1) --> 0 (seq1, nseq=4)... score -1.00 182s aligned (93 nodes, 108 edges) to (42 nodes, 41 edges): best local score = 69 @ (79 39) 182s ...Wrote 5 sequences to FASTA-PIR file pir.aln... 182s ...Read 5 sequences from sequence file test3.fa... 182s Performing iterative alignment... 182s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00 182s aligned (16 nodes, 15 edges) to (16 nodes, 15 edges): best local score = 79 @ (15 15) 182s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score -1.00 182s aligned (19 nodes, 20 edges) to (16 nodes, 15 edges): best local score = 72 @ (18 15) 182s Fusing cluster 3 (seq4, nseq=1) --> 0 (seq1, nseq=3)... score -1.00 182s aligned (23 nodes, 27 edges) to (16 nodes, 15 edges): best local score = 73 @ (22 15) 182s Fusing cluster 4 (seq5, nseq=1) --> 0 (seq1, nseq=4)... score -1.00 182s aligned (27 nodes, 35 edges) to (19 nodes, 18 edges): best local score = 34 @ (26 18) 182s ...Wrote 5 sequences to PO file po.aln... 182s ...Read 24 sequences from sequence file test4.fa... 182s Performing iterative alignment... 182s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00 182s aligned (20 nodes, 19 edges) to (46 nodes, 45 edges): best global score = 62 @ (19 45) 182s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score -1.00 182s aligned (48 nodes, 49 edges) to (46 nodes, 45 edges): best global score = 268 @ (47 45) 182s Fusing cluster 3 (seq4, nseq=1) --> 0 (seq1, nseq=3)... score -1.00 182s aligned (49 nodes, 51 edges) to (49 nodes, 48 edges): best global score = 240 @ (48 48) 182s Fusing cluster 4 (seq5, nseq=1) --> 0 (seq1, nseq=4)... score -1.00 182s aligned (52 nodes, 57 edges) to (46 nodes, 45 edges): best global score = 269 @ (51 45) 182s Fusing cluster 5 (seq6, nseq=1) --> 0 (seq1, nseq=5)... score -1.00 182s aligned (53 nodes, 59 edges) to (19 nodes, 18 edges): best global score = 78 @ (52 18) 182s Fusing cluster 6 (seq7, nseq=1) --> 0 (seq1, nseq=6)... score -1.00 182s aligned (53 nodes, 59 edges) to (37 nodes, 36 edges): best global score = 195 @ (52 36) 182s Fusing cluster 7 (seq8, nseq=1) --> 0 (seq1, nseq=7)... score -1.00 182s aligned (53 nodes, 59 edges) to (46 nodes, 45 edges): best global score = 276 @ (52 45) 182s Fusing cluster 8 (seq9, nseq=1) --> 0 (seq1, nseq=8)... score -1.00 182s aligned (53 nodes, 59 edges) to (46 nodes, 45 edges): best global score = 240 @ (52 45) 182s Fusing cluster 9 (seq10, nseq=1) --> 0 (seq1, nseq=9)... score -1.00 182s aligned (56 nodes, 65 edges) to (19 nodes, 18 edges): best global score = 98 @ (55 18) 182s Fusing cluster 10 (seq11, nseq=1) --> 0 (seq1, nseq=10)... score -1.00 182s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 276 @ (55 45) 182s Fusing cluster 11 (seq12, nseq=1) --> 0 (seq1, nseq=11)... score -1.00 182s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 276 @ (55 45) 182s Fusing cluster 12 (seq13, nseq=1) --> 0 (seq1, nseq=12)... score -1.00 182s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 276 @ (55 45) 182s Fusing cluster 13 (seq14, nseq=1) --> 0 (seq1, nseq=13)... score -1.00 182s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 263 @ (55 45) 182s Fusing cluster 14 (seq15, nseq=1) --> 0 (seq1, nseq=14)... score -1.00 182s aligned (58 nodes, 69 edges) to (46 nodes, 45 edges): best global score = 276 @ (57 45) 182s Fusing cluster 15 (seq16, nseq=1) --> 0 (seq1, nseq=15)... score -1.00 182s aligned (58 nodes, 69 edges) to (46 nodes, 45 edges): best global score = 270 @ (57 45) 182s Fusing cluster 16 (seq17, nseq=1) --> 0 (seq1, nseq=16)... score -1.00 182s aligned (59 nodes, 71 edges) to (46 nodes, 45 edges): best global score = 270 @ (58 45) 182s Fusing cluster 17 (seq18, nseq=1) --> 0 (seq1, nseq=17)... score -1.00 182s aligned (60 nodes, 73 edges) to (42 nodes, 41 edges): best global score = 201 @ (59 41) 182s Fusing cluster 18 (seq19, nseq=1) --> 0 (seq1, nseq=18)... score -1.00 182s aligned (61 nodes, 79 edges) to (4 nodes, 3 edges): best global score = -13 @ (60 3) 182s Fusing cluster 19 (seq20, nseq=1) --> 0 (seq1, nseq=19)... score -1.00 182s aligned (61 nodes, 80 edges) to (5 nodes, 4 edges): best global score = 23 @ (60 4) 182s Fusing cluster 20 (seq21, nseq=1) --> 0 (seq1, nseq=20)... score -1.00 182s aligned (62 nodes, 81 edges) to (24 nodes, 23 edges): best global score = 85 @ (61 23) 182s Fusing cluster 21 (seq22, nseq=1) --> 0 (seq1, nseq=21)... score -1.00 182s aligned (64 nodes, 84 edges) to (46 nodes, 45 edges): best global score = 276 @ (63 45) 182s Fusing cluster 22 (seq23, nseq=1) --> 0 (seq1, nseq=22)... score -1.00 182s aligned (64 nodes, 84 edges) to (44 nodes, 43 edges): best global score = 197 @ (63 43) 182s Fusing cluster 23 (seq24, nseq=1) --> 0 (seq1, nseq=23)... score -1.00 182s aligned (69 nodes, 95 edges) to (45 nodes, 44 edges): best global score = 200 @ (68 44) 182s ...Wrote 24 sequences to CLUSTAL file clustal.aln... 182s ...Read 3 sequences from sequence file test5.fa... 182s aligned (11 nodes, 10 edges) to (12 nodes, 11 edges): best local score = 36 @ (7 9) 182s Saving alignment score 1 (seq2), 0 (seq1) : 36.00 182s aligned (12 nodes, 11 edges) to (12 nodes, 11 edges): best local score = 22 @ (6 5) 182s Saving alignment score 2 (seq3), 0 (seq1) : 22.00 182s aligned (12 nodes, 11 edges) to (11 nodes, 10 edges): best local score = 14 @ (6 3) 182s Saving alignment score 2 (seq3), 1 (seq2) : 14.00 182s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score 36.00 182s aligned (12 nodes, 11 edges) to (11 nodes, 10 edges): best local score = 36 @ (9 7) 182s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score 22.00 182s aligned (17 nodes, 16 edges) to (12 nodes, 11 edges): best local score = 22 @ (7 6) 182s ...Wrote 3 sequences to CLUSTAL file clustal.aln... 182s autopkgtest [05:10:14]: test run-unit-test: -----------------------] 187s autopkgtest [05:10:19]: test run-unit-test: - - - - - - - - - - results - - - - - - - - - - 187s run-unit-test PASS 191s autopkgtest [05:10:23]: @@@@@@@@@@@@@@@@@@@@ summary 191s run-unit-test PASS