0s autopkgtest [21:47:02]: starting date and time: 2024-03-20 21:47:02+0000 0s autopkgtest [21:47:02]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [21:47:02]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.bf_7ued7/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:tox-delay,src:feature-check --apt-upgrade tox-delay --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=tox-delay/0.1.4-1 feature-check/2.1.0-2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos01-ppc64el-16.secgroup --name adt-noble-ppc64el-tox-delay-20240320-213052-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 268s autopkgtest [21:51:30]: testbed dpkg architecture: ppc64el 268s autopkgtest [21:51:30]: testbed apt version: 2.7.12 268s autopkgtest [21:51:30]: @@@@@@@@@@@@@@@@@@@@ test bed setup 270s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 270s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 270s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 270s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3811 kB] 271s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [500 kB] 271s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [684 kB] 271s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 271s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 271s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 271s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4161 kB] 271s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 271s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [58.6 kB] 271s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 275s Fetched 9406 kB in 3s (3284 kB/s) 275s Reading package lists... 278s Reading package lists... 278s Building dependency tree... 278s Reading state information... 279s Calculating upgrade... 279s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 279s Reading package lists... 279s Building dependency tree... 279s Reading state information... 279s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 280s sh: Attempting to set up Debian/Ubuntu apt sources automatically 280s sh: Distribution appears to be Ubuntu 281s Reading package lists... 281s Building dependency tree... 281s Reading state information... 282s eatmydata is already the newest version (131-1). 282s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 282s Reading package lists... 282s Building dependency tree... 282s Reading state information... 282s dbus is already the newest version (1.14.10-4ubuntu1). 282s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 282s Reading package lists... 283s Building dependency tree... 283s Reading state information... 283s rng-tools-debian is already the newest version (2.4). 283s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 283s Reading package lists... 283s Building dependency tree... 283s Reading state information... 283s The following packages will be REMOVED: 283s cloud-init* python3-configobj* python3-debconf* 284s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 284s After this operation, 3252 kB disk space will be freed. 284s (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 ... 70095 files and directories currently installed.) 284s Removing cloud-init (24.1.1-0ubuntu1) ... 284s Removing python3-configobj (5.0.8-3) ... 284s Removing python3-debconf (1.5.86) ... 284s Processing triggers for man-db (2.12.0-3) ... 285s (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 ... 69706 files and directories currently installed.) 285s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 286s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 286s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 286s invoke-rc.d: policy-rc.d denied execution of try-restart. 286s Reading package lists... 286s Building dependency tree... 286s Reading state information... 286s linux-generic is already the newest version (6.8.0-11.11+1). 286s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 287s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 287s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 287s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 290s Reading package lists... 290s Reading package lists... 290s Building dependency tree... 290s Reading state information... 290s Calculating upgrade... 290s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 290s Reading package lists... 291s Building dependency tree... 291s Reading state information... 291s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 291s autopkgtest [21:51:53]: rebooting testbed after setup commands that affected boot 459s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 467s autopkgtest [21:54:49]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 469s autopkgtest [21:54:51]: @@@@@@@@@@@@@@@@@@@@ apt-source tox-delay 471s Get:1 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (dsc) [2490 B] 471s Get:2 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (tar) [10.3 kB] 471s Get:3 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (asc) [833 B] 471s Get:4 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (diff) [5952 B] 471s gpgv: Signature made Thu Jun 15 23:22:46 2023 UTC 471s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 471s gpgv: issuer "roam@debian.org" 471s gpgv: Can't check signature: No public key 471s dpkg-source: warning: cannot verify inline signature for ./tox-delay_0.1.2-2.dsc: no acceptable signature found 471s autopkgtest [21:54:53]: testing package tox-delay version 0.1.2-2 472s autopkgtest [21:54:54]: build not needed 472s autopkgtest [21:54:54]: test upstream_python: preparing testbed 483s Reading package lists... 484s Building dependency tree... 484s Reading state information... 484s Starting pkgProblemResolver with broken count: 0 484s Starting 2 pkgProblemResolver with broken count: 0 484s Done 484s The following additional packages will be installed: 484s libjs-jquery libjs-sphinxdoc libjs-underscore python3-cachetools 484s python3-colorama python3-distlib python3-feature-check python3-filelock 484s python3-packaging python3-pip-whl python3-platformdirs python3-pluggy 484s python3-pyproject-api python3-setuptools-whl python3-utf8-locale 484s python3-virtualenv python3-wheel-whl tox tox-delay 484s Recommended packages: 484s javascript-common build-essential python3-dev 484s The following NEW packages will be installed: 484s autopkgtest-satdep libjs-jquery libjs-sphinxdoc libjs-underscore 484s python3-cachetools python3-colorama python3-distlib python3-feature-check 484s python3-filelock python3-packaging python3-pip-whl python3-platformdirs 484s python3-pluggy python3-pyproject-api python3-setuptools-whl 484s python3-utf8-locale python3-virtualenv python3-wheel-whl tox tox-delay 484s 0 upgraded, 20 newly installed, 0 to remove and 0 not upgraded. 484s Need to get 3981 kB/3982 kB of archives. 484s After this operation, 9272 kB of additional disk space will be used. 484s Get:1 /tmp/autopkgtest.vf7pHF/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [744 B] 484s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 485s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 485s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el libjs-sphinxdoc all 7.2.6-4 [149 kB] 485s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-cachetools all 5.3.0-2 [10.6 kB] 485s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-colorama all 0.4.6-4 [32.1 kB] 485s Get:7 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-distlib all 0.3.8-1 [318 kB] 485s Get:8 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-feature-check all 1.0.1-2 [11.1 kB] 485s Get:9 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-filelock all 3.13.1-1 [10.8 kB] 485s Get:10 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-packaging all 23.2-1 [40.6 kB] 485s Get:11 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-pip-whl all 24.0+dfsg-1 [1702 kB] 486s Get:12 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-platformdirs all 4.2.0-1 [16.1 kB] 486s Get:13 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-pluggy all 1.4.0-1 [20.4 kB] 486s Get:14 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-pyproject-api all 1.6.1-1 [50.9 kB] 486s Get:15 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-setuptools-whl all 68.1.2-2 [715 kB] 486s Get:16 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-utf8-locale all 1.0.3-1 [9412 B] 486s Get:17 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-wheel-whl all 0.42.0-1 [67.8 kB] 486s Get:18 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-virtualenv all 20.25.0+ds-1 [70.7 kB] 486s Get:19 http://ftpmaster.internal/ubuntu noble/universe ppc64el tox all 4.13.0-1 [302 kB] 486s Get:20 http://ftpmaster.internal/ubuntu noble/universe ppc64el tox-delay all 0.1.2-2 [7602 B] 486s Fetched 3981 kB in 2s (2226 kB/s) 486s Selecting previously unselected package libjs-jquery. 486s (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 ... 69651 files and directories currently installed.) 486s Preparing to unpack .../00-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 486s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 486s Selecting previously unselected package libjs-underscore. 487s Preparing to unpack .../01-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 487s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 487s Selecting previously unselected package libjs-sphinxdoc. 487s Preparing to unpack .../02-libjs-sphinxdoc_7.2.6-4_all.deb ... 487s Unpacking libjs-sphinxdoc (7.2.6-4) ... 487s Selecting previously unselected package python3-cachetools. 487s Preparing to unpack .../03-python3-cachetools_5.3.0-2_all.deb ... 487s Unpacking python3-cachetools (5.3.0-2) ... 487s Selecting previously unselected package python3-colorama. 487s Preparing to unpack .../04-python3-colorama_0.4.6-4_all.deb ... 487s Unpacking python3-colorama (0.4.6-4) ... 487s Selecting previously unselected package python3-distlib. 487s Preparing to unpack .../05-python3-distlib_0.3.8-1_all.deb ... 487s Unpacking python3-distlib (0.3.8-1) ... 487s Selecting previously unselected package python3-feature-check. 487s Preparing to unpack .../06-python3-feature-check_1.0.1-2_all.deb ... 487s Unpacking python3-feature-check (1.0.1-2) ... 487s Selecting previously unselected package python3-filelock. 487s Preparing to unpack .../07-python3-filelock_3.13.1-1_all.deb ... 487s Unpacking python3-filelock (3.13.1-1) ... 487s Selecting previously unselected package python3-packaging. 487s Preparing to unpack .../08-python3-packaging_23.2-1_all.deb ... 487s Unpacking python3-packaging (23.2-1) ... 487s Selecting previously unselected package python3-pip-whl. 487s Preparing to unpack .../09-python3-pip-whl_24.0+dfsg-1_all.deb ... 487s Unpacking python3-pip-whl (24.0+dfsg-1) ... 487s Selecting previously unselected package python3-platformdirs. 487s Preparing to unpack .../10-python3-platformdirs_4.2.0-1_all.deb ... 487s Unpacking python3-platformdirs (4.2.0-1) ... 487s Selecting previously unselected package python3-pluggy. 487s Preparing to unpack .../11-python3-pluggy_1.4.0-1_all.deb ... 487s Unpacking python3-pluggy (1.4.0-1) ... 487s Selecting previously unselected package python3-pyproject-api. 487s Preparing to unpack .../12-python3-pyproject-api_1.6.1-1_all.deb ... 487s Unpacking python3-pyproject-api (1.6.1-1) ... 487s Selecting previously unselected package python3-setuptools-whl. 487s Preparing to unpack .../13-python3-setuptools-whl_68.1.2-2_all.deb ... 487s Unpacking python3-setuptools-whl (68.1.2-2) ... 487s Selecting previously unselected package python3-utf8-locale. 487s Preparing to unpack .../14-python3-utf8-locale_1.0.3-1_all.deb ... 487s Unpacking python3-utf8-locale (1.0.3-1) ... 487s Selecting previously unselected package python3-wheel-whl. 487s Preparing to unpack .../15-python3-wheel-whl_0.42.0-1_all.deb ... 487s Unpacking python3-wheel-whl (0.42.0-1) ... 487s Selecting previously unselected package python3-virtualenv. 487s Preparing to unpack .../16-python3-virtualenv_20.25.0+ds-1_all.deb ... 487s Unpacking python3-virtualenv (20.25.0+ds-1) ... 487s Selecting previously unselected package tox. 487s Preparing to unpack .../17-tox_4.13.0-1_all.deb ... 487s Unpacking tox (4.13.0-1) ... 487s Selecting previously unselected package tox-delay. 487s Preparing to unpack .../18-tox-delay_0.1.2-2_all.deb ... 487s Unpacking tox-delay (0.1.2-2) ... 487s Selecting previously unselected package autopkgtest-satdep. 487s Preparing to unpack .../19-1-autopkgtest-satdep.deb ... 487s Unpacking autopkgtest-satdep (0) ... 487s Setting up python3-setuptools-whl (68.1.2-2) ... 487s Setting up python3-filelock (3.13.1-1) ... 487s Setting up tox-delay (0.1.2-2) ... 487s Setting up python3-cachetools (5.3.0-2) ... 487s Setting up python3-colorama (0.4.6-4) ... 487s Setting up python3-pip-whl (24.0+dfsg-1) ... 487s Setting up python3-feature-check (1.0.1-2) ... 488s Setting up python3-distlib (0.3.8-1) ... 488s Setting up python3-platformdirs (4.2.0-1) ... 488s Setting up python3-packaging (23.2-1) ... 488s Setting up python3-pluggy (1.4.0-1) ... 488s Setting up python3-utf8-locale (1.0.3-1) ... 488s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 488s Setting up python3-wheel-whl (0.42.0-1) ... 488s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 488s Setting up python3-virtualenv (20.25.0+ds-1) ... 489s Setting up libjs-sphinxdoc (7.2.6-4) ... 489s Setting up python3-pyproject-api (1.6.1-1) ... 489s Setting up tox (4.13.0-1) ... 489s Setting up autopkgtest-satdep (0) ... 489s Processing triggers for man-db (2.12.0-3) ... 492s (Reading database ... 70306 files and directories currently installed.) 492s Removing autopkgtest-satdep (0) ... 492s autopkgtest [21:55:14]: test upstream_python: python3 tests/functional.py -e /usr/bin/tox-delay 492s autopkgtest [21:55:14]: test upstream_python: [----------------------- 493s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 493s fourth thing-marker 493s fourth.: OK ✔ in 0.25 seconds 493s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 493s second thing-marker 494s second: OK ✔ in 0.13 seconds 494s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 494s first thing-marker 494s first: OK ✔ in 0.1 seconds 494s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 494s third thing-marker 494s fourth.: OK (0.25=setup[0.22]+cmd[0.02] seconds) 494s second: OK (0.13=setup[0.10]+cmd[0.03] seconds) 494s first: OK (0.10=setup[0.07]+cmd[0.03] seconds) 494s third: OK (0.10=setup[0.07]+cmd[0.03] seconds) 494s congratulations :) (0.69 seconds) 494s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 494s fourth -- this and that thing-marker 494s fourth.: OK ✔ in 0.06 seconds 494s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 494s second -- this and that thing-marker 494s second: OK ✔ in 0.03 seconds 494s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 494s first -- this and that thing-marker 494s first: OK ✔ in 0.03 seconds 494s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 494s third -- this and that thing-marker 494s fourth.: OK (0.06=setup[0.04]+cmd[0.02] seconds) 494s second: OK (0.03=setup[0.00]+cmd[0.02] seconds) 494s first: OK (0.03=setup[0.01]+cmd[0.02] seconds) 494s third: OK (0.03=setup[0.00]+cmd[0.02] seconds) 494s congratulations :) (0.24 seconds) 495s ROOT: skip environment second, matches filter '^(second|third)$' 495s ROOT: skip environment third, matches filter '^(second|third)$' 495s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 495s first thing-marker 495s first: OK ✔ in 0.06 seconds 495s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 495s fourth thing-marker 495s first: OK (0.06=setup[0.04]+cmd[0.02] seconds) 495s fourth.: OK (0.03=setup[0.00]+cmd[0.02] seconds) 495s congratulations :) (0.19 seconds) 496s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 496s second thing-marker 496s second: OK ✔ in 0.06 seconds 496s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 496s third thing-marker 496s second: OK (0.06=setup[0.04]+cmd[0.02] seconds) 496s third: OK (0.03=setup[0.00]+cmd[0.02] seconds) 496s congratulations :) (0.19 seconds) 496s ROOT: skip environment second, matches filter '^(second|third)$' 496s ROOT: skip environment third, matches filter '^(second|third)$' 496s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 496s first this and that thing-marker 496s first: OK ✔ in 0.06 seconds 496s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 496s fourth this and that thing-marker 496s first: OK (0.06=setup[0.04]+cmd[0.02] seconds) 496s fourth.: OK (0.03=setup[0.01]+cmd[0.02] seconds) 496s congratulations :) (0.19 seconds) 496s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 496s second this and that thing-marker 496s second: OK ✔ in 0.06 seconds 496s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 496s third this and that thing-marker 496s second: OK (0.06=setup[0.04]+cmd[0.02] seconds) 496s third: OK (0.03=setup[0.01]+cmd[0.02] seconds) 496s congratulations :) (0.19 seconds) 498s ROOT: skip environment third, matches filter '^(third)$' 498s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 498s first thing-marker 498s first: OK ✔ in 0.06 seconds 498s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 498s second thing-marker 498s second: OK ✔ in 0.03 seconds 498s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 498s fourth thing-marker 498s first: OK (0.06=setup[0.04]+cmd[0.02] seconds) 498s second: OK (0.03=setup[0.00]+cmd[0.02] seconds) 498s fourth.: OK (0.03=setup[0.01]+cmd[0.02] seconds) 498s congratulations :) (0.22 seconds) 499s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 499s third thing-marker 499s third: OK (0.06=setup[0.04]+cmd[0.02] seconds) 499s congratulations :) (0.16 seconds) 499s ROOT: skip environment third, matches filter '^(third)$' 499s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 499s first this and that thing-marker 499s first: OK ✔ in 0.06 seconds 499s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 499s second this and that thing-marker 499s second: OK ✔ in 0.03 seconds 499s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 499s fourth this and that thing-marker 499s first: OK (0.06=setup[0.04]+cmd[0.02] seconds) 499s second: OK (0.03=setup[0.01]+cmd[0.02] seconds) 499s fourth.: OK (0.03=setup[0.01]+cmd[0.02] seconds) 499s congratulations :) (0.22 seconds) 499s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 499s third this and that thing-marker 499s third: OK (0.06=setup[0.04]+cmd[0.03] seconds) 499s congratulations :) (0.17 seconds) 501s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 501s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 501s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 501s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 501s first thing-marker 501s first: OK (0.06=setup[0.04]+cmd[0.02] seconds) 501s congratulations :) (0.17 seconds) 501s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 501s third thing-marker 501s third: OK (0.07=setup[0.04]+cmd[0.03] seconds) 501s congratulations :) (0.18 seconds) 502s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 502s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 502s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 502s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 502s first this and that thing-marker 502s first: OK (0.06=setup[0.04]+cmd[0.02] seconds) 502s congratulations :) (0.18 seconds) 502s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 502s third this and that thing-marker 502s third: OK (0.07=setup[0.04]+cmd[0.02] seconds) 502s congratulations :) (0.17 seconds) 504s ROOT: skip environment second, matches filter '^(second|third)$' 504s ROOT: skip environment third, matches filter '^(second|third)$' 504s  ⠋ [0/2]  ⠙ [2/2] first | fourth. fourth.: OK ✔ in 0.1 seconds 504s   first: OK (0.11=setup[0.05]+cmd[0.06] seconds) 504s fourth.: OK (0.10=setup[0.05]+cmd[0.05] seconds) 504s congratulations :) (0.21 seconds) 504s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 504s second thing-marker 504s second: OK ✔ in 0.06 seconds 504s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 504s third thing-marker 504s second: OK (0.06=setup[0.04]+cmd[0.02] seconds) 504s third: OK (0.03=setup[0.01]+cmd[0.02] seconds) 504s congratulations :) (0.19 seconds) 505s ROOT: skip environment second, matches filter '^(second|third)$' 505s ROOT: skip environment third, matches filter '^(second|third)$' 505s  ⠋ [0/2]  ⠙ [2/2] first | fourth. first: OK ✔ in 0.1 seconds 505s   first: OK (0.10=setup[0.05]+cmd[0.05] seconds) 505s fourth.: OK (0.10=setup[0.05]+cmd[0.05] seconds) 505s congratulations :) (0.21 seconds) 505s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 505s second this and that thing-marker 505s second: OK ✔ in 0.07 seconds 505s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 505s third this and that thing-marker 505s second: OK (0.06=setup[0.04]+cmd[0.03] seconds) 505s third: OK (0.03=setup[0.01]+cmd[0.02] seconds) 505s congratulations :) (0.20 seconds) 507s ROOT: skip environment third, matches filter '^(third)$' 507s  ⠋ [0/3]  ⠙ [3/3] first | second | fourth. fourth.: OK ✔ in 0.14 seconds 507s first: OK ✔ in 0.15 seconds 507s   first: OK (0.15=setup[0.06]+cmd[0.09] seconds) 507s second: OK (0.15=setup[0.06]+cmd[0.09] seconds) 507s fourth.: OK (0.14=setup[0.06]+cmd[0.09] seconds) 507s congratulations :) (0.26 seconds) 507s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 507s third thing-marker 507s third: OK (0.06=setup[0.04]+cmd[0.02] seconds) 507s congratulations :) (0.18 seconds) 508s ROOT: skip environment third, matches filter '^(third)$' 508s  ⠋ [0/3]  ⠙ [3/3] first | second | fourth. fourth.: OK ✔ in 0.12 seconds 508s first: OK ✔ in 0.13 seconds 508s   first: OK (0.13=setup[0.06]+cmd[0.08] seconds) 508s second: OK (0.13=setup[0.06]+cmd[0.07] seconds) 508s fourth.: OK (0.12=setup[0.05]+cmd[0.07] seconds) 508s congratulations :) (0.24 seconds) 508s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 508s third this and that thing-marker 508s third: OK (0.06=setup[0.04]+cmd[0.02] seconds) 508s congratulations :) (0.17 seconds) 510s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 510s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 510s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 510s  ⠋ [0/1]   first: OK (0.06=setup[0.04]+cmd[0.03] seconds) 510s congratulations :) (0.17 seconds) 510s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 510s third thing-marker 510s third: OK (0.06=setup[0.04]+cmd[0.02] seconds) 510s congratulations :) (0.17 seconds) 511s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 511s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 511s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 511s  ⠋ [0/1]   first: OK (0.06=setup[0.04]+cmd[0.02] seconds) 511s congratulations :) (0.17 seconds) 511s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 511s third this and that thing-marker 511s third: OK (0.06=setup[0.04]+cmd[0.02] seconds) 511s congratulations :) (0.19 seconds) 513s Using UTF-8 settings: LC_ALL 'C.utf8' LANGUAGE '' 513s Trying to find the absolute path to the python3 Python interpreter 513s Apparently there is no need to set PYTHONPATH at all 513s Using /tmp/tmpcgb6kmxn as a temporary directory 513s Got a tox-delay feature version '0.1.2' 513s Wrote something to /tmp/tmpcgb6kmxn/tox.ini: 513s 513s [tox] 513s skipsdist = True 513s envlist = 513s first 513s second 513s third 513s fourth. 513s [testenv:first] 513s commands = 513s python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 513s 513s [testenv:second] 513s commands = 513s python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 513s 513s [testenv:third] 513s commands = 513s python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 513s 513s [testenv:fourth.] 513s commands = 513s python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 513s 513s Making sure Tox itself works 513s 513s Running tox for fourth.,second,first,third without output capturing 513s 513s 513s Running tox for fourth.,second,first,third and examining its output 513s 513s ['/usr/bin/python3', '-m', 'tox', '-e', 'fourth.,second,first,third'] 513s 513s Running tox for fourth.,second,first,third with arguments and examining its output 513s 513s ['/usr/bin/python3', '-m', 'tox', '-e', 'fourth.,second,first,third', '--', '--', 'this', 'and that'] 513s Testing tox-delay in non-parallel mode 513s Now let us delay some 513s 513s Running tox_delay for second,third without output capturing 513s 513s 513s Running tox_delay for second,third and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'second,third'] 513s 513s Running tox_delay for second,third with arguments and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'second,third', '--', '--', 'this', 'and that'] 513s Let us delay others 513s 513s Running tox_delay for third without output capturing 513s 513s 513s Running tox_delay for third and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third'] 513s 513s Running tox_delay for third with arguments and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third', '--', '--', 'this', 'and that'] 513s Ignore some environments 513s 513s Running tox_delay for third without output capturing 513s 513s 513s Running tox_delay for third and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.'] 513s 513s Running tox_delay for third with arguments and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.', '--', '--', 'this', 'and that'] 513s Testing tox-delay in parallel mode 513s Now let us delay some 513s 513s Running tox_delay for second,third without output capturing 513s 513s 513s Running tox_delay for second,third in parallel and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'second,third', '-p', 'all'] 513s 513s Running tox_delay for second,third with arguments in parallel and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'second,third', '-p', 'all', '--', '--', 'this', 'and that'] 513s Let us delay others 513s 513s Running tox_delay for third without output capturing 513s 513s 513s Running tox_delay for third in parallel and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third', '-p', 'all'] 513s 513s Running tox_delay for third with arguments in parallel and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third', '-p', 'all', '--', '--', 'this', 'and that'] 513s Ignore some environments 513s 513s Running tox_delay for third without output capturing 513s 513s 513s Running tox_delay for third in parallel and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.', '-p', 'all'] 513s 513s Running tox_delay for third with arguments in parallel and examining its output 513s 513s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.', '-p', 'all', '--', '--', 'this', 'and that'] 513s Seems fine 513s autopkgtest [21:55:35]: test upstream_python: -----------------------] 513s autopkgtest [21:55:35]: test upstream_python: - - - - - - - - - - results - - - - - - - - - - 513s upstream_python PASS 514s autopkgtest [21:55:36]: @@@@@@@@@@@@@@@@@@@@ summary 514s upstream_python PASS 528s Creating nova instance adt-noble-ppc64el-tox-delay-20240320-213052-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-ppc64el-server-20240320.img (UUID 2c394bf3-261b-42bf-b2a7-4487c1d5a6ac)...