0s autopkgtest [15:59:53]: starting date and time: 2024-03-22 15:59:53+0000 0s autopkgtest [15:59:53]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [15:59:53]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.hgj14b8v/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:sphinx --apt-upgrade ppft --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=sphinx/7.2.6-6 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-ppc64el-14.secgroup --name adt-noble-ppc64el-ppft-20240322-155953-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-ppc64el-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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 108s autopkgtest [16:01:41]: testbed dpkg architecture: ppc64el 108s autopkgtest [16:01:41]: testbed apt version: 2.7.12 108s autopkgtest [16:01:41]: @@@@@@@@@@@@@@@@@@@@ test bed setup 109s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 109s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB] 109s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3807 kB] 110s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 110s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [54.0 kB] 110s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [670 kB] 110s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 110s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 110s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 110s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4100 kB] 110s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 110s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [58.3 kB] 110s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 114s Fetched 9323 kB in 2s (4105 kB/s) 114s Reading package lists... 116s Reading package lists... 116s Building dependency tree... 116s Reading state information... 117s Calculating upgrade... 117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 118s sh: Attempting to set up Debian/Ubuntu apt sources automatically 118s sh: Distribution appears to be Ubuntu 119s Reading package lists... 119s Building dependency tree... 119s Reading state information... 119s eatmydata is already the newest version (131-1). 119s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 119s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s dbus is already the newest version (1.14.10-4ubuntu1). 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s rng-tools-debian is already the newest version (2.4). 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s The following packages will be REMOVED: 120s cloud-init* python3-configobj* python3-debconf* 121s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 121s After this operation, 3256 kB disk space will be freed. 121s (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 ... 70156 files and directories currently installed.) 121s Removing cloud-init (24.1.2-0ubuntu1) ... 121s Removing python3-configobj (5.0.8-3) ... 121s Removing python3-debconf (1.5.86) ... 121s Processing triggers for man-db (2.12.0-3) ... 122s (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 ... 69767 files and directories currently installed.) 122s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 123s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 123s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 123s invoke-rc.d: policy-rc.d denied execution of try-restart. 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s linux-generic is already the newest version (6.8.0-11.11+1). 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 124s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 124s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 126s Reading package lists... 126s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s Calculating upgrade... 127s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 128s autopkgtest [16:02:01]: rebooting testbed after setup commands that affected boot 171s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 177s autopkgtest [16:02:50]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 180s autopkgtest [16:02:53]: @@@@@@@@@@@@@@@@@@@@ apt-source ppft 182s Get:1 http://ftpmaster.internal/ubuntu noble/universe ppft 1.7.6.8+dfsg-1 (dsc) [3087 B] 182s Get:2 http://ftpmaster.internal/ubuntu noble/universe ppft 1.7.6.8+dfsg-1 (tar) [111 kB] 182s Get:3 http://ftpmaster.internal/ubuntu noble/universe ppft 1.7.6.8+dfsg-1 (diff) [4172 B] 182s gpgv: Signature made Wed Jan 31 00:18:28 2024 UTC 182s gpgv: using RSA key 7E7729476D87D6F11D91ACCBC293E7B461825ACE 182s gpgv: Can't check signature: No public key 182s dpkg-source: warning: cannot verify inline signature for ./ppft_1.7.6.8+dfsg-1.dsc: no acceptable signature found 182s autopkgtest [16:02:55]: testing package ppft version 1.7.6.8+dfsg-1 183s autopkgtest [16:02:56]: build not needed 183s autopkgtest [16:02:56]: test pytest: preparing testbed 184s Reading package lists... 185s Building dependency tree... 185s Reading state information... 185s Starting pkgProblemResolver with broken count: 0 185s Starting 2 pkgProblemResolver with broken count: 0 185s Done 185s The following additional packages will be installed: 185s python3-all python3-ppft 185s The following NEW packages will be installed: 185s autopkgtest-satdep python3-all python3-ppft 185s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 185s Need to get 24.8 kB/25.5 kB of archives. 186s After this operation, 165 kB of additional disk space will be used. 186s Get:1 /tmp/autopkgtest.5SqqNg/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [712 B] 186s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-all ppc64el 3.12.1-0ubuntu2 [904 B] 186s Get:3 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-ppft all 1.7.6.8+dfsg-1 [23.9 kB] 186s Fetched 24.8 kB in 0s (84.6 kB/s) 186s Selecting previously unselected package python3-all. 186s (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 ... 69712 files and directories currently installed.) 186s Preparing to unpack .../python3-all_3.12.1-0ubuntu2_ppc64el.deb ... 186s Unpacking python3-all (3.12.1-0ubuntu2) ... 186s Selecting previously unselected package python3-ppft. 186s Preparing to unpack .../python3-ppft_1.7.6.8+dfsg-1_all.deb ... 186s Unpacking python3-ppft (1.7.6.8+dfsg-1) ... 186s Selecting previously unselected package autopkgtest-satdep. 186s Preparing to unpack .../1-autopkgtest-satdep.deb ... 186s Unpacking autopkgtest-satdep (0) ... 186s Setting up python3-ppft (1.7.6.8+dfsg-1) ... 186s Setting up python3-all (3.12.1-0ubuntu2) ... 186s Setting up autopkgtest-satdep (0) ... 186s Processing triggers for man-db (2.12.0-3) ... 189s (Reading database ... 69746 files and directories currently installed.) 189s Removing autopkgtest-satdep (0) ... 190s autopkgtest [16:03:03]: test pytest: [----------------------- 190s Testing with python3.12: 192s Usage: python sum_primes.py [ncpus] 192s [ncpus] - the number of workers to run in parallel, 192s if omitted it will be set to the number of processors in the system 192s Starting pp with 1 workers 192s Sum of primes below 100 is 1060 192s Sum of primes below 100000 is 454396537 192s Sum of primes below 100100 is 454996777 192s Sum of primes below 100200 is 455898156 192s Sum of primes below 100300 is 456700218 192s Sum of primes below 100400 is 457603451 192s Sum of primes below 100500 is 458407033 192s Sum of primes below 100600 is 459412387 192s Sum of primes below 100700 is 460217613 192s Job execution statistics: 192s job count | % of all jobs | job time sum | time per job | job server 192s 9 | 100.00 | 1.5917 | 0.176860 | local 192s Time elapsed since server creation 1.5951752662658691 192s 0 active tasks, 1 cores 192s 196s .Usage: python quicksort.py [ncpus] 196s [ncpus] - the number of workers to run in parallel, 196s if omitted it will be set to the number of processors in the system 196s 196s Starting pp with 1 workers 196s first 30 numbers in increasing order: [0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 2, 2, 2, 2, 2, 2, 2, 2] 196s Job execution statistics: 196s job count | % of all jobs | job time sum | time per job | job server 196s 32 | 100.00 | 3.0991 | 0.096848 | local 196s Time elapsed since server creation 3.7423510551452637 196s 0 active tasks, 1 cores 196s 197s .Usage: python sum_primes.py [ncpus] 197s [ncpus] - the number of workers to run in parallel, 197s if omitted it will be set to the number of processors in the system 197s 197s Starting pp with 1 workers 197s Sum of primes below 100 is 1060 197s Sum of primes below 100000 is 454396537 197s Sum of primes below 100100 is 454996777 197s Sum of primes below 100200 is 455898156 197s Sum of primes below 100300 is 456700218 197s Sum of primes below 100400 is 457603451 197s Sum of primes below 100500 is 458407033 197s Sum of primes below 100600 is 459412387 197s Sum of primes below 100700 is 460217613 197s Job execution statistics: 197s job count | % of all jobs | job time sum | time per job | job server 197s 9 | 100.00 | 1.5620 | 0.173556 | local 197s Time elapsed since server creation 1.5643787384033203 197s 0 active tasks, 1 cores 197s 202s .Usage: python reverse_md5.py [ncpus] 202s [ncpus] - the number of workers to run in parallel, 202s if omitted it will be set to the number of processors in the system 202s 202s Starting ppft with 1 workers 202s hash = 48ae4d2d384dcc470e471f4169daa96a 202s Reverse md5 for 48ae4d2d384dcc470e471f4169daa96a is 1829182 202s Job execution statistics: 202s job count | % of all jobs | job time sum | time per job | job server 202s 119 | 100.00 | 3.9052 | 0.032817 | local 202s Time elapsed since server creation 3.9252541065216064 202s 1 active tasks, 1 cores 202s 216s .Usage: python dynamic_ncpus.py 216s 216s Starting pp with 1 workers 216s Partial sum is 0.693147205559988 | diff = -2.5000042702671976e-08 216s Time elapsed: 2.2952589988708496 s 216s 216s Starting pp with 2 workers 216s Partial sum is 0.693147205559988 | diff = -2.5000042702671976e-08 216s Time elapsed: 2.3475019931793213 s 216s 216s Starting pp with 4 workers 216s Partial sum is 0.693147205559988 | diff = -2.5000042702671976e-08 216s Time elapsed: 2.2885658740997314 s 216s 216s Starting pp with 8 workers 216s Partial sum is 0.693147205559988 | diff = -2.5000042702671976e-08 216s Time elapsed: 2.2720935344696045 s 216s 216s Starting pp with 16 workers 216s Partial sum is 0.693147205559988 | diff = -2.5000042702671976e-08 216s Time elapsed: 2.2919602394104004 s 216s 216s Starting pp with 1 workers 216s Partial sum is 0.693147205559988 | diff = -2.5000042702671976e-08 216s Time elapsed: 2.2894527912139893 s 216s 216s Job execution statistics: 216s job count | % of all jobs | job time sum | time per job | job server 216s 384 | 100.00 | 68.1768 | 0.177544 | local 216s Time elapsed since server creation 14.459666967391968 216s 0 active tasks, 1 cores 216s 219s .Usage: python callback.py [ncpus] 219s [ncpus] - the number of workers to run in parallel, 219s if omitted it will be set to the number of processors in the system 219s 219s Starting pp with 1 workers 219s Partial sum is 0.6931472055599898 | diff = -2.5000044479028816e-08 219s Job execution statistics: 219s job count | % of all jobs | job time sum | time per job | job server 219s 128 | 100.00 | 2.3085 | 0.018035 | local 219s Time elapsed since server creation 2.325087070465088 219s 0 active tasks, 1 cores 219s 221s .Usage: python auto_diff.py [ncpus] 221s [ncpus] - the number of workers to run in parallel, 221s if omitted it will be set to the number of processors in the system 221s 221s Starting pp with 1 workers 221s t_log(1.000000) = 0.000000, t_log'(1.000000) = 1.000000 221s t_log(1.031250) = 0.030772, t_log'(1.031250) = 0.969697 221s t_log(1.062500) = 0.060625, t_log'(1.062500) = 0.941176 221s t_log(1.093750) = 0.089612, t_log'(1.093750) = 0.914286 221s t_log(1.125000) = 0.117783, t_log'(1.125000) = 0.888889 221s t_log(1.156250) = 0.145182, t_log'(1.156250) = 0.864865 221s t_log(1.187500) = 0.171850, t_log'(1.187500) = 0.842105 221s t_log(1.218750) = 0.197826, t_log'(1.218750) = 0.820513 221s t_log(1.250000) = 0.223144, t_log'(1.250000) = 0.800000 221s t_log(1.281250) = 0.247836, t_log'(1.281250) = 0.780488 221s t_log(1.312500) = 0.271934, t_log'(1.312500) = 0.761905 221s t_log(1.343750) = 0.295464, t_log'(1.343750) = 0.744186 221s t_log(1.375000) = 0.318454, t_log'(1.375000) = 0.727273 221s t_log(1.406250) = 0.340927, t_log'(1.406250) = 0.711111 221s t_log(1.437500) = 0.362905, t_log'(1.437500) = 0.695652 221s t_log(1.468750) = 0.384412, t_log'(1.468750) = 0.680851 221s t_log(1.500000) = 0.405465, t_log'(1.500000) = 0.666667 221s t_log(1.531250) = 0.426084, t_log'(1.531250) = 0.653061 221s t_log(1.562500) = 0.446287, t_log'(1.562500) = 0.640000 221s t_log(1.593750) = 0.466090, t_log'(1.593750) = 0.627451 221s t_log(1.625000) = 0.485508, t_log'(1.625000) = 0.615385 221s t_log(1.656250) = 0.504556, t_log'(1.656250) = 0.603774 221s t_log(1.687500) = 0.523248, t_log'(1.687500) = 0.592593 221s t_log(1.718750) = 0.541597, t_log'(1.718750) = 0.581818 221s t_log(1.750000) = 0.559616, t_log'(1.750000) = 0.571429 221s t_log(1.781250) = 0.577315, t_log'(1.781250) = 0.561404 221s t_log(1.812500) = 0.594707, t_log'(1.812500) = 0.551724 221s t_log(1.843750) = 0.611802, t_log'(1.843750) = 0.542373 221s t_log(1.875000) = 0.628609, t_log'(1.875000) = 0.533333 221s t_log(1.906250) = 0.645138, t_log'(1.906250) = 0.524590 221s t_log(1.937500) = 0.661398, t_log'(1.937500) = 0.516129 221s t_log(1.968750) = 0.677399, t_log'(1.968750) = 0.507937 221s Job execution statistics: 221s job count | % of all jobs | job time sum | time per job | job server 221s 32 | 100.00 | 2.0128 | 0.062900 | local 221s Time elapsed since server creation 2.0229523181915283 221s 0 active tasks, 1 cores 221s 221s . 221s autopkgtest [16:03:34]: test pytest: -----------------------] 222s autopkgtest [16:03:35]: test pytest: - - - - - - - - - - results - - - - - - - - - - 222s pytest PASS 222s autopkgtest [16:03:35]: @@@@@@@@@@@@@@@@@@@@ summary 222s pytest PASS 233s Creating nova instance adt-noble-ppc64el-ppft-20240322-155953-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-ppc64el-server-20240322.img (UUID 8499323f-6fcd-4f77-8a53-77410adea6d3)...