0s autopkgtest [15:06:57]: starting date and time: 2024-03-22 15:06:57+0000 0s autopkgtest [15:06:57]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [15:06:57]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.9ztq_sbz/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:sphinx --apt-upgrade python-pytest-xprocess --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=sphinx/7.2.6-6 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-2.secgroup --name adt-noble-s390x-python-pytest-xprocess-20240322-150657-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://ftpmaster.internal/ubuntu/ 104s autopkgtest [15:08:41]: testbed dpkg architecture: s390x 104s autopkgtest [15:08:41]: testbed apt version: 2.7.12 104s autopkgtest [15:08:41]: @@@@@@@@@@@@@@@@@@@@ test bed setup 105s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 105s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [498 kB] 106s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [54.0 kB] 106s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3809 kB] 108s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 108s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [665 kB] 108s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 108s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 108s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 108s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4003 kB] 109s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 109s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 109s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 110s Fetched 9209 kB in 5s (1953 kB/s) 111s Reading package lists... 113s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Calculating upgrade... 114s The following packages will be upgraded: 114s cloud-init debianutils fonts-ubuntu-console python3-markupsafe 114s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 114s Need to get 719 kB of archives. 114s After this operation, 11.3 kB disk space will be freed. 114s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 114s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x fonts-ubuntu-console all 0.869+git20240321-0ubuntu1 [18.7 kB] 114s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x python3-markupsafe s390x 2.1.5-1build1 [12.8 kB] 114s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x cloud-init all 24.1.2-0ubuntu1 [597 kB] 115s Preconfiguring packages ... 115s Fetched 719 kB in 1s (1148 kB/s) 115s (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 ... 52171 files and directories currently installed.) 115s Preparing to unpack .../debianutils_5.17_s390x.deb ... 115s Unpacking debianutils (5.17) over (5.16) ... 115s Setting up debianutils (5.17) ... 115s (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 ... 52171 files and directories currently installed.) 115s Preparing to unpack .../fonts-ubuntu-console_0.869+git20240321-0ubuntu1_all.deb ... 115s Unpacking fonts-ubuntu-console (0.869+git20240321-0ubuntu1) over (0.869-0ubuntu1) ... 115s Preparing to unpack .../python3-markupsafe_2.1.5-1build1_s390x.deb ... 115s Unpacking python3-markupsafe (2.1.5-1build1) over (2.1.5-1) ... 115s Preparing to unpack .../cloud-init_24.1.2-0ubuntu1_all.deb ... 115s Unpacking cloud-init (24.1.2-0ubuntu1) over (24.1.1-0ubuntu1) ... 115s Setting up fonts-ubuntu-console (0.869+git20240321-0ubuntu1) ... 115s Setting up cloud-init (24.1.2-0ubuntu1) ... 117s Setting up python3-markupsafe (2.1.5-1build1) ... 117s Processing triggers for man-db (2.12.0-3) ... 118s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 118s Reading package lists... 118s Building dependency tree... 118s Reading state information... 118s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 119s Unknown architecture, assuming PC-style ttyS0 119s sh: Attempting to set up Debian/Ubuntu apt sources automatically 119s sh: Distribution appears to be Ubuntu 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s eatmydata is already the newest version (131-1). 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s dbus is already the newest version (1.14.10-4ubuntu1). 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 121s rng-tools-debian is already the newest version (2.4). 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s The following packages will be REMOVED: 121s cloud-init* python3-configobj* python3-debconf* 121s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 121s After this operation, 3256 kB disk space will be freed. 121s (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 ... 52170 files and directories currently installed.) 121s Removing cloud-init (24.1.2-0ubuntu1) ... 122s Removing python3-configobj (5.0.8-3) ... 122s Removing python3-debconf (1.5.86) ... 122s Processing triggers for man-db (2.12.0-3) ... 122s (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 ... 51781 files and directories currently installed.) 122s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 123s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 123s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 123s invoke-rc.d: policy-rc.d denied execution of try-restart. 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s linux-generic is already the newest version (6.8.0-11.11+1). 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 124s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 124s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 126s Reading package lists... 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 126s Calculating upgrade... 126s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s autopkgtest [15:09:04]: rebooting testbed after setup commands that affected boot 142s autopkgtest [15:09:19]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 145s autopkgtest [15:09:22]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 147s Get:1 http://ftpmaster.internal/ubuntu noble/universe python-pytest-xprocess 0.22.2-1 (dsc) [2426 B] 147s Get:2 http://ftpmaster.internal/ubuntu noble/universe python-pytest-xprocess 0.22.2-1 (tar) [31.3 kB] 147s Get:3 http://ftpmaster.internal/ubuntu noble/universe python-pytest-xprocess 0.22.2-1 (diff) [3104 B] 148s gpgv: Signature made Mon Aug 7 15:28:10 2023 UTC 148s gpgv: using RSA key A0B1A9F3508956130E7A425CD416AD15AC6B43FE 148s gpgv: Can't check signature: No public key 148s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_0.22.2-1.dsc: no acceptable signature found 148s autopkgtest [15:09:25]: testing package python-pytest-xprocess version 0.22.2-1 148s autopkgtest [15:09:25]: build not needed 149s autopkgtest [15:09:26]: test upstream: preparing testbed 150s Reading package lists... 150s Building dependency tree... 150s Reading state information... 150s Starting pkgProblemResolver with broken count: 0 150s Starting 2 pkgProblemResolver with broken count: 0 150s Done 151s The following additional packages will be installed: 151s python3-all python3-iniconfig python3-packaging python3-pluggy 151s python3-psutil python3-py python3-pytest python3-pytest-xprocess 151s Suggested packages: 151s subversion python-pytest-xprocess-doc 151s The following NEW packages will be installed: 151s autopkgtest-satdep python3-all python3-iniconfig python3-packaging 151s python3-pluggy python3-psutil python3-py python3-pytest 151s python3-pytest-xprocess 151s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded. 151s Need to get 651 kB/652 kB of archives. 151s After this operation, 3118 kB of additional disk space will be used. 151s Get:1 /tmp/autopkgtest.hqNmKg/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [728 B] 151s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x python3-all s390x 3.12.1-0ubuntu2 [908 B] 151s Get:3 http://ftpmaster.internal/ubuntu noble/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 151s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x python3-packaging all 23.2-1 [40.6 kB] 151s Get:5 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pluggy all 1.4.0-1 [20.4 kB] 151s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x python3-psutil s390x 5.9.8-1 [195 kB] 151s Get:7 http://ftpmaster.internal/ubuntu noble/universe s390x python3-py all 1.11.0-2 [72.7 kB] 151s Get:8 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pytest all 7.4.4-1 [305 kB] 152s Get:9 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pytest-xprocess all 0.22.2-1 [10.2 kB] 152s Fetched 651 kB in 1s (743 kB/s) 152s Selecting previously unselected package python3-all. 152s (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 ... 51726 files and directories currently installed.) 152s Preparing to unpack .../0-python3-all_3.12.1-0ubuntu2_s390x.deb ... 152s Unpacking python3-all (3.12.1-0ubuntu2) ... 152s Selecting previously unselected package python3-iniconfig. 152s Preparing to unpack .../1-python3-iniconfig_1.1.1-2_all.deb ... 152s Unpacking python3-iniconfig (1.1.1-2) ... 152s Selecting previously unselected package python3-packaging. 152s Preparing to unpack .../2-python3-packaging_23.2-1_all.deb ... 152s Unpacking python3-packaging (23.2-1) ... 152s Selecting previously unselected package python3-pluggy. 152s Preparing to unpack .../3-python3-pluggy_1.4.0-1_all.deb ... 152s Unpacking python3-pluggy (1.4.0-1) ... 152s Selecting previously unselected package python3-psutil. 152s Preparing to unpack .../4-python3-psutil_5.9.8-1_s390x.deb ... 152s Unpacking python3-psutil (5.9.8-1) ... 152s Selecting previously unselected package python3-py. 152s Preparing to unpack .../5-python3-py_1.11.0-2_all.deb ... 152s Unpacking python3-py (1.11.0-2) ... 152s Selecting previously unselected package python3-pytest. 152s Preparing to unpack .../6-python3-pytest_7.4.4-1_all.deb ... 152s Unpacking python3-pytest (7.4.4-1) ... 152s Selecting previously unselected package python3-pytest-xprocess. 152s Preparing to unpack .../7-python3-pytest-xprocess_0.22.2-1_all.deb ... 152s Unpacking python3-pytest-xprocess (0.22.2-1) ... 152s Selecting previously unselected package autopkgtest-satdep. 152s Preparing to unpack .../8-1-autopkgtest-satdep.deb ... 152s Unpacking autopkgtest-satdep (0) ... 152s Setting up python3-iniconfig (1.1.1-2) ... 152s Setting up python3-py (1.11.0-2) ... 152s Setting up python3-all (3.12.1-0ubuntu2) ... 152s Setting up python3-psutil (5.9.8-1) ... 153s Setting up python3-packaging (23.2-1) ... 153s Setting up python3-pluggy (1.4.0-1) ... 153s Setting up python3-pytest (7.4.4-1) ... 153s Setting up python3-pytest-xprocess (0.22.2-1) ... 153s Setting up autopkgtest-satdep (0) ... 153s Processing triggers for man-db (2.12.0-3) ... 156s (Reading database ... 52067 files and directories currently installed.) 156s Removing autopkgtest-satdep (0) ... 156s autopkgtest [15:09:33]: test upstream: [----------------------- 156s -=-=-=-=-=-=-=- running tests for python3.12 -=-=-=-=-=-=-=-=- 156s $ python3.12 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 157s ============================= test session starts ============================== 157s platform linux -- Python 3.12.2, pytest-7.4.4, pluggy-1.4.0 -- /usr/bin/python3.12 157s cachedir: .pytest_cache 157s rootdir: /tmp/autopkgtest.hqNmKg/autopkgtest_tmp 157s plugins: xprocess-0.22.2 157s collecting ... collected 45 items / 2 deselected / 43 selected 157s 157s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 157s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 157s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 162s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 167s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 171s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 171s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 171s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 172s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 172s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 172s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 172s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 172s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 173s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 173s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 173s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 173s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 174s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 174s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 174s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 174s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 174s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 175s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 175s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 175s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 175s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 176s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 176s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 176s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 176s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 176s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 179s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 181s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 183s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 183s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 184s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 184s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 184s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 184s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 184s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 186s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 189s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 191s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 191s 191s ====================== 43 passed, 2 deselected in 35.86s ======================= 191s pytest-xprocess reminder::Be sure to terminate the started process by running 'pytest --xkill' if you have not explicitly done so in your fixture with 'xprocess.getinfo().terminate()'. 191s autopkgtest [15:10:08]: test upstream: -----------------------] 192s upstream PASS 192s autopkgtest [15:10:09]: test upstream: - - - - - - - - - - results - - - - - - - - - - 192s autopkgtest [15:10:09]: @@@@@@@@@@@@@@@@@@@@ summary 192s upstream PASS 206s Creating nova instance adt-noble-s390x-python-pytest-xprocess-20240322-150657-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...