0s autopkgtest [21:28:29]: starting date and time: 2026-01-23 21:28:29+0000 0s autopkgtest [21:28:29]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [21:28:29]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.52fe4fu_/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade python-pytest-xprocess --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.14.2-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-s390x-18.secgroup --name adt-resolute-s390x-python-pytest-xprocess-20260123-212829-juju-7f2275-prod-proposed-migration-environment-15-2437ad81-01ab-4b9a-bbe4-b8ff48804798 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-s390x-python-pytest-xprocess-20260123-212829-juju-7f2275-prod-proposed-migration-environment-15-2437ad81-01ab-4b9a-bbe4-b8ff48804798 from image adt/ubuntu-resolute-s390x-server-20260123.img (UUID 5f2e306c-5dfc-4ef6-8be2-414f657c7562)... 69s autopkgtest [21:29:38]: testbed dpkg architecture: s390x 70s autopkgtest [21:29:39]: testbed apt version: 3.1.13 70s autopkgtest [21:29:39]: @@@@@@@@@@@@@@@@@@@@ test bed setup 70s autopkgtest [21:29:39]: testbed release detected to be: None 71s autopkgtest [21:29:40]: updating testbed package index (apt update) 71s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 71s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 71s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 71s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 71s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.6 kB] 71s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 71s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [97.3 kB] 71s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1026 kB] 71s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [109 kB] 71s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [843 kB] 71s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [9152 B] 71s Fetched 2235 kB in 1s (4087 kB/s) 73s Reading package lists... 73s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 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 75s Reading package lists... 75s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s Calculating upgrade... 76s The following packages were automatically installed and are no longer required: 76s python3.13 python3.13-minimal 76s Use 'sudo apt autoremove' to remove them. 77s The following NEW packages will be installed: 77s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 77s The following packages will be upgraded: 77s libplymouth5 libpython3-stdlib libtasn1-6 plymouth 77s plymouth-theme-ubuntu-text python3 python3-gdbm python3-jaraco.context 77s python3-minimal 77s 9 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 77s Need to get 7066 kB of archives. 77s After this operation, 24.3 MB of additional disk space will be used. 77s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-minimal s390x 3.14.2-1 [917 kB] 78s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14-minimal s390x 3.14.2-1 [2510 kB] 78s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-minimal s390x 3.14.2-1 [28.2 kB] 78s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3 s390x 3.14.2-1 [22.9 kB] 78s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-stdlib s390x 3.14.2-1 [2373 kB] 78s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14 s390x 3.14.2-1 [816 kB] 78s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libpython3-stdlib s390x 3.14.2-1 [10.9 kB] 78s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x libtasn1-6 s390x 4.21.0-2 [48.6 kB] 78s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x libplymouth5 s390x 24.004.60+git20250831.4a3c171d-0ubuntu5 [159 kB] 78s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x plymouth-theme-ubuntu-text s390x 24.004.60+git20250831.4a3c171d-0ubuntu5 [10.2 kB] 78s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x plymouth s390x 24.004.60+git20250831.4a3c171d-0ubuntu5 [152 kB] 78s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-gdbm s390x 3.14.2-1 [11.1 kB] 78s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x python3-jaraco.context all 6.0.1-2 [8198 B] 78s dpkg-preconfigure: unable to re-open stdin: No such file or directory 78s Fetched 7066 kB in 1s (7968 kB/s) 78s Selecting previously unselected package libpython3.14-minimal:s390x. 78s (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 ... 87685 files and directories currently installed.) 78s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_s390x.deb ... 78s Unpacking libpython3.14-minimal:s390x (3.14.2-1) ... 78s Selecting previously unselected package python3.14-minimal. 78s Preparing to unpack .../python3.14-minimal_3.14.2-1_s390x.deb ... 78s Unpacking python3.14-minimal (3.14.2-1) ... 78s Setting up libpython3.14-minimal:s390x (3.14.2-1) ... 78s Setting up python3.14-minimal (3.14.2-1) ... 82s (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 ... 88027 files and directories currently installed.) 87s Preparing to unpack .../python3-minimal_3.14.2-1_s390x.deb ... 87s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 87s Setting up python3-minimal (3.14.2-1) ... 87s (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 ... 88027 files and directories currently installed.) 87s Preparing to unpack .../0-python3_3.14.2-1_s390x.deb ... 87s running python pre-rtupdate hooks for python3.14... 87s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 87s Selecting previously unselected package libpython3.14-stdlib:s390x. 87s Preparing to unpack .../1-libpython3.14-stdlib_3.14.2-1_s390x.deb ... 87s Unpacking libpython3.14-stdlib:s390x (3.14.2-1) ... 87s Selecting previously unselected package python3.14. 87s Preparing to unpack .../2-python3.14_3.14.2-1_s390x.deb ... 87s Unpacking python3.14 (3.14.2-1) ... 87s Preparing to unpack .../3-libpython3-stdlib_3.14.2-1_s390x.deb ... 87s Unpacking libpython3-stdlib:s390x (3.14.2-1) over (3.13.9-3) ... 87s Preparing to unpack .../4-libtasn1-6_4.21.0-2_s390x.deb ... 87s Unpacking libtasn1-6:s390x (4.21.0-2) over (4.20.0-2ubuntu1) ... 87s Preparing to unpack .../5-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu5_s390x.deb ... 87s Unpacking libplymouth5:s390x (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 87s Preparing to unpack .../6-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu5_s390x.deb ... 87s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 87s Preparing to unpack .../7-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu5_s390x.deb ... 87s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 87s Preparing to unpack .../8-python3-gdbm_3.14.2-1_s390x.deb ... 87s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 87s Preparing to unpack .../9-python3-jaraco.context_6.0.1-2_all.deb ... 87s Unpacking python3-jaraco.context (6.0.1-2) over (6.0.1-1build1) ... 87s Setting up libpython3.14-stdlib:s390x (3.14.2-1) ... 87s Setting up libplymouth5:s390x (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 87s Setting up libtasn1-6:s390x (4.21.0-2) ... 87s Setting up python3.14 (3.14.2-1) ... 88s Setting up libpython3-stdlib:s390x (3.14.2-1) ... 88s Setting up python3-gdbm (3.14.2-1) ... 88s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 88s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 89s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 90s Setting up python3 (3.14.2-1) ... 90s running python rtupdate hooks for python3.14... 90s running python post-rtupdate hooks for python3.14... 90s Setting up python3-jaraco.context (6.0.1-2) ... 91s Processing triggers for libc-bin (2.42-2ubuntu4) ... 91s Processing triggers for systemd (259-1ubuntu2) ... 91s Processing triggers for man-db (2.13.1-1) ... 94s Processing triggers for initramfs-tools (0.150ubuntu7) ... 94s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 106s Using config file '/etc/zipl.conf' 106s Building bootmap in '/boot' 106s Adding IPL section 'ubuntu' (default) 106s Preparing boot device: vda. 106s Done. 106s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 106s Processing triggers for initramfs-tools (0.150ubuntu7) ... 106s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 113s Using config file '/etc/zipl.conf' 113s Building bootmap in '/boot' 113s Adding IPL section 'ubuntu' (default) 113s Preparing boot device: vda. 113s Done. 114s autopkgtest [21:30:23]: upgrading testbed (apt dist-upgrade and autopurge) 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 115s Calculating upgrade... 115s The following packages were automatically installed and are no longer required: 115s python3.13 python3.13-minimal 115s Use 'sudo apt autoremove' to remove them. 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s Solving dependencies... 116s The following packages will be REMOVED: 116s python3.13* python3.13-minimal* 117s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 117s After this operation, 8012 kB disk space will be freed. 117s (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 ... 88454 files and directories currently installed.) 117s Removing python3.13 (3.13.11-1) ... 117s Removing python3.13-minimal (3.13.11-1) ... 120s Processing triggers for man-db (2.13.1-1) ... 121s Processing triggers for systemd (259-1ubuntu2) ... 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 ... 88427 files and directories currently installed.) 121s Purging configuration files for python3.13-minimal (3.13.11-1) ... 122s autopkgtest [21:30:31]: rebooting testbed after setup commands that affected boot 155s autopkgtest [21:31:04]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP Mon Jan 12 15:39:23 UTC 2026 157s autopkgtest [21:31:06]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 159s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 1.0.2-2 (dsc) [2420 B] 159s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 1.0.2-2 (tar) [27.5 kB] 159s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 1.0.2-2 (diff) [3132 B] 159s gpgv: Signature made Fri Jan 2 10:35:26 2026 UTC 159s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 159s gpgv: issuer "tchet@debian.org" 159s gpgv: Can't check signature: No public key 159s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_1.0.2-2.dsc: no acceptable signature found 159s autopkgtest [21:31:08]: testing package python-pytest-xprocess version 1.0.2-2 159s autopkgtest [21:31:08]: build not needed 159s autopkgtest [21:31:08]: test upstream: preparing testbed 160s Reading package lists... 160s Building dependency tree... 160s Reading state information... 160s Solving dependencies... 160s The following NEW packages will be installed: 160s python3-all python3-iniconfig python3-pluggy python3-psutil python3-pytest 160s python3-pytest-xprocess python3.13 python3.13-minimal 160s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 160s Need to get 3670 kB of archives. 160s After this operation, 10.7 MB of additional disk space will be used. 160s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x python3.13-minimal s390x 3.13.11-1 [2405 kB] 161s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x python3.13 s390x 3.13.11-1 [758 kB] 161s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-all s390x 3.14.2-1 [890 B] 161s Get:4 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-iniconfig all 2.1.0-2 [6962 B] 161s Get:5 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pluggy all 1.6.0-2 [21.1 kB] 161s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x python3-psutil s390x 7.1.0-1ubuntu1 [191 kB] 161s Get:7 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest all 9.0.2-2 [277 kB] 161s Get:8 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-xprocess all 1.0.2-2 [10.3 kB] 161s Fetched 3670 kB in 1s (5363 kB/s) 161s Selecting previously unselected package python3.13-minimal. 161s (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 ... 88429 files and directories currently installed.) 161s Preparing to unpack .../0-python3.13-minimal_3.13.11-1_s390x.deb ... 161s Unpacking python3.13-minimal (3.13.11-1) ... 161s Selecting previously unselected package python3.13. 161s Preparing to unpack .../1-python3.13_3.13.11-1_s390x.deb ... 161s Unpacking python3.13 (3.13.11-1) ... 161s Selecting previously unselected package python3-all. 161s Preparing to unpack .../2-python3-all_3.14.2-1_s390x.deb ... 161s Unpacking python3-all (3.14.2-1) ... 161s Selecting previously unselected package python3-iniconfig. 161s Preparing to unpack .../3-python3-iniconfig_2.1.0-2_all.deb ... 161s Unpacking python3-iniconfig (2.1.0-2) ... 161s Selecting previously unselected package python3-pluggy. 161s Preparing to unpack .../4-python3-pluggy_1.6.0-2_all.deb ... 161s Unpacking python3-pluggy (1.6.0-2) ... 161s Selecting previously unselected package python3-psutil. 161s Preparing to unpack .../5-python3-psutil_7.1.0-1ubuntu1_s390x.deb ... 161s Unpacking python3-psutil (7.1.0-1ubuntu1) ... 161s Selecting previously unselected package python3-pytest. 161s Preparing to unpack .../6-python3-pytest_9.0.2-2_all.deb ... 161s Unpacking python3-pytest (9.0.2-2) ... 161s Selecting previously unselected package python3-pytest-xprocess. 161s Preparing to unpack .../7-python3-pytest-xprocess_1.0.2-2_all.deb ... 161s Unpacking python3-pytest-xprocess (1.0.2-2) ... 161s Setting up python3-iniconfig (2.1.0-2) ... 162s Setting up python3-psutil (7.1.0-1ubuntu1) ... 163s Setting up python3-pluggy (1.6.0-2) ... 163s Setting up python3.13-minimal (3.13.11-1) ... 164s Setting up python3.13 (3.13.11-1) ... 166s Setting up python3-pytest (9.0.2-2) ... 168s Setting up python3-all (3.14.2-1) ... 168s Setting up python3-pytest-xprocess (1.0.2-2) ... 168s Processing triggers for man-db (2.13.1-1) ... 169s Processing triggers for systemd (259-1ubuntu2) ... 170s autopkgtest [21:31:19]: test upstream: [----------------------- 170s -=-=-=-=-=-=-=- running tests for python3.13 -=-=-=-=-=-=-=-=- 170s $ python3.13 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 171s ============================= test session starts ============================== 171s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.13 171s cachedir: .pytest_cache 171s rootdir: /tmp/autopkgtest.wNeESL/autopkgtest_tmp 171s plugins: typeguard-4.4.4, xprocess-1.0.2 171s collecting ... collected 51 items / 2 deselected / 49 selected 171s 171s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 172s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 172s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 177s tests/test_callback.py::test_callback_fail[s1] PASSED [ 8%] 182s tests/test_callback.py::test_callback_fail[s2] PASSED [ 10%] 187s tests/test_callback.py::test_callback_fail[s3] PASSED [ 12%] 187s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 14%] 187s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 16%] 187s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 18%] 187s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 20%] 188s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 22%] 188s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 24%] 188s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 26%] 188s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 28%] 188s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 30%] 188s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 32%] 188s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 34%] 188s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 36%] 188s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 38%] 189s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 40%] 189s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 42%] 189s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 44%] 189s tests/test_process_initialization.py::test_startup_without_pattern[s1] PASSED [ 46%] 189s tests/test_process_initialization.py::test_startup_without_pattern[s2] PASSED [ 48%] 189s tests/test_process_initialization.py::test_startup_without_pattern[s3] PASSED [ 51%] 189s tests/test_process_initialization.py::test_startup_with_pattern_and_callback[s1-will not match-21] PASSED [ 53%] 189s tests/test_process_initialization.py::test_startup_with_pattern_and_callback[s2-spam, bacon, eggs-30] PASSED [ 55%] 190s tests/test_process_initialization.py::test_startup_with_pattern_and_callback[s3-finally started-130] PASSED [ 57%] 190s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 59%] 190s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 61%] 190s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 63%] 190s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 65%] 190s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 67%] 190s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 69%] 190s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 71%] 191s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 73%] 191s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 75%] 193s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 77%] 195s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 79%] 197s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 81%] 197s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 83%] 197s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 85%] 197s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 87%] 197s tests/test_resource_cleanup.py::test_0 PASSED [ 89%] 197s tests/test_resource_cleanup.py::test_1 PASSED [ 91%] 198s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 200s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 202s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 204s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 204s 204s ====================== 49 passed, 2 deselected in 33.30s ======================= 204s 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()'. 204s -=-=-=-=-=-=-=- running tests for python3.14 -=-=-=-=-=-=-=-=- 204s $ python3.14 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 205s ============================= test session starts ============================== 205s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 -- /usr/bin/python3.14 205s cachedir: .pytest_cache 205s rootdir: /tmp/autopkgtest.wNeESL/autopkgtest_tmp 205s plugins: typeguard-4.4.4, xprocess-1.0.2 205s collecting ... collected 51 items / 2 deselected / 49 selected 205s 205s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 205s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 205s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 210s tests/test_callback.py::test_callback_fail[s1] PASSED [ 8%] 215s tests/test_callback.py::test_callback_fail[s2] PASSED [ 10%] 220s tests/test_callback.py::test_callback_fail[s3] PASSED [ 12%] 221s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 14%] 221s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 16%] 221s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 18%] 221s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 20%] 221s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 22%] 221s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 24%] 222s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 26%] 222s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 28%] 222s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 30%] 222s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 32%] 222s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 34%] 222s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 36%] 222s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 38%] 222s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 40%] 222s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 42%] 223s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 44%] 223s tests/test_process_initialization.py::test_startup_without_pattern[s1] PASSED [ 46%] 223s tests/test_process_initialization.py::test_startup_without_pattern[s2] FAILED [ 48%] 223s tests/test_process_initialization.py::test_startup_without_pattern[s3] PASSED [ 51%] 223s tests/test_process_initialization.py::test_startup_with_pattern_and_callback[s1-will not match-21] PASSED [ 53%] 223s tests/test_process_initialization.py::test_startup_with_pattern_and_callback[s2-spam, bacon, eggs-30] FAILED [ 55%] 223s tests/test_process_initialization.py::test_startup_with_pattern_and_callback[s3-finally started-130] PASSED [ 57%] 224s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 59%] 224s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 61%] 224s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 63%] 225s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 65%] 225s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 67%] 226s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 69%] 226s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 71%] 227s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 73%] 227s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 75%] 229s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 77%] 231s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 79%] 233s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 81%] 233s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 83%] 234s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 85%] 234s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 87%] 234s tests/test_resource_cleanup.py::test_0 PASSED [ 89%] 234s tests/test_resource_cleanup.py::test_1 PASSED [ 91%] 234s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 237s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 239s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 241s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 241s 241s =================================== FAILURES =================================== 241s _______________________ test_startup_without_pattern[s2] _______________________ 241s 241s tcp_port = 50663, proc_name = 's2' 241s xprocess = 241s 241s @pytest.mark.parametrize("proc_name", ["s1", "s2", "s3"]) 241s def test_startup_without_pattern(tcp_port, proc_name, xprocess): 241s data = "bacon\n" 241s 241s class Starter(ProcessStarter): 241s args = [sys.executable, server_path, tcp_port, "--no-children"] 241s 241s def startup_check(self): 241s return request_response_cycle(tcp_port, data) 241s 241s > xprocess.ensure(proc_name, Starter) 241s 241s tests/test_process_initialization.py:131: 241s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 241s /usr/lib/python3/dist-packages/xprocess/xprocess.py:284: in ensure 241s if not starter.wait(log_file_handle): 241s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 241s /usr/lib/python3/dist-packages/xprocess/xprocess.py:413: in wait 241s callback_ok = self.wait_callback() 241s ^^^^^^^^^^^^^^^^^^^^ 241s /usr/lib/python3/dist-packages/xprocess/xprocess.py:388: in wait_callback 241s if self.startup_check(): 241s ^^^^^^^^^^^^^^^^^^^^ 241s tests/test_process_initialization.py:129: in startup_check 241s return request_response_cycle(tcp_port, data) 241s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 241s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 241s 241s tcp_port = 50663, data = 'bacon\n' 241s 241s def request_response_cycle(tcp_port, data): 241s """test started server instance by sending 241s request and checking response""" 241s with socket.socket(socket.AF_INET, socket.SOCK_STREAM) as sock: 241s > sock.connect(("localhost", tcp_port)) 241s E ConnectionRefusedError: [Errno 111] Connection refused 241s 241s tests/test_process_initialization.py:16: ConnectionRefusedError 241s ----------------------------- Captured stdout call ----------------------------- 241s /tmp/autopkgtest.wNeESL/autopkgtest_tmp/.pytest_cache/d/.xprocess/s2$ /usr/bin/python3.14 /tmp/autopkgtest.wNeESL/autopkgtest_tmp/tests/server.py 50663 --no-children 241s process 's2' started pid=1818 241s _______ test_startup_with_pattern_and_callback[s2-spam, bacon, eggs-30] ________ 241s 241s tcp_port = 59615, proc_name = 's2', proc_pttrn = 'spam, bacon, eggs', lines = 30 241s xprocess = 241s 241s @pytest.mark.parametrize( 241s "proc_name,proc_pttrn,lines", 241s [ 241s ("s1", "will not match", 21), 241s ("s2", "spam, bacon, eggs", 30), 241s ("s3", "finally started", 130), 241s ], 241s ) 241s def test_startup_with_pattern_and_callback( 241s tcp_port, proc_name, proc_pttrn, lines, xprocess 241s ): 241s data = "bacon\n" 241s 241s class Starter(ProcessStarter): 241s pattern = proc_pttrn 241s max_read_lines = lines 241s args = [sys.executable, server_path, tcp_port, "--no-children"] 241s 241s def startup_check(self): 241s return request_response_cycle(tcp_port, data) 241s 241s if proc_name == "s1": 241s with pytest.raises(RuntimeError): 241s xprocess.ensure(proc_name, Starter) 241s # since we made xprocess fail to start the server on purpose, we cannot 241s # terminate it using XProcessInfo.terminate method once it does not 241s # know the PID, process name or even that it is running, so we tell the 241s # server to terminate itself. 241s with socket.socket(socket.AF_INET, socket.SOCK_STREAM) as sock: 241s sock.connect(("localhost", tcp_port)) 241s sock.sendall(bytes("exit\n", "utf-8")) 241s else: 241s xprocess.ensure(proc_name, Starter) 241s info = xprocess.getinfo(proc_name) 241s assert info.isrunning() 241s > assert request_response_cycle(tcp_port, data) 241s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 241s 241s tests/test_process_initialization.py:172: 241s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 241s 241s tcp_port = 59615, data = 'bacon\n' 241s 241s def request_response_cycle(tcp_port, data): 241s """test started server instance by sending 241s request and checking response""" 241s with socket.socket(socket.AF_INET, socket.SOCK_STREAM) as sock: 241s > sock.connect(("localhost", tcp_port)) 241s E ConnectionRefusedError: [Errno 111] Connection refused 241s 241s tests/test_process_initialization.py:16: ConnectionRefusedError 241s =========================== short test summary info ============================ 241s FAILED tests/test_process_initialization.py::test_startup_without_pattern[s2] 241s FAILED tests/test_process_initialization.py::test_startup_with_pattern_and_callback[s2-spam, bacon, eggs-30] 241s ================= 2 failed, 47 passed, 2 deselected in 36.48s ================== 241s 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()'. 241s autopkgtest [21:32:30]: test upstream: -----------------------] 242s autopkgtest [21:32:31]: test upstream: - - - - - - - - - - results - - - - - - - - - - 242s upstream FAIL non-zero exit status 1 242s autopkgtest [21:32:31]: @@@@@@@@@@@@@@@@@@@@ summary 242s upstream FAIL non-zero exit status 1