0s autopkgtest [17:31:09]: starting date and time: 2025-11-17 17:31:09+0000 0s autopkgtest [17:31:09]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [17:31:09]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.2wdh6g11/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pytest --apt-upgrade terminado --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pytest/8.4.2-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-5.secgroup --name adt-resolute-arm64-terminado-20251117-173109-juju-7f2275-prod-proposed-migration-environment-20-2a858ba1-e96d-4a4d-9299-3ff3c7954753 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-arm64-terminado-20251117-173109-juju-7f2275-prod-proposed-migration-environment-20-2a858ba1-e96d-4a4d-9299-3ff3c7954753 from image adt/ubuntu-resolute-arm64-server-20251117.img (UUID 1cd33fbb-18df-4c5a-b8f0-2dcb25269485)... 77s autopkgtest [17:32:26]: testbed dpkg architecture: arm64 77s autopkgtest [17:32:26]: testbed apt version: 3.1.11 78s autopkgtest [17:32:27]: @@@@@@@@@@@@@@@@@@@@ test bed setup 78s autopkgtest [17:32:27]: testbed release detected to be: None 79s autopkgtest [17:32:28]: updating testbed package index (apt update) 79s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 80s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 80s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 80s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 80s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [9852 B] 80s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [779 kB] 80s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [22.9 kB] 80s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [73.2 kB] 81s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [143 kB] 81s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [3224 B] 81s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [107 kB] 81s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [324 B] 81s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [517 kB] 81s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [17.1 kB] 81s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [12.5 kB] 81s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [576 B] 83s Fetched 1774 kB in 2s (1005 kB/s) 85s Reading package lists... 86s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 86s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 86s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 86s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 88s Reading package lists... 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 89s Calculating upgrade... 89s The following packages will be upgraded: 89s apt libapt-pkg7.0 libcrypt-dev libcrypt1 usbutils 90s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s Need to get 2764 kB of archives. 90s After this operation, 104 kB of additional disk space will be used. 90s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libcrypt-dev arm64 1:4.5.1-1 [123 kB] 90s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 libcrypt1 arm64 1:4.5.1-1 [88.3 kB] 90s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 libapt-pkg7.0 arm64 3.1.12 [1063 kB] 91s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 apt arm64 3.1.12 [1407 kB] 91s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 usbutils arm64 1:019-1 [81.7 kB] 92s dpkg-preconfigure: unable to re-open stdin: No such file or directory 92s Fetched 2764 kB in 2s (1532 kB/s) 93s (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 ... 88137 files and directories currently installed.) 93s Preparing to unpack .../libcrypt-dev_1%3a4.5.1-1_arm64.deb ... 93s Unpacking libcrypt-dev:arm64 (1:4.5.1-1) over (1:4.4.38-1build1) ... 93s Preparing to unpack .../libcrypt1_1%3a4.5.1-1_arm64.deb ... 93s Unpacking libcrypt1:arm64 (1:4.5.1-1) over (1:4.4.38-1build1) ... 94s Setting up libcrypt1:arm64 (1:4.5.1-1) ... 94s (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 ... 88137 files and directories currently installed.) 94s Preparing to unpack .../libapt-pkg7.0_3.1.12_arm64.deb ... 94s Unpacking libapt-pkg7.0:arm64 (3.1.12) over (3.1.11) ... 94s Preparing to unpack .../archives/apt_3.1.12_arm64.deb ... 94s Unpacking apt (3.1.12) over (3.1.11) ... 95s Preparing to unpack .../usbutils_1%3a019-1_arm64.deb ... 95s Unpacking usbutils (1:019-1) over (1:018-2) ... 95s Setting up usbutils (1:019-1) ... 95s Setting up libcrypt-dev:arm64 (1:4.5.1-1) ... 95s Setting up libapt-pkg7.0:arm64 (3.1.12) ... 95s Setting up apt (3.1.12) ... 97s Processing triggers for man-db (2.13.1-1) ... 101s Processing triggers for libc-bin (2.42-2ubuntu2) ... 102s autopkgtest [17:32:51]: upgrading testbed (apt dist-upgrade and autopurge) 103s Reading package lists... 104s Building dependency tree... 104s Reading state information... 104s Calculating upgrade... 105s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 106s Solving dependencies... 107s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 107s autopkgtest [17:32:56]: rebooting testbed after setup commands that affected boot 148s autopkgtest [17:33:37]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 09:50:31 UTC 2025 151s autopkgtest [17:33:40]: @@@@@@@@@@@@@@@@@@@@ apt-source terminado 154s Get:1 http://ftpmaster.internal/ubuntu resolute/universe terminado 0.18.1-1 (dsc) [2406 B] 154s Get:2 http://ftpmaster.internal/ubuntu resolute/universe terminado 0.18.1-1 (tar) [32.4 kB] 154s Get:3 http://ftpmaster.internal/ubuntu resolute/universe terminado 0.18.1-1 (diff) [3964 B] 154s gpgv: Signature made Sun Jun 9 08:49:00 2024 UTC 154s gpgv: using RSA key 812EEFD8A3FBA4ACE4DF114B04C53BD7FE030551 154s gpgv: issuer "jpuydt@debian.org" 154s gpgv: Can't check signature: No public key 154s dpkg-source: warning: cannot verify inline signature for ./terminado_0.18.1-1.dsc: no acceptable signature found 155s autopkgtest [17:33:44]: testing package terminado version 0.18.1-1 155s autopkgtest [17:33:44]: build not needed 155s autopkgtest [17:33:44]: test runtestsuite3: preparing testbed 155s Reading package lists... 156s Building dependency tree... 156s Reading state information... 156s Solving dependencies... 157s The following NEW packages will be installed: 157s python3-iniconfig python3-pluggy python3-pytest python3-pytest-timeout 157s python3-terminado python3-tornado 157s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded. 157s Need to get 636 kB of archives. 157s After this operation, 3428 kB of additional disk space will be used. 157s Get:1 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-iniconfig all 2.1.0-1 [6840 B] 157s Get:2 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pluggy all 1.6.0-1 [21.0 kB] 157s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 python3-pytest all 8.4.2-1 [268 kB] 157s Get:4 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pytest-timeout all 2.3.1-2 [22.2 kB] 157s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-tornado arm64 6.5.2-3 [305 kB] 157s Get:6 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-terminado all 0.18.1-1 [13.2 kB] 158s Fetched 636 kB in 1s (898 kB/s) 159s Selecting previously unselected package python3-iniconfig. 159s (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 ... 88137 files and directories currently installed.) 159s Preparing to unpack .../0-python3-iniconfig_2.1.0-1_all.deb ... 159s Unpacking python3-iniconfig (2.1.0-1) ... 159s Selecting previously unselected package python3-pluggy. 159s Preparing to unpack .../1-python3-pluggy_1.6.0-1_all.deb ... 159s Unpacking python3-pluggy (1.6.0-1) ... 159s Selecting previously unselected package python3-pytest. 159s Preparing to unpack .../2-python3-pytest_8.4.2-1_all.deb ... 159s Unpacking python3-pytest (8.4.2-1) ... 159s Selecting previously unselected package python3-pytest-timeout. 159s Preparing to unpack .../3-python3-pytest-timeout_2.3.1-2_all.deb ... 159s Unpacking python3-pytest-timeout (2.3.1-2) ... 159s Selecting previously unselected package python3-tornado. 159s Preparing to unpack .../4-python3-tornado_6.5.2-3_arm64.deb ... 159s Unpacking python3-tornado (6.5.2-3) ... 159s Selecting previously unselected package python3-terminado. 160s Preparing to unpack .../5-python3-terminado_0.18.1-1_all.deb ... 160s Unpacking python3-terminado (0.18.1-1) ... 160s Setting up python3-iniconfig (2.1.0-1) ... 160s Setting up python3-tornado (6.5.2-3) ... 161s Setting up python3-pluggy (1.6.0-1) ... 161s Setting up python3-terminado (0.18.1-1) ... 161s Setting up python3-pytest (8.4.2-1) ... 162s Setting up python3-pytest-timeout (2.3.1-2) ... 163s Processing triggers for man-db (2.13.1-1) ... 165s autopkgtest [17:33:54]: test runtestsuite3: [----------------------- 166s ============================= test session starts ============================== 166s platform linux -- Python 3.13.9, pytest-8.4.2, pluggy-1.6.0 166s rootdir: /tmp/autopkgtest.1ieIIl/build.fGW/src 166s configfile: pyproject.toml 166s testpaths: tests/ 166s plugins: typeguard-4.4.2, timeout-2.3.1 166s timeout: 300.0s 166s timeout method: signal 166s timeout func_only: False 166s collected 9 items 166s 206s tests/basic_test.py ......... [100%] 206s 206s ============================= slowest 10 durations ============================= 206s 8.33s call tests/basic_test.py::NamedTermTests::test_namespace 206s 6.64s call tests/basic_test.py::UniqueTermTests::test_max_terminals 206s 6.59s call tests/basic_test.py::CommonTests::test_basic_command 206s 6.45s call tests/basic_test.py::NamedTermTests::test_max_terminals 206s 4.32s call tests/basic_test.py::UniqueTermTests::test_unique_processes 206s 4.20s call tests/basic_test.py::SingleTermTests::test_single_process 206s 2.36s call tests/basic_test.py::UniqueTermTests::test_large_io_doesnt_hang 206s 0.55s call tests/basic_test.py::CommonTests::test_basic 206s 0.15s call tests/basic_test.py::NamedTermTests::test_new 206s 206s (1 durations < 0.005s hidden. Use -vv to show these durations.) 206s ============================== 9 passed in 39.90s ============================== 207s + Exception Group Traceback (most recent call last): 207s | File "/usr/bin/pytest", line 8, in 207s | sys.exit(console_main()) 207s | ~~~~~~~~~~~~^^ 207s | File "/usr/lib/python3/dist-packages/_pytest/config/__init__.py", line 201, in console_main 207s | code = main() 207s | File "/usr/lib/python3/dist-packages/_pytest/config/__init__.py", line 175, in main 207s | ret: ExitCode | int = config.hook.pytest_cmdline_main(config=config) 207s | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^ 207s | File "/usr/lib/python3/dist-packages/pluggy/_hooks.py", line 512, in __call__ 207s | return self._hookexec(self.name, self._hookimpls.copy(), kwargs, firstresult) 207s | ~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | File "/usr/lib/python3/dist-packages/pluggy/_manager.py", line 120, in _hookexec 207s | return self._inner_hookexec(hook_name, methods, kwargs, firstresult) 207s | ~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | File "/usr/lib/python3/dist-packages/pluggy/_callers.py", line 167, in _multicall 207s | raise exception 207s | File "/usr/lib/python3/dist-packages/pluggy/_callers.py", line 121, in _multicall 207s | res = hook_impl.function(*args) 207s | File "/usr/lib/python3/dist-packages/_pytest/main.py", line 336, in pytest_cmdline_main 207s | return wrap_session(config, _main) 207s | File "/usr/lib/python3/dist-packages/_pytest/main.py", line 331, in wrap_session 207s | config._ensure_unconfigure() 207s | ~~~~~~~~~~~~~~~~~~~~~~~~~~^^ 207s | File "/usr/lib/python3/dist-packages/_pytest/config/__init__.py", line 1131, in _ensure_unconfigure 207s | self._cleanup_stack.close() 207s | ~~~~~~~~~~~~~~~~~~~~~~~~~^^ 207s | File "/usr/lib/python3.13/contextlib.py", line 627, in close 207s | self.__exit__(None, None, None) 207s | ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^ 207s | File "/usr/lib/python3.13/contextlib.py", line 619, in __exit__ 207s | raise exc 207s | File "/usr/lib/python3.13/contextlib.py", line 604, in __exit__ 207s | if cb(*exc_details): 207s | ~~^^^^^^^^^^^^^^ 207s | File "/usr/lib/python3.13/contextlib.py", line 482, in _exit_wrapper 207s | callback(*args, **kwds) 207s | ~~~~~~~~^^^^^^^^^^^^^^^ 207s | File "/usr/lib/python3/dist-packages/_pytest/unraisableexception.py", line 95, in cleanup 207s | collect_unraisable(config) 207s | ~~~~~~~~~~~~~~~~~~^^^^^^^^ 207s | File "/usr/lib/python3/dist-packages/_pytest/unraisableexception.py", line 81, in collect_unraisable 207s | raise ExceptionGroup("multiple unraisable exception warnings", errors) 207s | ExceptionGroup: multiple unraisable exception warnings (3 sub-exceptions) 207s +-+---------------- 1 ---------------- 207s | Traceback (most recent call last): 207s | File "/usr/lib/python3/dist-packages/tornado/websocket.py", line 1466, in __del__ 207s | warnings.warn("Unclosed WebSocketClientConnection", ResourceWarning) 207s | ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | ResourceWarning: Unclosed WebSocketClientConnection 207s | 207s | The above exception was the direct cause of the following exception: 207s | 207s | Traceback (most recent call last): 207s | File "/usr/lib/python3/dist-packages/_pytest/unraisableexception.py", line 67, in collect_unraisable 207s | warnings.warn(pytest.PytestUnraisableExceptionWarning(msg)) 207s | ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | pytest.PytestUnraisableExceptionWarning: Exception ignored in: 207s | Enable tracemalloc to get traceback where the object was allocated. 207s | See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 207s +---------------- 2 ---------------- 207s | Traceback (most recent call last): 207s | File "/usr/lib/python3/dist-packages/tornado/websocket.py", line 1466, in __del__ 207s | warnings.warn("Unclosed WebSocketClientConnection", ResourceWarning) 207s | ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | ResourceWarning: Unclosed WebSocketClientConnection 207s | 207s | The above exception was the direct cause of the following exception: 207s | 207s | Traceback (most recent call last): 207s | File "/usr/lib/python3/dist-packages/_pytest/unraisableexception.py", line 67, in collect_unraisable 207s | warnings.warn(pytest.PytestUnraisableExceptionWarning(msg)) 207s | ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | pytest.PytestUnraisableExceptionWarning: Exception ignored in: 207s | Enable tracemalloc to get traceback where the object was allocated. 207s | See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 207s +---------------- 3 ---------------- 207s | Traceback (most recent call last): 207s | File "/usr/lib/python3/dist-packages/tornado/websocket.py", line 1466, in __del__ 207s | warnings.warn("Unclosed WebSocketClientConnection", ResourceWarning) 207s | ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | ResourceWarning: Unclosed WebSocketClientConnection 207s | 207s | The above exception was the direct cause of the following exception: 207s | 207s | Traceback (most recent call last): 207s | File "/usr/lib/python3/dist-packages/_pytest/unraisableexception.py", line 67, in collect_unraisable 207s | warnings.warn(pytest.PytestUnraisableExceptionWarning(msg)) 207s | ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s | pytest.PytestUnraisableExceptionWarning: Exception ignored in: 207s | Enable tracemalloc to get traceback where the object was allocated. 207s | See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 207s +------------------------------------ 208s autopkgtest [17:34:37]: test runtestsuite3: -----------------------] 208s runtestsuite3 FAIL non-zero exit status 1 208s autopkgtest [17:34:37]: test runtestsuite3: - - - - - - - - - - results - - - - - - - - - - 209s autopkgtest [17:34:38]: @@@@@@@@@@@@@@@@@@@@ summary 209s runtestsuite3 FAIL non-zero exit status 1