0s autopkgtest [08:06:10]: starting date and time: 2024-03-23 08:06:10+0000 0s autopkgtest [08:06:10]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [08:06:10]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.hkcfglp_/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:sphinx --apt-upgrade python-pytest-xprocess --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=sphinx/7.2.6-6 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-ppc64el-20.secgroup --name adt-noble-ppc64el-python-pytest-xprocess-20240323-080610-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 109s autopkgtest [08:07:59]: testbed dpkg architecture: ppc64el 109s autopkgtest [08:07:59]: testbed apt version: 2.7.12 109s autopkgtest [08:07:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 110s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 110s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [4030 kB] 111s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB] 111s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 111s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB] 111s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [659 kB] 111s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 111s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 111s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 111s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4251 kB] 111s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 111s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [60.8 kB] 111s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 114s Fetched 9688 kB in 2s (4104 kB/s) 114s Reading package lists... 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s Calculating upgrade... 117s The following packages will be upgraded: 117s libbsd0 libc-bin libc6 locales 117s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s Need to get 8313 kB of archives. 117s After this operation, 2048 B of additional disk space will be used. 117s Get:1 http://ftpmaster.internal/ubuntu noble/main ppc64el libc6 ppc64el 2.39-0ubuntu6 [3274 kB] 118s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el libc-bin ppc64el 2.39-0ubuntu6 [751 kB] 118s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el libbsd0 ppc64el 0.12.1-1 [56.9 kB] 118s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el locales all 2.39-0ubuntu6 [4232 kB] 119s Preconfiguring packages ... 119s Fetched 8313 kB in 1s (5636 kB/s) 119s (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 ... 70156 files and directories currently installed.) 119s Preparing to unpack .../libc6_2.39-0ubuntu6_ppc64el.deb ... 119s Unpacking libc6:ppc64el (2.39-0ubuntu6) over (2.39-0ubuntu2) ... 120s Setting up libc6:ppc64el (2.39-0ubuntu6) ... 120s (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 ... 70156 files and directories currently installed.) 120s Preparing to unpack .../libc-bin_2.39-0ubuntu6_ppc64el.deb ... 120s Unpacking libc-bin (2.39-0ubuntu6) over (2.39-0ubuntu2) ... 120s Setting up libc-bin (2.39-0ubuntu6) ... 120s (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 ... 70156 files and directories currently installed.) 120s Preparing to unpack .../libbsd0_0.12.1-1_ppc64el.deb ... 120s Unpacking libbsd0:ppc64el (0.12.1-1) over (0.11.8-1) ... 120s Preparing to unpack .../locales_2.39-0ubuntu6_all.deb ... 120s Unpacking locales (2.39-0ubuntu6) over (2.39-0ubuntu2) ... 121s Setting up locales (2.39-0ubuntu6) ... 121s Generating locales (this might take a while)... 122s en_US.UTF-8... done 122s Generation complete. 122s Setting up libbsd0:ppc64el (0.12.1-1) ... 122s Processing triggers for man-db (2.12.0-3) ... 123s Processing triggers for libc-bin (2.39-0ubuntu6) ... 123s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s sh: Attempting to set up Debian/Ubuntu apt sources automatically 124s sh: Distribution appears to be Ubuntu 125s Reading package lists... 125s Building dependency tree... 125s Reading state information... 126s eatmydata is already the newest version (131-1). 126s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 126s dbus is already the newest version (1.14.10-4ubuntu1). 126s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 126s rng-tools-debian is already the newest version (2.4). 126s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 127s The following packages will be REMOVED: 127s cloud-init* python3-configobj* python3-debconf* 127s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 127s After this operation, 3256 kB disk space will be freed. 127s (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 ... 70156 files and directories currently installed.) 127s Removing cloud-init (24.1.2-0ubuntu1) ... 127s Removing python3-configobj (5.0.8-3) ... 127s Removing python3-debconf (1.5.86) ... 127s Processing triggers for man-db (2.12.0-3) ... 128s (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 ... 69767 files and directories currently installed.) 128s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 128s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 128s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 128s invoke-rc.d: policy-rc.d denied execution of try-restart. 128s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s linux-generic is already the newest version (6.8.0-11.11+1). 129s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 129s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 129s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 129s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 132s Reading package lists... 132s Reading package lists... 132s Building dependency tree... 132s Reading state information... 132s Calculating upgrade... 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 134s autopkgtest [08:08:23]: rebooting testbed after setup commands that affected boot 134s Reading package lists... 134s Building dependency tree... 134s Reading state information... 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 176s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 183s autopkgtest [08:09:13]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 186s autopkgtest [08:09:16]: @@@@@@@@@@@@@@@@@@@@ apt-source python-pytest-xprocess 188s Get:1 http://ftpmaster.internal/ubuntu noble/universe python-pytest-xprocess 0.22.2-1 (dsc) [2426 B] 188s Get:2 http://ftpmaster.internal/ubuntu noble/universe python-pytest-xprocess 0.22.2-1 (tar) [31.3 kB] 188s Get:3 http://ftpmaster.internal/ubuntu noble/universe python-pytest-xprocess 0.22.2-1 (diff) [3104 B] 188s gpgv: Signature made Mon Aug 7 15:28:10 2023 UTC 188s gpgv: using RSA key A0B1A9F3508956130E7A425CD416AD15AC6B43FE 188s gpgv: Can't check signature: No public key 188s dpkg-source: warning: cannot verify inline signature for ./python-pytest-xprocess_0.22.2-1.dsc: no acceptable signature found 188s autopkgtest [08:09:18]: testing package python-pytest-xprocess version 0.22.2-1 188s autopkgtest [08:09:18]: build not needed 189s autopkgtest [08:09:19]: test upstream: preparing testbed 195s Reading package lists... 195s Building dependency tree... 195s Reading state information... 195s Starting pkgProblemResolver with broken count: 0 195s Starting 2 pkgProblemResolver with broken count: 0 195s Done 196s The following additional packages will be installed: 196s python3-all python3-iniconfig python3-packaging python3-pluggy 196s python3-psutil python3-py python3-pytest python3-pytest-xprocess 196s Suggested packages: 196s subversion python-pytest-xprocess-doc 196s The following NEW packages will be installed: 196s autopkgtest-satdep python3-all python3-iniconfig python3-packaging 196s python3-pluggy python3-psutil python3-py python3-pytest 196s python3-pytest-xprocess 196s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded. 196s Need to get 652 kB/653 kB of archives. 196s After this operation, 3208 kB of additional disk space will be used. 196s Get:1 /tmp/autopkgtest.GfuWug/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [728 B] 196s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-all ppc64el 3.12.1-0ubuntu2 [904 B] 196s Get:3 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-iniconfig all 1.1.1-2 [6024 B] 196s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-packaging all 23.2-1 [40.6 kB] 196s Get:5 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-pluggy all 1.4.0-1 [20.4 kB] 196s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el python3-psutil ppc64el 5.9.8-1 [197 kB] 196s Get:7 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-py all 1.11.0-2 [72.7 kB] 196s Get:8 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-pytest all 7.4.4-1 [305 kB] 196s Get:9 http://ftpmaster.internal/ubuntu noble/universe ppc64el python3-pytest-xprocess all 0.22.2-1 [10.2 kB] 196s Fetched 652 kB in 1s (1201 kB/s) 196s Selecting previously unselected package python3-all. 197s (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 ... 69712 files and directories currently installed.) 197s Preparing to unpack .../0-python3-all_3.12.1-0ubuntu2_ppc64el.deb ... 197s Unpacking python3-all (3.12.1-0ubuntu2) ... 197s Selecting previously unselected package python3-iniconfig. 197s Preparing to unpack .../1-python3-iniconfig_1.1.1-2_all.deb ... 197s Unpacking python3-iniconfig (1.1.1-2) ... 197s Selecting previously unselected package python3-packaging. 197s Preparing to unpack .../2-python3-packaging_23.2-1_all.deb ... 197s Unpacking python3-packaging (23.2-1) ... 197s Selecting previously unselected package python3-pluggy. 197s Preparing to unpack .../3-python3-pluggy_1.4.0-1_all.deb ... 197s Unpacking python3-pluggy (1.4.0-1) ... 197s Selecting previously unselected package python3-psutil. 197s Preparing to unpack .../4-python3-psutil_5.9.8-1_ppc64el.deb ... 197s Unpacking python3-psutil (5.9.8-1) ... 197s Selecting previously unselected package python3-py. 197s Preparing to unpack .../5-python3-py_1.11.0-2_all.deb ... 197s Unpacking python3-py (1.11.0-2) ... 197s Selecting previously unselected package python3-pytest. 197s Preparing to unpack .../6-python3-pytest_7.4.4-1_all.deb ... 197s Unpacking python3-pytest (7.4.4-1) ... 197s Selecting previously unselected package python3-pytest-xprocess. 197s Preparing to unpack .../7-python3-pytest-xprocess_0.22.2-1_all.deb ... 197s Unpacking python3-pytest-xprocess (0.22.2-1) ... 197s Selecting previously unselected package autopkgtest-satdep. 197s Preparing to unpack .../8-1-autopkgtest-satdep.deb ... 197s Unpacking autopkgtest-satdep (0) ... 197s Setting up python3-iniconfig (1.1.1-2) ... 197s Setting up python3-py (1.11.0-2) ... 197s Setting up python3-all (3.12.1-0ubuntu2) ... 197s Setting up python3-psutil (5.9.8-1) ... 197s Setting up python3-packaging (23.2-1) ... 197s Setting up python3-pluggy (1.4.0-1) ... 198s Setting up python3-pytest (7.4.4-1) ... 198s Setting up python3-pytest-xprocess (0.22.2-1) ... 198s Setting up autopkgtest-satdep (0) ... 198s Processing triggers for man-db (2.12.0-3) ... 200s (Reading database ... 70053 files and directories currently installed.) 200s Removing autopkgtest-satdep (0) ... 201s autopkgtest [08:09:31]: test upstream: [----------------------- 201s -=-=-=-=-=-=-=- running tests for python3.12 -=-=-=-=-=-=-=-=- 201s $ python3.12 -m pytest -v -k "not test_interruption_cleanup and not test_interruption_does_not_cleanup" tests 201s ============================= test session starts ============================== 201s platform linux -- Python 3.12.2, pytest-7.4.4, pluggy-1.4.0 -- /usr/bin/python3.12 201s cachedir: .pytest_cache 201s rootdir: /tmp/autopkgtest.GfuWug/autopkgtest_tmp 201s plugins: xprocess-0.22.2 201s collecting ... collected 45 items / 2 deselected / 43 selected 201s 202s tests/test_callback.py::test_callback_success[s1] PASSED [ 2%] 202s tests/test_callback.py::test_callback_success[s2] PASSED [ 4%] 202s tests/test_callback.py::test_callback_success[s3] PASSED [ 6%] 207s tests/test_callback.py::test_callback_fail[s1] PASSED [ 9%] 212s tests/test_callback.py::test_callback_fail[s2] PASSED [ 11%] 217s tests/test_callback.py::test_callback_fail[s3] PASSED [ 13%] 217s tests/test_functional_workflow.py::test_functional_work_flow PASSED [ 16%] 218s tests/test_process_initialization.py::test_servers_start[s1] PASSED [ 18%] 218s tests/test_process_initialization.py::test_servers_start[s2] PASSED [ 20%] 218s tests/test_process_initialization.py::test_servers_start[s3] PASSED [ 23%] 218s tests/test_process_initialization.py::test_ensure_not_restart[s1] PASSED [ 25%] 219s tests/test_process_initialization.py::test_ensure_not_restart[s2] PASSED [ 27%] 219s tests/test_process_initialization.py::test_ensure_not_restart[s3] PASSED [ 30%] 219s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s1-started-21] PASSED [ 32%] 219s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s2-spam, bacon, eggs-30] PASSED [ 34%] 219s tests/test_process_initialization.py::test_startup_detection_max_read_lines[s3-finally started-130] PASSED [ 37%] 220s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s1] PASSED [ 39%] 220s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s2] PASSED [ 41%] 220s tests/test_process_initialization.py::test_runtime_error_on_start_fail[s3] PASSED [ 44%] 220s tests/test_process_initialization.py::test_popen_kwargs[s1] PASSED [ 46%] 220s tests/test_process_initialization.py::test_popen_kwargs[s2] PASSED [ 48%] 221s tests/test_process_initialization.py::test_popen_kwargs[s3] PASSED [ 51%] 221s tests/test_process_termination.py::test_clean_shutdown[s1] PASSED [ 53%] 221s tests/test_process_termination.py::test_clean_shutdown[s2] PASSED [ 55%] 221s tests/test_process_termination.py::test_clean_shutdown[s3] PASSED [ 58%] 222s tests/test_process_termination.py::test_terminate_no_pid[s1] PASSED [ 60%] 222s tests/test_process_termination.py::test_terminate_no_pid[s2] PASSED [ 62%] 222s tests/test_process_termination.py::test_terminate_no_pid[s3] PASSED [ 65%] 222s tests/test_process_termination.py::test_terminate_only_parent[s1] PASSED [ 67%] 222s tests/test_process_termination.py::test_terminate_only_parent[s2] PASSED [ 69%] 223s tests/test_process_termination.py::test_terminate_only_parent[s3] PASSED [ 72%] 225s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s1] PASSED [ 74%] 227s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s2] PASSED [ 76%] 229s tests/test_process_termination.py::test_sigkill_after_failed_sigterm[s3] PASSED [ 79%] 230s tests/test_process_termination.py::test_return_value_on_failure[s1] PASSED [ 81%] 230s tests/test_process_termination.py::test_return_value_on_failure[s2] PASSED [ 83%] 230s tests/test_process_termination.py::test_return_value_on_failure[s3] PASSED [ 86%] 230s tests/test_resource_cleanup.py::test_0 PASSED [ 88%] 230s tests/test_resource_cleanup.py::test_1 PASSED [ 90%] 230s tests/test_resource_cleanup.py::test_2 PASSED [ 93%] 233s tests/test_startup_timeout.py::test_timeout_raise_exception[s1] PASSED [ 95%] 235s tests/test_startup_timeout.py::test_timeout_raise_exception[s2] PASSED [ 97%] 237s tests/test_startup_timeout.py::test_timeout_raise_exception[s3] PASSED [100%] 237s 237s ====================== 43 passed, 2 deselected in 35.63s ======================= 237s 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()'. 237s autopkgtest [08:10:07]: test upstream: -----------------------] 239s upstream PASS 239s autopkgtest [08:10:09]: test upstream: - - - - - - - - - - results - - - - - - - - - - 239s autopkgtest [08:10:09]: @@@@@@@@@@@@@@@@@@@@ summary 239s upstream PASS 251s Creating nova instance adt-noble-ppc64el-python-pytest-xprocess-20240323-080610-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-ppc64el-server-20240322.img (UUID 8499323f-6fcd-4f77-8a53-77410adea6d3)...