0s autopkgtest [06:46:33]: starting date and time: 2025-10-24 06:46:33+0000 0s autopkgtest [06:46:33]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [06:46:33]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4sg5serw/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 builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-5.secgroup --name adt-resolute-amd64-python-pytest-xprocess-20251024-064633-juju-7f2275-prod-proposed-migration-environment-2-4a427968-52cd-47e9-8dc7-92f6d227a8cc --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-amd64-python-pytest-xprocess-20251024-064633-juju-7f2275-prod-proposed-migration-environment-2-4a427968-52cd-47e9-8dc7-92f6d227a8cc from image adt/ubuntu-resolute-amd64-server-20251024.img (UUID ade595eb-b10a-4956-9cba-6b0691e1909d)... 72s autopkgtest [06:47:45]: testbed dpkg architecture: amd64 72s autopkgtest [06:47:45]: testbed apt version: 3.1.8ubuntu1 72s autopkgtest [06:47:45]: @@@@@@@@@@@@@@@@@@@@ test bed setup 73s autopkgtest [06:47:46]: testbed release detected to be: None 74s autopkgtest [06:47:47]: updating testbed package index (apt update) 74s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 74s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 74s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 74s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 74s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [533 kB] 75s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [61.6 kB] 75s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [15.0 kB] 75s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 75s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [97.2 kB] 75s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [147 kB] 75s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3040 B] 75s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3208 B] 75s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [28.0 kB] 75s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 75s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [385 kB] 75s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [145 kB] 75s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [10.8 kB] 75s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [3156 B] 75s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [6744 B] 75s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [492 B] 76s Fetched 1532 kB in 1s (1274 kB/s) 77s Reading package lists... 78s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 78s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 79s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 80s Reading package lists... 80s Reading package lists... 80s Building dependency tree... 80s Reading state information... 80s Calculating upgrade... 81s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 81s autopkgtest [06:47:54]: upgrading testbed (apt dist-upgrade and autopurge) 81s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s Calculating upgrade... 82s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Solving dependencies... 82s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s autopkgtest [06:47:58]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:00:33 UTC 2025 85s autopkgtest [06:47:58]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 87s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (dsc) [2425 B] 87s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (tar) [31.3 kB] 87s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (diff) [3156 B] 87s gpgv: Signature made Tue Jan 7 15:14:38 2025 UTC 87s gpgv: using RSA key A0B1A9F3508956130E7A425CD416AD15AC6B43FE 87s gpgv: Can't check signature: No public key 87s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_0.22.2-2.dsc: no acceptable signature found 88s autopkgtest [06:48:01]: testing package python-pytest-xprocess version 0.22.2-2 88s autopkgtest [06:48:01]: build not needed 88s autopkgtest [06:48:01]: test upstream: preparing testbed 88s Reading package lists... 89s Building dependency tree... 89s Reading state information... 89s Solving dependencies... 89s The following NEW packages will be installed: 89s python3-all python3-iniconfig python3-packaging python3-pluggy 89s python3-psutil python3-py python3-pytest python3-pytest-xprocess 89s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 89s Need to get 620 kB of archives. 89s After this operation, 3301 kB of additional disk space will be used. 89s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-all amd64 3.13.7-1 [884 B] 89s Get:2 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 89s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-packaging all 25.0-1 [52.8 kB] 89s Get:4 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-1 [21.0 kB] 89s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-psutil amd64 7.0.0-2ubuntu1 [188 kB] 89s Get:6 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-py all 1.11.0-5 [72.9 kB] 90s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 python3-pytest all 8.4.2-1 [268 kB] 90s Get:8 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-xprocess all 0.22.2-2 [10.3 kB] 90s Fetched 620 kB in 1s (981 kB/s) 90s Selecting previously unselected package python3-all. 90s (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 ... 78459 files and directories currently installed.) 90s Preparing to unpack .../0-python3-all_3.13.7-1_amd64.deb ... 90s Unpacking python3-all (3.13.7-1) ... 90s Selecting previously unselected package python3-iniconfig. 90s Preparing to unpack .../1-python3-iniconfig_1.1.1-2_all.deb ... 90s Unpacking python3-iniconfig (1.1.1-2) ... 90s Selecting previously unselected package python3-packaging. 90s Preparing to unpack .../2-python3-packaging_25.0-1_all.deb ... 90s Unpacking python3-packaging (25.0-1) ... 90s Selecting previously unselected package python3-pluggy. 90s Preparing to unpack .../3-python3-pluggy_1.6.0-1_all.deb ... 90s Unpacking python3-pluggy (1.6.0-1) ... 90s Selecting previously unselected package python3-psutil. 90s Preparing to unpack .../4-python3-psutil_7.0.0-2ubuntu1_amd64.deb ... 90s Unpacking python3-psutil (7.0.0-2ubuntu1) ... 90s Selecting previously unselected package python3-py. 90s Preparing to unpack .../5-python3-py_1.11.0-5_all.deb ... 90s Unpacking python3-py (1.11.0-5) ... 90s Selecting previously unselected package python3-pytest. 90s Preparing to unpack .../6-python3-pytest_8.4.2-1_all.deb ... 90s Unpacking python3-pytest (8.4.2-1) ... 91s Selecting previously unselected package python3-pytest-xprocess. 91s Preparing to unpack .../7-python3-pytest-xprocess_0.22.2-2_all.deb ... 91s Unpacking python3-pytest-xprocess (0.22.2-2) ... 91s Setting up python3-iniconfig (1.1.1-2) ... 91s Setting up python3-py (1.11.0-5) ... 91s Setting up python3-all (3.13.7-1) ... 91s Setting up python3-psutil (7.0.0-2ubuntu1) ... 91s Setting up python3-packaging (25.0-1) ... 91s Setting up python3-pluggy (1.6.0-1) ... 92s Setting up python3-pytest (8.4.2-1) ... 92s Setting up python3-pytest-xprocess (0.22.2-2) ... 92s Processing triggers for man-db (2.13.1-1) ... 94s autopkgtest [06:48:07]: test upstream: [----------------------- 94s -=-=-=-=-=-=-=- running tests for python3.13 -=-=-=-=-=-=-=-=- 94s $ python3.13 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 94s ============================= test session starts ============================== 94s platform linux -- Python 3.13.9, pytest-8.4.2, pluggy-1.6.0 -- /usr/bin/python3.13 94s cachedir: .pytest_cache 94s rootdir: /tmp/autopkgtest.FEWbZH/autopkgtest_tmp 94s plugins: xprocess-0.22.2, typeguard-4.4.2 94s collecting ... collected 45 items / 2 deselected / 43 selected 94s 94s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 95s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 95s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 100s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 105s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 110s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 110s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 111s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 111s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 111s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 111s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 111s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 112s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 112s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 112s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 112s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 112s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 113s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 113s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 113s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 113s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 114s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 114s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 114s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 114s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 114s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 115s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 115s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 115s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 115s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 115s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 118s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 120s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 122s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 122s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 123s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 123s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 123s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 123s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 123s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 126s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 128s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 130s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 130s 130s ====================== 43 passed, 2 deselected in 36.01s ======================= 130s 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()'. 131s autopkgtest [06:48:44]: test upstream: -----------------------] 131s autopkgtest [06:48:44]: test upstream: - - - - - - - - - - results - - - - - - - - - - 131s upstream PASS 131s autopkgtest [06:48:44]: @@@@@@@@@@@@@@@@@@@@ summary 131s upstream PASS