0s autopkgtest [15:19:40]: starting date and time: 2025-03-15 15:19:40+0000
  0s autopkgtest [15:19:40]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production'
  0s autopkgtest [15:19:40]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.8qdxz3uh/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --apt-pocket=proposed=src:glibc --apt-upgrade poa --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- lxd -r lxd-armhf-10.145.243.242 lxd-armhf-10.145.243.242:autopkgtest/ubuntu/plucky/armhf
 24s autopkgtest [15:20:04]: testbed dpkg architecture: armhf
 26s autopkgtest [15:20:06]: testbed apt version: 2.9.33
 30s autopkgtest [15:20:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 32s autopkgtest [15:20:12]: testbed release detected to be: None
 41s autopkgtest [15:20:21]: updating testbed package index (apt update)
 44s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB]
 44s Get:2 http://ftpmaster.internal/ubuntu plucky InRelease [257 kB]
 44s Get:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease [126 kB]
 44s Get:4 http://ftpmaster.internal/ubuntu plucky-security InRelease [126 kB]
 44s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [404 kB]
 45s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB]
 45s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [101 kB]
 45s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [81.0 kB]
 45s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf c-n-f Metadata [1944 B]
 45s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted armhf c-n-f Metadata [116 B]
 45s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [326 kB]
 45s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf c-n-f Metadata [12.1 kB]
 45s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [3472 B]
 45s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf c-n-f Metadata [332 B]
 45s Get:15 http://ftpmaster.internal/ubuntu plucky/universe Sources [21.0 MB]
 48s Get:16 http://ftpmaster.internal/ubuntu plucky/main Sources [1400 kB]
 48s Get:17 http://ftpmaster.internal/ubuntu plucky/multiverse Sources [299 kB]
 48s Get:18 http://ftpmaster.internal/ubuntu plucky/main armhf Packages [1378 kB]
 48s Get:19 http://ftpmaster.internal/ubuntu plucky/main armhf c-n-f Metadata [29.4 kB]
 48s Get:20 http://ftpmaster.internal/ubuntu plucky/restricted armhf c-n-f Metadata [108 B]
 48s Get:21 http://ftpmaster.internal/ubuntu plucky/universe armhf Packages [15.1 MB]
 49s Get:22 http://ftpmaster.internal/ubuntu plucky/multiverse armhf Packages [172 kB]
 52s Fetched 41.0 MB in 8s (5006 kB/s)
 53s Reading package lists...
 60s autopkgtest [15:20:40]: upgrading testbed (apt dist-upgrade and autopurge)
 62s Reading package lists...
 63s Building dependency tree...
 63s Reading state information...
 63s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
 63s Starting 2 pkgProblemResolver with broken count: 0
 63s Done
 64s Entering ResolveByKeep
 64s 
 64s Calculating upgrade...
 65s The following packages will be upgraded:
 65s   libc-bin libc6 locales python3-jinja2 sos strace
 65s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 65s Need to get 8642 kB of archives.
 65s After this operation, 23.6 kB of additional disk space will be used.
 65s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc6 armhf 2.41-1ubuntu2 [2932 kB]
 66s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libc-bin armhf 2.41-1ubuntu2 [545 kB]
 66s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf locales all 2.41-1ubuntu2 [4246 kB]
 66s Get:4 http://ftpmaster.internal/ubuntu plucky/main armhf strace armhf 6.13+ds-1ubuntu1 [445 kB]
 66s Get:5 http://ftpmaster.internal/ubuntu plucky/main armhf python3-jinja2 all 3.1.5-2ubuntu1 [109 kB]
 66s Get:6 http://ftpmaster.internal/ubuntu plucky/main armhf sos all 4.9.0-5 [365 kB]
 67s Preconfiguring packages ...
 67s Fetched 8642 kB in 1s (6607 kB/s)
 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 ... 64655 files and directories currently installed.)
 67s Preparing to unpack .../libc6_2.41-1ubuntu2_armhf.deb ...
 67s Unpacking libc6:armhf (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
 67s Setting up libc6:armhf (2.41-1ubuntu2) ...
 68s (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 ... 64655 files and directories currently installed.)
 68s Preparing to unpack .../libc-bin_2.41-1ubuntu2_armhf.deb ...
 68s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
 68s Setting up libc-bin (2.41-1ubuntu2) ...
 68s (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 ... 64655 files and directories currently installed.)
 68s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ...
 68s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ...
 68s Preparing to unpack .../strace_6.13+ds-1ubuntu1_armhf.deb ...
 68s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ...
 68s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ...
 68s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ...
 68s Preparing to unpack .../archives/sos_4.9.0-5_all.deb ...
 69s Unpacking sos (4.9.0-5) over (4.9.0-4) ...
 69s Setting up sos (4.9.0-5) ...
 69s Setting up locales (2.41-1ubuntu2) ...
 70s Generating locales (this might take a while)...
 72s   en_US.UTF-8... done
 72s Generation complete.
 72s Setting up python3-jinja2 (3.1.5-2ubuntu1) ...
 72s Setting up strace (6.13+ds-1ubuntu1) ...
 72s Processing triggers for man-db (2.13.0-1) ...
 73s Processing triggers for systemd (257.3-1ubuntu3) ...
 76s Reading package lists...
 76s Building dependency tree...
 76s Reading state information...
 77s Starting pkgProblemResolver with broken count: 0
 77s Starting 2 pkgProblemResolver with broken count: 0
 77s Done
 77s Solving dependencies...
 78s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 80s autopkgtest [15:21:00]: rebooting testbed after setup commands that affected boot
124s autopkgtest [15:21:44]: testbed running kernel: Linux 6.8.0-52-generic #53~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Wed Jan 15 18:10:51 UTC 2
154s autopkgtest [15:22:14]: @@@@@@@@@@@@@@@@@@@@ apt-source poa
165s Get:1 http://ftpmaster.internal/ubuntu plucky/universe poa 2.0+20060928-10 (dsc) [2151 B]
165s Get:2 http://ftpmaster.internal/ubuntu plucky/universe poa 2.0+20060928-10 (tar) [62.6 kB]
165s Get:3 http://ftpmaster.internal/ubuntu plucky/universe poa 2.0+20060928-10 (diff) [12.4 kB]
166s gpgv: Signature made Sat Mar  1 15:38:26 2025 UTC
166s gpgv:                using RSA key 8F91B227C7D6F2B1948C8236793CF67E8F0D11DA
166s gpgv:                issuer "emollier@debian.org"
166s gpgv: Can't check signature: No public key
166s dpkg-source: warning: cannot verify inline signature for ./poa_2.0+20060928-10.dsc: no acceptable signature found
166s autopkgtest [15:22:26]: testing package poa version 2.0+20060928-10
168s autopkgtest [15:22:28]: build not needed
170s autopkgtest [15:22:30]: test run-unit-test: preparing testbed
172s Reading package lists...
173s Building dependency tree...
173s Reading state information...
174s Starting pkgProblemResolver with broken count: 0
174s Starting 2 pkgProblemResolver with broken count: 0
174s Done
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 51.2 kB of archives.
176s After this operation, 120 kB of additional disk space will be used.
176s Get:1 http://ftpmaster.internal/ubuntu plucky/universe armhf poa armhf 2.0+20060928-10 [51.2 kB]
177s Fetched 51.2 kB in 0s (182 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 ... 64655 files and directories currently installed.)
177s Preparing to unpack .../poa_2.0+20060928-10_armhf.deb ...
177s Unpacking poa (2.0+20060928-10) ...
177s Setting up poa (2.0+20060928-10) ...
177s Processing triggers for man-db (2.13.0-1) ...
191s autopkgtest [15:22:51]: test run-unit-test: [-----------------------
194s ...Read 2 sequences from sequence file test1.fa...
194s Performing iterative alignment...
194s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00
194s aligned (13 nodes, 12 edges) to (12 nodes, 11 edges): best local score = 26 @ (12 10)
194s ...Wrote 2 sequences to CLUSTAL file clustal.aln...
194s ...Read 2 sequences from MSA file clustal.aln...
194s ...Wrote 2 sequences to FASTA-PIR file pir.aln...
194s ...Read 5 sequences from sequence file test2.fa...
194s Performing iterative alignment...
194s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00
194s aligned (44 nodes, 43 edges) to (35 nodes, 34 edges): best local score = 140 @ (43 34)
194s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score -1.00
194s aligned (50 nodes, 51 edges) to (43 nodes, 42 edges): best local score = 112 @ (49 42)
194s Fusing cluster 3 (seq4, nseq=1) --> 0 (seq1, nseq=3)... score -1.00
194s aligned (71 nodes, 79 edges) to (44 nodes, 43 edges): best local score = 94 @ (68 42)
194s Fusing cluster 4 (seq5, nseq=1) --> 0 (seq1, nseq=4)... score -1.00
194s aligned (93 nodes, 108 edges) to (42 nodes, 41 edges): best local score = 69 @ (79 39)
194s ...Wrote 5 sequences to FASTA-PIR file pir.aln...
194s ...Read 5 sequences from sequence file test3.fa...
194s Performing iterative alignment...
194s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00
194s aligned (16 nodes, 15 edges) to (16 nodes, 15 edges): best local score = 79 @ (15 15)
194s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score -1.00
194s aligned (19 nodes, 20 edges) to (16 nodes, 15 edges): best local score = 72 @ (18 15)
194s Fusing cluster 3 (seq4, nseq=1) --> 0 (seq1, nseq=3)... score -1.00
194s aligned (23 nodes, 27 edges) to (16 nodes, 15 edges): best local score = 73 @ (22 15)
194s Fusing cluster 4 (seq5, nseq=1) --> 0 (seq1, nseq=4)... score -1.00
194s aligned (27 nodes, 35 edges) to (19 nodes, 18 edges): best local score = 34 @ (26 18)
194s ...Wrote 5 sequences to PO file po.aln...
194s ...Read 24 sequences from sequence file test4.fa...
194s Performing iterative alignment...
194s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score -1.00
194s aligned (20 nodes, 19 edges) to (46 nodes, 45 edges): best global score = 62 @ (19 45)
194s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score -1.00
194s aligned (48 nodes, 49 edges) to (46 nodes, 45 edges): best global score = 268 @ (47 45)
194s Fusing cluster 3 (seq4, nseq=1) --> 0 (seq1, nseq=3)... score -1.00
194s aligned (49 nodes, 51 edges) to (49 nodes, 48 edges): best global score = 240 @ (48 48)
194s Fusing cluster 4 (seq5, nseq=1) --> 0 (seq1, nseq=4)... score -1.00
194s aligned (52 nodes, 57 edges) to (46 nodes, 45 edges): best global score = 269 @ (51 45)
194s Fusing cluster 5 (seq6, nseq=1) --> 0 (seq1, nseq=5)... score -1.00
194s aligned (53 nodes, 59 edges) to (19 nodes, 18 edges): best global score = 78 @ (52 18)
194s Fusing cluster 6 (seq7, nseq=1) --> 0 (seq1, nseq=6)... score -1.00
194s aligned (53 nodes, 59 edges) to (37 nodes, 36 edges): best global score = 195 @ (52 36)
194s Fusing cluster 7 (seq8, nseq=1) --> 0 (seq1, nseq=7)... score -1.00
194s aligned (53 nodes, 59 edges) to (46 nodes, 45 edges): best global score = 276 @ (52 45)
194s Fusing cluster 8 (seq9, nseq=1) --> 0 (seq1, nseq=8)... score -1.00
194s aligned (53 nodes, 59 edges) to (46 nodes, 45 edges): best global score = 240 @ (52 45)
194s Fusing cluster 9 (seq10, nseq=1) --> 0 (seq1, nseq=9)... score -1.00
194s aligned (56 nodes, 65 edges) to (19 nodes, 18 edges): best global score = 98 @ (55 18)
194s Fusing cluster 10 (seq11, nseq=1) --> 0 (seq1, nseq=10)... score -1.00
194s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 276 @ (55 45)
194s Fusing cluster 11 (seq12, nseq=1) --> 0 (seq1, nseq=11)... score -1.00
194s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 276 @ (55 45)
194s Fusing cluster 12 (seq13, nseq=1) --> 0 (seq1, nseq=12)... score -1.00
194s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 276 @ (55 45)
194s Fusing cluster 13 (seq14, nseq=1) --> 0 (seq1, nseq=13)... score -1.00
194s aligned (56 nodes, 65 edges) to (46 nodes, 45 edges): best global score = 263 @ (55 45)
194s Fusing cluster 14 (seq15, nseq=1) --> 0 (seq1, nseq=14)... score -1.00
194s aligned (58 nodes, 69 edges) to (46 nodes, 45 edges): best global score = 276 @ (57 45)
194s Fusing cluster 15 (seq16, nseq=1) --> 0 (seq1, nseq=15)... score -1.00
194s aligned (58 nodes, 69 edges) to (46 nodes, 45 edges): best global score = 270 @ (57 45)
194s Fusing cluster 16 (seq17, nseq=1) --> 0 (seq1, nseq=16)... score -1.00
194s aligned (59 nodes, 71 edges) to (46 nodes, 45 edges): best global score = 270 @ (58 45)
194s Fusing cluster 17 (seq18, nseq=1) --> 0 (seq1, nseq=17)... score -1.00
194s aligned (60 nodes, 73 edges) to (42 nodes, 41 edges): best global score = 201 @ (59 41)
194s Fusing cluster 18 (seq19, nseq=1) --> 0 (seq1, nseq=18)... score -1.00
194s aligned (61 nodes, 79 edges) to (4 nodes, 3 edges): best global score = -13 @ (60 3)
194s Fusing cluster 19 (seq20, nseq=1) --> 0 (seq1, nseq=19)... score -1.00
194s aligned (61 nodes, 80 edges) to (5 nodes, 4 edges): best global score = 23 @ (60 4)
194s Fusing cluster 20 (seq21, nseq=1) --> 0 (seq1, nseq=20)... score -1.00
194s aligned (62 nodes, 81 edges) to (24 nodes, 23 edges): best global score = 85 @ (61 23)
194s Fusing cluster 21 (seq22, nseq=1) --> 0 (seq1, nseq=21)... score -1.00
194s aligned (64 nodes, 84 edges) to (46 nodes, 45 edges): best global score = 276 @ (63 45)
194s Fusing cluster 22 (seq23, nseq=1) --> 0 (seq1, nseq=22)... score -1.00
194s aligned (64 nodes, 84 edges) to (44 nodes, 43 edges): best global score = 197 @ (63 43)
194s Fusing cluster 23 (seq24, nseq=1) --> 0 (seq1, nseq=23)... score -1.00
194s aligned (69 nodes, 95 edges) to (45 nodes, 44 edges): best global score = 200 @ (68 44)
194s ...Wrote 24 sequences to CLUSTAL file clustal.aln...
194s ...Read 3 sequences from sequence file test5.fa...
194s aligned (11 nodes, 10 edges) to (12 nodes, 11 edges): best local score = 36 @ (7 9)
194s Saving alignment score 1 (seq2), 0 (seq1) : 36.00
194s aligned (12 nodes, 11 edges) to (12 nodes, 11 edges): best local score = 22 @ (6 5)
194s Saving alignment score 2 (seq3), 0 (seq1) : 22.00
194s aligned (12 nodes, 11 edges) to (11 nodes, 10 edges): best local score = 14 @ (6 3)
194s Saving alignment score 2 (seq3), 1 (seq2) : 14.00
194s Fusing cluster 1 (seq2, nseq=1) --> 0 (seq1, nseq=1)... score 36.00
194s aligned (12 nodes, 11 edges) to (11 nodes, 10 edges): best local score = 36 @ (9 7)
194s Fusing cluster 2 (seq3, nseq=1) --> 0 (seq1, nseq=2)... score 22.00
194s aligned (17 nodes, 16 edges) to (12 nodes, 11 edges): best local score = 22 @ (7 6)
194s ...Wrote 3 sequences to CLUSTAL file clustal.aln...
194s autopkgtest [15:22:54]: test run-unit-test: -----------------------]
200s autopkgtest [15:23:00]: test run-unit-test:  - - - - - - - - - - results - - - - - - - - - -
200s run-unit-test        PASS
205s autopkgtest [15:23:05]: @@@@@@@@@@@@@@@@@@@@ summary
205s run-unit-test        PASS