0s autopkgtest [17:18:26]: starting date and time: 2025-03-15 17:18:26+0000 0s autopkgtest [17:18:26]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [17:18:26]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.lw84mb5u/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-33.secgroup --name adt-plucky-arm64-veryfasttree-20250315-171825-juju-7f2275-prod-proposed-migration-environment-2-7135d202-5a1e-417e-8db2-0f99d708b672 --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -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/ 148s autopkgtest [17:20:54]: testbed dpkg architecture: arm64 148s autopkgtest [17:20:54]: testbed apt version: 2.9.33 148s autopkgtest [17:20:54]: @@@@@@@@@@@@@@@@@@@@ test bed setup 148s autopkgtest [17:20:54]: testbed release detected to be: None 149s autopkgtest [17:20:55]: updating testbed package index (apt update) 150s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [126 kB] 150s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 150s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 150s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 150s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [99.7 kB] 150s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [379 kB] 151s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.8 kB] 151s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [111 kB] 151s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 c-n-f Metadata [1856 B] 151s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 c-n-f Metadata [116 B] 151s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [324 kB] 151s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 c-n-f Metadata [14.7 kB] 151s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [4948 B] 151s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 c-n-f Metadata [268 B] 152s Fetched 1078 kB in 1s (721 kB/s) 153s Reading package lists... 154s Reading package lists... 154s Building dependency tree... 154s Reading state information... 155s Calculating upgrade... 155s Calculating upgrade... 155s The following packages will be upgraded: 155s pinentry-curses python3-jinja2 strace 156s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 156s Need to get 647 kB of archives. 156s After this operation, 11.3 kB of additional disk space will be used. 156s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 strace arm64 6.13+ds-1ubuntu1 [499 kB] 156s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 pinentry-curses arm64 1.3.1-2ubuntu3 [39.2 kB] 156s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-jinja2 all 3.1.5-2ubuntu1 [109 kB] 157s Fetched 647 kB in 1s (782 kB/s) 157s (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 ... 117701 files and directories currently installed.) 157s Preparing to unpack .../strace_6.13+ds-1ubuntu1_arm64.deb ... 157s Unpacking strace (6.13+ds-1ubuntu1) over (6.11-0ubuntu1) ... 158s Preparing to unpack .../pinentry-curses_1.3.1-2ubuntu3_arm64.deb ... 158s Unpacking pinentry-curses (1.3.1-2ubuntu3) over (1.3.1-2ubuntu2) ... 158s Preparing to unpack .../python3-jinja2_3.1.5-2ubuntu1_all.deb ... 158s Unpacking python3-jinja2 (3.1.5-2ubuntu1) over (3.1.5-2) ... 158s Setting up pinentry-curses (1.3.1-2ubuntu3) ... 158s Setting up python3-jinja2 (3.1.5-2ubuntu1) ... 158s Setting up strace (6.13+ds-1ubuntu1) ... 158s Processing triggers for man-db (2.13.0-1) ... 159s Reading package lists... 160s Building dependency tree... 160s Reading state information... 160s Solving dependencies... 161s The following packages will be REMOVED: 161s libnsl2* libpython3.12-minimal* libpython3.12-stdlib* libpython3.12t64* 161s libunwind8* linux-headers-6.11.0-8* linux-headers-6.11.0-8-generic* 161s linux-image-6.11.0-8-generic* linux-modules-6.11.0-8-generic* 161s linux-tools-6.11.0-8* linux-tools-6.11.0-8-generic* 161s 0 upgraded, 0 newly installed, 11 to remove and 5 not upgraded. 161s After this operation, 267 MB disk space will be freed. 162s (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 ... 117701 files and directories currently installed.) 162s Removing linux-tools-6.11.0-8-generic (6.11.0-8.8) ... 162s Removing linux-tools-6.11.0-8 (6.11.0-8.8) ... 162s Removing libpython3.12t64:arm64 (3.12.9-1) ... 162s Removing libpython3.12-stdlib:arm64 (3.12.9-1) ... 162s Removing libnsl2:arm64 (1.3.0-3build3) ... 162s Removing libpython3.12-minimal:arm64 (3.12.9-1) ... 162s Removing libunwind8:arm64 (1.6.2-3.1) ... 162s Removing linux-headers-6.11.0-8-generic (6.11.0-8.8) ... 162s Removing linux-headers-6.11.0-8 (6.11.0-8.8) ... 164s Removing linux-image-6.11.0-8-generic (6.11.0-8.8) ... 165s I: /boot/vmlinuz.old is now a symlink to vmlinuz-6.14.0-10-generic 165s I: /boot/initrd.img.old is now a symlink to initrd.img-6.14.0-10-generic 165s /etc/kernel/postrm.d/initramfs-tools: 165s update-initramfs: Deleting /boot/initrd.img-6.11.0-8-generic 165s /etc/kernel/postrm.d/zz-flash-kernel: 165s flash-kernel: Kernel 6.11.0-8-generic has been removed. 165s flash-kernel: A higher version (6.14.0-10-generic) is still installed, no reflashing required. 165s /etc/kernel/postrm.d/zz-update-grub: 165s Sourcing file `/etc/default/grub' 165s Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 165s Generating grub configuration file ... 166s Found linux image: /boot/vmlinuz-6.14.0-10-generic 166s Found initrd image: /boot/initrd.img-6.14.0-10-generic 166s Warning: os-prober will not be executed to detect other bootable partitions. 166s Systems on them will not be added to the GRUB boot configuration. 166s Check GRUB_DISABLE_OS_PROBER documentation entry. 166s Adding boot menu entry for UEFI Firmware Settings ... 166s done 166s Removing linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 167s Processing triggers for libc-bin (2.41-1ubuntu1) ... 167s (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 ... 81650 files and directories currently installed.) 167s Purging configuration files for linux-image-6.11.0-8-generic (6.11.0-8.8) ... 167s Purging configuration files for libpython3.12-minimal:arm64 (3.12.9-1) ... 167s Purging configuration files for linux-modules-6.11.0-8-generic (6.11.0-8.8) ... 167s autopkgtest [17:21:13]: upgrading testbed (apt dist-upgrade and autopurge) 168s Reading package lists... 168s Building dependency tree... 168s Reading state information... 170s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 170s Starting 2 pkgProblemResolver with broken count: 0 170s Done 171s Entering ResolveByKeep 172s 172s Calculating upgrade... 172s The following packages will be upgraded: 172s libc-bin libc-dev-bin libc6 libc6-dev locales 172s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 172s Need to get 9530 kB of archives. 172s After this operation, 0 B of additional disk space will be used. 172s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6-dev arm64 2.41-1ubuntu2 [1750 kB] 174s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-dev-bin arm64 2.41-1ubuntu2 [24.0 kB] 174s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc6 arm64 2.41-1ubuntu2 [2910 kB] 176s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libc-bin arm64 2.41-1ubuntu2 [600 kB] 177s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 locales all 2.41-1ubuntu2 [4246 kB] 180s Preconfiguring packages ... 181s Fetched 9530 kB in 8s (1237 kB/s) 181s (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 ... 81647 files and directories currently installed.) 181s Preparing to unpack .../libc6-dev_2.41-1ubuntu2_arm64.deb ... 181s Unpacking libc6-dev:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 181s Preparing to unpack .../libc-dev-bin_2.41-1ubuntu2_arm64.deb ... 181s Unpacking libc-dev-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 181s Preparing to unpack .../libc6_2.41-1ubuntu2_arm64.deb ... 181s Unpacking libc6:arm64 (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 181s Setting up libc6:arm64 (2.41-1ubuntu2) ... 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 ... 81647 files and directories currently installed.) 182s Preparing to unpack .../libc-bin_2.41-1ubuntu2_arm64.deb ... 182s Unpacking libc-bin (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 182s Setting up libc-bin (2.41-1ubuntu2) ... 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 ... 81647 files and directories currently installed.) 182s Preparing to unpack .../locales_2.41-1ubuntu2_all.deb ... 182s Unpacking locales (2.41-1ubuntu2) over (2.41-1ubuntu1) ... 182s Setting up locales (2.41-1ubuntu2) ... 183s Generating locales (this might take a while)... 185s en_US.UTF-8... done 185s Generation complete. 185s Setting up libc-dev-bin (2.41-1ubuntu2) ... 185s Setting up libc6-dev:arm64 (2.41-1ubuntu2) ... 185s Processing triggers for man-db (2.13.0-1) ... 186s Processing triggers for systemd (257.3-1ubuntu3) ... 187s Reading package lists... 188s Building dependency tree... 188s Reading state information... 188s Starting pkgProblemResolver with broken count: 0 188s Starting 2 pkgProblemResolver with broken count: 0 188s Done 189s Solving dependencies... 189s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 189s autopkgtest [17:21:35]: rebooting testbed after setup commands that affected boot 217s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 224s autopkgtest [17:22:10]: testbed running kernel: Linux 6.14.0-10-generic #10-Ubuntu SMP PREEMPT_DYNAMIC Wed Mar 12 15:45:31 UTC 2025 227s autopkgtest [17:22:13]: @@@@@@@@@@@@@@@@@@@@ apt-source veryfasttree 230s Get:1 http://ftpmaster.internal/ubuntu plucky/universe veryfasttree 4.0.4+dfsg-1 (dsc) [2137 B] 230s Get:2 http://ftpmaster.internal/ubuntu plucky/universe veryfasttree 4.0.4+dfsg-1 (tar) [164 kB] 230s Get:3 http://ftpmaster.internal/ubuntu plucky/universe veryfasttree 4.0.4+dfsg-1 (diff) [29.8 kB] 230s gpgv: Signature made Sat Nov 2 19:18:09 2024 UTC 230s gpgv: using RSA key 8F91B227C7D6F2B1948C8236793CF67E8F0D11DA 230s gpgv: issuer "emollier@debian.org" 230s gpgv: Can't check signature: No public key 230s dpkg-source: warning: cannot verify inline signature for ./veryfasttree_4.0.4+dfsg-1.dsc: no acceptable signature found 230s autopkgtest [17:22:16]: testing package veryfasttree version 4.0.4+dfsg-1 231s autopkgtest [17:22:17]: build not needed 231s autopkgtest [17:22:17]: test run-unit-test: preparing testbed 232s Reading package lists... 232s Building dependency tree... 232s Reading state information... 232s Starting pkgProblemResolver with broken count: 0 232s Starting 2 pkgProblemResolver with broken count: 0 232s Done 233s The following NEW packages will be installed: 233s libgomp1 veryfasttree 233s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 233s Need to get 701 kB of archives. 233s After this operation, 1939 kB of additional disk space will be used. 233s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libgomp1 arm64 15-20250222-0ubuntu1 [146 kB] 234s Get:2 http://ftpmaster.internal/ubuntu plucky/universe arm64 veryfasttree arm64 4.0.4+dfsg-1 [555 kB] 235s Fetched 701 kB in 1s (575 kB/s) 235s Selecting previously unselected package libgomp1:arm64. 235s (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 ... 81647 files and directories currently installed.) 235s Preparing to unpack .../libgomp1_15-20250222-0ubuntu1_arm64.deb ... 235s Unpacking libgomp1:arm64 (15-20250222-0ubuntu1) ... 235s Selecting previously unselected package veryfasttree. 235s Preparing to unpack .../veryfasttree_4.0.4+dfsg-1_arm64.deb ... 235s Unpacking veryfasttree (4.0.4+dfsg-1) ... 235s Setting up libgomp1:arm64 (15-20250222-0ubuntu1) ... 235s Setting up veryfasttree (4.0.4+dfsg-1) ... 235s Processing triggers for libc-bin (2.41-1ubuntu2) ... 235s Processing triggers for man-db (2.13.0-1) ... 237s autopkgtest [17:22:23]: test run-unit-test: [----------------------- 238s Command: VeryFastTree test.fasta 238s VeryFastTree Version 4.0.4 (OpenMP) 238s Alignment: test.fasta 238s Amino acid distances: BLOSUM45 Joins: balanced Support: SH-like 1000 238s Search: Normal +NNI +SPR (2 rounds range 10) +ML-NNI opt-each=1 238s TopHits: 1.00*sqrtN close=default refresh=0.80 238s ML Model: Jones-Taylor-Thorton, CAT approximation with 20 rate categories 238s Initial topology in 0.08 seconds 238s Refining topology: 31 rounds ME-NNIs, 2 rounds ME-SPRs, 15 rounds ML-NNIs 238s 0.10 seconds: ME NNI round 4 of 31, 62 of 202 splits, 3 changes (max delta 0.039) 238s 0.23 seconds: SPR round 1 of 2, 201 of 406 nodes 238s 0.36 seconds: SPR round 1 of 2, 401 of 406 nodes 238s 0.51 seconds: SPR round 2 of 2, 201 of 406 nodes 238s 0.64 seconds: SPR round 2 of 2, 401 of 406 nodes 238s Total branch-length 85.602 after 0.67 sec 238s 0.76 seconds: ML Lengths 101 of 202 splits 239s 1.02 seconds: ML NNI round 1 of 15, 101 of 202 splits, 18 changes (max delta 9.023) 239s 1.21 seconds: ML NNI round 1 of 15, 201 of 202 splits, 34 changes (max delta 10.114) 239s ML-NNI round 1: LogLk = -60072.236 NNIs 35 max delta 10.11 Time 1.22 239s 1.32 seconds: Site likelihoods with rate category 6 of 20 239s 1.43 seconds: Site likelihoods with rate category 12 of 20 239s 1.53 seconds: Site likelihoods with rate category 18 of 20 239s Switched to using 20 rate categories (CAT approximation) 239s Rate categories were divided by 1.107 so that average rate = 1.0 239s CAT-based log-likelihoods may not be comparable across runs 239s Use -gamma for approximate but comparable Gamma(20) log-likelihoods 239s 1.79 seconds: ML NNI round 2 of 15, 101 of 202 splits, 8 changes (max delta 11.434) 240s 1.99 seconds: ML NNI round 2 of 15, 201 of 202 splits, 14 changes (max delta 11.434) 240s ML-NNI round 2: LogLk = -57877.769 NNIs 15 max delta 11.43 Time 2.00 240s 2.22 seconds: ML NNI round 3 of 15, 101 of 202 splits, 2 changes (max delta 0.026) 240s ML-NNI round 3: LogLk = -57873.299 NNIs 3 max delta 1.04 Time 2.29 240s 2.43 seconds: ML NNI round 4 of 15, 69 of 202 splits, 0 changes 240s ML-NNI round 4: LogLk = -57873.241 NNIs 0 max delta 0.00 Time 2.44 240s Turning off heuristics for final round of ML NNIs (converged) 240s ML-NNI round 5: LogLk = -57871.301 NNIs 0 max delta 0.00 Time 1.30 (final) 241s Optimize all lengths: LogLk = -57839.689 Time 1.52 241s Total time: 2.16 seconds Unique: 204/204 Bad splits: 0/201 241s TreeCompleted 241s Command: VeryFastTree 241s VeryFastTree Version 4.0.4 (OpenMP) 241s Alignment: standard input 241s Amino acid distances: BLOSUM45 Joins: balanced Support: SH-like 1000 241s Search: Normal +NNI +SPR (2 rounds range 10) +ML-NNI opt-each=1 241s TopHits: 1.00*sqrtN close=default refresh=0.80 241s ML Model: Jones-Taylor-Thorton, CAT approximation with 20 rate categories 241s Initial topology in 0.07 seconds 241s Refining topology: 31 rounds ME-NNIs, 2 rounds ME-SPRs, 15 rounds ML-NNIs 241s 0.10 seconds: ME NNI round 5 of 31, 47 of 202 splits, 2 changes (max delta 0.023) 241s 0.23 seconds: SPR round 1 of 2, 201 of 406 nodes 242s 0.35 seconds: SPR round 1 of 2, 401 of 406 nodes 242s 0.50 seconds: SPR round 2 of 2, 201 of 406 nodes 242s 0.65 seconds: SPR round 2 of 2, 401 of 406 nodes 242s Total branch-length 85.602 after 0.68 sec 242s 0.77 seconds: ML Lengths 101 of 202 splits 242s 1.02 seconds: ML NNI round 1 of 15, 101 of 202 splits, 18 changes (max delta 9.023) 242s 1.21 seconds: ML NNI round 1 of 15, 201 of 202 splits, 34 changes (max delta 10.114) 242s ML-NNI round 1: LogLk = -60072.236 NNIs 35 max delta 10.11 Time 1.22 243s 1.31 seconds: Site likelihoods with rate category 5 of 20 243s 1.42 seconds: Site likelihoods with rate category 11 of 20 243s 1.52 seconds: Site likelihoods with rate category 17 of 20 243s Switched to using 20 rate categories (CAT approximation) 243s Rate categories were divided by 1.107 so that average rate = 1.0 243s CAT-based log-likelihoods may not be comparable across runs 243s Use -gamma for approximate but comparable Gamma(20) log-likelihoods 243s 1.79 seconds: ML NNI round 2 of 15, 101 of 202 splits, 8 changes (max delta 11.434) 243s 1.99 seconds: ML NNI round 2 of 15, 201 of 202 splits, 14 changes (max delta 11.434) 243s ML-NNI round 2: LogLk = -57877.769 NNIs 15 max delta 11.43 Time 2.00 243s 2.23 seconds: ML NNI round 3 of 15, 101 of 202 splits, 2 changes (max delta 0.026) 244s ML-NNI round 3: LogLk = -57873.299 NNIs 3 max delta 1.04 Time 2.30 244s 2.44 seconds: ML NNI round 4 of 15, 69 of 202 splits, 0 changes 244s ML-NNI round 4: LogLk = -57873.241 NNIs 0 max delta 0.00 Time 2.45 244s Turning off heuristics for final round of ML NNIs (converged) 244s 2.62 seconds: ML NNI round 5 of 15, 101 of 202 splits, 0 changes 244s 2.81 seconds: ML NNI round 5 of 15, 201 of 202 splits, 0 changes 244s ML-NNI round 5: LogLk = -57871.301 NNIs 0 max delta 0.00 Time 2.81 (final) 244s 2.92 seconds: ML Lengths 101 of 202 splits 244s 3.03 seconds: ML Lengths 201 of 202 splits 244s Optimize all lengths: LogLk = -57839.689 Time 3.04 245s 3.35 seconds: ML split tests for 100 of 201 internal splits 245s 3.66 seconds: ML split tests for 200 of 201 internal splits 245s Total time: 3.67 seconds Unique: 204/204 Bad splits: 0/201 245s TreeCompleted 245s autopkgtest [17:22:31]: test run-unit-test: -----------------------] 246s autopkgtest [17:22:32]: test run-unit-test: - - - - - - - - - - results - - - - - - - - - - 246s run-unit-test PASS 246s autopkgtest [17:22:32]: @@@@@@@@@@@@@@@@@@@@ summary 246s run-unit-test PASS 264s nova [W] Using flock in prodstack6-arm64 264s flock: timeout while waiting to get lock 264s Creating nova instance adt-plucky-arm64-veryfasttree-20250315-171825-juju-7f2275-prod-proposed-migration-environment-2-7135d202-5a1e-417e-8db2-0f99d708b672 from image adt/ubuntu-plucky-arm64-server-20250315.img (UUID bd6e766c-b51f-4b53-86d6-23aa4d18f524)... 264s nova [W] Timed out waiting for 0b9ed89f-17ea-4605-885c-eae24858b0b2 to get deleted.