0s autopkgtest [22:14:57]: starting date and time: 2024-03-20 22:14:57+0000 0s autopkgtest [22:14:57]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [22:14:57]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.rqw024on/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-2@bos01-s390x-2.secgroup --name adt-noble-s390x-tox-delay-20240320-221457-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 125s autopkgtest [22:17:02]: testbed dpkg architecture: s390x 125s autopkgtest [22:17:02]: testbed apt version: 2.7.12 125s autopkgtest [22:17:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 125s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 126s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 126s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3811 kB] 126s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 126s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [500 kB] 126s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [678 kB] 126s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 126s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 126s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 126s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4061 kB] 127s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 127s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.3 kB] 127s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 129s Fetched 9285 kB in 3s (3508 kB/s) 129s Reading package lists... 132s Reading package lists... 132s Building dependency tree... 132s Reading state information... 132s Calculating upgrade... 133s The following packages will be upgraded: 133s ubuntu-minimal ubuntu-standard 133s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 133s Need to get 21.5 kB of archives. 133s After this operation, 0 B of additional disk space will be used. 133s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x ubuntu-minimal s390x 1.536build1 [10.7 kB] 133s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x ubuntu-standard s390x 1.536build1 [10.7 kB] 133s Fetched 21.5 kB in 0s (102 kB/s) 134s (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 ... 51778 files and directories currently installed.) 134s Preparing to unpack .../ubuntu-minimal_1.536build1_s390x.deb ... 134s Unpacking ubuntu-minimal (1.536build1) over (1.536) ... 134s Preparing to unpack .../ubuntu-standard_1.536build1_s390x.deb ... 134s Unpacking ubuntu-standard (1.536build1) over (1.536) ... 134s Setting up ubuntu-minimal (1.536build1) ... 134s Setting up ubuntu-standard (1.536build1) ... 134s Reading package lists... 134s Building dependency tree... 134s Reading state information... 135s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s Unknown architecture, assuming PC-style ttyS0 135s sh: Attempting to set up Debian/Ubuntu apt sources automatically 135s sh: Distribution appears to be Ubuntu 136s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s eatmydata is already the newest version (131-1). 136s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 136s Reading package lists... 136s Building dependency tree... 136s Reading state information... 137s dbus is already the newest version (1.14.10-4ubuntu1). 137s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 137s Reading package lists... 137s Building dependency tree... 137s Reading state information... 137s rng-tools-debian is already the newest version (2.4). 137s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 137s Reading package lists... 137s Building dependency tree... 137s Reading state information... 138s The following packages will be REMOVED: 138s cloud-init* python3-configobj* python3-debconf* 138s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 138s After this operation, 3252 kB disk space will be freed. 138s (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 ... 51778 files and directories currently installed.) 138s Removing cloud-init (24.1.1-0ubuntu1) ... 138s Removing python3-configobj (5.0.8-3) ... 138s Removing python3-debconf (1.5.86) ... 138s Processing triggers for man-db (2.12.0-3) ... 139s (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 ... 51389 files and directories currently installed.) 139s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 139s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 139s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 139s invoke-rc.d: policy-rc.d denied execution of try-restart. 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s linux-generic is already the newest version (6.8.0-11.11+1). 140s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 141s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 141s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 141s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 143s Reading package lists... 144s Reading package lists... 144s Building dependency tree... 144s Reading state information... 144s Calculating upgrade... 144s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 144s Reading package lists... 144s Building dependency tree... 144s Reading state information... 145s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 145s autopkgtest [22:17:22]: rebooting testbed after setup commands that affected boot 163s autopkgtest [22:17:40]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 166s autopkgtest [22:17:43]: @@@@@@@@@@@@@@@@@@@@ apt-source tox-delay 168s Get:1 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (dsc) [2490 B] 168s Get:2 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (tar) [10.3 kB] 168s Get:3 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (asc) [833 B] 168s Get:4 http://ftpmaster.internal/ubuntu noble/universe tox-delay 0.1.2-2 (diff) [5952 B] 168s gpgv: Signature made Thu Jun 15 23:22:46 2023 UTC 168s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 168s gpgv: issuer "roam@debian.org" 168s gpgv: Can't check signature: No public key 168s dpkg-source: warning: cannot verify inline signature for ./tox-delay_0.1.2-2.dsc: no acceptable signature found 168s autopkgtest [22:17:45]: testing package tox-delay version 0.1.2-2 168s autopkgtest [22:17:45]: build not needed 168s autopkgtest [22:17:45]: test upstream_python: preparing testbed 174s Reading package lists... 174s Building dependency tree... 174s Reading state information... 175s Starting pkgProblemResolver with broken count: 0 175s Starting 2 pkgProblemResolver with broken count: 0 175s Done 175s The following additional packages will be installed: 175s libjs-jquery libjs-sphinxdoc libjs-underscore python3-cachetools 175s python3-colorama python3-distlib python3-feature-check python3-filelock 175s python3-packaging python3-pip-whl python3-platformdirs python3-pluggy 175s python3-pyproject-api python3-setuptools-whl python3-utf8-locale 175s python3-virtualenv python3-wheel-whl tox tox-delay 175s Recommended packages: 175s javascript-common build-essential python3-dev 175s The following NEW packages will be installed: 175s autopkgtest-satdep libjs-jquery libjs-sphinxdoc libjs-underscore 175s python3-cachetools python3-colorama python3-distlib python3-feature-check 175s python3-filelock python3-packaging python3-pip-whl python3-platformdirs 175s python3-pluggy python3-pyproject-api python3-setuptools-whl 175s python3-utf8-locale python3-virtualenv python3-wheel-whl tox tox-delay 175s 0 upgraded, 20 newly installed, 0 to remove and 0 not upgraded. 175s Need to get 3981 kB/3982 kB of archives. 175s After this operation, 9272 kB of additional disk space will be used. 175s Get:1 /tmp/autopkgtest.19L5kh/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [740 B] 175s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 176s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 176s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libjs-sphinxdoc all 7.2.6-4 [149 kB] 176s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x python3-cachetools all 5.3.0-2 [10.6 kB] 176s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x python3-colorama all 0.4.6-4 [32.1 kB] 176s Get:7 http://ftpmaster.internal/ubuntu noble/universe s390x python3-distlib all 0.3.8-1 [318 kB] 176s Get:8 http://ftpmaster.internal/ubuntu noble/universe s390x python3-feature-check all 1.0.1-2 [11.1 kB] 176s Get:9 http://ftpmaster.internal/ubuntu noble/universe s390x python3-filelock all 3.13.1-1 [10.8 kB] 176s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x python3-packaging all 23.2-1 [40.6 kB] 176s Get:11 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pip-whl all 24.0+dfsg-1 [1702 kB] 176s Get:12 http://ftpmaster.internal/ubuntu noble/universe s390x python3-platformdirs all 4.2.0-1 [16.1 kB] 176s Get:13 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pluggy all 1.4.0-1 [20.4 kB] 176s Get:14 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pyproject-api all 1.6.1-1 [50.9 kB] 176s Get:15 http://ftpmaster.internal/ubuntu noble/universe s390x python3-setuptools-whl all 68.1.2-2 [715 kB] 176s Get:16 http://ftpmaster.internal/ubuntu noble/universe s390x python3-utf8-locale all 1.0.3-1 [9412 B] 176s Get:17 http://ftpmaster.internal/ubuntu noble/universe s390x python3-wheel-whl all 0.42.0-1 [67.8 kB] 176s Get:18 http://ftpmaster.internal/ubuntu noble/universe s390x python3-virtualenv all 20.25.0+ds-1 [70.7 kB] 176s Get:19 http://ftpmaster.internal/ubuntu noble/universe s390x tox all 4.13.0-1 [302 kB] 176s Get:20 http://ftpmaster.internal/ubuntu noble/universe s390x tox-delay all 0.1.2-2 [7602 B] 176s Fetched 3981 kB in 1s (4644 kB/s) 176s Selecting previously unselected package libjs-jquery. 176s (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 ... 51332 files and directories currently installed.) 176s Preparing to unpack .../00-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 176s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 176s Selecting previously unselected package libjs-underscore. 176s Preparing to unpack .../01-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 176s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 176s Selecting previously unselected package libjs-sphinxdoc. 176s Preparing to unpack .../02-libjs-sphinxdoc_7.2.6-4_all.deb ... 176s Unpacking libjs-sphinxdoc (7.2.6-4) ... 176s Selecting previously unselected package python3-cachetools. 176s Preparing to unpack .../03-python3-cachetools_5.3.0-2_all.deb ... 176s Unpacking python3-cachetools (5.3.0-2) ... 176s Selecting previously unselected package python3-colorama. 176s Preparing to unpack .../04-python3-colorama_0.4.6-4_all.deb ... 176s Unpacking python3-colorama (0.4.6-4) ... 176s Selecting previously unselected package python3-distlib. 176s Preparing to unpack .../05-python3-distlib_0.3.8-1_all.deb ... 176s Unpacking python3-distlib (0.3.8-1) ... 177s Selecting previously unselected package python3-feature-check. 177s Preparing to unpack .../06-python3-feature-check_1.0.1-2_all.deb ... 177s Unpacking python3-feature-check (1.0.1-2) ... 177s Selecting previously unselected package python3-filelock. 177s Preparing to unpack .../07-python3-filelock_3.13.1-1_all.deb ... 177s Unpacking python3-filelock (3.13.1-1) ... 177s Selecting previously unselected package python3-packaging. 177s Preparing to unpack .../08-python3-packaging_23.2-1_all.deb ... 177s Unpacking python3-packaging (23.2-1) ... 177s Selecting previously unselected package python3-pip-whl. 177s Preparing to unpack .../09-python3-pip-whl_24.0+dfsg-1_all.deb ... 177s Unpacking python3-pip-whl (24.0+dfsg-1) ... 177s Selecting previously unselected package python3-platformdirs. 177s Preparing to unpack .../10-python3-platformdirs_4.2.0-1_all.deb ... 177s Unpacking python3-platformdirs (4.2.0-1) ... 177s Selecting previously unselected package python3-pluggy. 177s Preparing to unpack .../11-python3-pluggy_1.4.0-1_all.deb ... 177s Unpacking python3-pluggy (1.4.0-1) ... 177s Selecting previously unselected package python3-pyproject-api. 177s Preparing to unpack .../12-python3-pyproject-api_1.6.1-1_all.deb ... 177s Unpacking python3-pyproject-api (1.6.1-1) ... 177s Selecting previously unselected package python3-setuptools-whl. 177s Preparing to unpack .../13-python3-setuptools-whl_68.1.2-2_all.deb ... 177s Unpacking python3-setuptools-whl (68.1.2-2) ... 177s Selecting previously unselected package python3-utf8-locale. 177s Preparing to unpack .../14-python3-utf8-locale_1.0.3-1_all.deb ... 177s Unpacking python3-utf8-locale (1.0.3-1) ... 177s Selecting previously unselected package python3-wheel-whl. 177s Preparing to unpack .../15-python3-wheel-whl_0.42.0-1_all.deb ... 177s Unpacking python3-wheel-whl (0.42.0-1) ... 177s Selecting previously unselected package python3-virtualenv. 177s Preparing to unpack .../16-python3-virtualenv_20.25.0+ds-1_all.deb ... 177s Unpacking python3-virtualenv (20.25.0+ds-1) ... 177s Selecting previously unselected package tox. 177s Preparing to unpack .../17-tox_4.13.0-1_all.deb ... 177s Unpacking tox (4.13.0-1) ... 177s Selecting previously unselected package tox-delay. 177s Preparing to unpack .../18-tox-delay_0.1.2-2_all.deb ... 177s Unpacking tox-delay (0.1.2-2) ... 177s Selecting previously unselected package autopkgtest-satdep. 177s Preparing to unpack .../19-1-autopkgtest-satdep.deb ... 177s Unpacking autopkgtest-satdep (0) ... 177s Setting up python3-setuptools-whl (68.1.2-2) ... 177s Setting up python3-filelock (3.13.1-1) ... 177s Setting up tox-delay (0.1.2-2) ... 177s Setting up python3-cachetools (5.3.0-2) ... 177s Setting up python3-colorama (0.4.6-4) ... 177s Setting up python3-pip-whl (24.0+dfsg-1) ... 177s Setting up python3-feature-check (1.0.1-2) ... 177s Setting up python3-distlib (0.3.8-1) ... 178s Setting up python3-platformdirs (4.2.0-1) ... 178s Setting up python3-packaging (23.2-1) ... 178s Setting up python3-pluggy (1.4.0-1) ... 178s Setting up python3-utf8-locale (1.0.3-1) ... 178s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 178s Setting up python3-wheel-whl (0.42.0-1) ... 178s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 178s Setting up python3-virtualenv (20.25.0+ds-1) ... 178s Setting up libjs-sphinxdoc (7.2.6-4) ... 178s Setting up python3-pyproject-api (1.6.1-1) ... 179s Setting up tox (4.13.0-1) ... 179s Setting up autopkgtest-satdep (0) ... 179s Processing triggers for man-db (2.12.0-3) ... 182s (Reading database ... 51987 files and directories currently installed.) 182s Removing autopkgtest-satdep (0) ... 183s autopkgtest [22:18:00]: test upstream_python: python3 tests/functional.py -e /usr/bin/tox-delay 183s autopkgtest [22:18:00]: test upstream_python: [----------------------- 183s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 183s fourth thing-marker 183s fourth.: OK ✔ in 0.22 seconds 183s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 183s second thing-marker 184s second: OK ✔ in 0.09 seconds 184s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 184s first thing-marker 184s first: OK ✔ in 0.12 seconds 184s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 184s third thing-marker 184s fourth.: OK (0.22=setup[0.19]+cmd[0.03] seconds) 184s second: OK (0.09=setup[0.05]+cmd[0.03] seconds) 184s first: OK (0.11=setup[0.07]+cmd[0.04] seconds) 184s third: OK (0.09=setup[0.05]+cmd[0.04] seconds) 184s congratulations :) (0.61 seconds) 184s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 184s fourth -- this and that thing-marker 184s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 184s second -- this and that thing-marker 184s fourth.: OK ✔ in 0.08 seconds 184s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 184s first -- this and that thing-marker 184s second: OK ✔ in 0.04 seconds 184s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' -- this 'and that' 184s third -- this and that thing-marker 184s first: OK ✔ in 0.04 seconds 184s fourth.: OK (0.07=setup[0.04]+cmd[0.04] seconds) 184s second: OK (0.04=setup[0.01]+cmd[0.03] seconds) 184s first: OK (0.04=setup[0.00]+cmd[0.04] seconds) 184s third: OK (0.04=setup[0.00]+cmd[0.04] seconds) 184s congratulations :) (0.28 seconds) 186s ROOT: skip environment second, matches filter '^(second|third)$' 186s ROOT: skip environment third, matches filter '^(second|third)$' 186s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 186s first thing-marker 186s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 186s fourth thing-marker 186s first: OK ✔ in 0.08 seconds 186s first: OK (0.08=setup[0.04]+cmd[0.04] seconds) 186s fourth.: OK (0.04=setup[0.00]+cmd[0.04] seconds) 186s congratulations :) (0.21 seconds) 186s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 186s second thing-marker 186s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 186s third thing-marker 186s second: OK ✔ in 0.09 seconds 186s second: OK (0.09=setup[0.03]+cmd[0.05] seconds) 186s third: OK (0.06=setup[0.01]+cmd[0.05] seconds) 186s congratulations :) (0.26 seconds) 187s ROOT: skip environment second, matches filter '^(second|third)$' 187s ROOT: skip environment third, matches filter '^(second|third)$' 187s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 187s first this and that thing-marker 187s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 187s fourth this and that thing-marker 187s first: OK ✔ in 0.08 seconds 187s first: OK (0.08=setup[0.04]+cmd[0.04] seconds) 187s fourth.: OK (0.04=setup[0.00]+cmd[0.04] seconds) 187s congratulations :) (0.20 seconds) 187s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 187s second this and that thing-marker 187s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 187s third this and that thing-marker 187s second: OK ✔ in 0.08 seconds 187s second: OK (0.08=setup[0.04]+cmd[0.04] seconds) 187s third: OK (0.03=setup[0.00]+cmd[0.03] seconds) 187s congratulations :) (0.19 seconds) 189s ROOT: skip environment third, matches filter '^(third)$' 189s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 189s first thing-marker 189s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 189s second thing-marker 189s first: OK ✔ in 0.07 seconds 189s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 189s fourth thing-marker 189s second: OK ✔ in 0.04 seconds 189s first: OK (0.07=setup[0.04]+cmd[0.03] seconds) 189s second: OK (0.04=setup[0.00]+cmd[0.04] seconds) 189s fourth.: OK (0.04=setup[0.00]+cmd[0.04] seconds) 189s congratulations :) (0.26 seconds) 189s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 189s third thing-marker 189s third: OK (0.07=setup[0.03]+cmd[0.04] seconds) 189s congratulations :) (0.17 seconds) 190s ROOT: skip environment third, matches filter '^(third)$' 190s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 190s first this and that thing-marker 190s first: OK ✔ in 0.09 seconds 190s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 190s second this and that thing-marker 190s fourth.: commands[0]> python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 190s fourth this and that thing-marker 190s second: OK ✔ in 0.04 seconds 190s first: OK (0.08=setup[0.05]+cmd[0.04] seconds) 190s second: OK (0.04=setup[0.01]+cmd[0.03] seconds) 190s fourth.: OK (0.04=setup[0.01]+cmd[0.03] seconds) 190s congratulations :) (0.26 seconds) 190s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 190s third this and that thing-marker 190s third: OK (0.13=setup[0.07]+cmd[0.06] seconds) 190s congratulations :) (0.21 seconds) 192s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 192s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 192s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 192s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 192s first thing-marker 192s first: OK (0.06=setup[0.03]+cmd[0.04] seconds) 192s congratulations :) (0.14 seconds) 193s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 193s third thing-marker 193s third: OK (0.07=setup[0.03]+cmd[0.04] seconds) 193s congratulations :) (0.15 seconds) 193s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 193s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 193s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 193s first: commands[0]> python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 193s first this and that thing-marker 193s first: OK (0.06=setup[0.03]+cmd[0.03] seconds) 193s congratulations :) (0.15 seconds) 193s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 193s third this and that thing-marker 193s third: OK (0.08=setup[0.04]+cmd[0.04] seconds) 193s congratulations :) (0.19 seconds) 195s ROOT: skip environment second, matches filter '^(second|third)$' 195s ROOT: skip environment third, matches filter '^(second|third)$' 196s  ⠋ [0/2]  ⠙ [2/2] first | fourth. first: OK ✔ in 0.12 seconds 196s   first: OK (0.12=setup[0.03]+cmd[0.08] seconds) 196s fourth.: OK (0.11=setup[0.03]+cmd[0.08] seconds) 196s congratulations :) (0.20 seconds) 196s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 196s second thing-marker 196s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 196s third thing-marker 196s second: OK ✔ in 0.08 seconds 196s second: OK (0.08=setup[0.04]+cmd[0.04] seconds) 196s third: OK (0.04=setup[0.00]+cmd[0.04] seconds) 196s congratulations :) (0.22 seconds) 196s ROOT: skip environment second, matches filter '^(second|third)$' 196s ROOT: skip environment third, matches filter '^(second|third)$' 196s  ⠋ [0/2]  ⠙ [2/2] first | fourth. fourth.: OK ✔ in 0.11 seconds 196s   first: OK (0.14=setup[0.06]+cmd[0.08] seconds) 196s fourth.: OK (0.11=setup[0.06]+cmd[0.05] seconds) 196s congratulations :) (0.24 seconds) 197s second: commands[0]> python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 197s second this and that thing-marker 197s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 197s third this and that thing-marker 197s second: OK ✔ in 0.06 seconds 197s second: OK (0.06=setup[0.03]+cmd[0.03] seconds) 197s third: OK (0.04=setup[0.00]+cmd[0.04] seconds) 197s congratulations :) (0.18 seconds) 199s ROOT: skip environment third, matches filter '^(third)$' 199s  ⠋ [0/3]  ⠙ [3/3] first | second | fourth. fourth.: OK ✔ in 0.16 seconds 199s second: OK ✔ in 0.17 seconds 199s   first: OK (0.19=setup[0.07]+cmd[0.12] seconds) 199s second: OK (0.17=setup[0.08]+cmd[0.09] seconds) 199s fourth.: OK (0.16=setup[0.06]+cmd[0.10] seconds) 199s congratulations :) (0.32 seconds) 200s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 200s third thing-marker 200s third: OK (0.14=setup[0.09]+cmd[0.05] seconds) 200s congratulations :) (0.27 seconds) 200s ROOT: skip environment third, matches filter '^(third)$' 200s  ⠋ [0/3]  ⠙ [3/3] first | second | fourth. second: OK ✔ in 0.16 seconds 200s fourth.: OK ✔ in 0.15 seconds 200s   first: OK (0.17=setup[0.04]+cmd[0.13] seconds) 200s second: OK (0.16=setup[0.04]+cmd[0.12] seconds) 200s fourth.: OK (0.15=setup[0.03]+cmd[0.13] seconds) 200s congratulations :) (0.24 seconds) 200s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 200s third this and that thing-marker 200s third: OK (0.07=setup[0.03]+cmd[0.04] seconds) 200s congratulations :) (0.15 seconds) 202s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 202s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 202s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 202s  ⠋ [0/1]   first: OK (0.09=setup[0.04]+cmd[0.05] seconds) 202s congratulations :) (0.19 seconds) 203s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' 203s third thing-marker 203s third: OK (0.08=setup[0.04]+cmd[0.04] seconds) 203s congratulations :) (0.19 seconds) 203s ROOT: skip environment second, matches filter '^(second|fourth\\.|third)$' 203s ROOT: skip environment third, matches filter '^(second|fourth\\.|third)$' 203s ROOT: skip environment fourth., matches filter '^(second|fourth\\.|third)$' 203s  ⠋ [0/1]   first: OK (0.10=setup[0.05]+cmd[0.05] seconds) 203s congratulations :) (0.24 seconds) 204s third: commands[0]> python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' this 'and that' 204s third this and that thing-marker 204s third: OK (0.06=setup[0.03]+cmd[0.03] seconds) 204s congratulations :) (0.15 seconds) 205s Using UTF-8 settings: LC_ALL 'C.utf8' LANGUAGE '' 205s Trying to find the absolute path to the python3 Python interpreter 205s Apparently there is no need to set PYTHONPATH at all 205s Using /tmp/tmp9pzmfmh3 as a temporary directory 205s Got a tox-delay feature version '0.1.2' 205s Wrote something to /tmp/tmp9pzmfmh3/tox.ini: 205s 205s [tox] 205s skipsdist = True 205s envlist = 205s first 205s second 205s third 205s fourth. 205s [testenv:first] 205s commands = 205s python3 -c 'import sys; lst=["first"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 205s 205s [testenv:second] 205s commands = 205s python3 -c 'import sys; lst=["second"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 205s 205s [testenv:third] 205s commands = 205s python3 -c 'import sys; lst=["third"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 205s 205s [testenv:fourth.] 205s commands = 205s python3 -c 'import sys; lst=["fourth"] + sys.argv[1:] + ["thing-marker"]; print(" ".join(lst))' {posargs} 205s 205s Making sure Tox itself works 205s 205s Running tox for fourth.,second,first,third without output capturing 205s 205s 205s Running tox for fourth.,second,first,third and examining its output 205s 205s ['/usr/bin/python3', '-m', 'tox', '-e', 'fourth.,second,first,third'] 205s 205s Running tox for fourth.,second,first,third with arguments and examining its output 205s 205s ['/usr/bin/python3', '-m', 'tox', '-e', 'fourth.,second,first,third', '--', '--', 'this', 'and that'] 205s Testing tox-delay in non-parallel mode 205s Now let us delay some 205s 205s Running tox_delay for second,third without output capturing 205s 205s 205s Running tox_delay for second,third and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'second,third'] 205s 205s Running tox_delay for second,third with arguments and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'second,third', '--', '--', 'this', 'and that'] 205s Let us delay others 205s 205s Running tox_delay for third without output capturing 205s 205s 205s Running tox_delay for third and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third'] 205s 205s Running tox_delay for third with arguments and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third', '--', '--', 'this', 'and that'] 205s Ignore some environments 205s 205s Running tox_delay for third without output capturing 205s 205s 205s Running tox_delay for third and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.'] 205s 205s Running tox_delay for third with arguments and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.', '--', '--', 'this', 'and that'] 205s Testing tox-delay in parallel mode 205s Now let us delay some 205s 205s Running tox_delay for second,third without output capturing 205s 205s 205s Running tox_delay for second,third in parallel and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'second,third', '-p', 'all'] 205s 205s Running tox_delay for second,third with arguments in parallel and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'second,third', '-p', 'all', '--', '--', 'this', 'and that'] 205s Let us delay others 205s 205s Running tox_delay for third without output capturing 205s 205s 205s Running tox_delay for third in parallel and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third', '-p', 'all'] 205s 205s Running tox_delay for third with arguments in parallel and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third', '-p', 'all', '--', '--', 'this', 'and that'] 205s Ignore some environments 205s 205s Running tox_delay for third without output capturing 205s 205s 205s Running tox_delay for third in parallel and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.', '-p', 'all'] 205s 205s Running tox_delay for third with arguments in parallel and examining its output 205s 205s ['/usr/bin/tox-delay', '-e', 'third', '-i', 'second,fourth.', '-p', 'all', '--', '--', 'this', 'and that'] 205s Seems fine 206s autopkgtest [22:18:23]: test upstream_python: -----------------------] 206s autopkgtest [22:18:23]: test upstream_python: - - - - - - - - - - results - - - - - - - - - - 206s upstream_python PASS 207s autopkgtest [22:18:24]: @@@@@@@@@@@@@@@@@@@@ summary 207s upstream_python PASS 223s Creating nova instance adt-noble-s390x-tox-delay-20240320-221457-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240319.img (UUID fdb88291-221e-4465-b557-6bcc22878017)...