0s autopkgtest [21:12:26]: starting date and time: 2025-10-20 21:12:26+0000 0s autopkgtest [21:12:26]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [21:12:26]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.j3pcgg9x/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-arm64-1.secgroup --name adt-resolute-arm64-python-pytest-xprocess-20251020-211226-juju-7f2275-prod-proposed-migration-environment-15-5892313b-fdb3-4d0f-938e-15c93d05050c --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-arm64-python-pytest-xprocess-20251020-211226-juju-7f2275-prod-proposed-migration-environment-15-5892313b-fdb3-4d0f-938e-15c93d05050c from image adt/ubuntu-resolute-arm64-server-20251020.img (UUID 53f7e888-0b33-40b7-be7f-09b2db152d64)... 55s autopkgtest [21:13:21]: testbed dpkg architecture: arm64 55s autopkgtest [21:13:21]: testbed apt version: 3.1.8ubuntu1 56s autopkgtest [21:13:22]: @@@@@@@@@@@@@@@@@@@@ test bed setup 56s autopkgtest [21:13:22]: testbed release detected to be: None 57s autopkgtest [21:13:23]: updating testbed package index (apt update) 57s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [83.3 kB] 57s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 57s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 57s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 57s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [14.3 kB] 57s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 57s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [59.3 kB] 57s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [646 kB] 58s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [115 kB] 58s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [43.8 kB] 58s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [409 kB] 58s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [5672 B] 58s Fetched 1382 kB in 1s (1210 kB/s) 59s Reading package lists... 60s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 60s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 60s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 60s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 61s Reading package lists... 61s Reading package lists... 61s Building dependency tree... 61s Reading state information... 61s Calculating upgrade... 62s The following packages will be upgraded: 62s libbrotli1 libjson-c5 python3-inflect python3-oauthlib python3-zipp 62s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 62s Need to get 512 kB of archives. 62s After this operation, 31.7 kB of additional disk space will be used. 62s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libjson-c5 arm64 0.18+ds-1.1 [37.5 kB] 62s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 libbrotli1 arm64 1.1.0-2build6 [336 kB] 62s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-inflect all 7.5.0-1 [33.9 kB] 62s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-oauthlib all 3.3.1-1 [93.5 kB] 62s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-zipp all 3.23.0-1 [10.4 kB] 63s dpkg-preconfigure: unable to re-open stdin: No such file or directory 63s Fetched 512 kB in 1s (912 kB/s) 63s (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 ... 83364 files and directories currently installed.) 63s Preparing to unpack .../libjson-c5_0.18+ds-1.1_arm64.deb ... 63s Unpacking libjson-c5:arm64 (0.18+ds-1.1) over (0.18+ds-1) ... 63s Preparing to unpack .../libbrotli1_1.1.0-2build6_arm64.deb ... 63s Unpacking libbrotli1:arm64 (1.1.0-2build6) over (1.1.0-2build5) ... 63s Preparing to unpack .../python3-inflect_7.5.0-1_all.deb ... 63s Unpacking python3-inflect (7.5.0-1) over (7.3.1-2) ... 63s Preparing to unpack .../python3-oauthlib_3.3.1-1_all.deb ... 63s Unpacking python3-oauthlib (3.3.1-1) over (3.2.2-3) ... 64s Preparing to unpack .../python3-zipp_3.23.0-1_all.deb ... 64s Unpacking python3-zipp (3.23.0-1) over (3.21.0-1) ... 64s Setting up libbrotli1:arm64 (1.1.0-2build6) ... 64s Setting up python3-zipp (3.23.0-1) ... 64s Setting up python3-inflect (7.5.0-1) ... 64s Setting up python3-oauthlib (3.3.1-1) ... 64s Setting up libjson-c5:arm64 (0.18+ds-1.1) ... 64s Processing triggers for libc-bin (2.42-0ubuntu3) ... 64s autopkgtest [21:13:30]: upgrading testbed (apt dist-upgrade and autopurge) 65s Reading package lists... 65s Building dependency tree... 65s Reading state information... 65s Calculating upgrade... 66s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Solving dependencies... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s autopkgtest [21:13:36]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 09:50:31 UTC 2025 70s autopkgtest [21:13:36]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 72s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (dsc) [2425 B] 72s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (tar) [31.3 kB] 72s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (diff) [3156 B] 72s gpgv: Signature made Tue Jan 7 15:14:38 2025 UTC 72s gpgv: using RSA key A0B1A9F3508956130E7A425CD416AD15AC6B43FE 72s gpgv: Can't check signature: No public key 72s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_0.22.2-2.dsc: no acceptable signature found 72s autopkgtest [21:13:38]: testing package python-pytest-xprocess version 0.22.2-2 72s autopkgtest [21:13:38]: build not needed 73s autopkgtest [21:13:39]: test upstream: preparing testbed 73s Reading package lists... 73s Building dependency tree... 73s Reading state information... 73s Solving dependencies... 74s The following NEW packages will be installed: 74s python3-all python3-iniconfig python3-packaging python3-pluggy 74s python3-psutil python3-py python3-pytest python3-pytest-xprocess 74s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 74s Need to get 620 kB of archives. 74s After this operation, 3387 kB of additional disk space will be used. 74s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-all arm64 3.13.7-1 [884 B] 74s Get:2 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-iniconfig all 1.1.1-2 [6024 B] 74s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-packaging all 25.0-1 [52.8 kB] 74s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 python3-pluggy all 1.6.0-1 [21.0 kB] 74s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-psutil arm64 7.0.0-2ubuntu1 [188 kB] 74s Get:6 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-py all 1.11.0-4 [72.7 kB] 74s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 python3-pytest all 8.4.2-1 [268 kB] 75s Get:8 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pytest-xprocess all 0.22.2-2 [10.3 kB] 75s Fetched 620 kB in 1s (981 kB/s) 75s Selecting previously unselected package python3-all. 75s (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 ... 83374 files and directories currently installed.) 75s Preparing to unpack .../0-python3-all_3.13.7-1_arm64.deb ... 75s Unpacking python3-all (3.13.7-1) ... 75s Selecting previously unselected package python3-iniconfig. 75s Preparing to unpack .../1-python3-iniconfig_1.1.1-2_all.deb ... 75s Unpacking python3-iniconfig (1.1.1-2) ... 75s Selecting previously unselected package python3-packaging. 75s Preparing to unpack .../2-python3-packaging_25.0-1_all.deb ... 75s Unpacking python3-packaging (25.0-1) ... 75s Selecting previously unselected package python3-pluggy. 75s Preparing to unpack .../3-python3-pluggy_1.6.0-1_all.deb ... 75s Unpacking python3-pluggy (1.6.0-1) ... 75s Selecting previously unselected package python3-psutil. 75s Preparing to unpack .../4-python3-psutil_7.0.0-2ubuntu1_arm64.deb ... 75s Unpacking python3-psutil (7.0.0-2ubuntu1) ... 75s Selecting previously unselected package python3-py. 75s Preparing to unpack .../5-python3-py_1.11.0-4_all.deb ... 75s Unpacking python3-py (1.11.0-4) ... 75s Selecting previously unselected package python3-pytest. 75s Preparing to unpack .../6-python3-pytest_8.4.2-1_all.deb ... 75s Unpacking python3-pytest (8.4.2-1) ... 75s Selecting previously unselected package python3-pytest-xprocess. 75s Preparing to unpack .../7-python3-pytest-xprocess_0.22.2-2_all.deb ... 75s Unpacking python3-pytest-xprocess (0.22.2-2) ... 75s Setting up python3-iniconfig (1.1.1-2) ... 75s Setting up python3-py (1.11.0-4) ... 76s Setting up python3-all (3.13.7-1) ... 76s Setting up python3-psutil (7.0.0-2ubuntu1) ... 76s Setting up python3-packaging (25.0-1) ... 76s Setting up python3-pluggy (1.6.0-1) ... 76s Setting up python3-pytest (8.4.2-1) ... 77s Setting up python3-pytest-xprocess (0.22.2-2) ... 77s Processing triggers for man-db (2.13.1-1) ... 78s autopkgtest [21:13:44]: test upstream: [----------------------- 79s -=-=-=-=-=-=-=- running tests for python3.13 -=-=-=-=-=-=-=-=- 79s $ python3.13 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 79s ============================= test session starts ============================== 79s platform linux -- Python 3.13.7, pytest-8.4.2, pluggy-1.6.0 -- /usr/bin/python3.13 79s cachedir: .pytest_cache 79s rootdir: /tmp/autopkgtest.pt9sHI/autopkgtest_tmp 79s plugins: typeguard-4.4.2, xprocess-0.22.2 79s collecting ... collected 45 items / 2 deselected / 43 selected 79s 79s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 79s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 80s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 85s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 90s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 95s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 95s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 95s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 96s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 96s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 96s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 96s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 96s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 97s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 97s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 97s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 97s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 98s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 98s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 98s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 98s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 98s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 99s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 99s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 99s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 99s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 100s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 100s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 100s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 100s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 100s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 103s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 105s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 107s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 107s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 108s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 108s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 108s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 108s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 108s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 110s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 113s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 115s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 115s 115s ====================== 43 passed, 2 deselected in 35.88s ======================= 115s 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()'. 115s autopkgtest [21:14:21]: test upstream: -----------------------] 116s upstream PASS 116s autopkgtest [21:14:22]: test upstream: - - - - - - - - - - results - - - - - - - - - - 116s autopkgtest [21:14:22]: @@@@@@@@@@@@@@@@@@@@ summary 116s upstream PASS