0s autopkgtest [09:58:07]: starting date and time: 2026-02-05 09:58:07+0000 0s autopkgtest [09:58:07]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [09:58:07]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.t0j3j4ie/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade python-pyinstrument --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-ppc64el-19.secgroup --name adt-resolute-ppc64el-python-pyinstrument-20260205-095807-juju-7f2275-prod-proposed-migration-environment-15-e3975678-ab0f-49f8-83c7-27adc490eb7e --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-ppc64el-python-pyinstrument-20260205-095807-juju-7f2275-prod-proposed-migration-environment-15-e3975678-ab0f-49f8-83c7-27adc490eb7e from image adt/ubuntu-resolute-ppc64el-server-20260205.img (UUID f866c950-0b62-4023-bac6-0f13279e15ed)... 63s autopkgtest [09:59:10]: testbed dpkg architecture: ppc64el 63s autopkgtest [09:59:10]: testbed apt version: 3.1.14 64s autopkgtest [09:59:11]: @@@@@@@@@@@@@@@@@@@@ test bed setup 64s autopkgtest [09:59:11]: testbed release detected to be: None 65s autopkgtest [09:59:12]: updating testbed package index (apt update) 66s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 66s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 66s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 66s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 66s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB] 66s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 66s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 66s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [228 kB] 66s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [261 kB] 66s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [1514 kB] 67s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [21.9 kB] 67s Fetched 3910 kB in 1s (2877 kB/s) 68s Reading package lists... 69s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 69s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 69s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 69s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 70s Reading package lists... 70s Reading package lists... 70s Building dependency tree... 70s Reading state information... 70s Calculating upgrade... 70s The following packages will be upgraded: 70s libc-bin libc-gconv-modules-extra libc6 locales pollinate 70s python3-referencing sed 70s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s Need to get 8612 kB of archives. 70s After this operation, 0 B of additional disk space will be used. 70s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el sed ppc64el 4.9-2build3 [211 kB] 70s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc-gconv-modules-extra ppc64el 2.42-2ubuntu5 [1448 kB] 71s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc6 ppc64el 2.42-2ubuntu5 [1913 kB] 71s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc-bin ppc64el 2.42-2ubuntu5 [748 kB] 71s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el locales all 2.42-2ubuntu5 [4255 kB] 71s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el pollinate all 4.33-4ubuntu5 [14.0 kB] 71s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 71s dpkg-preconfigure: unable to re-open stdin: No such file or directory 71s Fetched 8612 kB in 0s (17.4 MB/s) 72s (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 ... 82008 files and directories currently installed.) 72s Preparing to unpack .../sed_4.9-2build3_ppc64el.deb ... 72s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 73s Setting up sed (4.9-2build3) ... 73s (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 ... 82008 files and directories currently installed.) 73s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_ppc64el.deb ... 73s Unpacking libc-gconv-modules-extra:ppc64el (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 73s Setting up libc-gconv-modules-extra:ppc64el (2.42-2ubuntu5) ... 73s (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 ... 82008 files and directories currently installed.) 73s Preparing to unpack .../libc6_2.42-2ubuntu5_ppc64el.deb ... 73s Unpacking libc6:ppc64el (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 74s Setting up libc6:ppc64el (2.42-2ubuntu5) ... 74s (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 ... 82008 files and directories currently installed.) 74s Preparing to unpack .../libc-bin_2.42-2ubuntu5_ppc64el.deb ... 74s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 74s Setting up libc-bin (2.42-2ubuntu5) ... 74s (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 ... 82008 files and directories currently installed.) 74s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 74s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 75s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 75s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 75s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 75s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 75s Setting up locales (2.42-2ubuntu5) ... 75s Generating locales (this might take a while)... 77s en_US.UTF-8... done 77s Generation complete. 77s Setting up pollinate (4.33-4ubuntu5) ... 87s Setting up python3-referencing (0.36.2-1ubuntu2) ... 88s Processing triggers for man-db (2.13.1-1) ... 89s Processing triggers for install-info (7.2-5) ... 89s Processing triggers for systemd (259-1ubuntu3) ... 90s autopkgtest [09:59:37]: upgrading testbed (apt dist-upgrade and autopurge) 90s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s Calculating upgrade... 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s Solving dependencies... 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s autopkgtest [09:59:38]: rebooting testbed after setup commands that affected boot 129s autopkgtest [10:00:16]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:45:54 UTC 2026 131s autopkgtest [10:00:18]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pyinstrument 133s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.2+ds-1 (dsc) [2747 B] 133s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.2+ds-1 (tar) [1148 kB] 133s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.2+ds-1 (diff) [7276 B] 133s gpgv: Signature made Sat Jan 24 07:56:12 2026 UTC 133s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 133s gpgv: issuer "c.schoenert@t-online.de" 133s gpgv: Can't check signature: No public key 133s dpkg-source: warning: cannot verify inline signature for ./python-pyinstrument_5.1.2+ds-1.dsc: no acceptable signature found 134s autopkgtest [10:00:21]: testing package python-pyinstrument version 5.1.2+ds-1 135s autopkgtest [10:00:22]: build not needed 138s autopkgtest [10:00:25]: test upstream-tests: preparing testbed 138s Reading package lists... 138s Building dependency tree... 138s Reading state information... 138s Solving dependencies... 138s The following NEW packages will be installed: 138s docutils-common libjson-perl libpython3.14-minimal libpython3.14-stdlib 138s python-pyinstrument-doc python3-alabaster python3-all python3-defusedxml 138s python3-docutils python3-flaky python3-greenlet python3-imagesize 138s python3-iniconfig python3-mdit-py-plugins python3-myst-parser 138s python3-outcome python3-pluggy python3-pyinstrument python3-pytest 138s python3-pytest-asyncio python3-roman-numerals python3-sniffio 138s python3-snowballstemmer python3-sortedcontainers python3-sphinx python3-trio 138s python3.14 python3.14-minimal sgml-base sphinx-common xml-core 139s 0 upgraded, 31 newly installed, 0 to remove and 0 not upgraded. 139s Need to get 10.3 MB of archives. 139s After this operation, 45.7 MB of additional disk space will be used. 139s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-minimal ppc64el 3.14.2-1 [921 kB] 139s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14-minimal ppc64el 3.14.2-1 [2712 kB] 139s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el sgml-base all 1.31+nmu1 [11.0 kB] 139s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el xml-core all 0.19build1 [20.3 kB] 139s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el docutils-common all 0.22.4+dfsg-1 [130 kB] 139s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjson-perl all 4.10000-1 [81.9 kB] 139s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-stdlib ppc64el 3.14.2-1 [2444 kB] 139s Get:8 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python-pyinstrument-doc all 5.1.2+ds-1 [341 kB] 139s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14 ppc64el 3.14.2-1 [816 kB] 139s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-all ppc64el 3.13.9-3 [890 B] 139s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-defusedxml all 0.7.1-3build1 [42.4 kB] 139s Get:12 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-roman-numerals all 4.1.0-1 [8660 B] 139s Get:13 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-docutils all 0.22.4+dfsg-1 [439 kB] 139s Get:14 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-flaky all 3.8.1-4 [16.8 kB] 139s Get:15 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-imagesize all 1.4.1-1build1 [6900 B] 139s Get:16 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-iniconfig all 2.1.0-2 [6962 B] 139s Get:17 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-mdit-py-plugins all 0.5.0-1 [31.5 kB] 139s Get:18 http://ftpmaster.internal/ubuntu resolute/main ppc64el sphinx-common all 8.2.3-12 [657 kB] 139s Get:19 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-alabaster all 0.7.16-0.1build1 [18.6 kB] 139s Get:20 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-snowballstemmer all 3.0.1-1 [65.9 kB] 139s Get:21 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-sphinx all 8.2.3-12 [483 kB] 139s Get:22 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-myst-parser all 4.0.1-1build1 [64.8 kB] 139s Get:23 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-outcome all 1.2.0-2 [6208 B] 139s Get:24 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pluggy all 1.6.0-2 [21.1 kB] 139s Get:25 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pyinstrument ppc64el 5.1.2+ds-1 [119 kB] 139s Get:26 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pytest all 9.0.2-2 [277 kB] 139s Get:27 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pytest-asyncio all 1.3.0-1 [13.8 kB] 139s Get:28 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-sniffio all 1.3.1-1 [7262 B] 139s Get:29 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-sortedcontainers all 2.4.0-2build1 [27.1 kB] 139s Get:30 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-trio all 0.32.0-1 [342 kB] 139s Get:31 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-greenlet ppc64el 3.2.4-3 [190 kB] 140s Fetched 10.3 MB in 1s (10.3 MB/s) 140s Selecting previously unselected package libpython3.14-minimal:ppc64el. 140s (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 ... 82008 files and directories currently installed.) 140s Preparing to unpack .../00-libpython3.14-minimal_3.14.2-1_ppc64el.deb ... 140s Unpacking libpython3.14-minimal:ppc64el (3.14.2-1) ... 140s Selecting previously unselected package python3.14-minimal. 140s Preparing to unpack .../01-python3.14-minimal_3.14.2-1_ppc64el.deb ... 140s Unpacking python3.14-minimal (3.14.2-1) ... 140s Selecting previously unselected package sgml-base. 140s Preparing to unpack .../02-sgml-base_1.31+nmu1_all.deb ... 140s Unpacking sgml-base (1.31+nmu1) ... 140s Selecting previously unselected package xml-core. 140s Preparing to unpack .../03-xml-core_0.19build1_all.deb ... 140s Unpacking xml-core (0.19build1) ... 140s Selecting previously unselected package docutils-common. 140s Preparing to unpack .../04-docutils-common_0.22.4+dfsg-1_all.deb ... 140s Unpacking docutils-common (0.22.4+dfsg-1) ... 140s Selecting previously unselected package libjson-perl. 140s Preparing to unpack .../05-libjson-perl_4.10000-1_all.deb ... 140s Unpacking libjson-perl (4.10000-1) ... 140s Selecting previously unselected package libpython3.14-stdlib:ppc64el. 140s Preparing to unpack .../06-libpython3.14-stdlib_3.14.2-1_ppc64el.deb ... 140s Unpacking libpython3.14-stdlib:ppc64el (3.14.2-1) ... 140s Selecting previously unselected package python-pyinstrument-doc. 140s Preparing to unpack .../07-python-pyinstrument-doc_5.1.2+ds-1_all.deb ... 140s Unpacking python-pyinstrument-doc (5.1.2+ds-1) ... 140s Selecting previously unselected package python3.14. 140s Preparing to unpack .../08-python3.14_3.14.2-1_ppc64el.deb ... 140s Unpacking python3.14 (3.14.2-1) ... 140s Selecting previously unselected package python3-all. 140s Preparing to unpack .../09-python3-all_3.13.9-3_ppc64el.deb ... 140s Unpacking python3-all (3.13.9-3) ... 140s Selecting previously unselected package python3-defusedxml. 140s Preparing to unpack .../10-python3-defusedxml_0.7.1-3build1_all.deb ... 140s Unpacking python3-defusedxml (0.7.1-3build1) ... 140s Selecting previously unselected package python3-roman-numerals. 140s Preparing to unpack .../11-python3-roman-numerals_4.1.0-1_all.deb ... 140s Unpacking python3-roman-numerals (4.1.0-1) ... 140s Selecting previously unselected package python3-docutils. 140s Preparing to unpack .../12-python3-docutils_0.22.4+dfsg-1_all.deb ... 140s Unpacking python3-docutils (0.22.4+dfsg-1) ... 140s Selecting previously unselected package python3-flaky. 140s Preparing to unpack .../13-python3-flaky_3.8.1-4_all.deb ... 140s Unpacking python3-flaky (3.8.1-4) ... 140s Selecting previously unselected package python3-imagesize. 140s Preparing to unpack .../14-python3-imagesize_1.4.1-1build1_all.deb ... 140s Unpacking python3-imagesize (1.4.1-1build1) ... 140s Selecting previously unselected package python3-iniconfig. 140s Preparing to unpack .../15-python3-iniconfig_2.1.0-2_all.deb ... 140s Unpacking python3-iniconfig (2.1.0-2) ... 140s Selecting previously unselected package python3-mdit-py-plugins. 140s Preparing to unpack .../16-python3-mdit-py-plugins_0.5.0-1_all.deb ... 140s Unpacking python3-mdit-py-plugins (0.5.0-1) ... 141s Selecting previously unselected package sphinx-common. 141s Preparing to unpack .../17-sphinx-common_8.2.3-12_all.deb ... 141s Unpacking sphinx-common (8.2.3-12) ... 141s Selecting previously unselected package python3-alabaster. 141s Preparing to unpack .../18-python3-alabaster_0.7.16-0.1build1_all.deb ... 141s Unpacking python3-alabaster (0.7.16-0.1build1) ... 141s Selecting previously unselected package python3-snowballstemmer. 141s Preparing to unpack .../19-python3-snowballstemmer_3.0.1-1_all.deb ... 141s Unpacking python3-snowballstemmer (3.0.1-1) ... 141s Selecting previously unselected package python3-sphinx. 141s Preparing to unpack .../20-python3-sphinx_8.2.3-12_all.deb ... 141s Unpacking python3-sphinx (8.2.3-12) ... 141s Selecting previously unselected package python3-myst-parser. 141s Preparing to unpack .../21-python3-myst-parser_4.0.1-1build1_all.deb ... 141s Unpacking python3-myst-parser (4.0.1-1build1) ... 141s Selecting previously unselected package python3-outcome. 141s Preparing to unpack .../22-python3-outcome_1.2.0-2_all.deb ... 141s Unpacking python3-outcome (1.2.0-2) ... 141s Selecting previously unselected package python3-pluggy. 141s Preparing to unpack .../23-python3-pluggy_1.6.0-2_all.deb ... 141s Unpacking python3-pluggy (1.6.0-2) ... 141s Selecting previously unselected package python3-pyinstrument. 141s Preparing to unpack .../24-python3-pyinstrument_5.1.2+ds-1_ppc64el.deb ... 141s Unpacking python3-pyinstrument (5.1.2+ds-1) ... 141s Selecting previously unselected package python3-pytest. 141s Preparing to unpack .../25-python3-pytest_9.0.2-2_all.deb ... 141s Unpacking python3-pytest (9.0.2-2) ... 141s Selecting previously unselected package python3-pytest-asyncio. 141s Preparing to unpack .../26-python3-pytest-asyncio_1.3.0-1_all.deb ... 141s Unpacking python3-pytest-asyncio (1.3.0-1) ... 141s Selecting previously unselected package python3-sniffio. 141s Preparing to unpack .../27-python3-sniffio_1.3.1-1_all.deb ... 141s Unpacking python3-sniffio (1.3.1-1) ... 141s Selecting previously unselected package python3-sortedcontainers. 141s Preparing to unpack .../28-python3-sortedcontainers_2.4.0-2build1_all.deb ... 141s Unpacking python3-sortedcontainers (2.4.0-2build1) ... 141s Selecting previously unselected package python3-trio. 141s Preparing to unpack .../29-python3-trio_0.32.0-1_all.deb ... 141s Unpacking python3-trio (0.32.0-1) ... 141s Selecting previously unselected package python3-greenlet. 141s Preparing to unpack .../30-python3-greenlet_3.2.4-3_ppc64el.deb ... 141s Unpacking python3-greenlet (3.2.4-3) ... 141s Setting up python3-iniconfig (2.1.0-2) ... 141s Setting up python3-sniffio (1.3.1-1) ... 141s Setting up python3-outcome (1.2.0-2) ... 142s Setting up python3-defusedxml (0.7.1-3build1) ... 142s Setting up python3-alabaster (0.7.16-0.1build1) ... 142s Setting up libpython3.14-minimal:ppc64el (3.14.2-1) ... 142s Setting up python3-mdit-py-plugins (0.5.0-1) ... 142s Setting up python3-sortedcontainers (2.4.0-2build1) ... 142s Setting up python3-flaky (3.8.1-4) ... 142s Setting up python3-pyinstrument (5.1.2+ds-1) ... 143s Setting up python3-trio (0.32.0-1) ... 145s Setting up python3-snowballstemmer (3.0.1-1) ... 145s Setting up python3-greenlet (3.2.4-3) ... 145s Setting up python3-pluggy (1.6.0-2) ... 145s Setting up libjson-perl (4.10000-1) ... 145s Setting up python3-roman-numerals (4.1.0-1) ... 145s Setting up python-pyinstrument-doc (5.1.2+ds-1) ... 145s Setting up sgml-base (1.31+nmu1) ... 145s Setting up python3.14-minimal (3.14.2-1) ... 145s Setting up python3-imagesize (1.4.1-1build1) ... 146s Setting up libpython3.14-stdlib:ppc64el (3.14.2-1) ... 146s Setting up python3-pytest (9.0.2-2) ... 146s Setting up sphinx-common (8.2.3-12) ... 146s Setting up python3-pytest-asyncio (1.3.0-1) ... 146s Setting up xml-core (0.19build1) ... 147s Setting up python3.14 (3.14.2-1) ... 148s Setting up python3-all (3.13.9-3) ... 148s Processing triggers for systemd (259-1ubuntu3) ... 148s Processing triggers for man-db (2.13.1-1) ... 149s Processing triggers for sgml-base (1.31+nmu1) ... 149s Setting up docutils-common (0.22.4+dfsg-1) ... 149s Processing triggers for sgml-base (1.31+nmu1) ... 149s Setting up python3-docutils (0.22.4+dfsg-1) ... 150s Setting up python3-sphinx (8.2.3-12) ... 152s Setting up python3-myst-parser (4.0.1-1build1) ... 153s autopkgtest [10:00:40]: test upstream-tests: [----------------------- 153s 153s *************************** 153s *** Testing with python3.14 153s *************************** 153s 153s Content of current working folder: 153s 153s total 12 153s drwxr-xr-x 3 ubuntu ubuntu 4096 Feb 5 10:00 . 153s drwxrwxrwx 5 root root 4096 Feb 5 10:00 .. 153s drwxrwxr-x 3 ubuntu ubuntu 4096 Jan 4 18:25 test 153s Running tests... 153s 154s ============================= test session starts ============================== 154s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.14 154s cachedir: .pytest_cache 154s rootdir: /tmp/autopkgtest.QavUsf/autopkgtest_tmp 154s plugins: asyncio-1.3.0, flaky-3.8.1, typeguard-4.4.4 154s asyncio: mode=Mode.STRICT, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function 154s collecting ... collected 131 items / 8 deselected / 123 selected 154s 154s test/low_level/test_context.py::test_context_type[setstatprofile0] PASSED [ 0%] 154s test/low_level/test_context.py::test_context_type[setstatprofile1] PASSED [ 1%] 154s test/low_level/test_context.py::test_context_tracking[setstatprofile0] PASSED [ 2%] 154s test/low_level/test_context.py::test_context_tracking[setstatprofile1] PASSED [ 3%] 154s test/low_level/test_custom_timer.py::test_increment[setstatprofile0] PASSED [ 4%] 154s test/low_level/test_custom_timer.py::test_increment[setstatprofile1] PASSED [ 4%] 154s test/low_level/test_floatclock.py::test_floatclock PASSED [ 5%] 154s test/low_level/test_floatclock.py::test_is_in_seconds PASSED [ 6%] 154s test/low_level/test_frame_info.py::test_frame_info PASSED [ 7%] 154s test/low_level/test_frame_info.py::test_frame_info_hide_true PASSED [ 8%] 154s test/low_level/test_frame_info.py::test_frame_info_hide_false PASSED [ 8%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_method] PASSED [ 9%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method] PASSED [ 10%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_with_cell_variable] PASSED [ 11%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method_where_cls_is_reassigned] PASSED [ 12%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_method] PASSED [ 13%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method] PASSED [ 13%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_with_cell_variable] PASSED [ 14%] 154s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method_where_cls_is_reassigned] PASSED [ 15%] 155s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile0] PASSED [ 16%] 156s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile1] PASSED [ 17%] 157s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile0] PASSED [ 17%] 158s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile1] PASSED [ 18%] 158s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile0] PASSED [ 19%] 158s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile1] PASSED [ 20%] 159s test/low_level/test_threaded.py::test_threaded PASSED [ 21%] 159s test/low_level/test_timing_thread.py::test PASSED [ 21%] 159s test/low_level/test_timing_thread.py::test_max_subscribers PASSED [ 22%] 159s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation0] PASSED [ 23%] 160s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation1] PASSED [ 24%] 160s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation0] PASSED [ 25%] 160s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation1] PASSED [ 26%] 160s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation0] PASSED [ 26%] 160s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation1] PASSED [ 27%] 160s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation0] PASSED [ 28%] 161s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation1] PASSED [ 29%] 161s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation0] PASSED [ 30%] 161s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation1] PASSED [ 30%] 161s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation0] PASSED [ 31%] 161s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation1] PASSED [ 32%] 162s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation0] PASSED [ 33%] 162s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation1] PASSED [ 34%] 162s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation0] PASSED [ 34%] 162s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation1] PASSED [ 35%] 162s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation0] PASSED [ 36%] 163s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation1] PASSED [ 37%] 163s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation0] PASSED [ 38%] 163s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation1] PASSED [ 39%] 163s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation0] PASSED [ 39%] 163s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation1] PASSED [ 40%] 163s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation0] PASSED [ 41%] 163s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation1] PASSED [ 42%] 164s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation0] PASSED [ 43%] 164s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation1] PASSED [ 43%] 164s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation0] PASSED [ 44%] 164s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation1] PASSED [ 45%] 164s test/test_cmdline_main.py::test_renderer_option PASSED [ 46%] 164s test/test_cmdline_main.py::test_json_renderer_option PASSED [ 47%] 164s test/test_cmdline_main.py::test_dotted_renderer_option PASSED [ 47%] 164s test/test_context_manager.py::test_profile_context_decorator PASSED [ 48%] 164s test/test_context_manager.py::test_profile_context_manager PASSED [ 49%] 164s test/test_ipython_magic.py::test_magics SKIPPED (requires --only-ipy...) [ 50%] 164s test/test_ipython_magic.py::test_magic_empty_line SKIPPED (requires ...) [ 51%] 164s test/test_ipython_magic.py::test_magic_no_variable_expansion SKIPPED [ 52%] 164s test/test_ipython_magic.py::test_pyinstrument_handles_interrupt_silently SKIPPED [ 52%] 164s test/test_ipython_magic.py::test_async_cell_with_pyinstrument SKIPPED [ 53%] 164s test/test_overflow.py::test_console PASSED [ 54%] 165s test/test_overflow.py::test_html PASSED [ 55%] 165s test/test_overflow.py::test_json PASSED [ 56%] 165s test/test_overflow.py::test_speedscope PASSED [ 56%] 165s test/test_overflow.py::test_prof PASSED [ 57%] 165s test/test_processors.py::test_frame_passthrough_none PASSED [ 58%] 165s test/test_processors.py::test_remove_importlib PASSED [ 59%] 165s test/test_processors.py::test_remove_hidden PASSED [ 60%] 165s test/test_processors.py::test_merge_consecutive_self_time PASSED [ 60%] 165s test/test_processors.py::test_aggregate_repeated_calls PASSED [ 61%] 165s test/test_processors.py::test_remove_irrelevant_nodes PASSED [ 62%] 165s test/test_processors.py::test_remove_unnecessary_self_time_nodes PASSED [ 63%] 165s test/test_processors.py::test_group_library_frames_processor PASSED [ 64%] 165s test/test_profiler.py::test_collapses_multiple_calls_by_default PASSED [ 65%] 165s test/test_profiler.py::test_profiler_retains_multiple_calls PASSED [ 65%] 165s test/test_profiler.py::test_two_functions PASSED [ 66%] 165s test/test_profiler.py::test_class_methods PASSED [ 67%] 165s test/test_profiler.py::test_context_manager PASSED [ 68%] 165s test/test_profiler.py::test_json_output PASSED [ 69%] 165s test/test_profiler.py::test_speedscope_output PASSED [ 69%] 165s test/test_profiler.py::test_empty_profile PASSED [ 70%] 165s test/test_profiler.py::test_state_management PASSED [ 71%] 165s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[print-ConsoleRenderer] PASSED [ 72%] 165s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_text-ConsoleRenderer] PASSED [ 73%] 165s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_html-HTMLRenderer] PASSED [ 73%] 165s test/test_profiler.py::test_profiler_raises_on_double_subscribe PASSED [ 74%] 165s test/test_profiler_async.py::test_sleep PASSED [ 75%] 165s test/test_profiler_async.py::test_sleep_trio PASSED [ 76%] 165s test/test_profiler_async.py::test_profiler_task_isolation[asyncio] PASSED [ 77%] 165s test/test_profiler_async.py::test_profiler_task_isolation[trio] PASSED [ 78%] 165s test/test_profiler_async.py::test_greenlet PASSED [ 78%] 165s test/test_profiler_async.py::test_strict_with_greenlet PASSED [ 79%] 165s test/test_pstats_renderer.py::test_pstats_renderer PASSED [ 80%] 165s test/test_pstats_renderer.py::test_round_trip_encoding_of_binary_data PASSED [ 81%] 165s test/test_pstats_renderer.py::test_sum_of_tottime PASSED [ 82%] 165s test/test_renderers.py::test_empty_profile[ConsoleRenderer] PASSED [ 82%] 165s test/test_renderers.py::test_empty_profile[JSONRenderer] PASSED [ 83%] 165s test/test_renderers.py::test_empty_profile[PstatsRenderer] PASSED [ 84%] 165s test/test_renderers.py::test_empty_profile[SpeedscopeRenderer] PASSED [ 85%] 165s test/test_renderers.py::test_timeline_doesnt_crash[ConsoleRenderer] PASSED [ 86%] 165s test/test_renderers.py::test_timeline_doesnt_crash[JSONRenderer] PASSED [ 86%] 165s test/test_renderers.py::test_timeline_doesnt_crash[PstatsRenderer] PASSED [ 87%] 165s test/test_renderers.py::test_timeline_doesnt_crash[SpeedscopeRenderer] PASSED [ 88%] 165s test/test_renderers.py::test_show_all_doesnt_crash[ConsoleRenderer] PASSED [ 89%] 165s test/test_renderers.py::test_show_all_doesnt_crash[JSONRenderer] PASSED [ 90%] 165s test/test_renderers.py::test_show_all_doesnt_crash[PstatsRenderer] PASSED [ 91%] 165s test/test_renderers.py::test_show_all_doesnt_crash[SpeedscopeRenderer] PASSED [ 91%] 165s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[self] PASSED [ 92%] 165s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[total] PASSED [ 93%] 165s test/test_renderers.py::test_html_renderer_resampling PASSED [ 94%] 165s test/test_stack_sampler.py::test_create PASSED [ 95%] 165s test/test_stack_sampler.py::test_get_samples PASSED [ 95%] 165s test/test_stack_sampler.py::test_multiple_samplers PASSED [ 96%] 165s test/test_stack_sampler.py::test_multiple_samplers_async_error PASSED [ 97%] 165s test/test_stack_sampler.py::test_multiple_contexts PASSED [ 98%] 165s test/test_stack_sampler.py::test_same_callback_twice_error PASSED [ 99%] 165s test/test_threading.py::test_profiler_access_from_multiple_threads PASSED [100%] 165s 165s ================ 118 passed, 5 skipped, 8 deselected in 11.46s ================= 165s 165s *************************** 165s *** Testing with python3.13 165s *************************** 165s 165s Content of current working folder: 165s 165s total 12 165s drwxr-xr-x 3 ubuntu ubuntu 4096 Feb 5 10:00 . 165s drwxrwxrwx 5 root root 4096 Feb 5 10:00 .. 165s drwxrwxr-x 4 ubuntu ubuntu 4096 Feb 5 10:00 test 165s Running tests... 165s 166s ============================= test session starts ============================== 166s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.13 166s cachedir: .pytest_cache 166s rootdir: /tmp/autopkgtest.QavUsf/autopkgtest_tmp 166s plugins: asyncio-1.3.0, flaky-3.8.1, typeguard-4.4.4 166s asyncio: mode=Mode.STRICT, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function 166s collecting ... collected 131 items / 8 deselected / 123 selected 166s 166s test/low_level/test_context.py::test_context_type[setstatprofile0] PASSED [ 0%] 166s test/low_level/test_context.py::test_context_type[setstatprofile1] PASSED [ 1%] 166s test/low_level/test_context.py::test_context_tracking[setstatprofile0] PASSED [ 2%] 166s test/low_level/test_context.py::test_context_tracking[setstatprofile1] PASSED [ 3%] 166s test/low_level/test_custom_timer.py::test_increment[setstatprofile0] PASSED [ 4%] 166s test/low_level/test_custom_timer.py::test_increment[setstatprofile1] PASSED [ 4%] 166s test/low_level/test_floatclock.py::test_floatclock PASSED [ 5%] 166s test/low_level/test_floatclock.py::test_is_in_seconds PASSED [ 6%] 166s test/low_level/test_frame_info.py::test_frame_info PASSED [ 7%] 166s test/low_level/test_frame_info.py::test_frame_info_hide_true PASSED [ 8%] 166s test/low_level/test_frame_info.py::test_frame_info_hide_false PASSED [ 8%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_method] PASSED [ 9%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method] PASSED [ 10%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_with_cell_variable] PASSED [ 11%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method_where_cls_is_reassigned] PASSED [ 12%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_method] PASSED [ 13%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method] PASSED [ 13%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_with_cell_variable] PASSED [ 14%] 166s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method_where_cls_is_reassigned] PASSED [ 15%] 169s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile0] PASSED [ 16%] 169s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile1] PASSED [ 17%] 169s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile0] PASSED [ 17%] 170s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile1] PASSED [ 18%] 170s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile0] PASSED [ 19%] 170s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile1] PASSED [ 20%] 172s test/low_level/test_threaded.py::test_threaded PASSED [ 21%] 172s test/low_level/test_timing_thread.py::test PASSED [ 21%] 172s test/low_level/test_timing_thread.py::test_max_subscribers PASSED [ 22%] 172s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation0] PASSED [ 23%] 172s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation1] PASSED [ 24%] 172s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation0] PASSED [ 25%] 172s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation1] PASSED [ 26%] 173s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation0] PASSED [ 26%] 173s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation1] PASSED [ 27%] 173s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation0] PASSED [ 28%] 173s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation1] PASSED [ 29%] 173s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation0] PASSED [ 30%] 173s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation1] PASSED [ 30%] 174s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation0] PASSED [ 31%] 174s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation1] PASSED [ 32%] 174s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation0] PASSED [ 33%] 174s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation1] PASSED [ 34%] 174s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation0] PASSED [ 34%] 175s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation1] PASSED [ 35%] 175s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation0] PASSED [ 36%] 175s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation1] PASSED [ 37%] 175s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation0] PASSED [ 38%] 175s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation1] PASSED [ 39%] 175s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation0] PASSED [ 39%] 176s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation1] PASSED [ 40%] 176s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation0] PASSED [ 41%] 176s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation1] PASSED [ 42%] 176s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation0] PASSED [ 43%] 176s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation1] PASSED [ 43%] 176s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation0] PASSED [ 44%] 176s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation1] PASSED [ 45%] 176s test/test_cmdline_main.py::test_renderer_option PASSED [ 46%] 177s test/test_cmdline_main.py::test_json_renderer_option PASSED [ 47%] 177s test/test_cmdline_main.py::test_dotted_renderer_option PASSED [ 47%] 177s test/test_context_manager.py::test_profile_context_decorator PASSED [ 48%] 177s test/test_context_manager.py::test_profile_context_manager PASSED [ 49%] 177s test/test_ipython_magic.py::test_magics SKIPPED (requires --only-ipy...) [ 50%] 177s test/test_ipython_magic.py::test_magic_empty_line SKIPPED (requires ...) [ 51%] 177s test/test_ipython_magic.py::test_magic_no_variable_expansion SKIPPED [ 52%] 177s test/test_ipython_magic.py::test_pyinstrument_handles_interrupt_silently SKIPPED [ 52%] 177s test/test_ipython_magic.py::test_async_cell_with_pyinstrument SKIPPED [ 53%] 177s test/test_overflow.py::test_console PASSED [ 54%] 177s test/test_overflow.py::test_html PASSED [ 55%] 177s test/test_overflow.py::test_json PASSED [ 56%] 177s test/test_overflow.py::test_speedscope PASSED [ 56%] 177s test/test_overflow.py::test_prof PASSED [ 57%] 177s test/test_processors.py::test_frame_passthrough_none PASSED [ 58%] 177s test/test_processors.py::test_remove_importlib PASSED [ 59%] 177s test/test_processors.py::test_remove_hidden PASSED [ 60%] 177s test/test_processors.py::test_merge_consecutive_self_time PASSED [ 60%] 177s test/test_processors.py::test_aggregate_repeated_calls PASSED [ 61%] 177s test/test_processors.py::test_remove_irrelevant_nodes PASSED [ 62%] 177s test/test_processors.py::test_remove_unnecessary_self_time_nodes PASSED [ 63%] 177s test/test_processors.py::test_group_library_frames_processor PASSED [ 64%] 177s test/test_profiler.py::test_collapses_multiple_calls_by_default PASSED [ 65%] 177s test/test_profiler.py::test_profiler_retains_multiple_calls PASSED [ 65%] 177s test/test_profiler.py::test_two_functions PASSED [ 66%] 177s test/test_profiler.py::test_class_methods PASSED [ 67%] 177s test/test_profiler.py::test_context_manager PASSED [ 68%] 177s test/test_profiler.py::test_json_output PASSED [ 69%] 177s test/test_profiler.py::test_speedscope_output PASSED [ 69%] 177s test/test_profiler.py::test_empty_profile PASSED [ 70%] 177s test/test_profiler.py::test_state_management PASSED [ 71%] 177s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[print-ConsoleRenderer] PASSED [ 72%] 177s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_text-ConsoleRenderer] PASSED [ 73%] 177s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_html-HTMLRenderer] PASSED [ 73%] 177s test/test_profiler.py::test_profiler_raises_on_double_subscribe PASSED [ 74%] 177s test/test_profiler_async.py::test_sleep PASSED [ 75%] 177s test/test_profiler_async.py::test_sleep_trio PASSED [ 76%] 177s test/test_profiler_async.py::test_profiler_task_isolation[asyncio] PASSED [ 77%] 177s test/test_profiler_async.py::test_profiler_task_isolation[trio] PASSED [ 78%] 177s test/test_profiler_async.py::test_greenlet PASSED [ 78%] 177s test/test_profiler_async.py::test_strict_with_greenlet PASSED [ 79%] 177s test/test_pstats_renderer.py::test_pstats_renderer PASSED [ 80%] 177s test/test_pstats_renderer.py::test_round_trip_encoding_of_binary_data PASSED [ 81%] 177s test/test_pstats_renderer.py::test_sum_of_tottime PASSED [ 82%] 177s test/test_renderers.py::test_empty_profile[ConsoleRenderer] PASSED [ 82%] 177s test/test_renderers.py::test_empty_profile[JSONRenderer] PASSED [ 83%] 177s test/test_renderers.py::test_empty_profile[PstatsRenderer] PASSED [ 84%] 177s test/test_renderers.py::test_empty_profile[SpeedscopeRenderer] PASSED [ 85%] 177s test/test_renderers.py::test_timeline_doesnt_crash[ConsoleRenderer] PASSED [ 86%] 177s test/test_renderers.py::test_timeline_doesnt_crash[JSONRenderer] PASSED [ 86%] 177s test/test_renderers.py::test_timeline_doesnt_crash[PstatsRenderer] PASSED [ 87%] 177s test/test_renderers.py::test_timeline_doesnt_crash[SpeedscopeRenderer] PASSED [ 88%] 177s test/test_renderers.py::test_show_all_doesnt_crash[ConsoleRenderer] PASSED [ 89%] 177s test/test_renderers.py::test_show_all_doesnt_crash[JSONRenderer] PASSED [ 90%] 177s test/test_renderers.py::test_show_all_doesnt_crash[PstatsRenderer] PASSED [ 91%] 177s test/test_renderers.py::test_show_all_doesnt_crash[SpeedscopeRenderer] PASSED [ 91%] 177s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[self] PASSED [ 92%] 177s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[total] PASSED [ 93%] 177s test/test_renderers.py::test_html_renderer_resampling PASSED [ 94%] 177s test/test_stack_sampler.py::test_create PASSED [ 95%] 177s test/test_stack_sampler.py::test_get_samples PASSED [ 95%] 177s test/test_stack_sampler.py::test_multiple_samplers PASSED [ 96%] 177s test/test_stack_sampler.py::test_multiple_samplers_async_error PASSED [ 97%] 177s test/test_stack_sampler.py::test_multiple_contexts PASSED [ 98%] 177s test/test_stack_sampler.py::test_same_callback_twice_error PASSED [ 99%] 178s test/test_threading.py::test_profiler_access_from_multiple_threads PASSED [100%] 178s 178s ================ 118 passed, 5 skipped, 8 deselected in 11.93s ================= 178s 178s autopkgtest [10:01:05]: test upstream-tests: -----------------------] 179s autopkgtest [10:01:06]: test upstream-tests: - - - - - - - - - - results - - - - - - - - - - 179s upstream-tests PASS 180s autopkgtest [10:01:07]: test autodep8-python3: preparing testbed 214s Creating nova instance adt-resolute-ppc64el-python-pyinstrument-20260205-095807-juju-7f2275-prod-proposed-migration-environment-15-e3975678-ab0f-49f8-83c7-27adc490eb7e from image adt/ubuntu-resolute-ppc64el-server-20260205.img (UUID f866c950-0b62-4023-bac6-0f13279e15ed)... 272s autopkgtest [10:02:39]: testbed dpkg architecture: ppc64el 273s autopkgtest [10:02:40]: testbed apt version: 3.1.14 273s autopkgtest [10:02:40]: @@@@@@@@@@@@@@@@@@@@ test bed setup 273s autopkgtest [10:02:40]: testbed release detected to be: resolute 274s autopkgtest [10:02:41]: updating testbed package index (apt update) 274s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 274s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 274s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 274s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 274s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1696 kB] 274s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 274s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [227 kB] 274s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 274s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [261 kB] 275s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [1492 kB] 275s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [21.9 kB] 275s Fetched 3863 kB in 1s (5153 kB/s) 276s Reading package lists... 276s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 276s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 276s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 276s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 277s Reading package lists... 277s Reading package lists... 277s Building dependency tree... 277s Reading state information... 278s Calculating upgrade... 278s The following packages will be upgraded: 278s libc-bin libc-gconv-modules-extra libc6 locales pollinate 278s python3-referencing sed 278s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 278s Need to get 8612 kB of archives. 278s After this operation, 0 B of additional disk space will be used. 278s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el sed ppc64el 4.9-2build3 [211 kB] 278s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc-gconv-modules-extra ppc64el 2.42-2ubuntu5 [1448 kB] 278s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc6 ppc64el 2.42-2ubuntu5 [1913 kB] 278s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libc-bin ppc64el 2.42-2ubuntu5 [748 kB] 278s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el locales all 2.42-2ubuntu5 [4255 kB] 278s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el pollinate all 4.33-4ubuntu5 [14.0 kB] 278s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 279s dpkg-preconfigure: unable to re-open stdin: No such file or directory 279s Fetched 8612 kB in 1s (14.6 MB/s) 279s (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 ... 82008 files and directories currently installed.) 279s Preparing to unpack .../sed_4.9-2build3_ppc64el.deb ... 279s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 279s Setting up sed (4.9-2build3) ... 279s (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 ... 82008 files and directories currently installed.) 279s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_ppc64el.deb ... 279s Unpacking libc-gconv-modules-extra:ppc64el (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 279s Setting up libc-gconv-modules-extra:ppc64el (2.42-2ubuntu5) ... 279s (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 ... 82008 files and directories currently installed.) 279s Preparing to unpack .../libc6_2.42-2ubuntu5_ppc64el.deb ... 280s Unpacking libc6:ppc64el (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 280s Setting up libc6:ppc64el (2.42-2ubuntu5) ... 280s (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 ... 82008 files and directories currently installed.) 280s Preparing to unpack .../libc-bin_2.42-2ubuntu5_ppc64el.deb ... 280s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 280s Setting up libc-bin (2.42-2ubuntu5) ... 280s (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 ... 82008 files and directories currently installed.) 280s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 280s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 281s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 281s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 281s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 281s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 281s Setting up locales (2.42-2ubuntu5) ... 282s Generating locales (this might take a while)... 283s en_US.UTF-8... done 283s Generation complete. 283s Setting up pollinate (4.33-4ubuntu5) ... 294s Setting up python3-referencing (0.36.2-1ubuntu2) ... 294s Processing triggers for man-db (2.13.1-1) ... 296s Processing triggers for install-info (7.2-5) ... 296s Processing triggers for systemd (259-1ubuntu3) ... 297s autopkgtest [10:03:04]: upgrading testbed (apt dist-upgrade and autopurge) 297s Reading package lists... 297s Building dependency tree... 297s Reading state information... 297s Calculating upgrade... 298s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 298s Reading package lists... 298s Building dependency tree... 298s Reading state information... 298s Solving dependencies... 298s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 298s autopkgtest [10:03:05]: rebooting testbed after setup commands that affected boot 338s Reading package lists... 338s Building dependency tree... 338s Reading state information... 338s Solving dependencies... 339s The following NEW packages will be installed: 339s libpython3.14-minimal libpython3.14-stdlib python3-all python3-pyinstrument 339s python3.14 python3.14-minimal 339s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded. 339s Need to get 7013 kB of archives. 339s After this operation, 28.3 MB of additional disk space will be used. 339s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-minimal ppc64el 3.14.2-1 [921 kB] 339s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14-minimal ppc64el 3.14.2-1 [2712 kB] 339s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-stdlib ppc64el 3.14.2-1 [2444 kB] 340s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14 ppc64el 3.14.2-1 [816 kB] 340s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-all ppc64el 3.13.9-3 [890 B] 340s Get:6 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pyinstrument ppc64el 5.1.2+ds-1 [119 kB] 340s Fetched 7013 kB in 1s (5479 kB/s) 340s Selecting previously unselected package libpython3.14-minimal:ppc64el. 340s (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 ... 82008 files and directories currently installed.) 340s Preparing to unpack .../0-libpython3.14-minimal_3.14.2-1_ppc64el.deb ... 340s Unpacking libpython3.14-minimal:ppc64el (3.14.2-1) ... 340s Selecting previously unselected package python3.14-minimal. 340s Preparing to unpack .../1-python3.14-minimal_3.14.2-1_ppc64el.deb ... 340s Unpacking python3.14-minimal (3.14.2-1) ... 341s Selecting previously unselected package libpython3.14-stdlib:ppc64el. 341s Preparing to unpack .../2-libpython3.14-stdlib_3.14.2-1_ppc64el.deb ... 341s Unpacking libpython3.14-stdlib:ppc64el (3.14.2-1) ... 341s Selecting previously unselected package python3.14. 341s Preparing to unpack .../3-python3.14_3.14.2-1_ppc64el.deb ... 341s Unpacking python3.14 (3.14.2-1) ... 341s Selecting previously unselected package python3-all. 341s Preparing to unpack .../4-python3-all_3.13.9-3_ppc64el.deb ... 341s Unpacking python3-all (3.13.9-3) ... 341s Selecting previously unselected package python3-pyinstrument. 341s Preparing to unpack .../5-python3-pyinstrument_5.1.2+ds-1_ppc64el.deb ... 341s Unpacking python3-pyinstrument (5.1.2+ds-1) ... 341s Setting up libpython3.14-minimal:ppc64el (3.14.2-1) ... 341s Setting up python3-pyinstrument (5.1.2+ds-1) ... 341s Setting up python3.14-minimal (3.14.2-1) ... 342s Setting up libpython3.14-stdlib:ppc64el (3.14.2-1) ... 342s Setting up python3.14 (3.14.2-1) ... 343s Setting up python3-all (3.13.9-3) ... 343s Processing triggers for man-db (2.13.1-1) ... 344s Processing triggers for systemd (259-1ubuntu3) ... 347s autopkgtest [10:03:54]: test autodep8-python3: set -e ; for py in $(py3versions -r 2>/dev/null) ; do cd "$AUTOPKGTEST_TMP" ; echo "Testing with $py:" ; $py -c "import pyinstrument; print(pyinstrument)" ; done 347s autopkgtest [10:03:54]: test autodep8-python3: [----------------------- 347s Testing with python3.14: 347s 347s Testing with python3.13: 347s 347s autopkgtest [10:03:54]: test autodep8-python3: -----------------------] 348s autodep8-python3 PASS (superficial) 348s autopkgtest [10:03:55]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 348s autopkgtest [10:03:55]: @@@@@@@@@@@@@@@@@@@@ summary 348s upstream-tests PASS 348s autodep8-python3 PASS (superficial)