1s autopkgtest [05:38:28]: starting date and time: 2026-02-05 05:38:28+0000 1s autopkgtest [05:38:28]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 1s autopkgtest [05:38:28]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.xmon3j1v/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc,src:chiark-tcl --apt-upgrade python-pyinstrument --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 chiark-tcl/1.3.7build1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-arm64-19.secgroup --name adt-resolute-arm64-python-pyinstrument-20260205-053827-juju-7f2275-prod-proposed-migration-environment-20-77c1919f-239e-4cd5-8fdb-ef417ff85b83 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 7s Creating nova instance adt-resolute-arm64-python-pyinstrument-20260205-053827-juju-7f2275-prod-proposed-migration-environment-20-77c1919f-239e-4cd5-8fdb-ef417ff85b83 from image adt/ubuntu-resolute-arm64-server-20260204.img (UUID f58d981d-b271-4157-b9b1-fd704695563c)... 75s autopkgtest [05:39:42]: testbed dpkg architecture: arm64 75s autopkgtest [05:39:42]: testbed apt version: 3.1.14 76s autopkgtest [05:39:43]: @@@@@@@@@@@@@@@@@@@@ test bed setup 76s autopkgtest [05:39:43]: testbed release detected to be: None 77s autopkgtest [05:39:44]: updating testbed package index (apt update) 78s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 78s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 78s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 78s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB] 79s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [228 kB] 79s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 79s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 79s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [269 kB] 79s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [7264 B] 79s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [52.9 kB] 79s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [328 B] 79s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [1556 kB] 79s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [41.9 kB] 79s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [26.0 kB] 79s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [792 B] 82s Fetched 4067 kB in 2s (1991 kB/s) 83s Reading package lists... 84s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 84s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 84s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 84s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 85s Reading package lists... 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 86s Calculating upgrade... 86s The following packages will be upgraded: 86s libc-bin libc-gconv-modules-extra libc6 locales pollinate python3-linkify-it 86s python3-referencing sed 86s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 8110 kB of archives. 86s After this operation, 0 B of additional disk space will be used. 86s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 sed arm64 4.9-2build3 [193 kB] 86s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-gconv-modules-extra arm64 2.42-2ubuntu5 [1413 kB] 88s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc6 arm64 2.42-2ubuntu5 [1594 kB] 90s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-bin arm64 2.42-2ubuntu5 [599 kB] 91s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 locales all 2.42-2ubuntu5 [4255 kB] 98s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-linkify-it all 2.0.3-1ubuntu3 [19.4 kB] 98s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 pollinate all 4.33-4ubuntu5 [14.0 kB] 98s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 98s dpkg-preconfigure: unable to re-open stdin: No such file or directory 98s Fetched 8110 kB in 12s (679 kB/s) 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 ... 89360 files and directories currently installed.) 98s Preparing to unpack .../sed_4.9-2build3_arm64.deb ... 98s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 99s Setting up sed (4.9-2build3) ... 99s (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 ... 89360 files and directories currently installed.) 99s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_arm64.deb ... 99s Unpacking libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 99s Setting up libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) ... 99s (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 ... 89360 files and directories currently installed.) 99s Preparing to unpack .../libc6_2.42-2ubuntu5_arm64.deb ... 99s Unpacking libc6:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 99s Setting up libc6:arm64 (2.42-2ubuntu5) ... 99s (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 ... 89360 files and directories currently installed.) 99s Preparing to unpack .../libc-bin_2.42-2ubuntu5_arm64.deb ... 99s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 100s Setting up libc-bin (2.42-2ubuntu5) ... 100s (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 ... 89360 files and directories currently installed.) 100s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 100s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 100s Preparing to unpack .../python3-linkify-it_2.0.3-1ubuntu3_all.deb ... 100s Unpacking python3-linkify-it (2.0.3-1ubuntu3) over (2.0.3-1ubuntu2) ... 100s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 100s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 100s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 100s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 100s Setting up locales (2.42-2ubuntu5) ... 101s Generating locales (this might take a while)... 103s en_US.UTF-8... done 103s Generation complete. 103s Setting up pollinate (4.33-4ubuntu5) ... 114s Setting up python3-linkify-it (2.0.3-1ubuntu3) ... 114s Setting up python3-referencing (0.36.2-1ubuntu2) ... 114s Processing triggers for man-db (2.13.1-1) ... 115s Processing triggers for install-info (7.2-5) ... 115s Processing triggers for systemd (259-1ubuntu3) ... 116s autopkgtest [05:40:23]: upgrading testbed (apt dist-upgrade and autopurge) 116s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s Calculating upgrade... 117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s Reading package lists... 118s Building dependency tree... 118s Reading state information... 118s Solving dependencies... 118s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 118s autopkgtest [05:40:25]: rebooting testbed after setup commands that affected boot 158s autopkgtest [05:41:05]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 161s autopkgtest [05:41:08]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pyinstrument 164s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.2+ds-1 (dsc) [2747 B] 164s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.2+ds-1 (tar) [1148 kB] 164s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pyinstrument 5.1.2+ds-1 (diff) [7276 B] 165s gpgv: Signature made Sat Jan 24 07:56:12 2026 UTC 165s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 165s gpgv: issuer "c.schoenert@t-online.de" 165s gpgv: Can't check signature: No public key 165s dpkg-source: warning: cannot verify inline signature for ./python-pyinstrument_5.1.2+ds-1.dsc: no acceptable signature found 165s autopkgtest [05:41:12]: testing package python-pyinstrument version 5.1.2+ds-1 165s autopkgtest [05:41:12]: build not needed 166s autopkgtest [05:41:13]: test upstream-tests: preparing testbed 166s Reading package lists... 167s Building dependency tree... 167s Reading state information... 167s Solving dependencies... 167s The following NEW packages will be installed: 167s docutils-common libjson-perl libpython3.14-minimal libpython3.14-stdlib 167s python-pyinstrument-doc python3-alabaster python3-all python3-defusedxml 167s python3-docutils python3-flaky python3-greenlet python3-imagesize 167s python3-iniconfig python3-mdit-py-plugins python3-myst-parser 167s python3-outcome python3-pluggy python3-pyinstrument python3-pytest 167s python3-pytest-asyncio python3-roman-numerals python3-sniffio 167s python3-snowballstemmer python3-sortedcontainers python3-sphinx python3-trio 167s python3.14 python3.14-minimal sgml-base sphinx-common xml-core 167s 0 upgraded, 31 newly installed, 0 to remove and 0 not upgraded. 167s Need to get 10.0 MB of archives. 167s After this operation, 43.8 MB of additional disk space will be used. 167s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-minimal arm64 3.14.2-1 [917 kB] 168s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14-minimal arm64 3.14.2-1 [2548 kB] 171s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 sgml-base all 1.31+nmu1 [11.0 kB] 171s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 xml-core all 0.19build1 [20.3 kB] 171s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 docutils-common all 0.22.4+dfsg-1 [130 kB] 171s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 libjson-perl all 4.10000-1 [81.9 kB] 171s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-stdlib arm64 3.14.2-1 [2351 kB] 173s Get:8 http://ftpmaster.internal/ubuntu resolute/universe arm64 python-pyinstrument-doc all 5.1.2+ds-1 [341 kB] 173s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14 arm64 3.14.2-1 [816 kB] 174s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-all arm64 3.13.9-3 [890 B] 174s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-defusedxml all 0.7.1-3build1 [42.4 kB] 174s Get:12 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-roman-numerals all 4.1.0-1 [8660 B] 174s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-docutils all 0.22.4+dfsg-1 [439 kB] 174s Get:14 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-flaky all 3.8.1-4 [16.8 kB] 174s Get:15 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-imagesize all 1.4.1-1build1 [6900 B] 174s Get:16 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-iniconfig all 2.1.0-2 [6962 B] 174s Get:17 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-mdit-py-plugins all 0.5.0-1 [31.5 kB] 174s Get:18 http://ftpmaster.internal/ubuntu resolute/main arm64 sphinx-common all 8.2.3-12 [657 kB] 174s Get:19 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-alabaster all 0.7.16-0.1build1 [18.6 kB] 174s Get:20 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-snowballstemmer all 3.0.1-1 [65.9 kB] 174s Get:21 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-sphinx all 8.2.3-12 [483 kB] 174s Get:22 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-myst-parser all 4.0.1-1build1 [64.8 kB] 174s Get:23 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-outcome all 1.2.0-2 [6208 B] 174s Get:24 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pluggy all 1.6.0-2 [21.1 kB] 174s Get:25 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pyinstrument arm64 5.1.2+ds-1 [118 kB] 174s Get:26 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pytest all 9.0.2-2 [277 kB] 175s Get:27 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pytest-asyncio all 1.3.0-1 [13.8 kB] 175s Get:28 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-sniffio all 1.3.1-1 [7262 B] 175s Get:29 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-sortedcontainers all 2.4.0-2build1 [27.1 kB] 175s Get:30 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-trio all 0.32.0-1 [342 kB] 175s Get:31 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-greenlet arm64 3.2.4-3 [178 kB] 175s Fetched 10.0 MB in 7s (1352 kB/s) 175s Selecting previously unselected package libpython3.14-minimal:arm64. 175s (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 ... 89360 files and directories currently installed.) 175s Preparing to unpack .../00-libpython3.14-minimal_3.14.2-1_arm64.deb ... 175s Unpacking libpython3.14-minimal:arm64 (3.14.2-1) ... 175s Selecting previously unselected package python3.14-minimal. 175s Preparing to unpack .../01-python3.14-minimal_3.14.2-1_arm64.deb ... 175s Unpacking python3.14-minimal (3.14.2-1) ... 175s Selecting previously unselected package sgml-base. 175s Preparing to unpack .../02-sgml-base_1.31+nmu1_all.deb ... 175s Unpacking sgml-base (1.31+nmu1) ... 175s Selecting previously unselected package xml-core. 175s Preparing to unpack .../03-xml-core_0.19build1_all.deb ... 175s Unpacking xml-core (0.19build1) ... 175s Selecting previously unselected package docutils-common. 175s Preparing to unpack .../04-docutils-common_0.22.4+dfsg-1_all.deb ... 175s Unpacking docutils-common (0.22.4+dfsg-1) ... 175s Selecting previously unselected package libjson-perl. 175s Preparing to unpack .../05-libjson-perl_4.10000-1_all.deb ... 175s Unpacking libjson-perl (4.10000-1) ... 175s Selecting previously unselected package libpython3.14-stdlib:arm64. 175s Preparing to unpack .../06-libpython3.14-stdlib_3.14.2-1_arm64.deb ... 175s Unpacking libpython3.14-stdlib:arm64 (3.14.2-1) ... 175s Selecting previously unselected package python-pyinstrument-doc. 175s Preparing to unpack .../07-python-pyinstrument-doc_5.1.2+ds-1_all.deb ... 175s Unpacking python-pyinstrument-doc (5.1.2+ds-1) ... 175s Selecting previously unselected package python3.14. 176s Preparing to unpack .../08-python3.14_3.14.2-1_arm64.deb ... 176s Unpacking python3.14 (3.14.2-1) ... 176s Selecting previously unselected package python3-all. 176s Preparing to unpack .../09-python3-all_3.13.9-3_arm64.deb ... 176s Unpacking python3-all (3.13.9-3) ... 176s Selecting previously unselected package python3-defusedxml. 176s Preparing to unpack .../10-python3-defusedxml_0.7.1-3build1_all.deb ... 176s Unpacking python3-defusedxml (0.7.1-3build1) ... 176s Selecting previously unselected package python3-roman-numerals. 176s Preparing to unpack .../11-python3-roman-numerals_4.1.0-1_all.deb ... 176s Unpacking python3-roman-numerals (4.1.0-1) ... 176s Selecting previously unselected package python3-docutils. 176s Preparing to unpack .../12-python3-docutils_0.22.4+dfsg-1_all.deb ... 176s Unpacking python3-docutils (0.22.4+dfsg-1) ... 176s Selecting previously unselected package python3-flaky. 176s Preparing to unpack .../13-python3-flaky_3.8.1-4_all.deb ... 176s Unpacking python3-flaky (3.8.1-4) ... 176s Selecting previously unselected package python3-imagesize. 176s Preparing to unpack .../14-python3-imagesize_1.4.1-1build1_all.deb ... 176s Unpacking python3-imagesize (1.4.1-1build1) ... 176s Selecting previously unselected package python3-iniconfig. 176s Preparing to unpack .../15-python3-iniconfig_2.1.0-2_all.deb ... 176s Unpacking python3-iniconfig (2.1.0-2) ... 176s Selecting previously unselected package python3-mdit-py-plugins. 176s Preparing to unpack .../16-python3-mdit-py-plugins_0.5.0-1_all.deb ... 176s Unpacking python3-mdit-py-plugins (0.5.0-1) ... 176s Selecting previously unselected package sphinx-common. 176s Preparing to unpack .../17-sphinx-common_8.2.3-12_all.deb ... 176s Unpacking sphinx-common (8.2.3-12) ... 176s Selecting previously unselected package python3-alabaster. 176s Preparing to unpack .../18-python3-alabaster_0.7.16-0.1build1_all.deb ... 176s Unpacking python3-alabaster (0.7.16-0.1build1) ... 176s Selecting previously unselected package python3-snowballstemmer. 176s Preparing to unpack .../19-python3-snowballstemmer_3.0.1-1_all.deb ... 176s Unpacking python3-snowballstemmer (3.0.1-1) ... 176s Selecting previously unselected package python3-sphinx. 176s Preparing to unpack .../20-python3-sphinx_8.2.3-12_all.deb ... 176s Unpacking python3-sphinx (8.2.3-12) ... 176s Selecting previously unselected package python3-myst-parser. 176s Preparing to unpack .../21-python3-myst-parser_4.0.1-1build1_all.deb ... 176s Unpacking python3-myst-parser (4.0.1-1build1) ... 176s Selecting previously unselected package python3-outcome. 176s Preparing to unpack .../22-python3-outcome_1.2.0-2_all.deb ... 176s Unpacking python3-outcome (1.2.0-2) ... 176s Selecting previously unselected package python3-pluggy. 176s Preparing to unpack .../23-python3-pluggy_1.6.0-2_all.deb ... 176s Unpacking python3-pluggy (1.6.0-2) ... 176s Selecting previously unselected package python3-pyinstrument. 176s Preparing to unpack .../24-python3-pyinstrument_5.1.2+ds-1_arm64.deb ... 176s Unpacking python3-pyinstrument (5.1.2+ds-1) ... 176s Selecting previously unselected package python3-pytest. 176s Preparing to unpack .../25-python3-pytest_9.0.2-2_all.deb ... 176s Unpacking python3-pytest (9.0.2-2) ... 176s Selecting previously unselected package python3-pytest-asyncio. 176s Preparing to unpack .../26-python3-pytest-asyncio_1.3.0-1_all.deb ... 176s Unpacking python3-pytest-asyncio (1.3.0-1) ... 176s Selecting previously unselected package python3-sniffio. 176s Preparing to unpack .../27-python3-sniffio_1.3.1-1_all.deb ... 176s Unpacking python3-sniffio (1.3.1-1) ... 176s Selecting previously unselected package python3-sortedcontainers. 176s Preparing to unpack .../28-python3-sortedcontainers_2.4.0-2build1_all.deb ... 176s Unpacking python3-sortedcontainers (2.4.0-2build1) ... 176s Selecting previously unselected package python3-trio. 176s Preparing to unpack .../29-python3-trio_0.32.0-1_all.deb ... 176s Unpacking python3-trio (0.32.0-1) ... 176s Selecting previously unselected package python3-greenlet. 176s Preparing to unpack .../30-python3-greenlet_3.2.4-3_arm64.deb ... 176s Unpacking python3-greenlet (3.2.4-3) ... 176s Setting up python3-iniconfig (2.1.0-2) ... 177s Setting up python3-sniffio (1.3.1-1) ... 177s Setting up python3-outcome (1.2.0-2) ... 177s Setting up python3-defusedxml (0.7.1-3build1) ... 177s Setting up python3-alabaster (0.7.16-0.1build1) ... 177s Setting up libpython3.14-minimal:arm64 (3.14.2-1) ... 177s Setting up python3-mdit-py-plugins (0.5.0-1) ... 178s Setting up python3-sortedcontainers (2.4.0-2build1) ... 178s Setting up python3-flaky (3.8.1-4) ... 180s Setting up python3-pyinstrument (5.1.2+ds-1) ... 180s Setting up python3-trio (0.32.0-1) ... 180s Setting up python3-snowballstemmer (3.0.1-1) ... 180s Setting up python3-greenlet (3.2.4-3) ... 180s Setting up python3-pluggy (1.6.0-2) ... 180s Setting up libjson-perl (4.10000-1) ... 180s Setting up python3-roman-numerals (4.1.0-1) ... 180s Setting up python-pyinstrument-doc (5.1.2+ds-1) ... 180s Setting up sgml-base (1.31+nmu1) ... 180s Setting up python3.14-minimal (3.14.2-1) ... 181s Setting up python3-imagesize (1.4.1-1build1) ... 181s Setting up libpython3.14-stdlib:arm64 (3.14.2-1) ... 181s Setting up python3-pytest (9.0.2-2) ... 182s Setting up sphinx-common (8.2.3-12) ... 182s Setting up python3-pytest-asyncio (1.3.0-1) ... 182s Setting up xml-core (0.19build1) ... 182s Setting up python3.14 (3.14.2-1) ... 183s Setting up python3-all (3.13.9-3) ... 183s Processing triggers for systemd (259-1ubuntu3) ... 183s Processing triggers for man-db (2.13.1-1) ... 184s Processing triggers for sgml-base (1.31+nmu1) ... 184s Setting up docutils-common (0.22.4+dfsg-1) ... 184s Processing triggers for sgml-base (1.31+nmu1) ... 184s Setting up python3-docutils (0.22.4+dfsg-1) ... 185s Setting up python3-sphinx (8.2.3-12) ... 186s Setting up python3-myst-parser (4.0.1-1build1) ... 188s autopkgtest [05:41:35]: test upstream-tests: [----------------------- 188s 188s *************************** 188s *** Testing with python3.14 188s *************************** 188s 188s Content of current working folder: 188s 188s total 12 188s drwxr-xr-x 3 ubuntu ubuntu 4096 Feb 5 05:41 . 188s drwxrwxrwx 5 root root 4096 Feb 5 05:41 .. 188s drwxrwxr-x 3 ubuntu ubuntu 4096 Jan 4 18:25 test 188s Running tests... 188s 188s ============================= test session starts ============================== 188s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.14 188s cachedir: .pytest_cache 188s rootdir: /tmp/autopkgtest.zqXm5q/autopkgtest_tmp 188s plugins: flaky-3.8.1, typeguard-4.4.4, asyncio-1.3.0 188s asyncio: mode=Mode.STRICT, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function 189s collecting ... collected 131 items / 8 deselected / 123 selected 189s 189s test/low_level/test_context.py::test_context_type[setstatprofile0] PASSED [ 0%] 189s test/low_level/test_context.py::test_context_type[setstatprofile1] PASSED [ 1%] 189s test/low_level/test_context.py::test_context_tracking[setstatprofile0] PASSED [ 2%] 189s test/low_level/test_context.py::test_context_tracking[setstatprofile1] PASSED [ 3%] 189s test/low_level/test_custom_timer.py::test_increment[setstatprofile0] PASSED [ 4%] 189s test/low_level/test_custom_timer.py::test_increment[setstatprofile1] PASSED [ 4%] 189s test/low_level/test_floatclock.py::test_floatclock PASSED [ 5%] 189s test/low_level/test_floatclock.py::test_is_in_seconds PASSED [ 6%] 189s test/low_level/test_frame_info.py::test_frame_info PASSED [ 7%] 189s test/low_level/test_frame_info.py::test_frame_info_hide_true PASSED [ 8%] 189s test/low_level/test_frame_info.py::test_frame_info_hide_false PASSED [ 8%] 189s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_method] PASSED [ 9%] 189s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method] PASSED [ 10%] 189s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_with_cell_variable] PASSED [ 11%] 189s 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%] 189s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_method] PASSED [ 13%] 189s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method] PASSED [ 13%] 189s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_with_cell_variable] PASSED [ 14%] 189s 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%] 190s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile0] PASSED [ 16%] 191s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile1] PASSED [ 17%] 192s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile0] PASSED [ 17%] 193s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile1] PASSED [ 18%] 193s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile0] PASSED [ 19%] 193s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile1] PASSED [ 20%] 194s test/low_level/test_threaded.py::test_threaded PASSED [ 21%] 194s test/low_level/test_timing_thread.py::test PASSED [ 21%] 194s test/low_level/test_timing_thread.py::test_max_subscribers PASSED [ 22%] 194s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation0] PASSED [ 23%] 194s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation1] PASSED [ 24%] 194s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation0] PASSED [ 25%] 195s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation1] PASSED [ 26%] 195s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation0] PASSED [ 26%] 195s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation1] PASSED [ 27%] 195s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation0] PASSED [ 28%] 195s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation1] PASSED [ 29%] 195s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation0] PASSED [ 30%] 195s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation1] PASSED [ 30%] 196s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation0] PASSED [ 31%] 196s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation1] PASSED [ 32%] 196s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation0] PASSED [ 33%] 196s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation1] PASSED [ 34%] 197s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation0] PASSED [ 34%] 197s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation1] PASSED [ 35%] 197s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation0] PASSED [ 36%] 197s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation1] PASSED [ 37%] 197s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation0] PASSED [ 38%] 198s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation1] PASSED [ 39%] 198s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation0] PASSED [ 39%] 198s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation1] PASSED [ 40%] 198s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation0] PASSED [ 41%] 198s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation1] PASSED [ 42%] 198s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation0] PASSED [ 43%] 199s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation1] PASSED [ 43%] 199s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation0] PASSED [ 44%] 199s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation1] PASSED [ 45%] 199s test/test_cmdline_main.py::test_renderer_option PASSED [ 46%] 199s test/test_cmdline_main.py::test_json_renderer_option PASSED [ 47%] 199s test/test_cmdline_main.py::test_dotted_renderer_option PASSED [ 47%] 199s test/test_context_manager.py::test_profile_context_decorator PASSED [ 48%] 199s test/test_context_manager.py::test_profile_context_manager PASSED [ 49%] 199s test/test_ipython_magic.py::test_magics SKIPPED (requires --only-ipy...) [ 50%] 199s test/test_ipython_magic.py::test_magic_empty_line SKIPPED (requires ...) [ 51%] 199s test/test_ipython_magic.py::test_magic_no_variable_expansion SKIPPED [ 52%] 199s test/test_ipython_magic.py::test_pyinstrument_handles_interrupt_silently SKIPPED [ 52%] 199s test/test_ipython_magic.py::test_async_cell_with_pyinstrument SKIPPED [ 53%] 199s test/test_overflow.py::test_console PASSED [ 54%] 199s test/test_overflow.py::test_html PASSED [ 55%] 200s test/test_overflow.py::test_json PASSED [ 56%] 200s test/test_overflow.py::test_speedscope PASSED [ 56%] 200s test/test_overflow.py::test_prof PASSED [ 57%] 200s test/test_processors.py::test_frame_passthrough_none PASSED [ 58%] 200s test/test_processors.py::test_remove_importlib PASSED [ 59%] 200s test/test_processors.py::test_remove_hidden PASSED [ 60%] 200s test/test_processors.py::test_merge_consecutive_self_time PASSED [ 60%] 200s test/test_processors.py::test_aggregate_repeated_calls PASSED [ 61%] 200s test/test_processors.py::test_remove_irrelevant_nodes PASSED [ 62%] 200s test/test_processors.py::test_remove_unnecessary_self_time_nodes PASSED [ 63%] 200s test/test_processors.py::test_group_library_frames_processor PASSED [ 64%] 200s test/test_profiler.py::test_collapses_multiple_calls_by_default PASSED [ 65%] 200s test/test_profiler.py::test_profiler_retains_multiple_calls PASSED [ 65%] 200s test/test_profiler.py::test_two_functions PASSED [ 66%] 200s test/test_profiler.py::test_class_methods PASSED [ 67%] 200s test/test_profiler.py::test_context_manager PASSED [ 68%] 200s test/test_profiler.py::test_json_output PASSED [ 69%] 200s test/test_profiler.py::test_speedscope_output PASSED [ 69%] 200s test/test_profiler.py::test_empty_profile PASSED [ 70%] 200s test/test_profiler.py::test_state_management PASSED [ 71%] 200s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[print-ConsoleRenderer] PASSED [ 72%] 200s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_text-ConsoleRenderer] PASSED [ 73%] 200s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_html-HTMLRenderer] PASSED [ 73%] 200s test/test_profiler.py::test_profiler_raises_on_double_subscribe PASSED [ 74%] 200s test/test_profiler_async.py::test_sleep PASSED [ 75%] 200s test/test_profiler_async.py::test_sleep_trio PASSED [ 76%] 200s test/test_profiler_async.py::test_profiler_task_isolation[asyncio] PASSED [ 77%] 200s test/test_profiler_async.py::test_profiler_task_isolation[trio] PASSED [ 78%] 200s test/test_profiler_async.py::test_greenlet PASSED [ 78%] 200s test/test_profiler_async.py::test_strict_with_greenlet PASSED [ 79%] 200s test/test_pstats_renderer.py::test_pstats_renderer PASSED [ 80%] 200s test/test_pstats_renderer.py::test_round_trip_encoding_of_binary_data PASSED [ 81%] 200s test/test_pstats_renderer.py::test_sum_of_tottime PASSED [ 82%] 200s test/test_renderers.py::test_empty_profile[ConsoleRenderer] PASSED [ 82%] 200s test/test_renderers.py::test_empty_profile[JSONRenderer] PASSED [ 83%] 200s test/test_renderers.py::test_empty_profile[PstatsRenderer] PASSED [ 84%] 200s test/test_renderers.py::test_empty_profile[SpeedscopeRenderer] PASSED [ 85%] 200s test/test_renderers.py::test_timeline_doesnt_crash[ConsoleRenderer] PASSED [ 86%] 200s test/test_renderers.py::test_timeline_doesnt_crash[JSONRenderer] PASSED [ 86%] 200s test/test_renderers.py::test_timeline_doesnt_crash[PstatsRenderer] PASSED [ 87%] 200s test/test_renderers.py::test_timeline_doesnt_crash[SpeedscopeRenderer] PASSED [ 88%] 200s test/test_renderers.py::test_show_all_doesnt_crash[ConsoleRenderer] PASSED [ 89%] 200s test/test_renderers.py::test_show_all_doesnt_crash[JSONRenderer] PASSED [ 90%] 200s test/test_renderers.py::test_show_all_doesnt_crash[PstatsRenderer] PASSED [ 91%] 200s test/test_renderers.py::test_show_all_doesnt_crash[SpeedscopeRenderer] PASSED [ 91%] 200s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[self] PASSED [ 92%] 200s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[total] PASSED [ 93%] 200s test/test_renderers.py::test_html_renderer_resampling PASSED [ 94%] 200s test/test_stack_sampler.py::test_create PASSED [ 95%] 200s test/test_stack_sampler.py::test_get_samples PASSED [ 95%] 200s test/test_stack_sampler.py::test_multiple_samplers PASSED [ 96%] 200s test/test_stack_sampler.py::test_multiple_samplers_async_error PASSED [ 97%] 200s test/test_stack_sampler.py::test_multiple_contexts PASSED [ 98%] 200s test/test_stack_sampler.py::test_same_callback_twice_error PASSED [ 99%] 200s test/test_threading.py::test_profiler_access_from_multiple_threads PASSED [100%] 200s 200s ================ 118 passed, 5 skipped, 8 deselected in 11.72s ================= 200s 200s *************************** 200s *** Testing with python3.13 200s *************************** 200s 200s Content of current working folder: 200s 200s total 12 200s drwxr-xr-x 3 ubuntu ubuntu 4096 Feb 5 05:41 . 200s drwxrwxrwx 5 root root 4096 Feb 5 05:41 .. 200s drwxrwxr-x 4 ubuntu ubuntu 4096 Feb 5 05:41 test 200s Running tests... 200s 201s ============================= test session starts ============================== 201s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.13 201s cachedir: .pytest_cache 201s rootdir: /tmp/autopkgtest.zqXm5q/autopkgtest_tmp 201s plugins: flaky-3.8.1, typeguard-4.4.4, asyncio-1.3.0 201s asyncio: mode=Mode.STRICT, debug=False, asyncio_default_fixture_loop_scope=None, asyncio_default_test_loop_scope=function 201s collecting ... collected 131 items / 8 deselected / 123 selected 201s 201s test/low_level/test_context.py::test_context_type[setstatprofile0] PASSED [ 0%] 201s test/low_level/test_context.py::test_context_type[setstatprofile1] PASSED [ 1%] 201s test/low_level/test_context.py::test_context_tracking[setstatprofile0] PASSED [ 2%] 201s test/low_level/test_context.py::test_context_tracking[setstatprofile1] PASSED [ 3%] 201s test/low_level/test_custom_timer.py::test_increment[setstatprofile0] PASSED [ 4%] 201s test/low_level/test_custom_timer.py::test_increment[setstatprofile1] PASSED [ 4%] 201s test/low_level/test_floatclock.py::test_floatclock PASSED [ 5%] 201s test/low_level/test_floatclock.py::test_is_in_seconds PASSED [ 6%] 201s test/low_level/test_frame_info.py::test_frame_info PASSED [ 7%] 201s test/low_level/test_frame_info.py::test_frame_info_hide_true PASSED [ 8%] 201s test/low_level/test_frame_info.py::test_frame_info_hide_false PASSED [ 8%] 201s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_method] PASSED [ 9%] 201s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_for_a_class_method] PASSED [ 10%] 201s test/low_level/test_frame_info.py::test_frame_info_with_classes[True-get_frame_info_with_cell_variable] PASSED [ 11%] 201s 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%] 201s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_method] PASSED [ 13%] 201s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_for_a_class_method] PASSED [ 13%] 201s test/low_level/test_frame_info.py::test_frame_info_with_classes[False-get_frame_info_with_cell_variable] PASSED [ 14%] 201s 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%] 202s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile0] PASSED [ 16%] 203s test/low_level/test_setstatprofile.py::test_100ms[setstatprofile1] PASSED [ 17%] 204s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile0] PASSED [ 17%] 205s test/low_level/test_setstatprofile.py::test_10ms[setstatprofile1] PASSED [ 18%] 205s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile0] PASSED [ 19%] 205s test/low_level/test_setstatprofile.py::test_internal_object_compatibility[setstatprofile1] PASSED [ 20%] 205s test/low_level/test_threaded.py::test_threaded PASSED [ 21%] 206s test/low_level/test_timing_thread.py::test PASSED [ 21%] 206s test/low_level/test_timing_thread.py::test_max_subscribers PASSED [ 22%] 206s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation0] PASSED [ 23%] 206s test/test_cmdline.py::TestCommandLine::test_command_line[pyinstrument_invocation1] PASSED [ 24%] 206s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation0] PASSED [ 25%] 206s test/test_cmdline.py::TestCommandLine::test_module_running[pyinstrument_invocation1] PASSED [ 26%] 207s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation0] PASSED [ 26%] 207s test/test_cmdline.py::TestCommandLine::test_single_file_module_running[pyinstrument_invocation1] PASSED [ 27%] 207s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation0] PASSED [ 28%] 207s test/test_cmdline.py::TestCommandLine::test_running_yourself_as_module[pyinstrument_invocation1] PASSED [ 29%] 207s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation0] PASSED [ 30%] 207s test/test_cmdline.py::TestCommandLine::test_path[pyinstrument_invocation1] PASSED [ 30%] 208s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation0] PASSED [ 31%] 208s test/test_cmdline.py::TestCommandLine::test_program_passed_as_string[pyinstrument_invocation1] PASSED [ 32%] 208s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation0] PASSED [ 33%] 208s test/test_cmdline.py::TestCommandLine::test_session_save_and_load[pyinstrument_invocation1] PASSED [ 34%] 209s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation0] PASSED [ 34%] 209s test/test_cmdline.py::TestCommandLine::test_interval[pyinstrument_invocation1] PASSED [ 35%] 209s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation0] PASSED [ 36%] 209s test/test_cmdline.py::TestCommandLine::test_invocation_machinery_is_trimmed[pyinstrument_invocation1] PASSED [ 37%] 209s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation0] PASSED [ 38%] 210s test/test_cmdline.py::TestCommandLine::test_target_description[pyinstrument_invocation1] PASSED [ 39%] 210s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation0] PASSED [ 39%] 210s test/test_cmdline.py::TestCommandLine::test_target_description_format[pyinstrument_invocation1] PASSED [ 40%] 210s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation0] PASSED [ 41%] 210s test/test_cmdline.py::TestCommandLine::test_target_description_format_errors[pyinstrument_invocation1] PASSED [ 42%] 210s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation0] PASSED [ 43%] 211s test/test_cmdline.py::TestCommandLine::test_binary_output[pyinstrument_invocation1] PASSED [ 43%] 211s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation0] PASSED [ 44%] 211s test/test_cmdline.py::TestCommandLine::test_program_exit_code[pyinstrument_invocation1] PASSED [ 45%] 211s test/test_cmdline_main.py::test_renderer_option PASSED [ 46%] 211s test/test_cmdline_main.py::test_json_renderer_option PASSED [ 47%] 211s test/test_cmdline_main.py::test_dotted_renderer_option PASSED [ 47%] 211s test/test_context_manager.py::test_profile_context_decorator PASSED [ 48%] 211s test/test_context_manager.py::test_profile_context_manager PASSED [ 49%] 211s test/test_ipython_magic.py::test_magics SKIPPED (requires --only-ipy...) [ 50%] 211s test/test_ipython_magic.py::test_magic_empty_line SKIPPED (requires ...) [ 51%] 211s test/test_ipython_magic.py::test_magic_no_variable_expansion SKIPPED [ 52%] 211s test/test_ipython_magic.py::test_pyinstrument_handles_interrupt_silently SKIPPED [ 52%] 211s test/test_ipython_magic.py::test_async_cell_with_pyinstrument SKIPPED [ 53%] 211s test/test_overflow.py::test_console PASSED [ 54%] 211s test/test_overflow.py::test_html PASSED [ 55%] 211s test/test_overflow.py::test_json PASSED [ 56%] 212s test/test_overflow.py::test_speedscope PASSED [ 56%] 212s test/test_overflow.py::test_prof PASSED [ 57%] 212s test/test_processors.py::test_frame_passthrough_none PASSED [ 58%] 212s test/test_processors.py::test_remove_importlib PASSED [ 59%] 212s test/test_processors.py::test_remove_hidden PASSED [ 60%] 212s test/test_processors.py::test_merge_consecutive_self_time PASSED [ 60%] 212s test/test_processors.py::test_aggregate_repeated_calls PASSED [ 61%] 212s test/test_processors.py::test_remove_irrelevant_nodes PASSED [ 62%] 212s test/test_processors.py::test_remove_unnecessary_self_time_nodes PASSED [ 63%] 212s test/test_processors.py::test_group_library_frames_processor PASSED [ 64%] 212s test/test_profiler.py::test_collapses_multiple_calls_by_default PASSED [ 65%] 212s test/test_profiler.py::test_profiler_retains_multiple_calls PASSED [ 65%] 212s test/test_profiler.py::test_two_functions PASSED [ 66%] 212s test/test_profiler.py::test_class_methods PASSED [ 67%] 212s test/test_profiler.py::test_context_manager PASSED [ 68%] 212s test/test_profiler.py::test_json_output PASSED [ 69%] 212s test/test_profiler.py::test_speedscope_output PASSED [ 69%] 212s test/test_profiler.py::test_empty_profile PASSED [ 70%] 212s test/test_profiler.py::test_state_management PASSED [ 71%] 212s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[print-ConsoleRenderer] PASSED [ 72%] 212s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_text-ConsoleRenderer] PASSED [ 73%] 212s test/test_profiler.py::test_profiler_convenience_methods_have_all_options_available[output_html-HTMLRenderer] PASSED [ 73%] 212s test/test_profiler.py::test_profiler_raises_on_double_subscribe PASSED [ 74%] 212s test/test_profiler_async.py::test_sleep PASSED [ 75%] 212s test/test_profiler_async.py::test_sleep_trio PASSED [ 76%] 212s test/test_profiler_async.py::test_profiler_task_isolation[asyncio] PASSED [ 77%] 212s test/test_profiler_async.py::test_profiler_task_isolation[trio] PASSED [ 78%] 212s test/test_profiler_async.py::test_greenlet PASSED [ 78%] 212s test/test_profiler_async.py::test_strict_with_greenlet PASSED [ 79%] 212s test/test_pstats_renderer.py::test_pstats_renderer PASSED [ 80%] 212s test/test_pstats_renderer.py::test_round_trip_encoding_of_binary_data PASSED [ 81%] 212s test/test_pstats_renderer.py::test_sum_of_tottime PASSED [ 82%] 212s test/test_renderers.py::test_empty_profile[ConsoleRenderer] PASSED [ 82%] 212s test/test_renderers.py::test_empty_profile[JSONRenderer] PASSED [ 83%] 212s test/test_renderers.py::test_empty_profile[PstatsRenderer] PASSED [ 84%] 212s test/test_renderers.py::test_empty_profile[SpeedscopeRenderer] PASSED [ 85%] 212s test/test_renderers.py::test_timeline_doesnt_crash[ConsoleRenderer] PASSED [ 86%] 212s test/test_renderers.py::test_timeline_doesnt_crash[JSONRenderer] PASSED [ 86%] 212s test/test_renderers.py::test_timeline_doesnt_crash[PstatsRenderer] PASSED [ 87%] 212s test/test_renderers.py::test_timeline_doesnt_crash[SpeedscopeRenderer] PASSED [ 88%] 212s test/test_renderers.py::test_show_all_doesnt_crash[ConsoleRenderer] PASSED [ 89%] 212s test/test_renderers.py::test_show_all_doesnt_crash[JSONRenderer] PASSED [ 90%] 212s test/test_renderers.py::test_show_all_doesnt_crash[PstatsRenderer] PASSED [ 91%] 212s test/test_renderers.py::test_show_all_doesnt_crash[SpeedscopeRenderer] PASSED [ 91%] 212s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[self] PASSED [ 92%] 212s test/test_renderers.py::test_console_renderer_flat_doesnt_crash[total] PASSED [ 93%] 212s test/test_renderers.py::test_html_renderer_resampling PASSED [ 94%] 212s test/test_stack_sampler.py::test_create PASSED [ 95%] 212s test/test_stack_sampler.py::test_get_samples PASSED [ 95%] 212s test/test_stack_sampler.py::test_multiple_samplers PASSED [ 96%] 212s test/test_stack_sampler.py::test_multiple_samplers_async_error PASSED [ 97%] 212s test/test_stack_sampler.py::test_multiple_contexts PASSED [ 98%] 212s test/test_stack_sampler.py::test_same_callback_twice_error PASSED [ 99%] 212s test/test_threading.py::test_profiler_access_from_multiple_threads PASSED [100%] 212s 212s ================ 118 passed, 5 skipped, 8 deselected in 11.40s ================= 212s 212s autopkgtest [05:41:59]: test upstream-tests: -----------------------] 213s autopkgtest [05:42:00]: test upstream-tests: - - - - - - - - - - results - - - - - - - - - - 213s upstream-tests PASS 213s autopkgtest [05:42:00]: test autodep8-python3: preparing testbed 1403s Creating nova instance adt-resolute-arm64-python-pyinstrument-20260205-053827-juju-7f2275-prod-proposed-migration-environment-20-77c1919f-239e-4cd5-8fdb-ef417ff85b83 from image adt/ubuntu-resolute-arm64-server-20260204.img (UUID f58d981d-b271-4157-b9b1-fd704695563c)... 1484s autopkgtest [06:03:11]: testbed dpkg architecture: arm64 1484s autopkgtest [06:03:11]: testbed apt version: 3.1.14 1485s autopkgtest [06:03:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1485s autopkgtest [06:03:12]: testbed release detected to be: resolute 1486s autopkgtest [06:03:13]: updating testbed package index (apt update) 1486s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 1486s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 1486s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 1486s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 1486s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [228 kB] 1486s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB] 1486s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B] 1487s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB] 1487s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [269 kB] 1487s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [7264 B] 1487s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [52.9 kB] 1487s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [328 B] 1487s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [1556 kB] 1487s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [41.9 kB] 1487s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [26.0 kB] 1487s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [792 B] 1491s Fetched 4067 kB in 1s (3317 kB/s) 1491s Reading package lists... 1492s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 1492s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 1492s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 1492s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 1493s Reading package lists... 1493s Reading package lists... 1493s Building dependency tree... 1493s Reading state information... 1494s Calculating upgrade... 1494s The following packages will be upgraded: 1494s libc-bin libc-gconv-modules-extra libc6 locales pollinate python3-linkify-it 1494s python3-referencing sed 1494s 8 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1494s Need to get 8110 kB of archives. 1494s After this operation, 0 B of additional disk space will be used. 1494s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 sed arm64 4.9-2build3 [193 kB] 1494s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-gconv-modules-extra arm64 2.42-2ubuntu5 [1413 kB] 1494s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc6 arm64 2.42-2ubuntu5 [1594 kB] 1494s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libc-bin arm64 2.42-2ubuntu5 [599 kB] 1494s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 locales all 2.42-2ubuntu5 [4255 kB] 1494s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-linkify-it all 2.0.3-1ubuntu3 [19.4 kB] 1494s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 pollinate all 4.33-4ubuntu5 [14.0 kB] 1494s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-referencing all 0.36.2-1ubuntu2 [22.2 kB] 1495s dpkg-preconfigure: unable to re-open stdin: No such file or directory 1495s Fetched 8110 kB in 0s (27.2 MB/s) 1495s (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 ... 89360 files and directories currently installed.) 1495s Preparing to unpack .../sed_4.9-2build3_arm64.deb ... 1495s Unpacking sed (4.9-2build3) over (4.9-2build2) ... 1495s Setting up sed (4.9-2build3) ... 1495s (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 ... 89360 files and directories currently installed.) 1495s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_arm64.deb ... 1495s Unpacking libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 1495s Setting up libc-gconv-modules-extra:arm64 (2.42-2ubuntu5) ... 1495s (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 ... 89360 files and directories currently installed.) 1495s Preparing to unpack .../libc6_2.42-2ubuntu5_arm64.deb ... 1495s Unpacking libc6:arm64 (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 1495s Setting up libc6:arm64 (2.42-2ubuntu5) ... 1496s (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 ... 89360 files and directories currently installed.) 1496s Preparing to unpack .../libc-bin_2.42-2ubuntu5_arm64.deb ... 1496s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 1496s Setting up libc-bin (2.42-2ubuntu5) ... 1496s (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 ... 89360 files and directories currently installed.) 1496s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ... 1496s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ... 1496s Preparing to unpack .../python3-linkify-it_2.0.3-1ubuntu3_all.deb ... 1496s Unpacking python3-linkify-it (2.0.3-1ubuntu3) over (2.0.3-1ubuntu2) ... 1496s Preparing to unpack .../pollinate_4.33-4ubuntu5_all.deb ... 1496s Unpacking pollinate (4.33-4ubuntu5) over (4.33-4ubuntu4) ... 1496s Preparing to unpack .../python3-referencing_0.36.2-1ubuntu2_all.deb ... 1496s Unpacking python3-referencing (0.36.2-1ubuntu2) over (0.36.2-1ubuntu1) ... 1496s Setting up locales (2.42-2ubuntu5) ... 1497s Generating locales (this might take a while)... 1499s en_US.UTF-8... done 1499s Generation complete. 1499s Setting up pollinate (4.33-4ubuntu5) ... 1509s Setting up python3-linkify-it (2.0.3-1ubuntu3) ... 1510s Setting up python3-referencing (0.36.2-1ubuntu2) ... 1510s Processing triggers for man-db (2.13.1-1) ... 1511s Processing triggers for install-info (7.2-5) ... 1511s Processing triggers for systemd (259-1ubuntu3) ... 1512s autopkgtest [06:03:39]: upgrading testbed (apt dist-upgrade and autopurge) 1512s Reading package lists... 1512s Building dependency tree... 1512s Reading state information... 1512s Calculating upgrade... 1513s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1513s Reading package lists... 1514s Building dependency tree... 1514s Reading state information... 1514s Solving dependencies... 1514s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1514s autopkgtest [06:03:41]: rebooting testbed after setup commands that affected boot 1552s Reading package lists... 1552s Building dependency tree... 1552s Reading state information... 1552s Solving dependencies... 1553s The following NEW packages will be installed: 1553s libpython3.14-minimal libpython3.14-stdlib python3-all python3-pyinstrument 1553s python3.14 python3.14-minimal 1553s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded. 1553s Need to get 6751 kB of archives. 1553s After this operation, 26.6 MB of additional disk space will be used. 1553s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-minimal arm64 3.14.2-1 [917 kB] 1553s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14-minimal arm64 3.14.2-1 [2548 kB] 1554s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-stdlib arm64 3.14.2-1 [2351 kB] 1554s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14 arm64 3.14.2-1 [816 kB] 1554s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-all arm64 3.13.9-3 [890 B] 1554s Get:6 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pyinstrument arm64 5.1.2+ds-1 [118 kB] 1554s Fetched 6751 kB in 1s (5484 kB/s) 1554s Selecting previously unselected package libpython3.14-minimal:arm64. 1555s (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 ... 89360 files and directories currently installed.) 1555s Preparing to unpack .../0-libpython3.14-minimal_3.14.2-1_arm64.deb ... 1555s Unpacking libpython3.14-minimal:arm64 (3.14.2-1) ... 1555s Selecting previously unselected package python3.14-minimal. 1555s Preparing to unpack .../1-python3.14-minimal_3.14.2-1_arm64.deb ... 1555s Unpacking python3.14-minimal (3.14.2-1) ... 1555s Selecting previously unselected package libpython3.14-stdlib:arm64. 1555s Preparing to unpack .../2-libpython3.14-stdlib_3.14.2-1_arm64.deb ... 1555s Unpacking libpython3.14-stdlib:arm64 (3.14.2-1) ... 1555s Selecting previously unselected package python3.14. 1555s Preparing to unpack .../3-python3.14_3.14.2-1_arm64.deb ... 1555s Unpacking python3.14 (3.14.2-1) ... 1555s Selecting previously unselected package python3-all. 1555s Preparing to unpack .../4-python3-all_3.13.9-3_arm64.deb ... 1555s Unpacking python3-all (3.13.9-3) ... 1555s Selecting previously unselected package python3-pyinstrument. 1555s Preparing to unpack .../5-python3-pyinstrument_5.1.2+ds-1_arm64.deb ... 1555s Unpacking python3-pyinstrument (5.1.2+ds-1) ... 1555s Setting up libpython3.14-minimal:arm64 (3.14.2-1) ... 1555s Setting up python3-pyinstrument (5.1.2+ds-1) ... 1555s Setting up python3.14-minimal (3.14.2-1) ... 1556s Setting up libpython3.14-stdlib:arm64 (3.14.2-1) ... 1556s Setting up python3.14 (3.14.2-1) ... 1557s Setting up python3-all (3.13.9-3) ... 1557s Processing triggers for man-db (2.13.1-1) ... 1558s Processing triggers for systemd (259-1ubuntu3) ... 1561s autopkgtest [06:04:28]: 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 1561s autopkgtest [06:04:28]: test autodep8-python3: [----------------------- 1561s Testing with python3.14: 1561s 1561s Testing with python3.13: 1561s 1562s autopkgtest [06:04:29]: test autodep8-python3: -----------------------] 1562s autodep8-python3 PASS (superficial) 1562s autopkgtest [06:04:29]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 1562s autopkgtest [06:04:29]: @@@@@@@@@@@@@@@@@@@@ summary 1562s upstream-tests PASS 1562s autodep8-python3 PASS (superficial)