0s autopkgtest [12:13:06]: starting date and time: 2025-01-08 12:13:06+0000 0s autopkgtest [12:13:06]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [12:13:06]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.jt6hve35/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:sphinx --apt-upgrade python-pytest-xprocess --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=sphinx/8.1.3-4 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-23.secgroup --name adt-plucky-s390x-python-pytest-xprocess-20250108-121305-juju-7f2275-prod-proposed-migration-environment-2-d0872160-86a5-4cf3-b147-b8146f17932e --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 97s autopkgtest [12:14:43]: testbed dpkg architecture: s390x 97s autopkgtest [12:14:43]: testbed apt version: 2.9.18 98s autopkgtest [12:14:44]: @@@@@@@@@@@@@@@@@@@@ test bed setup 98s autopkgtest [12:14:44]: testbed release detected to be: None 98s autopkgtest [12:14:44]: updating testbed package index (apt update) 99s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 99s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 99s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 99s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 99s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 99s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [817 kB] 99s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [129 kB] 99s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.3 kB] 99s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [235 kB] 99s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [756 B] 99s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [914 kB] 99s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [5576 B] 100s Fetched 2201 kB in 1s (2139 kB/s) 100s Reading package lists... 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 101s Calculating upgrade... 101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 101s autopkgtest [12:14:47]: upgrading testbed (apt dist-upgrade and autopurge) 102s Reading package lists... 102s Building dependency tree... 102s Reading state information... 102s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 102s Starting 2 pkgProblemResolver with broken count: 0 102s Done 102s Entering ResolveByKeep 102s 102s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 102s Reading package lists... 103s Building dependency tree... 103s Reading state information... 103s Starting pkgProblemResolver with broken count: 0 103s Starting 2 pkgProblemResolver with broken count: 0 103s Done 103s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s autopkgtest [12:14:51]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 106s autopkgtest [12:14:52]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 107s Get:1 http://ftpmaster.internal/ubuntu plucky/universe python-pytest-xprocess 0.22.2-1 (dsc) [2426 B] 107s Get:2 http://ftpmaster.internal/ubuntu plucky/universe python-pytest-xprocess 0.22.2-1 (tar) [31.3 kB] 107s Get:3 http://ftpmaster.internal/ubuntu plucky/universe python-pytest-xprocess 0.22.2-1 (diff) [3104 B] 108s gpgv: Signature made Mon Aug 7 15:28:10 2023 UTC 108s gpgv: using RSA key A0B1A9F3508956130E7A425CD416AD15AC6B43FE 108s gpgv: Can't check signature: No public key 108s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_0.22.2-1.dsc: no acceptable signature found 108s autopkgtest [12:14:54]: testing package python-pytest-xprocess version 0.22.2-1 108s autopkgtest [12:14:54]: build not needed 108s autopkgtest [12:14:54]: test upstream: preparing testbed 108s Reading package lists... 109s Building dependency tree... 109s Reading state information... 109s Starting pkgProblemResolver with broken count: 0 109s Starting 2 pkgProblemResolver with broken count: 0 109s Done 109s The following NEW packages will be installed: 109s libpython3.13-minimal libpython3.13-stdlib python3-all python3-iniconfig 109s python3-packaging python3-pluggy python3-psutil python3-py python3-pytest 109s python3-pytest-xprocess python3.13 python3.13-minimal 109s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 109s Need to get 6655 kB of archives. 109s After this operation, 26.0 MB of additional disk space will be used. 109s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-minimal s390x 3.13.1-2 [880 kB] 109s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-minimal s390x 3.13.1-2 [2364 kB] 110s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-stdlib s390x 3.13.1-2 [2074 kB] 110s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13 s390x 3.13.1-2 [729 kB] 110s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3-all s390x 3.12.8-1 [894 B] 110s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 110s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x python3-packaging all 24.2-1 [51.5 kB] 110s Get:8 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 110s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x python3-psutil s390x 5.9.8-2build3 [195 kB] 110s Get:10 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-py all 1.11.0-2 [72.7 kB] 110s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pytest all 8.3.3-1 [251 kB] 110s Get:12 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pytest-xprocess all 0.22.2-1 [10.2 kB] 110s Fetched 6655 kB in 1s (7669 kB/s) 110s Selecting previously unselected package libpython3.13-minimal:s390x. 110s (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 ... 55616 files and directories currently installed.) 110s Preparing to unpack .../00-libpython3.13-minimal_3.13.1-2_s390x.deb ... 110s Unpacking libpython3.13-minimal:s390x (3.13.1-2) ... 110s Selecting previously unselected package python3.13-minimal. 110s Preparing to unpack .../01-python3.13-minimal_3.13.1-2_s390x.deb ... 110s Unpacking python3.13-minimal (3.13.1-2) ... 110s Selecting previously unselected package libpython3.13-stdlib:s390x. 110s Preparing to unpack .../02-libpython3.13-stdlib_3.13.1-2_s390x.deb ... 110s Unpacking libpython3.13-stdlib:s390x (3.13.1-2) ... 110s Selecting previously unselected package python3.13. 110s Preparing to unpack .../03-python3.13_3.13.1-2_s390x.deb ... 110s Unpacking python3.13 (3.13.1-2) ... 110s Selecting previously unselected package python3-all. 110s Preparing to unpack .../04-python3-all_3.12.8-1_s390x.deb ... 110s Unpacking python3-all (3.12.8-1) ... 110s Selecting previously unselected package python3-iniconfig. 110s Preparing to unpack .../05-python3-iniconfig_1.1.1-2_all.deb ... 110s Unpacking python3-iniconfig (1.1.1-2) ... 110s Selecting previously unselected package python3-packaging. 110s Preparing to unpack .../06-python3-packaging_24.2-1_all.deb ... 110s Unpacking python3-packaging (24.2-1) ... 110s Selecting previously unselected package python3-pluggy. 110s Preparing to unpack .../07-python3-pluggy_1.5.0-1_all.deb ... 110s Unpacking python3-pluggy (1.5.0-1) ... 110s Selecting previously unselected package python3-psutil. 110s Preparing to unpack .../08-python3-psutil_5.9.8-2build3_s390x.deb ... 110s Unpacking python3-psutil (5.9.8-2build3) ... 110s Selecting previously unselected package python3-py. 110s Preparing to unpack .../09-python3-py_1.11.0-2_all.deb ... 110s Unpacking python3-py (1.11.0-2) ... 110s Selecting previously unselected package python3-pytest. 110s Preparing to unpack .../10-python3-pytest_8.3.3-1_all.deb ... 110s Unpacking python3-pytest (8.3.3-1) ... 110s Selecting previously unselected package python3-pytest-xprocess. 110s Preparing to unpack .../11-python3-pytest-xprocess_0.22.2-1_all.deb ... 110s Unpacking python3-pytest-xprocess (0.22.2-1) ... 110s Setting up python3-iniconfig (1.1.1-2) ... 111s Setting up python3-py (1.11.0-2) ... 111s Setting up python3-psutil (5.9.8-2build3) ... 111s Setting up libpython3.13-minimal:s390x (3.13.1-2) ... 111s Setting up python3-packaging (24.2-1) ... 111s Setting up python3-pluggy (1.5.0-1) ... 112s Setting up python3.13-minimal (3.13.1-2) ... 112s Setting up libpython3.13-stdlib:s390x (3.13.1-2) ... 112s Setting up python3.13 (3.13.1-2) ... 113s Setting up python3-pytest (8.3.3-1) ... 113s Setting up python3-all (3.12.8-1) ... 113s Setting up python3-pytest-xprocess (0.22.2-1) ... 113s Processing triggers for man-db (2.13.0-1) ... 114s Processing triggers for systemd (257-2ubuntu1) ... 115s autopkgtest [12:15:01]: test upstream: [----------------------- 115s -=-=-=-=-=-=-=- running tests for python3.13 -=-=-=-=-=-=-=-=- 115s $ python3.13 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 115s ============================= test session starts ============================== 115s platform linux -- Python 3.13.1, pytest-8.3.3, pluggy-1.5.0 -- /usr/bin/python3.13 115s cachedir: .pytest_cache 115s rootdir: /tmp/autopkgtest.NG08Oc/autopkgtest_tmp 115s plugins: xprocess-0.22.2, typeguard-4.4.1 115s collecting ... collected 45 items / 2 deselected / 43 selected 115s 115s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 116s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 116s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 121s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 126s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 131s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 131s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 131s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 132s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 132s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 132s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 132s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 132s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 133s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 133s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 133s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 133s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 133s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 134s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 134s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 134s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 134s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 135s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 135s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 135s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 135s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 135s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 136s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 136s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 136s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 136s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 138s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 141s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 143s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 143s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 143s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 144s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 144s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 144s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 144s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 146s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 148s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 151s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 151s 151s ====================== 43 passed, 2 deselected in 35.47s ======================= 151s 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()'. 151s -=-=-=-=-=-=-=- running tests for python3.12 -=-=-=-=-=-=-=-=- 151s $ python3.12 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 151s ============================= test session starts ============================== 151s platform linux -- Python 3.12.8, pytest-8.3.3, pluggy-1.5.0 -- /usr/bin/python3.12 151s cachedir: .pytest_cache 151s rootdir: /tmp/autopkgtest.NG08Oc/autopkgtest_tmp 151s plugins: xprocess-0.22.2, typeguard-4.4.1 151s collecting ... collected 45 items / 2 deselected / 43 selected 151s 151s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 151s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 151s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 157s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 162s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 167s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 167s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 167s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 167s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 168s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 168s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 168s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 168s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 168s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 169s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 169s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 169s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 169s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 169s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 170s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 170s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 170s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 170s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 170s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 171s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 171s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 171s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 171s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 171s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 172s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 172s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 174s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 176s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 179s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 179s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 179s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 179s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 179s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 180s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 180s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 182s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 184s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 186s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 186s 186s ====================== 43 passed, 2 deselected in 35.61s ======================= 186s 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()'. 187s autopkgtest [12:16:13]: test upstream: -----------------------] 187s upstream PASS 187s autopkgtest [12:16:13]: test upstream: - - - - - - - - - - results - - - - - - - - - - 188s autopkgtest [12:16:14]: @@@@@@@@@@@@@@@@@@@@ summary 188s upstream PASS 205s nova [W] Using flock in prodstack6-s390x 205s Creating nova instance adt-plucky-s390x-python-pytest-xprocess-20250108-121305-juju-7f2275-prod-proposed-migration-environment-2-d0872160-86a5-4cf3-b147-b8146f17932e from image adt/ubuntu-plucky-s390x-server-20250108.img (UUID 1b305a85-9036-4003-b130-4539b3c95093)... 205s nova [W] Timed out waiting for 944dd348-6a33-48be-8362-c3fc13eb2b3e to get deleted.