0s autopkgtest [17:13:37]: starting date and time: 2025-10-20 17:13:37+0000 0s autopkgtest [17:13:37]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [17:13:37]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.p65g7m9e/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pytest,src:python-pluggy --apt-upgrade python-pytest-xprocess --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=pytest/8.4.2-1 python-pluggy/1.6.0-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-15.secgroup --name adt-resolute-s390x-python-pytest-xprocess-20251020-171337-juju-7f2275-prod-proposed-migration-environment-2-a76e647f-639b-4a6c-97f6-f2d1af10468e --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-s390x-python-pytest-xprocess-20251020-171337-juju-7f2275-prod-proposed-migration-environment-2-a76e647f-639b-4a6c-97f6-f2d1af10468e from image adt/ubuntu-resolute-s390x-server-20251020.img (UUID af6042e7-5f94-4102-8fd4-5557e2338897)... 45s autopkgtest [17:14:22]: testbed dpkg architecture: s390x 45s autopkgtest [17:14:22]: testbed apt version: 3.1.8ubuntu1 46s autopkgtest [17:14:23]: @@@@@@@@@@@@@@@@@@@@ test bed setup 46s autopkgtest [17:14:23]: testbed release detected to be: None 47s autopkgtest [17:14:24]: updating testbed package index (apt update) 47s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [83.3 kB] 48s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 48s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 48s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 48s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [14.3 kB] 48s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 48s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [59.3 kB] 48s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [646 kB] 48s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [108 kB] 48s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted s390x Packages [940 B] 48s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [395 kB] 48s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [3812 B] 48s Fetched 1316 kB in 1s (1123 kB/s) 49s Reading package lists... 50s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 50s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 50s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 50s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 51s Reading package lists... 51s Reading package lists... 51s Building dependency tree... 51s Reading state information... 51s Calculating upgrade... 51s The following packages will be upgraded: 51s libbrotli1 libjson-c5 python3-inflect python3-oauthlib python3-zipp 51s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 51s Need to get 554 kB of archives. 51s After this operation, 31.7 kB of additional disk space will be used. 51s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libjson-c5 s390x 0.18+ds-1.1 [36.8 kB] 51s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x libbrotli1 s390x 1.1.0-2build6 [379 kB] 52s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x python3-inflect all 7.5.0-1 [33.9 kB] 52s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x python3-oauthlib all 3.3.1-1 [93.5 kB] 52s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x python3-zipp all 3.23.0-1 [10.4 kB] 52s dpkg-preconfigure: unable to re-open stdin: No such file or directory 52s Fetched 554 kB in 1s (932 kB/s) 52s (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 ... 56857 files and directories currently installed.) 52s Preparing to unpack .../libjson-c5_0.18+ds-1.1_s390x.deb ... 52s Unpacking libjson-c5:s390x (0.18+ds-1.1) over (0.18+ds-1) ... 52s Preparing to unpack .../libbrotli1_1.1.0-2build6_s390x.deb ... 52s Unpacking libbrotli1:s390x (1.1.0-2build6) over (1.1.0-2build5) ... 52s Preparing to unpack .../python3-inflect_7.5.0-1_all.deb ... 52s Unpacking python3-inflect (7.5.0-1) over (7.3.1-2) ... 52s Preparing to unpack .../python3-oauthlib_3.3.1-1_all.deb ... 52s Unpacking python3-oauthlib (3.3.1-1) over (3.2.2-3) ... 52s Preparing to unpack .../python3-zipp_3.23.0-1_all.deb ... 52s Unpacking python3-zipp (3.23.0-1) over (3.21.0-1) ... 52s Setting up libbrotli1:s390x (1.1.0-2build6) ... 52s Setting up python3-zipp (3.23.0-1) ... 52s Setting up python3-inflect (7.5.0-1) ... 52s Setting up python3-oauthlib (3.3.1-1) ... 52s Setting up libjson-c5:s390x (0.18+ds-1.1) ... 52s Processing triggers for libc-bin (2.42-0ubuntu3) ... 53s autopkgtest [17:14:30]: upgrading testbed (apt dist-upgrade and autopurge) 53s Reading package lists... 53s Building dependency tree... 53s Reading state information... 53s Calculating upgrade... 53s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 53s Reading package lists... 54s Building dependency tree... 54s Reading state information... 54s Solving dependencies... 54s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 57s autopkgtest [17:14:34]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP Mon Sep 22 08:56:47 UTC 2025 57s autopkgtest [17:14:34]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 59s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (dsc) [2425 B] 59s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (tar) [31.3 kB] 59s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (diff) [3156 B] 59s gpgv: Signature made Tue Jan 7 15:14:38 2025 UTC 59s gpgv: using RSA key A0B1A9F3508956130E7A425CD416AD15AC6B43FE 59s gpgv: Can't check signature: No public key 59s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_0.22.2-2.dsc: no acceptable signature found 59s autopkgtest [17:14:36]: testing package python-pytest-xprocess version 0.22.2-2 59s autopkgtest [17:14:36]: build not needed 60s autopkgtest [17:14:37]: test upstream: preparing testbed 60s Reading package lists... 60s Building dependency tree... 60s Reading state information... 60s Solving dependencies... 60s The following NEW packages will be installed: 60s python3-all python3-iniconfig python3-packaging python3-pluggy 60s python3-psutil python3-py python3-pytest python3-pytest-xprocess 60s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 60s Need to get 620 kB of archives. 60s After this operation, 3297 kB of additional disk space will be used. 60s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x python3-all s390x 3.13.7-1 [886 B] 60s Get:2 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 60s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x python3-packaging all 25.0-1 [52.8 kB] 60s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x python3-pluggy all 1.6.0-1 [21.0 kB] 60s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x python3-psutil s390x 7.0.0-2ubuntu1 [188 kB] 61s Get:6 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-py all 1.11.0-4 [72.7 kB] 61s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x python3-pytest all 8.4.2-1 [268 kB] 61s Get:8 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-xprocess all 0.22.2-2 [10.3 kB] 61s Fetched 620 kB in 1s (900 kB/s) 61s Selecting previously unselected package python3-all. 61s (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 ... 56867 files and directories currently installed.) 61s Preparing to unpack .../0-python3-all_3.13.7-1_s390x.deb ... 61s Unpacking python3-all (3.13.7-1) ... 61s Selecting previously unselected package python3-iniconfig. 61s Preparing to unpack .../1-python3-iniconfig_1.1.1-2_all.deb ... 61s Unpacking python3-iniconfig (1.1.1-2) ... 61s Selecting previously unselected package python3-packaging. 61s Preparing to unpack .../2-python3-packaging_25.0-1_all.deb ... 61s Unpacking python3-packaging (25.0-1) ... 61s Selecting previously unselected package python3-pluggy. 61s Preparing to unpack .../3-python3-pluggy_1.6.0-1_all.deb ... 61s Unpacking python3-pluggy (1.6.0-1) ... 61s Selecting previously unselected package python3-psutil. 61s Preparing to unpack .../4-python3-psutil_7.0.0-2ubuntu1_s390x.deb ... 61s Unpacking python3-psutil (7.0.0-2ubuntu1) ... 61s Selecting previously unselected package python3-py. 61s Preparing to unpack .../5-python3-py_1.11.0-4_all.deb ... 61s Unpacking python3-py (1.11.0-4) ... 61s Selecting previously unselected package python3-pytest. 61s Preparing to unpack .../6-python3-pytest_8.4.2-1_all.deb ... 61s Unpacking python3-pytest (8.4.2-1) ... 61s Selecting previously unselected package python3-pytest-xprocess. 61s Preparing to unpack .../7-python3-pytest-xprocess_0.22.2-2_all.deb ... 61s Unpacking python3-pytest-xprocess (0.22.2-2) ... 61s Setting up python3-iniconfig (1.1.1-2) ... 61s Setting up python3-py (1.11.0-4) ... 61s Setting up python3-all (3.13.7-1) ... 61s Setting up python3-psutil (7.0.0-2ubuntu1) ... 62s Setting up python3-packaging (25.0-1) ... 62s Setting up python3-pluggy (1.6.0-1) ... 62s Setting up python3-pytest (8.4.2-1) ... 62s Setting up python3-pytest-xprocess (0.22.2-2) ... 62s Processing triggers for man-db (2.13.1-1) ... 64s autopkgtest [17:14:41]: test upstream: [----------------------- 64s -=-=-=-=-=-=-=- running tests for python3.13 -=-=-=-=-=-=-=-=- 64s $ python3.13 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 64s ============================= test session starts ============================== 64s platform linux -- Python 3.13.7, pytest-8.4.2, pluggy-1.6.0 -- /usr/bin/python3.13 64s cachedir: .pytest_cache 64s rootdir: /tmp/autopkgtest.Tk16w9/autopkgtest_tmp 64s plugins: xprocess-0.22.2, typeguard-4.4.2 64s collecting ... collected 45 items / 2 deselected / 43 selected 64s 64s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 65s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 65s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 70s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 75s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 80s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 80s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 80s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 81s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 81s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 81s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 81s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 81s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 82s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 82s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 82s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 82s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 82s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 83s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 83s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 83s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 83s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 83s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 84s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 84s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 84s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 84s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 85s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 85s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 85s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 85s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 87s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 90s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 92s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 92s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 92s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 92s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 93s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 93s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 93s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 95s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 98s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 100s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 100s 100s ====================== 43 passed, 2 deselected in 35.64s ======================= 100s 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()'. 100s autopkgtest [17:15:17]: test upstream: -----------------------] 101s upstream PASS 101s autopkgtest [17:15:18]: test upstream: - - - - - - - - - - results - - - - - - - - - - 101s autopkgtest [17:15:18]: @@@@@@@@@@@@@@@@@@@@ summary 101s upstream PASS