0s autopkgtest [19:40:34]: starting date and time: 2025-03-15 19:40:34+0000 0s autopkgtest [19:40:34]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [19:40:34]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.11sdxwbb/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade veryfasttree --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-20@bos03-s390x-27.secgroup --name adt-plucky-s390x-veryfasttree-20250315-194033-juju-7f2275-prod-proposed-migration-environment-20-833b5a04-baed-4658-b445-ee1f8d4611b7 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --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/ 91s autopkgtest [19:42:05]: testbed dpkg architecture: s390x 91s autopkgtest [19:42:05]: testbed apt version: 2.9.33 91s autopkgtest [19:42:05]: @@@@@@@@@@@@@@@@@@@@ test bed setup 91s autopkgtest [19:42:05]: testbed release detected to be: None 92s autopkgtest [19:42:06]: updating testbed package index (apt update) 92s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 93s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 93s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 93s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 93s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [45.1 kB] 93s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [369 kB] 93s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.5 kB] 93s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [77.3 kB] 93s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x c-n-f Metadata [1824 B] 93s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x c-n-f Metadata [116 B] 93s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [314 kB] 93s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x c-n-f Metadata [13.3 kB] 93s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [3532 B] 93s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x c-n-f Metadata [240 B] 93s Fetched 965 kB in 1s (1036 kB/s) 94s Reading package lists... 94s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Calculating upgrade... 95s Calculating upgrade... 95s The following packages were automatically installed and are no longer required: 95s libnsl2 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 95s linux-headers-6.11.0-8 linux-headers-6.11.0-8-generic 95s linux-modules-6.11.0-8-generic linux-tools-6.11.0-8 95s linux-tools-6.11.0-8-generic 95s Use 'sudo apt autoremove' to remove them. 95s The following packages will be upgraded: 95s pinentry-curses python3-jinja2 strace 95s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 95s Need to get 652 kB of archives. 95s After this operation, 27.6 kB of additional disk space will be used. 95s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x strace s390x 6.13+ds-1ubuntu1 [500 kB] 95s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x pinentry-curses s390x 1.3.1-2ubuntu3 [42.9 kB] 95s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 96s Fetched 652 kB in 1s (1011 kB/s) 96s (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.) 96s Preparing to unpack .../strace_6.13+ds-1ubuntu1_s390x.deb ... 96s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 96s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_s390x.deb ... 96s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 96s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 96s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 96s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 96s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 96s Setting up strace (6.13+ds-1ubuntu1) ... 96s Processing triggers for man-db (2.13.0-1) ... 97s Reading package lists... 97s Building dependency tree... 97s Reading state information... 97s Solving dependencies... 97s The following packages will be REMOVED: 97s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 97s linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 97s linux-modules-6.11.0-8-generic* linux-tools-6.11.0-8* 97s linux-tools-6.11.0-8-generic* 97s 0 upgraded, 0 newly installed, 9 to remove and 5 not upgraded. 97s After this operation, 167 MB disk space will be freed. 97s (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.) 97s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 97s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 97s Removing libpython3.12t64:s390x (3.12.9-1) ... 97s Removing libpython3.12-stdlib:s390x (3.12.9-1) ... 97s Removing libnsl2:s390x (1.3.0-3build3) ... 97s Removing libpython3.12-minimal:s390x (3.12.9-1) ... 97s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 97s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 98s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 98s Processing triggers for libc-bin (2.41-1ubuntu1) ... 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 ... 56328 files and directories currently installed.) 98s Purging configuration files for libpython3.12-minimal:s390x (3.12.9-1) ... 98s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 98s autopkgtest [19:42:12]: upgrading testbed (apt dist-upgrade and autopurge) 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 99s Starting 2 pkgProblemResolver with broken count: 0 99s Done 99s Entering ResolveByKeep 99s 99s Calculating upgrade... 99s The following packages will be upgraded: 99s libc-bin libc-dev-bin libc6 libc6-dev locales 100s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 100s Need to get 9512 kB of archives. 100s After this operation, 8192 B of additional disk space will be used. 100s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6-dev s390x 2.41-1ubuntu2 [1678 kB] 101s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-dev-bin s390x 2.41-1ubuntu2 [24.3 kB] 101s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc6 s390x 2.41-1ubuntu2 [2892 kB] 104s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libc-bin s390x 2.41-1ubuntu2 [671 kB] 105s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x locales all 2.41-1ubuntu2 [4246 kB] 108s Preconfiguring packages ... 108s Fetched 9512 kB in 8s (1161 kB/s) 108s (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.) 108s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_s390x.deb ... 108s Unpacking libc6-dev:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_s390x.deb ... 108s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Preparing to unpack .../libc6_2.41-1ubuntu2_s390x.deb ... 108s Unpacking libc6:s390x (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Setting up libc6:s390x (2.41-1ubuntu2) ... 108s (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.) 108s Preparing to unpack .../libc-bin_2.41-1ubuntu2_s390x.deb ... 108s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Setting up libc-bin (2.41-1ubuntu2) ... 108s (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.) 108s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 108s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 108s Setting up locales (2.41-1ubuntu2) ... 109s Generating locales (this might take a while)... 110s en_US.UTF-8... done 110s Generation complete. 110s Setting up libc-dev-bin (2.41-1ubuntu2) ... 110s Setting up libc6-dev:s390x (2.41-1ubuntu2) ... 110s Processing triggers for man-db (2.13.0-1) ... 110s Processing triggers for systemd (257.3-1ubuntu3) ... 111s Reading package lists... 111s Building dependency tree... 111s Reading state information... 111s Starting pkgProblemResolver with broken count: 0 111s Starting 2 pkgProblemResolver with broken count: 0 111s Done 112s Solving dependencies... 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s autopkgtest [19:42:26]: rebooting testbed after setup commands that affected boot 131s autopkgtest [19:42:45]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP Wed Mar 12 14:53:49 UTC 2025 133s autopkgtest [19:42:47]: @@@@@@@@@@@@@@@@@@@@ apt-source veryfasttree 135s Get:1 http://ftpmaster.internal/ubuntu plucky/universe veryfasttree 4.0.4+dfsg-1 (dsc) [2137 B] 135s Get:2 http://ftpmaster.internal/ubuntu plucky/universe veryfasttree 4.0.4+dfsg-1 (tar) [164 kB] 135s Get:3 http://ftpmaster.internal/ubuntu plucky/universe veryfasttree 4.0.4+dfsg-1 (diff) [29.8 kB] 135s gpgv: Signature made Sat Nov 2 19:18:09 2024 UTC 135s gpgv: using RSA key 8F91B227C7D6F2B1948C8236793CF67E8F0D11DA 135s gpgv: issuer "emollier@debian.org" 135s gpgv: Can't check signature: No public key 135s dpkg-source: warning: cannot verify inline signature for ./veryfasttree_4.0.4+dfsg-1.dsc: no acceptable signature found 135s autopkgtest [19:42:49]: testing package veryfasttree version 4.0.4+dfsg-1 135s autopkgtest [19:42:49]: build not needed 136s autopkgtest [19:42:50]: test run-unit-test: preparing testbed 136s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s Starting pkgProblemResolver with broken count: 0 136s Starting 2 pkgProblemResolver with broken count: 0 136s Done 136s The following NEW packages will be installed: 136s libgomp1 veryfasttree 137s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 137s Need to get 832 kB of archives. 137s After this operation, 2341 kB of additional disk space will be used. 137s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libgomp1 s390x 15-20250222-0ubuntu1 [152 kB] 137s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x veryfasttree s390x 4.0.4+dfsg-1 [680 kB] 138s Fetched 832 kB in 1s (778 kB/s) 138s Selecting previously unselected package libgomp1:s390x. 138s (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.) 138s Preparing to unpack .../libgomp1_15-20250222-0ubuntu1_s390x.deb ... 138s Unpacking libgomp1:s390x (15-20250222-0ubuntu1) ... 138s Selecting previously unselected package veryfasttree. 138s Preparing to unpack .../veryfasttree_4.0.4+dfsg-1_s390x.deb ... 138s Unpacking veryfasttree (4.0.4+dfsg-1) ... 138s Setting up libgomp1:s390x (15-20250222-0ubuntu1) ... 138s Setting up veryfasttree (4.0.4+dfsg-1) ... 138s Processing triggers for libc-bin (2.41-1ubuntu2) ... 138s Processing triggers for man-db (2.13.0-1) ... 140s autopkgtest [19:42:54]: test run-unit-test: [----------------------- 140s Command: VeryFastTree test.fasta 140s VeryFastTree Version 4.0.4 (OpenMP) 140s Alignment: test.fasta 140s Amino acid distances: BLOSUM45 Joins: balanced Support: SH-like 1000 140s Search: Normal +NNI +SPR (2 rounds range 10) +ML-NNI opt-each=1 140s TopHits: 1.00*sqrtN close=default refresh=0.80 140s ML Model: Jones-Taylor-Thorton, CAT approximation with 20 rate categories 140s 0.10 seconds: Joined 200 of 201 140s Initial topology in 0.10 seconds 140s Refining topology: 31 rounds ME-NNIs, 2 rounds ME-SPRs, 15 rounds ML-NNIs 140s 0.23 seconds: SPR round 1 of 2, 101 of 406 nodes 140s 0.43 seconds: SPR round 1 of 2, 301 of 406 nodes 140s 0.54 seconds: ME NNI round 11 of 31, 101 of 202 splits, 0 changes 140s 0.65 seconds: SPR round 2 of 2, 101 of 406 nodes 141s 0.75 seconds: SPR round 2 of 2, 201 of 406 nodes 141s 0.94 seconds: SPR round 2 of 2, 401 of 406 nodes 141s Total branch-length 85.602 after 0.98 sec 141s 1.13 seconds: ML Lengths 101 of 202 splits 141s 1.29 seconds: ML Lengths 201 of 202 splits 141s 1.57 seconds: ML NNI round 1 of 15, 101 of 202 splits, 18 changes (max delta 9.021) 142s 1.88 seconds: ML NNI round 1 of 15, 201 of 202 splits, 34 changes (max delta 10.113) 142s ML-NNI round 1: LogLk = -60072.245 NNIs 35 max delta 10.11 Time 1.89 142s 2.00 seconds: Site likelihoods with rate category 4 of 20 142s 2.12 seconds: Site likelihoods with rate category 8 of 20 142s 2.24 seconds: Site likelihoods with rate category 12 of 20 142s 2.36 seconds: Site likelihoods with rate category 16 of 20 142s 2.47 seconds: Site likelihoods with rate category 20 of 20 142s Switched to using 20 rate categories (CAT approximation) 142s Rate categories were divided by 1.107 so that average rate = 1.0 142s CAT-based log-likelihoods may not be comparable across runs 142s Use -gamma for approximate but comparable Gamma(20) log-likelihoods 143s 2.81 seconds: ML NNI round 2 of 15, 101 of 202 splits, 8 changes (max delta 11.433) 143s 3.12 seconds: ML NNI round 2 of 15, 201 of 202 splits, 14 changes (max delta 11.433) 143s ML-NNI round 2: LogLk = -57877.799 NNIs 15 max delta 11.43 Time 3.14 143s 3.48 seconds: ML NNI round 3 of 15, 101 of 202 splits, 2 changes (max delta 0.026) 143s 3.58 seconds: ML NNI round 3 of 15, 135 of 202 splits, 3 changes (max delta 1.039) 143s ML-NNI round 3: LogLk = -57873.325 NNIs 3 max delta 1.04 Time 3.58 144s 3.81 seconds: ML NNI round 4 of 15, 69 of 202 splits, 0 changes 144s ML-NNI round 4: LogLk = -57873.268 NNIs 0 max delta 0.00 Time 3.81 144s Turning off heuristics for final round of ML NNIs (converged) 144s 4.09 seconds: ML NNI round 5 of 15, 101 of 202 splits, 0 changes 144s 4.37 seconds: ML NNI round 5 of 15, 201 of 202 splits, 0 changes 144s ML-NNI round 5: LogLk = -57871.325 NNIs 0 max delta 0.00 Time 4.38 (final) 144s 4.54 seconds: ML Lengths 101 of 202 splits 144s 4.71 seconds: ML Lengths 201 of 202 splits 144s Optimize all lengths: LogLk = -57839.690 Time 4.72 145s 5.19 seconds: ML split tests for 100 of 201 internal splits 145s 5.67 seconds: ML split tests for 200 of 201 internal splits 145s Total time: 5.68 seconds Unique: 204/204 Bad splits: 0/201 145s TreeCompleted 145s Command: VeryFastTree 145s VeryFastTree Version 4.0.4 (OpenMP) 145s Alignment: standard input 145s Amino acid distances: BLOSUM45 Joins: balanced Support: SH-like 1000 145s Search: Normal +NNI +SPR (2 rounds range 10) +ML-NNI opt-each=1 145s TopHits: 1.00*sqrtN close=default refresh=0.80 146s ML Model: Jones-Taylor-Thorton, CAT approximation with 20 rate categories 146s Initial topology in 0.10 seconds 146s Refining topology: 31 rounds ME-NNIs, 2 rounds ME-SPRs, 15 rounds ML-NNIs 146s 0.10 seconds: ME NNI round 1 of 31, 101 of 202 splits, 16 changes (max delta 0.090) 146s 0.23 seconds: SPR round 1 of 2, 101 of 406 nodes 146s 0.42 seconds: SPR round 1 of 2, 301 of 406 nodes 146s 0.53 seconds: ME NNI round 11 of 31, 101 of 202 splits, 0 changes 146s 0.64 seconds: SPR round 2 of 2, 101 of 406 nodes 146s 0.83 seconds: SPR round 2 of 2, 301 of 406 nodes 146s 0.94 seconds: SPR round 2 of 2, 407 of 406 nodes 146s Total branch-length 85.602 after 0.96 sec 147s 1.12 seconds: ML Lengths 101 of 202 splits 147s 1.27 seconds: ML Lengths 201 of 202 splits 147s 1.55 seconds: ML NNI round 1 of 15, 101 of 202 splits, 18 changes (max delta 9.021) 147s 1.86 seconds: ML NNI round 1 of 15, 201 of 202 splits, 34 changes (max delta 10.113) 147s ML-NNI round 1: LogLk = -60072.245 NNIs 35 max delta 10.11 Time 1.88 147s 1.99 seconds: Site likelihoods with rate category 4 of 20 148s 2.10 seconds: Site likelihoods with rate category 8 of 20 148s 2.22 seconds: Site likelihoods with rate category 12 of 20 148s 2.33 seconds: Site likelihoods with rate category 16 of 20 148s 2.45 seconds: Site likelihoods with rate category 20 of 20 148s Switched to using 20 rate categories (CAT approximation) 148s Rate categories were divided by 1.107 so that average rate = 1.0 148s CAT-based log-likelihoods may not be comparable across runs 148s Use -gamma for approximate but comparable Gamma(20) log-likelihoods 148s 2.79 seconds: ML NNI round 2 of 15, 101 of 202 splits, 8 changes (max delta 11.433) 149s 3.10 seconds: ML NNI round 2 of 15, 201 of 202 splits, 14 changes (max delta 11.433) 149s ML-NNI round 2: LogLk = -57877.799 NNIs 15 max delta 11.43 Time 3.12 149s 3.45 seconds: ML NNI round 3 of 15, 101 of 202 splits, 2 changes (max delta 0.026) 149s 3.55 seconds: ML NNI round 3 of 15, 135 of 202 splits, 3 changes (max delta 1.039) 149s ML-NNI round 3: LogLk = -57873.325 NNIs 3 max delta 1.04 Time 3.56 149s 3.79 seconds: ML NNI round 4 of 15, 69 of 202 splits, 0 changes 149s ML-NNI round 4: LogLk = -57873.268 NNIs 0 max delta 0.00 Time 3.79 149s Turning off heuristics for final round of ML NNIs (converged) 150s 4.07 seconds: ML NNI round 5 of 15, 101 of 202 splits, 0 changes 150s 4.35 seconds: ML NNI round 5 of 15, 201 of 202 splits, 0 changes 150s ML-NNI round 5: LogLk = -57871.325 NNIs 0 max delta 0.00 Time 4.36 (final) 150s 4.52 seconds: ML Lengths 101 of 202 splits 150s 4.69 seconds: ML Lengths 201 of 202 splits 150s Optimize all lengths: LogLk = -57839.690 Time 4.70 151s 5.17 seconds: ML split tests for 100 of 201 internal splits 151s 5.65 seconds: ML split tests for 200 of 201 internal splits 151s Total time: 5.66 seconds Unique: 204/204 Bad splits: 0/201 151s TreeCompleted 151s autopkgtest [19:43:05]: test run-unit-test: -----------------------] 152s autopkgtest [19:43:06]: test run-unit-test: - - - - - - - - - - results - - - - - - - - - - 152s run-unit-test PASS 152s autopkgtest [19:43:06]: @@@@@@@@@@@@@@@@@@@@ summary 152s run-unit-test PASS 169s nova [W] Using flock in prodstack6-s390x 169s Creating nova instance adt-plucky-s390x-veryfasttree-20250315-194033-juju-7f2275-prod-proposed-migration-environment-20-833b5a04-baed-4658-b445-ee1f8d4611b7 from image adt/ubuntu-plucky-s390x-server-20250315.img (UUID 3d3557fa-fd0f-4bba-9b89-8d5964e09f61)... 169s nova [W] Timed out waiting for c0c12816-10d0-47c7-9f10-afeff2ba3bbd to get deleted.