0s autopkgtest [18:42:07]: starting date and time: 2025-03-15 18:42:07+0000 0s autopkgtest [18:42:07]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [18:42:07]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.tag4_n43/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade roguenarok --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.41-1ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-5.secgroup --name adt-plucky-s390x-roguenarok-20250315-184207-juju-7f2275-prod-proposed-migration-environment-2-e3f92892-a70b-4b5a-9d48-ef3066158a34 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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/ 128s autopkgtest [18:44:15]: testbed dpkg architecture: s390x 128s autopkgtest [18:44:15]: testbed apt version: 2.9.33 128s autopkgtest [18:44:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 128s autopkgtest [18:44:15]: testbed release detected to be: None 129s autopkgtest [18:44:16]: updating testbed package index (apt update) 129s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 130s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 130s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 130s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 130s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 130s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 130s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 130s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [113 kB] 130s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 130s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 130s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [320 kB] 130s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.4 kB] 130s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3776 B] 130s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 131s Fetched 1073 kB in 1s (858 kB/s) 131s Reading package lists... 132s Reading package lists... 132s Building dependency tree... 132s Reading state information... 132s Calculating upgrade... 132s Calculating upgrade... 132s The following packages were automatically installed and are no longer required: 132s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 132s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 132s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 132s linux-tools-6.11.0-8-generic 132s Use 'sudo apt autoremove' to remove them. 132s The following packages will be upgraded: 132s pinentry-curses python3-jinja2 strace 132s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 132s Need to get 652 kB of archives. 132s After this operation, 27.6 kB of additional disk space will be used. 132s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 133s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 133s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 133s Fetched 652 kB in 1s (751 kB/s) 133s (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 ... 81428 files and directories currently installed.) 133s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 133s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 133s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 133s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 133s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 134s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 134s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 134s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 134s Setting up strace (6.13+ds-1ubuntu1) ... 134s Processing triggers for man-db (2.13.0-1) ... 134s Reading package lists... 134s Building dependency tree... 134s Reading state information... 135s Solving dependencies... 135s The following packages will be REMOVED: 135s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 135s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 135s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 135s linux-tools-6.11.0-8-generic* 135s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 135s After this operation, 167 MB disk space will be freed. 135s (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 ... 81428 files and directories currently installed.) 135s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 135s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 135s Removing libpython3.12t64:s390x (3.12.9-1) ... 135s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 135s Removing libnsl2:s390x (1.3.0-3build3) ... 135s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 135s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 135s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 136s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 136s Processing triggers for libc-bin (2.41-1ubuntu1) ... 136s (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 ... 56328 files and directories currently installed.) 136s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 136s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 136s autopkgtest [18:44:23]: upgrading testbed (apt dist-upgrade and autopurge) 137s Reading package lists... 137s Building dependency tree... 137s Reading state information... 137s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 137s Starting 2 pkgProblemResolver with broken count: 0 137s Done 137s Entering ResolveByKeep 137s 137s Calculating upgrade... 137s The following packages will be upgraded: 137s libc-bin libc-dev-bin libc6 libc6-dev locales 138s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 138s Need to get 9512 kB of archives. 138s After this operation, 8192 B of additional disk space will be used. 138s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 139s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 139s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 141s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 141s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 144s Preconfiguring packages ... 144s Fetched 9512 kB in 6s (1480 kB/s) 144s (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 ... 56326 files and directories currently installed.) 144s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 144s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 144s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 144s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 144s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 144s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 144s Setting up libc6:s390x (2.41-1ubuntu2) ... 145s (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 ... 56326 files and directories currently installed.) 145s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 145s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 145s Setting up libc-bin (2.41-1ubuntu2) ... 145s (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 ... 56326 files and directories currently installed.) 145s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 145s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 145s Setting up locales (2.41-1ubuntu2) ... 145s Generating locales (this might take a while)... 146s en_US.UTF-8... done 146s Generation complete. 146s Setting up libc-dev-bin (2.41-1ubuntu2) ... 146s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 146s Processing triggers for man-db (2.13.0-1) ... 147s Processing triggers for systemd (257.3-1ubuntu3) ... 148s Reading package lists... 148s Building dependency tree... 148s Reading state information... 148s Starting pkgProblemResolver with broken count: 0 148s Starting 2 pkgProblemResolver with broken count: 0 148s Done 148s Solving dependencies... 148s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 148s autopkgtest [18:44:35]: rebooting testbed after setup commands that affected boot 167s autopkgtest [18:44:54]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025 169s autopkgtest [18:44:56]: @@@@@@@@@@@@@@@@@@@@ apt-source roguenarok 171s Get:1 http://ftpmaster.internal/ubuntu plucky/universe roguenarok 1.0.1-3 (dsc) [1994 B] 171s Get:2 http://ftpmaster.internal/ubuntu plucky/universe roguenarok 1.0.1-3 (tar) [280 kB] 171s Get:3 http://ftpmaster.internal/ubuntu plucky/universe roguenarok 1.0.1-3 (diff) [7564 B] 171s gpgv: Signature made Mon Jan 17 13:04:18 2022 UTC 171s gpgv: using RSA key F1F007320A035541F0A663CA578A0494D1C646D1 171s gpgv: issuer "tille@debian.org" 171s gpgv: Can't check signature: No public key 171s dpkg-source: warning: cannot verify inline signature for ./roguenarok_1.0.1-3.dsc: no acceptable signature found 171s autopkgtest [18:44:58]: testing package roguenarok version 1.0.1-3 172s autopkgtest [18:44:59]: build not needed 172s autopkgtest [18:44:59]: test run-unit-test: preparing testbed 173s Reading package lists... 173s Building dependency tree... 173s Reading state information... 173s Starting pkgProblemResolver with broken count: 0 173s Starting 2 pkgProblemResolver with broken count: 0 173s Done 173s The following NEW packages will be installed: 173s roguenarok 173s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 173s Need to get 201 kB of archives. 173s After this operation, 2231 kB of additional disk space will be used. 173s Get:1 http://ftpmaster.internal/ubuntu plucky/universe s390x roguenarok s390x 1.0.1-3 [201 kB] 174s Fetched 201 kB in 0s (468 kB/s) 174s Selecting previously unselected package roguenarok. 174s (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 ... 56326 files and directories currently installed.) 174s Preparing to unpack .../roguenarok_1.0.1-3_s390x.deb ... 174s Unpacking roguenarok (1.0.1-3) ... 174s Setting up roguenarok (1.0.1-3) ... 174s Processing triggers for man-db (2.13.0-1) ... 175s autopkgtest [18:45:02]: test run-unit-test: [----------------------- 176s 176s This is RogueNaRok version 1.0 released by Andre J. Aberer in 2011-10-25. 176s 176s Found a total of 150 taxa in first tree of tree collection 150.bs 176s Expecting all remaining trees in collection to have the same taxon set 176s 176s mode: optimization on consensus tree. Bipartition is part of consensus, if it occurs in more than 500 trees 176s [0.044318] initialisation done (initScore = 0.570381, numBip=4183) 176s [0.000367] sorting bipartition profile 176s [0.013644] computed / updated events 176s [0.000005] combined events 176s [0.000177] calculated per dropset improvement 176s [0.000203] executed the merging events 176s >9 Species172 1.238000 0.578803 176s [0.000261] sorting bipartition profile 176s [0.002715] computed / updated events 176s [0.000004] combined events 176s [0.000100] calculated per dropset improvement 176s [0.000178] executed the merging events 176s >89 Species099 0.801000 0.584252 176s [0.000208] sorting bipartition profile 176s [0.001325] computed / updated events 176s [0.000004] combined events 176s [0.000090] calculated per dropset improvement 176s [0.000201] executed the merging events 176s >16 Species028 0.712000 0.589095 176s [0.000243] sorting bipartition profile 176s [0.001250] computed / updated events 176s [0.000003] combined events 176s [0.000101] calculated per dropset improvement 176s [0.000191] executed the merging events 176s >43 Species077 0.592000 0.593122 176s [0.000251] sorting bipartition profile 176s [0.002033] computed / updated events 176s [0.000004] combined events 176s [0.000096] calculated per dropset improvement 176s [0.000203] executed the merging events 176s >148 Species137 0.539000 0.596789 176s [0.000268] sorting bipartition profile 176s [0.003308] computed / updated events 176s [0.000004] combined events 176s [0.000092] calculated per dropset improvement 176s [0.000153] executed the merging events 176s >22 Species095 0.318000 0.598952 176s [0.000200] sorting bipartition profile 176s [0.000904] computed / updated events 176s [0.000004] combined events 176s [0.000083] calculated per dropset improvement 176s [0.000166] executed the merging events 176s >38 Species106 0.318000 0.601116 176s [0.000229] sorting bipartition profile 176s [0.001291] computed / updated events 176s [0.000003] combined events 176s [0.000088] calculated per dropset improvement 176s [0.000166] executed the merging events 176s >8 Species025 0.292000 0.603102 176s [0.000236] sorting bipartition profile 176s [0.001306] computed / updated events 176s [0.000003] combined events 176s [0.000091] calculated per dropset improvement 176s [0.000151] executed the merging events 176s >23 Species019 0.405000 0.605857 176s [0.000228] sorting bipartition profile 176s [0.001157] computed / updated events 176s [0.000003] combined events 176s [0.000088] calculated per dropset improvement 176s [0.000137] executed the merging events 176s >60 Species121 0.223000 0.607374 176s [0.000194] sorting bipartition profile 176s [0.000787] computed / updated events 176s [0.000003] combined events 176s [0.000081] calculated per dropset improvement 176s [0.000136] executed the merging events 176s >29 Species006 0.196000 0.608707 176s [0.000204] sorting bipartition profile 176s [0.000857] computed / updated events 176s [0.000004] combined events 176s [0.000082] calculated per dropset improvement 176s [0.000133] executed the merging events 176s >5 Species026 0.109000 0.609449 176s [0.000191] sorting bipartition profile 176s [0.000471] computed / updated events 176s [0.000003] combined events 176s [0.000078] calculated per dropset improvement 176s [0.000144] executed the merging events 176s >31 Species154 0.101000 0.610136 176s [0.000217] sorting bipartition profile 176s [0.000864] computed / updated events 176s [0.000004] combined events 176s [0.000086] calculated per dropset improvement 176s [0.000126] executed the merging events 176s >37 Species186 0.088000 0.610735 176s [0.000187] sorting bipartition profile 176s [0.000188] computed / updated events 176s [0.000002] combined events 176s [0.000076] calculated per dropset improvement 176s [0.000140] executed the merging events 176s >57 Species053 0.085000 0.611313 176s [0.000216] sorting bipartition profile 176s [0.000858] computed / updated events 176s [0.000003] combined events 176s [0.000078] calculated per dropset improvement 176s [0.000121] executed the merging events 176s >80 Species086 0.074000 0.611816 176s [0.000189] sorting bipartition profile 176s [0.000408] computed / updated events 176s [0.000003] combined events 176s [0.000072] calculated per dropset improvement 176s [0.000123] executed the merging events 176s >104 Species014 0.068000 0.612279 176s [0.000216] sorting bipartition profile 176s [0.001046] computed / updated events 176s [0.000007] combined events 176s [0.000077] calculated per dropset improvement 176s [0.000119] executed the merging events 176s >65 Species027 0.065000 0.612721 176s [0.000186] sorting bipartition profile 176s [0.000363] computed / updated events 176s [0.000003] combined events 176s [0.000071] calculated per dropset improvement 176s [0.000134] executed the merging events 176s >95 Species120 0.034000 0.612952 176s [0.000214] sorting bipartition profile 176s [0.001092] computed / updated events 176s [0.000004] combined events 176s [0.000075] calculated per dropset improvement 176s [0.000141] executed the merging events 176s >96 Species122 0.034000 0.613184 176s [0.000234] sorting bipartition profile 176s [0.001578] computed / updated events 176s [0.000004] combined events 176s [0.000074] calculated per dropset improvement 176s [0.000111] executed the merging events 176s >83 Species126 0.015000 0.613286 176s [0.000196] sorting bipartition profile 176s [0.000529] computed / updated events 176s [0.000003] combined events 176s [0.000068] calculated per dropset improvement 176s [0.000115] executed the merging events 176s >75 Species070 0.015000 0.613388 176s [0.000197] sorting bipartition profile 176s [0.000594] computed / updated events 176s [0.000003] combined events 176s [0.000068] calculated per dropset improvement 176s [0.000109] executed the merging events 176s >97 Species191 0.004000 0.613415 176s [0.000217] sorting bipartition profile 176s [0.001131] computed / updated events 176s [0.000004] combined events 176s [0.000065] calculated per dropset improvement 176s [0.000112] executed the merging events 176s >147 Species175 0.002000 0.613429 176s [0.000205] sorting bipartition profile 176s [0.000763] computed / updated events 176s [0.000003] combined events 176s [0.000064] calculated per dropset improvement 176s [0.000097] executed the merging events 176s >100 Species022 0.535000 0.617068 176s [0.000206] sorting bipartition profile 176s [0.000544] computed / updated events 176s [0.000003] combined events 176s [0.000064] calculated per dropset improvement 176s [0.000098] executed the merging events 176s >98 Species132 0.026000 0.617245 176s [0.000184] sorting bipartition profile 176s [0.000370] computed / updated events 176s [0.000003] combined events 176s [0.000061] calculated per dropset improvement 176s [0.000092] executed the merging events 176s >58 Species187 0.001000 0.617252 176s [0.000192] sorting bipartition profile 176s [0.000403] computed / updated events 176s [0.000003] combined events 176s [0.000065] calculated per dropset improvement 176s total time elapsed: 0.098462 176s 176s This is RogueNaRok version 1.0 released by Andre J. Aberer in 2011-10-25. 176s 176s Found a total of 150 taxa in first tree of tree collection 150.bs 176s Expecting all remaining trees in collection to have the same taxon set 176s 176s mode: optimization of support of ML tree bipartitions in the bootstrap tree set. 176s [0.044329] initialisation done (initScore = 0.658408, numBip=4183) 176s [0.000374] sorting bipartition profile 176s [0.013454] computed / updated events 176s [0.000007] combined events 176s [0.000265] calculated per dropset improvement 176s [0.000203] executed the merging events 176s >43 Species077 0.337000 0.660701 176s [0.000253] sorting bipartition profile 176s [0.002250] computed / updated events 176s [0.000004] combined events 176s [0.000164] calculated per dropset improvement 176s [0.000195] executed the merging events 176s >9 Species172 0.264000 0.662497 176s [0.000263] sorting bipartition profile 176s [0.002552] computed / updated events 176s [0.000003] combined events 176s [0.000167] calculated per dropset improvement 176s [0.000187] executed the merging events 176s >23 Species019 0.238000 0.664116 176s [0.000241] sorting bipartition profile 176s [0.001936] computed / updated events 176s [0.000005] combined events 176s [0.000166] calculated per dropset improvement 176s [0.000207] executed the merging events 176s >148 Species137 0.237000 0.665728 176s [0.000271] sorting bipartition profile 176s [0.003349] computed / updated events 176s [0.000004] combined events 176s [0.000161] calculated per dropset improvement 176s [0.000188] executed the merging events 176s >147 Species175 0.204000 0.667116 176s [0.000260] sorting bipartition profile 176s [0.002606] computed / updated events 176s [0.000005] combined events 176s [0.000154] calculated per dropset improvement 176s [0.000151] executed the merging events 176s >27 Species042 0.181000 0.668347 176s [0.000216] sorting bipartition profile 176s [0.001254] computed / updated events 176s [0.000003] combined events 176s [0.000149] calculated per dropset improvement 176s [0.000166] executed the merging events 176s >16 Species028 0.107000 0.669075 176s [0.000239] sorting bipartition profile 176s [0.000970] computed / updated events 176s [0.000004] combined events 176s [0.000155] calculated per dropset improvement 176s total time elapsed: 0.077540 176s RogueNaRok_droppedRogues.id1: OK 176s RogueNaRok_droppedRogues.id2: OK 176s autopkgtest [18:45:03]: test run-unit-test: -----------------------] 177s autopkgtest [18:45:04]: test run-unit-test: - - - - - - - - - - results - - - - - - - - - - 177s run-unit-test PASS 177s autopkgtest [18:45:04]: @@@@@@@@@@@@@@@@@@@@ summary 177s run-unit-test PASS 196s nova [W] Using flock in prodstack6-s390x 196s flock: timeout while waiting to get lock 196s Creating nova instance adt-plucky-s390x-roguenarok-20250315-184207-juju-7f2275-prod-proposed-migration-environment-2-e3f92892-a70b-4b5a-9d48-ef3066158a34 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 196s nova [W] Timed out waiting for f6901c10-7cfd-4598-881a-89f6ce66e0f0 to get deleted.