0s autopkgtest [02:24:17]: starting date and time: 2025-10-22 02:24:17+0000 0s autopkgtest [02:24:17]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [02:24:17]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ebmntss5/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-9.secgroup --name adt-resolute-ppc64el-python-pytest-xprocess-20251022-022417-juju-7f2275-prod-proposed-migration-environment-2-5efc5022-8d2e-4b48-93f5-2b2fb0bd5659 --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-ppc64el-python-pytest-xprocess-20251022-022417-juju-7f2275-prod-proposed-migration-environment-2-5efc5022-8d2e-4b48-93f5-2b2fb0bd5659 from image adt/ubuntu-resolute-ppc64el-server-20251021.img (UUID c177abc7-cbc1-4b1f-967f-196ea722a402)... 58s autopkgtest [02:25:15]: testbed dpkg architecture: ppc64el 58s autopkgtest [02:25:15]: testbed apt version: 3.1.8ubuntu1 58s autopkgtest [02:25:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 58s autopkgtest [02:25:15]: testbed release detected to be: None 59s autopkgtest [02:25:16]: updating testbed package index (apt update) 60s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [83.3 kB] 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 60s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [56.6 kB] 60s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 60s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [15.0 kB] 60s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [586 kB] 60s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [121 kB] 60s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted ppc64el Packages [940 B] 60s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [383 kB] 61s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [4596 B] 61s Fetched 1255 kB in 1s (1099 kB/s) 61s Reading package lists... 62s Failed to check for VM: Permission denied 62s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 62s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 62s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 62s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 63s Reading package lists... 63s Reading package lists... 64s Building dependency tree... 64s Reading state information... 64s Calculating upgrade... 64s The following NEW packages will be installed: 64s 3cpio 64s The following packages will be upgraded: 64s dracut-install initramfs-tools initramfs-tools-bin initramfs-tools-core 64s 4 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 64s Need to get 443 kB of archives. 64s After this operation, 874 kB of additional disk space will be used. 64s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el 3cpio ppc64el 0.10.2-0ubuntu1 [301 kB] 65s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el dracut-install ppc64el 108-8ubuntu1 [49.5 kB] 65s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el initramfs-tools-bin ppc64el 0.150ubuntu4 [33.7 kB] 65s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el initramfs-tools all 0.150ubuntu4 [8440 B] 65s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el initramfs-tools-core all 0.150ubuntu4 [50.4 kB] 65s dpkg-preconfigure: unable to re-open stdin: No such file or directory 65s Fetched 443 kB in 1s (568 kB/s) 65s Selecting previously unselected package 3cpio. 65s (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 ... 76480 files and directories currently installed.) 65s Preparing to unpack .../3cpio_0.10.2-0ubuntu1_ppc64el.deb ... 65s Unpacking 3cpio (0.10.2-0ubuntu1) ... 65s Preparing to unpack .../dracut-install_108-8ubuntu1_ppc64el.deb ... 65s Unpacking dracut-install (108-8ubuntu1) over (108-3ubuntu3) ... 65s Preparing to unpack .../initramfs-tools-bin_0.150ubuntu4_ppc64el.deb ... 65s Unpacking initramfs-tools-bin (0.150ubuntu4) over (0.150ubuntu3) ... 65s Preparing to unpack .../initramfs-tools_0.150ubuntu4_all.deb ... 65s Unpacking initramfs-tools (0.150ubuntu4) over (0.150ubuntu3) ... 66s Preparing to unpack .../initramfs-tools-core_0.150ubuntu4_all.deb ... 66s Unpacking initramfs-tools-core (0.150ubuntu4) over (0.150ubuntu3) ... 66s Setting up 3cpio (0.10.2-0ubuntu1) ... 66s Setting up dracut-install (108-8ubuntu1) ... 66s Setting up initramfs-tools-bin (0.150ubuntu4) ... 66s Setting up initramfs-tools-core (0.150ubuntu4) ... 66s Setting up initramfs-tools (0.150ubuntu4) ... 66s update-initramfs: deferring update (trigger activated) 66s Processing triggers for man-db (2.13.1-1) ... 67s Processing triggers for initramfs-tools (0.150ubuntu4) ... 67s update-initramfs: Generating /boot/initrd.img-6.17.0-5-generic 74s autopkgtest [02:25:31]: upgrading testbed (apt dist-upgrade and autopurge) 74s Reading package lists... 74s Building dependency tree... 74s Reading state information... 74s Calculating upgrade... 74s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 74s Reading package lists... 74s Building dependency tree... 74s Reading state information... 75s Solving dependencies... 75s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 75s autopkgtest [02:25:32]: rebooting testbed after setup commands that affected boot 101s autopkgtest [02:25:58]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:02:41 UTC 2025 103s autopkgtest [02:26:00]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 105s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (dsc) [2425 B] 105s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (tar) [31.3 kB] 105s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-pytest-xprocess 0.22.2-2 (diff) [3156 B] 105s gpgv: Signature made Tue Jan 7 15:14:38 2025 UTC 105s gpgv: using RSA key A0B1A9F3508956130E7A425CD416AD15AC6B43FE 105s gpgv: Can't check signature: No public key 105s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_0.22.2-2.dsc: no acceptable signature found 105s autopkgtest [02:26:02]: testing package python-pytest-xprocess version 0.22.2-2 106s autopkgtest [02:26:03]: build not needed 106s autopkgtest [02:26:03]: test upstream: preparing testbed 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s Solving dependencies... 106s The following NEW packages will be installed: 106s python3-all python3-iniconfig python3-packaging python3-pluggy 106s python3-psutil python3-py python3-pytest python3-pytest-xprocess 107s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 107s Need to get 622 kB of archives. 107s After this operation, 3387 kB of additional disk space will be used. 107s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-all ppc64el 3.13.7-1 [884 B] 107s Get:2 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-iniconfig all 1.1.1-2 [6024 B] 107s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-packaging all 25.0-1 [52.8 kB] 107s Get:4 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pluggy all 1.6.0-1 [21.0 kB] 107s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-psutil ppc64el 7.0.0-2ubuntu1 [190 kB] 107s Get:6 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-py all 1.11.0-5 [72.9 kB] 107s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el python3-pytest all 8.4.2-1 [268 kB] 107s Get:8 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pytest-xprocess all 0.22.2-2 [10.3 kB] 107s Fetched 622 kB in 1s (916 kB/s) 107s Selecting previously unselected package python3-all. 108s (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 ... 76486 files and directories currently installed.) 108s Preparing to unpack .../0-python3-all_3.13.7-1_ppc64el.deb ... 108s Unpacking python3-all (3.13.7-1) ... 108s Selecting previously unselected package python3-iniconfig. 108s Preparing to unpack .../1-python3-iniconfig_1.1.1-2_all.deb ... 108s Unpacking python3-iniconfig (1.1.1-2) ... 108s Selecting previously unselected package python3-packaging. 108s Preparing to unpack .../2-python3-packaging_25.0-1_all.deb ... 108s Unpacking python3-packaging (25.0-1) ... 108s Selecting previously unselected package python3-pluggy. 108s Preparing to unpack .../3-python3-pluggy_1.6.0-1_all.deb ... 108s Unpacking python3-pluggy (1.6.0-1) ... 108s Selecting previously unselected package python3-psutil. 108s Preparing to unpack .../4-python3-psutil_7.0.0-2ubuntu1_ppc64el.deb ... 108s Unpacking python3-psutil (7.0.0-2ubuntu1) ... 108s Selecting previously unselected package python3-py. 108s Preparing to unpack .../5-python3-py_1.11.0-5_all.deb ... 108s Unpacking python3-py (1.11.0-5) ... 108s Selecting previously unselected package python3-pytest. 108s Preparing to unpack .../6-python3-pytest_8.4.2-1_all.deb ... 108s Unpacking python3-pytest (8.4.2-1) ... 108s Selecting previously unselected package python3-pytest-xprocess. 108s Preparing to unpack .../7-python3-pytest-xprocess_0.22.2-2_all.deb ... 108s Unpacking python3-pytest-xprocess (0.22.2-2) ... 108s Setting up python3-iniconfig (1.1.1-2) ... 108s Setting up python3-py (1.11.0-5) ... 108s Setting up python3-all (3.13.7-1) ... 108s Setting up python3-psutil (7.0.0-2ubuntu1) ... 108s Setting up python3-packaging (25.0-1) ... 108s Setting up python3-pluggy (1.6.0-1) ... 108s Setting up python3-pytest (8.4.2-1) ... 109s Setting up python3-pytest-xprocess (0.22.2-2) ... 109s Processing triggers for man-db (2.13.1-1) ... 110s autopkgtest [02:26:07]: test upstream: [----------------------- 111s -=-=-=-=-=-=-=- running tests for python3.13 -=-=-=-=-=-=-=-=- 111s $ python3.13 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 111s ============================= test session starts ============================== 111s platform linux -- Python 3.13.9, pytest-8.4.2, pluggy-1.6.0 -- /usr/bin/python3.13 111s cachedir: .pytest_cache 111s rootdir: /tmp/autopkgtest.wkTXWk/autopkgtest_tmp 111s plugins: typeguard-4.4.2, xprocess-0.22.2 111s collecting ... collected 45 items / 2 deselected / 43 selected 111s 111s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 111s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 112s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 117s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 122s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 127s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 127s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 127s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 127s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 128s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 128s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 128s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 128s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 129s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 129s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 129s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 129s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 129s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 130s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 130s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 130s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 130s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 130s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 131s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 131s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 131s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 131s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 131s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 132s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 132s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 132s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 134s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 137s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 139s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 139s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 139s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 139s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 140s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 140s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 140s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 142s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 144s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 147s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 147s 147s ====================== 43 passed, 2 deselected in 35.61s ======================= 147s 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()'. 147s autopkgtest [02:26:44]: test upstream: -----------------------] 147s upstream PASS 147s autopkgtest [02:26:44]: test upstream: - - - - - - - - - - results - - - - - - - - - - 148s autopkgtest [02:26:45]: @@@@@@@@@@@@@@@@@@@@ summary 148s upstream PASS