0s autopkgtest [15:17:52]: starting date and time: 2026-01-24 15:17:52+0000 0s autopkgtest [15:17:52]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [15:17:52]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3v5acc_9/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade python-pyinstrument --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.14.2-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-9.secgroup --name adt-resolute-amd64-python-pyinstrument-20260124-151751-juju-7f2275-prod-proposed-migration-environment-2-a0e4e4b2-29c6-4709-8530-b3ccc6922811 --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-amd64-python-pyinstrument-20260124-151751-juju-7f2275-prod-proposed-migration-environment-2-a0e4e4b2-29c6-4709-8530-b3ccc6922811 from image adt/ubuntu-resolute-amd64-server-20260124.img (UUID 46811f22-3700-43f4-953b-ee3c613d71bb)... 85s autopkgtest [15:19:17]: testbed dpkg architecture: amd64 86s autopkgtest [15:19:18]: testbed apt version: 3.1.13 86s autopkgtest [15:19:18]: @@@@@@@@@@@@@@@@@@@@ test bed setup 86s autopkgtest [15:19:18]: testbed release detected to be: None 87s autopkgtest [15:19:19]: updating testbed package index (apt update) 87s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 87s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 87s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 87s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 87s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 87s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 87s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [91.5 kB] 87s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [994 kB] 88s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [113 kB] 88s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [96.0 kB] 88s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3712 B] 88s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 88s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 88s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 88s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [876 kB] 88s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [373 kB] 88s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [24.9 kB] 88s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 88s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [12.4 kB] 88s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [824 B] 89s Fetched 2758 kB in 1s (1944 kB/s) 89s Reading package lists... 90s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 90s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 90s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 90s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 90s Reading package lists... 90s Reading package lists... 90s Building dependency tree... 90s Reading state information... 91s Calculating upgrade... 91s The following packages were automatically installed and are no longer required: 91s python3.13 python3.13-minimal 91s Use 'sudo apt autoremove' to remove them. 91s The following NEW packages will be installed: 91s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 91s The following packages will be upgraded: 91s libpython3-stdlib python3 python3-gdbm python3-minimal rust-coreutils 91s 5 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 91s Need to get 10.4 MB of archives. 91s After this operation, 25.2 MB of additional disk space will be used. 91s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 91s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 92s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 92s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 92s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 93s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 93s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 93s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 rust-coreutils amd64 0.5.0-0ubuntu1 [3628 kB] 93s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 93s dpkg-preconfigure: unable to re-open stdin: No such file or directory 93s Fetched 10.4 MB in 2s (4562 kB/s) 93s Selecting previously unselected package libpython3.14-minimal:amd64. 93s (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 ... 124177 files and directories currently installed.) 93s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 93s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 93s Selecting previously unselected package python3.14-minimal. 93s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 93s Unpacking python3.14-minimal (3.14.2-1) ... 93s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 93s Setting up python3.14-minimal (3.14.2-1) ... 94s (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 ... 124519 files and directories currently installed.) 94s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 94s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 94s Setting up python3-minimal (3.14.2-1) ... 94s (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 ... 124519 files and directories currently installed.) 94s Preparing to unpack .../python3_3.14.2-1_amd64.deb ... 94s running python pre-rtupdate hooks for python3.14... 94s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 94s Selecting previously unselected package libpython3.14-stdlib:amd64. 94s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_amd64.deb ... 94s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 94s Selecting previously unselected package python3.14. 94s Preparing to unpack .../python3.14_3.14.2-1_amd64.deb ... 94s Unpacking python3.14 (3.14.2-1) ... 95s Preparing to unpack .../libpython3-stdlib_3.14.2-1_amd64.deb ... 95s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 95s Preparing to unpack .../rust-coreutils_0.5.0-0ubuntu1_amd64.deb ... 95s Unpacking rust-coreutils (0.5.0-0ubuntu1) over (0.2.2-0ubuntu2) ... 95s Setting up rust-coreutils (0.5.0-0ubuntu1) ... 95s (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 ... 124980 files and directories currently installed.) 95s Preparing to unpack .../python3-gdbm_3.14.2-1_amd64.deb ... 95s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 95s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 95s Setting up python3.14 (3.14.2-1) ... 95s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 95s Setting up python3-gdbm (3.14.2-1) ... 95s Setting up python3 (3.14.2-1) ... 95s running python rtupdate hooks for python3.14... 95s running python post-rtupdate hooks for python3.14... 95s Processing triggers for man-db (2.13.1-1) ... 96s Processing triggers for systemd (259-1ubuntu2) ... 96s autopkgtest [15:19:28]: upgrading testbed (apt dist-upgrade and autopurge) 96s Reading package lists... 96s Building dependency tree... 96s Reading state information... 96s Calculating upgrade... 96s The following packages were automatically installed and are no longer required: 96s python3.13 python3.13-minimal 96s Use 'sudo apt autoremove' to remove them. 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 97s Reading package lists... 97s Building dependency tree... 97s Reading state information... 97s Solving dependencies... 97s The following packages will be REMOVED: 97s python3.13* python3.13-minimal* 97s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 97s After this operation, 7860 kB disk space will be freed. 97s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 124978 files and directories currently installed.) 97s Removing python3.13 (3.13.11-1) ... 97s Removing python3.13-minimal (3.13.11-1) ... 98s Processing triggers for man-db (2.13.1-1) ... 98s Processing triggers for systemd (259-1ubuntu2) ... 98s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 124951 files and directories currently installed.) 98s Purging configuration files for python3.13-minimal (3.13.11-1) ... 100s autopkgtest [15:19:32]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:49:02 UTC 2026 100s autopkgtest [15:19:32]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pyinstrument 102s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.1+ds-2 (dsc) [2748 B] 102s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.1+ds-2 (tar) [1146 kB] 102s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.1+ds-2 (diff) [6852 B] 102s gpgv: Signature made Thu Dec 11 19:02:31 2025 UTC 102s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 102s gpgv: issuer "c.schoenert@t-online.de" 102s gpgv: Can't check signature: No public key 102s dpkg-source: warning: cannot verify inline signature for ./python-pyinstrument_5.1.1+ds-2.dsc: no acceptable signature found 102s autopkgtest [15:19:34]: testing package python-pyinstrument version 5.1.1+ds-2 103s autopkgtest [15:19:35]: build not needed 103s autopkgtest [15:19:35]: test upstream-tests: preparing testbed 103s Reading package lists... 103s Building dependency tree... 103s Reading state information... 103s Solving dependencies... 104s The following NEW packages will be installed: 104s docutils-common libjson-perl python-pyinstrument-doc python3-alabaster 104s python3-all python3-defusedxml python3-docutils python3-flaky 104s python3-greenlet python3-imagesize python3-iniconfig python3-mdit-py-plugins 104s python3-myst-parser python3-outcome python3-pluggy python3-pyinstrument 104s python3-pytest python3-pytest-asyncio python3-roman-numerals python3-sniffio 104s python3-snowballstemmer python3-sortedcontainers python3-sphinx python3-trio 104s python3.13 python3.13-minimal sgml-base sphinx-common xml-core 104s 0 upgraded, 29 newly installed, 0 to remove and 0 not upgraded. 104s Need to get 6587 kB of archives. 104s After this operation, 25.4 MB of additional disk space will be used. 104s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13-minimal amd64 3.13.11-1 [2408 kB] 104s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13 amd64 3.13.11-1 [758 kB] 104s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 sgml-base all 1.31+nmu1 [11.0 kB] 104s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 xml-core all 0.19build1 [20.3 kB] 104s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 docutils-common all 0.22.4+dfsg-1 [130 kB] 104s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libjson-perl all 4.10000-1 [81.9 kB] 104s Get:7 http://ftpmaster.internal/ubuntu resolute/universe amd64 python-pyinstrument-doc all 5.1.1+ds-2 [340 kB] 104s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.14.2-1 [890 B] 104s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-defusedxml all 0.7.1-3build1 [42.4 kB] 104s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-roman-numerals all 4.1.0-1 [8660 B] 104s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-docutils all 0.22.4+dfsg-1 [439 kB] 104s Get:12 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-flaky all 3.8.1-4 [16.8 kB] 104s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-imagesize all 1.4.1-1build1 [6900 B] 104s Get:14 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 2.1.0-2 [6962 B] 104s Get:15 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-mdit-py-plugins all 0.5.0-1 [31.5 kB] 104s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 sphinx-common all 8.2.3-12 [657 kB] 104s Get:17 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-alabaster all 0.7.16-0.1build1 [18.6 kB] 104s Get:18 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-snowballstemmer all 3.0.1-1 [65.9 kB] 104s Get:19 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-sphinx all 8.2.3-12 [483 kB] 104s Get:20 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-myst-parser all 4.0.1-1 [64.9 kB] 104s Get:21 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-outcome all 1.2.0-2 [6208 B] 104s Get:22 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-2 [21.1 kB] 104s Get:23 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pyinstrument amd64 5.1.1+ds-2 [117 kB] 104s Get:24 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest all 9.0.2-2 [277 kB] 104s Get:25 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-asyncio all 1.3.0-1 [13.8 kB] 104s Get:26 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-sniffio all 1.3.1-1 [7262 B] 104s Get:27 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-sortedcontainers all 2.4.0-2build1 [27.1 kB] 104s Get:28 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-trio all 0.32.0-1 [342 kB] 104s Get:29 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-greenlet amd64 3.2.4-3 [185 kB] 104s Fetched 6587 kB in 0s (13.4 MB/s) 104s Selecting previously unselected package python3.13-minimal. 104s (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 ... 124953 files and directories currently installed.) 104s Preparing to unpack .../00-python3.13-minimal_3.13.11-1_amd64.deb ... 104s Unpacking python3.13-minimal (3.13.11-1) ... 104s Selecting previously unselected package python3.13. 104s Preparing to unpack .../01-python3.13_3.13.11-1_amd64.deb ... 104s Unpacking python3.13 (3.13.11-1) ... 104s Selecting previously unselected package sgml-base. 104s Preparing to unpack .../02-sgml-base_1.31+nmu1_all.deb ... 104s Unpacking sgml-base (1.31+nmu1) ... 104s Selecting previously unselected package xml-core. 104s Preparing to unpack .../03-xml-core_0.19build1_all.deb ... 104s Unpacking xml-core (0.19build1) ... 104s Selecting previously unselected package docutils-common. 104s Preparing to unpack .../04-docutils-common_0.22.4+dfsg-1_all.deb ... 104s Unpacking docutils-common (0.22.4+dfsg-1) ... 104s Selecting previously unselected package libjson-perl. 104s Preparing to unpack .../05-libjson-perl_4.10000-1_all.deb ... 104s Unpacking libjson-perl (4.10000-1) ... 104s Selecting previously unselected package python-pyinstrument-doc. 104s Preparing to unpack .../06-python-pyinstrument-doc_5.1.1+ds-2_all.deb ... 104s Unpacking python-pyinstrument-doc (5.1.1+ds-2) ... 104s Selecting previously unselected package python3-all. 104s Preparing to unpack .../07-python3-all_3.14.2-1_amd64.deb ... 104s Unpacking python3-all (3.14.2-1) ... 104s Selecting previously unselected package python3-defusedxml. 104s Preparing to unpack .../08-python3-defusedxml_0.7.1-3build1_all.deb ... 104s Unpacking python3-defusedxml (0.7.1-3build1) ... 104s Selecting previously unselected package python3-roman-numerals. 104s Preparing to unpack .../09-python3-roman-numerals_4.1.0-1_all.deb ... 104s Unpacking python3-roman-numerals (4.1.0-1) ... 104s Selecting previously unselected package python3-docutils. 104s Preparing to unpack .../10-python3-docutils_0.22.4+dfsg-1_all.deb ... 104s Unpacking python3-docutils (0.22.4+dfsg-1) ... 104s Selecting previously unselected package python3-flaky. 104s Preparing to unpack .../11-python3-flaky_3.8.1-4_all.deb ... 104s Unpacking python3-flaky (3.8.1-4) ... 104s Selecting previously unselected package python3-imagesize. 104s Preparing to unpack .../12-python3-imagesize_1.4.1-1build1_all.deb ... 104s Unpacking python3-imagesize (1.4.1-1build1) ... 104s Selecting previously unselected package python3-iniconfig. 104s Preparing to unpack .../13-python3-iniconfig_2.1.0-2_all.deb ... 104s Unpacking python3-iniconfig (2.1.0-2) ... 104s Selecting previously unselected package python3-mdit-py-plugins. 104s Preparing to unpack .../14-python3-mdit-py-plugins_0.5.0-1_all.deb ... 104s Unpacking python3-mdit-py-plugins (0.5.0-1) ... 104s Selecting previously unselected package sphinx-common. 104s Preparing to unpack .../15-sphinx-common_8.2.3-12_all.deb ... 104s Unpacking sphinx-common (8.2.3-12) ... 105s Selecting previously unselected package python3-alabaster. 105s Preparing to unpack .../16-python3-alabaster_0.7.16-0.1build1_all.deb ... 105s Unpacking python3-alabaster (0.7.16-0.1build1) ... 105s Selecting previously unselected package python3-snowballstemmer. 105s Preparing to unpack .../17-python3-snowballstemmer_3.0.1-1_all.deb ... 105s Unpacking python3-snowballstemmer (3.0.1-1) ... 105s Selecting previously unselected package python3-sphinx. 105s Preparing to unpack .../18-python3-sphinx_8.2.3-12_all.deb ... 105s Unpacking python3-sphinx (8.2.3-12) ... 105s Selecting previously unselected package python3-myst-parser. 105s Preparing to unpack .../19-python3-myst-parser_4.0.1-1_all.deb ... 105s Unpacking python3-myst-parser (4.0.1-1) ... 105s Selecting previously unselected package python3-outcome. 105s Preparing to unpack .../20-python3-outcome_1.2.0-2_all.deb ... 105s Unpacking python3-outcome (1.2.0-2) ... 105s Selecting previously unselected package python3-pluggy. 105s Preparing to unpack .../21-python3-pluggy_1.6.0-2_all.deb ... 105s Unpacking python3-pluggy (1.6.0-2) ... 105s Selecting previously unselected package python3-pyinstrument. 105s Preparing to unpack .../22-python3-pyinstrument_5.1.1+ds-2_amd64.deb ... 105s Unpacking python3-pyinstrument (5.1.1+ds-2) ... 105s Selecting previously unselected package python3-pytest. 105s Preparing to unpack .../23-python3-pytest_9.0.2-2_all.deb ... 105s Unpacking python3-pytest (9.0.2-2) ... 105s Selecting previously unselected package python3-pytest-asyncio. 105s Preparing to unpack .../24-python3-pytest-asyncio_1.3.0-1_all.deb ... 105s Unpacking python3-pytest-asyncio (1.3.0-1) ... 105s Selecting previously unselected package python3-sniffio. 105s Preparing to unpack .../25-python3-sniffio_1.3.1-1_all.deb ... 105s Unpacking python3-sniffio (1.3.1-1) ... 105s Selecting previously unselected package python3-sortedcontainers. 105s Preparing to unpack .../26-python3-sortedcontainers_2.4.0-2build1_all.deb ... 105s Unpacking python3-sortedcontainers (2.4.0-2build1) ... 105s Selecting previously unselected package python3-trio. 105s Preparing to unpack .../27-python3-trio_0.32.0-1_all.deb ... 105s Unpacking python3-trio (0.32.0-1) ... 105s Selecting previously unselected package python3-greenlet. 105s Preparing to unpack .../28-python3-greenlet_3.2.4-3_amd64.deb ... 105s Unpacking python3-greenlet (3.2.4-3) ... 105s Setting up python3-iniconfig (2.1.0-2) ... 105s Setting up python3-sniffio (1.3.1-1) ... 105s Setting up python3-outcome (1.2.0-2) ... 105s Setting up python3-defusedxml (0.7.1-3build1) ... 105s Setting up python3-alabaster (0.7.16-0.1build1) ... 105s Setting up python3-mdit-py-plugins (0.5.0-1) ... 105s Setting up python3-sortedcontainers (2.4.0-2build1) ... 106s Setting up python3-flaky (3.8.1-4) ... 106s Setting up python3-pyinstrument (5.1.1+ds-2) ... 106s Setting up python3-trio (0.32.0-1) ... 106s Setting up python3-snowballstemmer (3.0.1-1) ... 107s Setting up python3-greenlet (3.2.4-3) ... 107s Setting up python3-pluggy (1.6.0-2) ... 107s Setting up libjson-perl (4.10000-1) ... 107s Setting up python3-roman-numerals (4.1.0-1) ... 107s Setting up python3.13-minimal (3.13.11-1) ... 108s Setting up python-pyinstrument-doc (5.1.1+ds-2) ... 108s Setting up sgml-base (1.31+nmu1) ... 108s Setting up python3-imagesize (1.4.1-1build1) ... 108s Setting up python3.13 (3.13.11-1) ... 108s Setting up python3-pytest (9.0.2-2) ... 109s Setting up python3-all (3.14.2-1) ... 109s Setting up sphinx-common (8.2.3-12) ... 109s Setting up python3-pytest-asyncio (1.3.0-1) ... 109s Setting up xml-core (0.19build1) ... 109s Processing triggers for systemd (259-1ubuntu2) ... 109s Processing triggers for man-db (2.13.1-1) ... 109s Processing triggers for sgml-base (1.31+nmu1) ... 109s Setting up docutils-common (0.22.4+dfsg-1) ... 109s Processing triggers for sgml-base (1.31+nmu1) ... 109s Setting up python3-docutils (0.22.4+dfsg-1) ... 110s Setting up python3-sphinx (8.2.3-12) ... 111s Setting up python3-myst-parser (4.0.1-1) ... 112s autopkgtest [15:19:44]: test upstream-tests: [----------------------- 112s 112s *************************** 112s *** Testing with python3.13 112s *************************** 112s 112s Content of current working folder: 112s 112s total 12 112s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 24 15:19 . 112s drwxrwxrwx 5 root root 4096 Jan 24 15:19 .. 112s drwxrwxr-x 3 ubuntu ubuntu 4096 Aug 12 11:21 test 112s Running tests... 112s 112s ============================= test session starts ============================== 112s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.13 112s cachedir: .pytest_cache 112s rootdir: /tmp/autopkgtest.FXzFyk/autopkgtest_tmp 112s plugins: flaky-3.8.1, typeguard-4.4.4, asyncio-1.3.0 112s asyncio: mode=Mode.STRICT, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function 112s collecting ... collected 122 items / 8 deselected / 114 selected 112s 112s test/low_level/test_context.py::test_context_type[setstatprofile0] PASSED [ 0%] 112s test/low_level/test_context.py::test_context_type[setstatprofile1] PASSED [ 1%] 112s test/low_level/test_context.py::test_context_tracking[setstatprofile0] PASSED [ 2%] 112s test/low_level/test_context.py::test_context_tracking[setstatprofile1] PASSED [ 3%] 112s test/low_level/test_custom_timer.py::test_increment[setstatprofile0] PASSED [ 4%] 112s test/low_level/test_custom_timer.py::test_increment[setstatprofile1] PASSED [ 5%] 112s test/low_level/test_floatclock.py::test_floatclock PASSED [ 6%] 112s test/low_level/test_floatclock.py::test_is_in_seconds PASSED [ 7%] 112s test/low_level/test_frame_info.py::test_frame_info PASSED [ 7%] 112s test/low_level/test_frame_info.py::test_frame_info_hide_true PASSED [ 8%] 112s test/low_level/test_frame_info.py::test_frame_info_hide_false PASSED [ 9%] 112s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_method] PASSED [ 10%] 112s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method] PASSED [ 11%] 112s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_with_cell_variable] PASSED [ 12%] 112s 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 [ 13%] 112s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_method] PASSED [ 14%] 112s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method] PASSED [ 14%] 112s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_with_cell_variable] PASSED [ 15%] 112s 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 [ 16%] 113s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile0] PASSED [ 17%] 114s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile1] PASSED [ 18%] 115s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile0] PASSED [ 19%] 116s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile1] PASSED [ 20%] 116s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile0] PASSED [ 21%] 116s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile1] PASSED [ 21%] 117s test/low_level/test_threaded.py::test_threaded PASSED [ 22%] 117s test/low_level/test_timing_thread.py::test PASSED [ 23%] 117s test/low_level/test_timing_thread.py::test_max_subscribers PASSED [ 24%] 118s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation0] PASSED [ 25%] 118s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation1] PASSED [ 26%] 118s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation0] PASSED [ 27%] 118s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation1] PASSED [ 28%] 118s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation0] PASSED [ 28%] 118s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation1] PASSED [ 29%] 118s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation0] PASSED [ 30%] 118s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation1] PASSED [ 31%] 119s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation0] PASSED [ 32%] 119s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation1] PASSED [ 33%] 119s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation0] PASSED [ 34%] 119s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation1] PASSED [ 35%] 119s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation0] PASSED [ 35%] 120s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation1] PASSED [ 36%] 120s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation0] PASSED [ 37%] 120s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation1] PASSED [ 38%] 120s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation0] PASSED [ 39%] 120s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation1] PASSED [ 40%] 120s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation0] PASSED [ 41%] 120s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation1] PASSED [ 42%] 120s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation0] PASSED [ 42%] 121s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation1] PASSED [ 43%] 121s test/test_cmdline_main.py::test_renderer_option PASSED [ 44%] 121s test/test_cmdline_main.py::test_json_renderer_option PASSED [ 45%] 121s test/test_cmdline_main.py::test_dotted_renderer_option PASSED [ 46%] 121s test/test_context_manager.py::test_profile_context_decorator PASSED [ 47%] 121s test/test_context_manager.py::test_profile_context_manager PASSED [ 48%] 121s test/test_ipython_magic.py::test_magics SKIPPED (requires --only-ipy...) [ 49%] 121s test/test_ipython_magic.py::test_magic_empty_line SKIPPED (requires ...) [ 50%] 121s test/test_ipython_magic.py::test_magic_no_variable_expansion SKIPPED [ 50%] 121s test/test_ipython_magic.py::test_pyinstrument_handles_interrupt_silently SKIPPED [ 51%] 121s test/test_ipython_magic.py::test_async_cell_with_pyinstrument SKIPPED [ 52%] 121s test/test_overflow.py::test_console PASSED [ 53%] 121s test/test_overflow.py::test_html PASSED [ 54%] 121s test/test_overflow.py::test_json PASSED [ 55%] 121s test/test_overflow.py::test_speedscope PASSED [ 56%] 121s test/test_overflow.py::test_prof PASSED [ 57%] 121s test/test_processors.py::test_frame_passthrough_none PASSED [ 57%] 121s test/test_processors.py::test_remove_importlib PASSED [ 58%] 121s test/test_processors.py::test_remove_hidden PASSED [ 59%] 121s test/test_processors.py::test_merge_consecutive_self_time PASSED [ 60%] 121s test/test_processors.py::test_aggregate_repeated_calls PASSED [ 61%] 121s test/test_processors.py::test_remove_irrelevant_nodes PASSED [ 62%] 121s test/test_processors.py::test_remove_unnecessary_self_time_nodes PASSED [ 63%] 121s test/test_processors.py::test_group_library_frames_processor PASSED [ 64%] 121s test/test_profiler.py::test_collapses_multiple_calls_by_default PASSED [ 64%] 121s test/test_profiler.py::test_profiler_retains_multiple_calls PASSED [ 65%] 121s test/test_profiler.py::test_two_functions PASSED [ 66%] 121s test/test_profiler.py::test_class_methods PASSED [ 67%] 121s test/test_profiler.py::test_context_manager PASSED [ 68%] 121s test/test_profiler.py::test_json_output PASSED [ 69%] 121s test/test_profiler.py::test_speedscope_output PASSED [ 70%] 121s test/test_profiler.py::test_empty_profile PASSED [ 71%] 121s test/test_profiler.py::test_state_management PASSED [ 71%] 121s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[print-ConsoleRenderer] PASSED [ 72%] 121s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_text-ConsoleRenderer] PASSED [ 73%] 121s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_html-HTMLRenderer] PASSED [ 74%] 121s test/test_profiler_async.py::test_sleep PASSED [ 75%] 121s test/test_profiler_async.py::test_sleep_trio PASSED [ 76%] 121s test/test_profiler_async.py::test_profiler_task_isolation[asyncio] PASSED [ 77%] 121s test/test_profiler_async.py::test_profiler_task_isolation[trio] PASSED [ 78%] 121s test/test_profiler_async.py::test_greenlet PASSED [ 78%] 121s test/test_profiler_async.py::test_strict_with_greenlet PASSED [ 79%] 121s test/test_pstats_renderer.py::test_pstats_renderer PASSED [ 80%] 121s test/test_pstats_renderer.py::test_round_trip_encoding_of_binary_data PASSED [ 81%] 121s test/test_pstats_renderer.py::test_sum_of_tottime PASSED [ 82%] 121s test/test_renderers.py::test_empty_profile[ConsoleRenderer] PASSED [ 83%] 121s test/test_renderers.py::test_empty_profile[JSONRenderer] PASSED [ 84%] 121s test/test_renderers.py::test_empty_profile[PstatsRenderer] PASSED [ 85%] 121s test/test_renderers.py::test_empty_profile[SpeedscopeRenderer] PASSED [ 85%] 121s test/test_renderers.py::test_timeline_doesnt_crash[ConsoleRenderer] PASSED [ 86%] 121s test/test_renderers.py::test_timeline_doesnt_crash[JSONRenderer] PASSED [ 87%] 121s test/test_renderers.py::test_timeline_doesnt_crash[PstatsRenderer] PASSED [ 88%] 121s test/test_renderers.py::test_timeline_doesnt_crash[SpeedscopeRenderer] PASSED [ 89%] 121s test/test_renderers.py::test_show_all_doesnt_crash[ConsoleRenderer] PASSED [ 90%] 121s test/test_renderers.py::test_show_all_doesnt_crash[JSONRenderer] PASSED [ 91%] 121s test/test_renderers.py::test_show_all_doesnt_crash[PstatsRenderer] PASSED [ 92%] 121s test/test_renderers.py::test_show_all_doesnt_crash[SpeedscopeRenderer] PASSED [ 92%] 121s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[self] PASSED [ 93%] 121s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[total] PASSED [ 94%] 121s test/test_stack_sampler.py::test_create PASSED [ 95%] 121s test/test_stack_sampler.py::test_get_samples PASSED [ 96%] 121s test/test_stack_sampler.py::test_multiple_samplers PASSED [ 97%] 121s test/test_stack_sampler.py::test_multiple_samplers_async_error PASSED [ 98%] 121s test/test_stack_sampler.py::test_multiple_contexts PASSED [ 99%] 122s test/test_threading.py::test_profiler_access_from_multiple_threads PASSED [100%] 122s 122s ================= 109 passed, 5 skipped, 8 deselected in 9.29s ================= 122s 122s *************************** 122s *** Testing with python3.14 122s *************************** 122s 122s Content of current working folder: 122s 122s total 12 122s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 24 15:19 . 122s drwxrwxrwx 5 root root 4096 Jan 24 15:19 .. 122s drwxrwxr-x 4 ubuntu ubuntu 4096 Jan 24 15:19 test 122s Running tests... 122s 122s ============================= test session starts ============================== 122s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.14 122s cachedir: .pytest_cache 122s rootdir: /tmp/autopkgtest.FXzFyk/autopkgtest_tmp 122s plugins: flaky-3.8.1, typeguard-4.4.4, asyncio-1.3.0 122s asyncio: mode=Mode.STRICT, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function 122s collecting ... collected 122 items / 8 deselected / 114 selected 122s 122s test/low_level/test_context.py::test_context_type[setstatprofile0] PASSED [ 0%] 122s test/low_level/test_context.py::test_context_type[setstatprofile1] PASSED [ 1%] 122s test/low_level/test_context.py::test_context_tracking[setstatprofile0] PASSED [ 2%] 122s test/low_level/test_context.py::test_context_tracking[setstatprofile1] PASSED [ 3%] 122s test/low_level/test_custom_timer.py::test_increment[setstatprofile0] PASSED [ 4%] 122s test/low_level/test_custom_timer.py::test_increment[setstatprofile1] PASSED [ 5%] 122s test/low_level/test_floatclock.py::test_floatclock PASSED [ 6%] 122s test/low_level/test_floatclock.py::test_is_in_seconds PASSED [ 7%] 122s test/low_level/test_frame_info.py::test_frame_info PASSED [ 7%] 122s test/low_level/test_frame_info.py::test_frame_info_hide_true PASSED [ 8%] 122s test/low_level/test_frame_info.py::test_frame_info_hide_false PASSED [ 9%] 122s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_method] PASSED [ 10%] 122s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method] PASSED [ 11%] 122s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_with_cell_variable] PASSED [ 12%] 122s 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 [ 13%] 122s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_method] PASSED [ 14%] 122s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method] PASSED [ 14%] 122s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_with_cell_variable] PASSED [ 15%] 122s 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 [ 16%] 123s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile0] PASSED [ 17%] 124s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile1] PASSED [ 18%] 125s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile0] PASSED [ 19%] 126s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile1] PASSED [ 20%] 126s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile0] PASSED [ 21%] 126s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile1] PASSED [ 21%] 127s test/low_level/test_threaded.py::test_threaded PASSED [ 22%] 127s test/low_level/test_timing_thread.py::test FAILED [ 23%] 127s test/low_level/test_timing_thread.py::test_max_subscribers PASSED [ 24%] 127s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation0] PASSED [ 25%] 127s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation1] PASSED [ 26%] 127s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation0] PASSED [ 27%] 127s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation1] PASSED [ 28%] 128s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation0] PASSED [ 28%] 128s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation1] PASSED [ 29%] 128s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation0] PASSED [ 30%] 128s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation1] PASSED [ 31%] 128s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation0] PASSED [ 32%] 128s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation1] PASSED [ 33%] 128s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation0] PASSED [ 34%] 129s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation1] PASSED [ 35%] 129s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation0] PASSED [ 35%] 129s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation1] PASSED [ 36%] 129s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation0] PASSED [ 37%] 129s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation1] PASSED [ 38%] 129s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation0] PASSED [ 39%] 130s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation1] PASSED [ 40%] 130s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation0] PASSED [ 41%] 130s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation1] PASSED [ 42%] 130s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation0] PASSED [ 42%] 130s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation1] PASSED [ 43%] 130s test/test_cmdline_main.py::test_renderer_option PASSED [ 44%] 130s test/test_cmdline_main.py::test_json_renderer_option PASSED [ 45%] 130s test/test_cmdline_main.py::test_dotted_renderer_option PASSED [ 46%] 130s test/test_context_manager.py::test_profile_context_decorator PASSED [ 47%] 130s test/test_context_manager.py::test_profile_context_manager PASSED [ 48%] 130s test/test_ipython_magic.py::test_magics SKIPPED (requires --only-ipy...) [ 49%] 130s test/test_ipython_magic.py::test_magic_empty_line SKIPPED (requires ...) [ 50%] 130s test/test_ipython_magic.py::test_magic_no_variable_expansion SKIPPED [ 50%] 130s test/test_ipython_magic.py::test_pyinstrument_handles_interrupt_silently SKIPPED [ 51%] 130s test/test_ipython_magic.py::test_async_cell_with_pyinstrument SKIPPED [ 52%] 130s test/test_overflow.py::test_console PASSED [ 53%] 130s test/test_overflow.py::test_html PASSED [ 54%] 131s test/test_overflow.py::test_json PASSED [ 55%] 131s test/test_overflow.py::test_speedscope PASSED [ 56%] 131s test/test_overflow.py::test_prof PASSED [ 57%] 131s test/test_processors.py::test_frame_passthrough_none PASSED [ 57%] 131s test/test_processors.py::test_remove_importlib PASSED [ 58%] 131s test/test_processors.py::test_remove_hidden PASSED [ 59%] 131s test/test_processors.py::test_merge_consecutive_self_time PASSED [ 60%] 131s test/test_processors.py::test_aggregate_repeated_calls PASSED [ 61%] 131s test/test_processors.py::test_remove_irrelevant_nodes PASSED [ 62%] 131s test/test_processors.py::test_remove_unnecessary_self_time_nodes PASSED [ 63%] 131s test/test_processors.py::test_group_library_frames_processor PASSED [ 64%] 131s test/test_profiler.py::test_collapses_multiple_calls_by_default PASSED [ 64%] 131s test/test_profiler.py::test_profiler_retains_multiple_calls PASSED [ 65%] 131s test/test_profiler.py::test_two_functions PASSED [ 66%] 131s test/test_profiler.py::test_class_methods PASSED [ 67%] 131s test/test_profiler.py::test_context_manager PASSED [ 68%] 131s test/test_profiler.py::test_json_output PASSED [ 69%] 131s test/test_profiler.py::test_speedscope_output PASSED [ 70%] 131s test/test_profiler.py::test_empty_profile PASSED [ 71%] 131s test/test_profiler.py::test_state_management PASSED [ 71%] 131s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[print-ConsoleRenderer] PASSED [ 72%] 131s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_text-ConsoleRenderer] PASSED [ 73%] 131s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_html-HTMLRenderer] PASSED [ 74%] 131s test/test_profiler_async.py::test_sleep PASSED [ 75%] 131s test/test_profiler_async.py::test_sleep_trio PASSED [ 76%] 131s test/test_profiler_async.py::test_profiler_task_isolation[asyncio] PASSED [ 77%] 131s test/test_profiler_async.py::test_profiler_task_isolation[trio] PASSED [ 78%] 131s test/test_profiler_async.py::test_greenlet PASSED [ 78%] 131s test/test_profiler_async.py::test_strict_with_greenlet PASSED [ 79%] 131s test/test_pstats_renderer.py::test_pstats_renderer PASSED [ 80%] 131s test/test_pstats_renderer.py::test_round_trip_encoding_of_binary_data PASSED [ 81%] 131s test/test_pstats_renderer.py::test_sum_of_tottime PASSED [ 82%] 131s test/test_renderers.py::test_empty_profile[ConsoleRenderer] PASSED [ 83%] 131s test/test_renderers.py::test_empty_profile[JSONRenderer] PASSED [ 84%] 131s test/test_renderers.py::test_empty_profile[PstatsRenderer] PASSED [ 85%] 131s test/test_renderers.py::test_empty_profile[SpeedscopeRenderer] PASSED [ 85%] 131s test/test_renderers.py::test_timeline_doesnt_crash[ConsoleRenderer] PASSED [ 86%] 131s test/test_renderers.py::test_timeline_doesnt_crash[JSONRenderer] PASSED [ 87%] 131s test/test_renderers.py::test_timeline_doesnt_crash[PstatsRenderer] PASSED [ 88%] 131s test/test_renderers.py::test_timeline_doesnt_crash[SpeedscopeRenderer] PASSED [ 89%] 131s test/test_renderers.py::test_show_all_doesnt_crash[ConsoleRenderer] PASSED [ 90%] 131s test/test_renderers.py::test_show_all_doesnt_crash[JSONRenderer] PASSED [ 91%] 131s test/test_renderers.py::test_show_all_doesnt_crash[PstatsRenderer] PASSED [ 92%] 131s test/test_renderers.py::test_show_all_doesnt_crash[SpeedscopeRenderer] PASSED [ 92%] 131s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[self] PASSED [ 93%] 131s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[total] PASSED [ 94%] 131s test/test_stack_sampler.py::test_create PASSED [ 95%] 131s test/test_stack_sampler.py::test_get_samples PASSED [ 96%] 131s test/test_stack_sampler.py::test_multiple_samplers PASSED [ 97%] 131s test/test_stack_sampler.py::test_multiple_samplers_async_error PASSED [ 98%] 131s test/test_stack_sampler.py::test_multiple_contexts PASSED [ 99%] 131s test/test_threading.py::test_profiler_access_from_multiple_threads PASSED [100%] 131s 131s =================================== FAILURES =================================== 131s _____________________________________ test _____________________________________ 131s 131s @flaky_in_ci 131s def test(): 131s # check the thread isn't running to begin with 131s assert pyi_timing_thread_get_interval() == -1 131s 131s time_before = pyi_timing_thread_get_time() 131s time.sleep(WAIT_TIME) 131s assert pyi_timing_thread_get_time() == time_before 131s 131s # subscribe 131s subscription_id = pyi_timing_thread_subscribe(0.001) 131s try: 131s assert subscription_id >= 0 131s 131s assert pyi_timing_thread_get_interval() == 0.001 131s 131s # check it's updating 131s busy_wait(WAIT_TIME) 131s time_a = pyi_timing_thread_get_time() 131s assert time_a > time_before 131s busy_wait(WAIT_TIME) 131s time_b = pyi_timing_thread_get_time() 131s > assert time_b > time_a 131s E assert 101.588143816 > 101.588143816 131s 131s test/low_level/test_timing_thread.py:69: AssertionError 131s =========================== short test summary info ============================ 131s FAILED test/low_level/test_timing_thread.py::test - assert 101.588143816 > 10... 131s ============ 1 failed, 108 passed, 5 skipped, 8 deselected in 9.19s ============ 131s autopkgtest [15:20:03]: test upstream-tests: -----------------------] 132s upstream-tests FAIL non-zero exit status 1 132s autopkgtest [15:20:04]: test upstream-tests: - - - - - - - - - - results - - - - - - - - - - 132s autopkgtest [15:20:04]: test autodep8-python3: preparing testbed 151s Creating nova instance adt-resolute-amd64-python-pyinstrument-20260124-151751-juju-7f2275-prod-proposed-migration-environment-2-a0e4e4b2-29c6-4709-8530-b3ccc6922811 from image adt/ubuntu-resolute-amd64-server-20260124.img (UUID 46811f22-3700-43f4-953b-ee3c613d71bb)... 229s autopkgtest [15:21:41]: testbed dpkg architecture: amd64 229s autopkgtest [15:21:41]: testbed apt version: 3.1.13 230s autopkgtest [15:21:42]: @@@@@@@@@@@@@@@@@@@@ test bed setup 230s autopkgtest [15:21:42]: testbed release detected to be: resolute 231s autopkgtest [15:21:42]: updating testbed package index (apt update) 231s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 231s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 231s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 231s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 231s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 231s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [994 kB] 231s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 231s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [91.5 kB] 231s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [113 kB] 231s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [96.0 kB] 231s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3712 B] 231s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 231s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 231s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 231s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [373 kB] 231s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [876 kB] 231s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [24.9 kB] 231s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [12.4 kB] 232s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 232s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [824 B] 232s Fetched 2758 kB in 1s (2985 kB/s) 233s Reading package lists... 233s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 233s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 233s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 233s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 234s Reading package lists... 234s Reading package lists... 234s Building dependency tree... 234s Reading state information... 234s Calculating upgrade... 234s The following packages were automatically installed and are no longer required: 234s python3.13 python3.13-minimal 234s Use 'sudo apt autoremove' to remove them. 234s The following NEW packages will be installed: 234s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 234s The following packages will be upgraded: 234s libpython3-stdlib python3 python3-gdbm python3-minimal rust-coreutils 234s 5 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 234s Need to get 10.4 MB of archives. 234s After this operation, 25.2 MB of additional disk space will be used. 234s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 234s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 234s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 234s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 234s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 234s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 234s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 234s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 rust-coreutils amd64 0.5.0-0ubuntu1 [3628 kB] 234s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 235s dpkg-preconfigure: unable to re-open stdin: No such file or directory 235s Fetched 10.4 MB in 0s (35.6 MB/s) 235s Selecting previously unselected package libpython3.14-minimal:amd64. 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 ... 124177 files and directories currently installed.) 235s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 235s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 235s Selecting previously unselected package python3.14-minimal. 235s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 235s Unpacking python3.14-minimal (3.14.2-1) ... 235s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 235s Setting up python3.14-minimal (3.14.2-1) ... 236s (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 ... 124519 files and directories currently installed.) 236s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 236s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 236s Setting up python3-minimal (3.14.2-1) ... 236s (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 ... 124519 files and directories currently installed.) 236s Preparing to unpack .../python3_3.14.2-1_amd64.deb ... 236s running python pre-rtupdate hooks for python3.14... 236s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 236s Selecting previously unselected package libpython3.14-stdlib:amd64. 236s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_amd64.deb ... 236s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 236s Selecting previously unselected package python3.14. 236s Preparing to unpack .../python3.14_3.14.2-1_amd64.deb ... 236s Unpacking python3.14 (3.14.2-1) ... 236s Preparing to unpack .../libpython3-stdlib_3.14.2-1_amd64.deb ... 236s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 236s Preparing to unpack .../rust-coreutils_0.5.0-0ubuntu1_amd64.deb ... 236s Unpacking rust-coreutils (0.5.0-0ubuntu1) over (0.2.2-0ubuntu2) ... 236s Setting up rust-coreutils (0.5.0-0ubuntu1) ... 236s (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 ... 124980 files and directories currently installed.) 236s Preparing to unpack .../python3-gdbm_3.14.2-1_amd64.deb ... 236s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 236s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 236s Setting up python3.14 (3.14.2-1) ... 237s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 237s Setting up python3-gdbm (3.14.2-1) ... 237s Setting up python3 (3.14.2-1) ... 237s running python rtupdate hooks for python3.14... 237s running python post-rtupdate hooks for python3.14... 237s Processing triggers for man-db (2.13.1-1) ... 237s Processing triggers for systemd (259-1ubuntu2) ... 237s autopkgtest [15:21:49]: upgrading testbed (apt dist-upgrade and autopurge) 237s Reading package lists... 238s Building dependency tree... 238s Reading state information... 238s Calculating upgrade... 238s The following packages were automatically installed and are no longer required: 238s python3.13 python3.13-minimal 238s Use 'sudo apt autoremove' to remove them. 238s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 238s Reading package lists... 238s Building dependency tree... 238s Reading state information... 238s Solving dependencies... 238s The following packages will be REMOVED: 238s python3.13* python3.13-minimal* 238s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 238s After this operation, 7860 kB disk space will be freed. 238s (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 ... 124978 files and directories currently installed.) 238s Removing python3.13 (3.13.11-1) ... 238s Removing python3.13-minimal (3.13.11-1) ... 239s Processing triggers for man-db (2.13.1-1) ... 239s Processing triggers for systemd (259-1ubuntu2) ... 239s (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 ... 124951 files and directories currently installed.) 239s Purging configuration files for python3.13-minimal (3.13.11-1) ... 242s Reading package lists... 242s Building dependency tree... 242s Reading state information... 242s Solving dependencies... 242s The following NEW packages will be installed: 242s python3-all python3-pyinstrument python3.13 python3.13-minimal 242s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 242s Need to get 3283 kB of archives. 242s After this operation, 8362 kB of additional disk space will be used. 242s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13-minimal amd64 3.13.11-1 [2408 kB] 242s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13 amd64 3.13.11-1 [758 kB] 242s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.14.2-1 [890 B] 242s Get:4 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pyinstrument amd64 5.1.1+ds-2 [117 kB] 242s Fetched 3283 kB in 0s (13.6 MB/s) 242s Selecting previously unselected package python3.13-minimal. 242s (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 ... 124953 files and directories currently installed.) 242s Preparing to unpack .../python3.13-minimal_3.13.11-1_amd64.deb ... 242s Unpacking python3.13-minimal (3.13.11-1) ... 242s Selecting previously unselected package python3.13. 242s Preparing to unpack .../python3.13_3.13.11-1_amd64.deb ... 242s Unpacking python3.13 (3.13.11-1) ... 242s Selecting previously unselected package python3-all. 242s Preparing to unpack .../python3-all_3.14.2-1_amd64.deb ... 242s Unpacking python3-all (3.14.2-1) ... 242s Selecting previously unselected package python3-pyinstrument. 242s Preparing to unpack .../python3-pyinstrument_5.1.1+ds-2_amd64.deb ... 242s Unpacking python3-pyinstrument (5.1.1+ds-2) ... 242s Setting up python3-pyinstrument (5.1.1+ds-2) ... 243s Setting up python3.13-minimal (3.13.11-1) ... 243s Setting up python3.13 (3.13.11-1) ... 244s Setting up python3-all (3.14.2-1) ... 244s Processing triggers for man-db (2.13.1-1) ... 244s Processing triggers for systemd (259-1ubuntu2) ... 245s autopkgtest [15:21:57]: 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 245s autopkgtest [15:21:57]: test autodep8-python3: [----------------------- 245s Testing with python3.13: 246s 246s Testing with python3.14: 246s 246s autopkgtest [15:21:58]: test autodep8-python3: -----------------------] 246s autodep8-python3 PASS (superficial) 246s autopkgtest [15:21:58]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 247s autopkgtest [15:21:59]: @@@@@@@@@@@@@@@@@@@@ summary 247s upstream-tests FAIL non-zero exit status 1 247s autodep8-python3 PASS (superficial)