0s autopkgtest [17:04:20]: starting date and time: 2024-11-15 17:04:20+0000 0s autopkgtest [17:04:20]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [17:04:20]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ltl45aay/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python-aiohttp,src:yarl --apt-upgrade python-jsonrpc-websocket --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python-aiohttp/3.10.10-2build1 yarl/1.13.1-1build1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-4.secgroup --name adt-plucky-amd64-python-jsonrpc-websocket-20241115-170419-juju-7f2275-prod-proposed-migration-environment-2-4596c70a-b7fe-4c8a-a6da-7d53c4417927 --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -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,keyserver.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/ 48s autopkgtest [17:05:08]: testbed dpkg architecture: amd64 48s autopkgtest [17:05:08]: testbed apt version: 2.9.8 48s autopkgtest [17:05:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup 49s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 49s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 49s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [94.2 kB] 49s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 49s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1052 kB] 49s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [178 kB] 49s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [111 kB] 49s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [32.6 kB] 49s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [825 kB] 49s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [299 kB] 49s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [9416 B] 49s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [740 B] 50s Fetched 2699 kB in 1s (2590 kB/s) 50s Reading package lists... 52s Reading package lists... 52s Building dependency tree... 52s Reading state information... 52s Calculating upgrade... 53s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 53s Reading package lists... 53s Building dependency tree... 53s Reading state information... 53s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 53s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 53s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 53s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 54s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 54s Reading package lists... 54s Reading package lists... 55s Building dependency tree... 55s Reading state information... 55s Calculating upgrade... 55s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 55s Reading package lists... 55s Building dependency tree... 55s Reading state information... 56s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 58s autopkgtest [17:05:18]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 58s autopkgtest [17:05:18]: @@@@@@@@@@@@@@@@@@@@ apt-source python-jsonrpc-websocket 60s Get:1 http://ftpmaster.internal/ubuntu plucky/universe python-jsonrpc-websocket 3.1.5-2 (dsc) [2418 B] 60s Get:2 http://ftpmaster.internal/ubuntu plucky/universe python-jsonrpc-websocket 3.1.5-2 (tar) [8987 B] 60s Get:3 http://ftpmaster.internal/ubuntu plucky/universe python-jsonrpc-websocket 3.1.5-2 (diff) [2724 B] 60s gpgv: Signature made Sat Sep 14 16:22:19 2024 UTC 60s gpgv: using RSA key FB8ACFA78C726089C38AD0269605A1098C63B92A 60s gpgv: Can't check signature: No public key 60s dpkg-source: warning: cannot verify inline signature for ./python-jsonrpc-websocket_3.1.5-2.dsc: no acceptable signature found 60s autopkgtest [17:05:20]: testing package python-jsonrpc-websocket version 3.1.5-2 60s autopkgtest [17:05:20]: build not needed 60s autopkgtest [17:05:20]: test run-tests: preparing testbed 61s Reading package lists... 62s Building dependency tree... 62s Reading state information... 62s Starting pkgProblemResolver with broken count: 0 62s Starting 2 pkgProblemResolver with broken count: 0 62s Done 62s The following additional packages will be installed: 62s python3-aiohappyeyeballs python3-aiohttp python3-aiosignal python3-all 62s python3-async-generator python3-async-timeout python3-frozenlist 62s python3-iniconfig python3-jsonrpc-base python3-jsonrpc-websocket 62s python3-multidict python3-packaging python3-pluggy python3-pytest 62s python3-pytest-asyncio python3-yarl 62s Recommended packages: 62s libjs-jquery python3-aiodns 62s The following NEW packages will be installed: 62s autopkgtest-satdep python3-aiohappyeyeballs python3-aiohttp 62s python3-aiosignal python3-all python3-async-generator python3-async-timeout 62s python3-frozenlist python3-iniconfig python3-jsonrpc-base 62s python3-jsonrpc-websocket python3-multidict python3-packaging python3-pluggy 62s python3-pytest python3-pytest-asyncio python3-yarl 62s 0 upgraded, 17 newly installed, 0 to remove and 0 not upgraded. 62s Need to get 979 kB/979 kB of archives. 62s After this operation, 5014 kB of additional disk space will be used. 62s Get:1 /tmp/autopkgtest.vmjsuE/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [736 B] 62s Get:2 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-aiohappyeyeballs all 2.4.3-1 [10.5 kB] 62s Get:3 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-multidict amd64 6.1.0-1 [34.3 kB] 63s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 python3-yarl amd64 1.13.1-1build1 [127 kB] 63s Get:5 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-async-timeout all 4.0.3-1 [6412 B] 63s Get:6 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-frozenlist amd64 1.5.0-1 [53.2 kB] 63s Get:7 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-aiosignal all 1.3.1-1 [5172 B] 63s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 python3-aiohttp amd64 3.10.10-2build1 [377 kB] 63s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-all amd64 3.12.6-0ubuntu1 [886 B] 63s Get:10 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-async-generator all 1.10-4 [17.5 kB] 63s Get:11 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 63s Get:12 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-jsonrpc-base all 2.2.0-2 [6742 B] 63s Get:13 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-jsonrpc-websocket all 3.1.5-2 [9492 B] 63s Get:14 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-packaging all 24.1-1 [41.4 kB] 63s Get:15 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-pluggy all 1.5.0-1 [21.0 kB] 63s Get:16 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-pytest all 8.3.3-1 [251 kB] 63s Get:17 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-pytest-asyncio all 0.20.3-1.3 [10.7 kB] 63s Fetched 979 kB in 1s (1692 kB/s) 63s Selecting previously unselected package python3-aiohappyeyeballs. 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 ... 75576 files and directories currently installed.) 63s Preparing to unpack .../00-python3-aiohappyeyeballs_2.4.3-1_all.deb ... 63s Unpacking python3-aiohappyeyeballs (2.4.3-1) ... 63s Selecting previously unselected package python3-multidict. 63s Preparing to unpack .../01-python3-multidict_6.1.0-1_amd64.deb ... 63s Unpacking python3-multidict (6.1.0-1) ... 63s Selecting previously unselected package python3-yarl. 63s Preparing to unpack .../02-python3-yarl_1.13.1-1build1_amd64.deb ... 63s Unpacking python3-yarl (1.13.1-1build1) ... 63s Selecting previously unselected package python3-async-timeout. 63s Preparing to unpack .../03-python3-async-timeout_4.0.3-1_all.deb ... 63s Unpacking python3-async-timeout (4.0.3-1) ... 63s Selecting previously unselected package python3-frozenlist. 63s Preparing to unpack .../04-python3-frozenlist_1.5.0-1_amd64.deb ... 63s Unpacking python3-frozenlist (1.5.0-1) ... 63s Selecting previously unselected package python3-aiosignal. 63s Preparing to unpack .../05-python3-aiosignal_1.3.1-1_all.deb ... 63s Unpacking python3-aiosignal (1.3.1-1) ... 63s Selecting previously unselected package python3-aiohttp. 63s Preparing to unpack .../06-python3-aiohttp_3.10.10-2build1_amd64.deb ... 63s Unpacking python3-aiohttp (3.10.10-2build1) ... 63s Selecting previously unselected package python3-all. 63s Preparing to unpack .../07-python3-all_3.12.6-0ubuntu1_amd64.deb ... 63s Unpacking python3-all (3.12.6-0ubuntu1) ... 63s Selecting previously unselected package python3-async-generator. 63s Preparing to unpack .../08-python3-async-generator_1.10-4_all.deb ... 63s Unpacking python3-async-generator (1.10-4) ... 63s Selecting previously unselected package python3-iniconfig. 63s Preparing to unpack .../09-python3-iniconfig_1.1.1-2_all.deb ... 63s Unpacking python3-iniconfig (1.1.1-2) ... 63s Selecting previously unselected package python3-jsonrpc-base. 63s Preparing to unpack .../10-python3-jsonrpc-base_2.2.0-2_all.deb ... 63s Unpacking python3-jsonrpc-base (2.2.0-2) ... 63s Selecting previously unselected package python3-jsonrpc-websocket. 64s Preparing to unpack .../11-python3-jsonrpc-websocket_3.1.5-2_all.deb ... 64s Unpacking python3-jsonrpc-websocket (3.1.5-2) ... 64s Selecting previously unselected package python3-packaging. 64s Preparing to unpack .../12-python3-packaging_24.1-1_all.deb ... 64s Unpacking python3-packaging (24.1-1) ... 64s Selecting previously unselected package python3-pluggy. 64s Preparing to unpack .../13-python3-pluggy_1.5.0-1_all.deb ... 64s Unpacking python3-pluggy (1.5.0-1) ... 64s Selecting previously unselected package python3-pytest. 64s Preparing to unpack .../14-python3-pytest_8.3.3-1_all.deb ... 64s Unpacking python3-pytest (8.3.3-1) ... 64s Selecting previously unselected package python3-pytest-asyncio. 64s Preparing to unpack .../15-python3-pytest-asyncio_0.20.3-1.3_all.deb ... 64s Unpacking python3-pytest-asyncio (0.20.3-1.3) ... 64s Selecting previously unselected package autopkgtest-satdep. 64s Preparing to unpack .../16-1-autopkgtest-satdep.deb ... 64s Unpacking autopkgtest-satdep (0) ... 64s Setting up python3-jsonrpc-base (2.2.0-2) ... 64s Setting up python3-iniconfig (1.1.1-2) ... 64s Setting up python3-async-generator (1.10-4) ... 64s Setting up python3-all (3.12.6-0ubuntu1) ... 64s Setting up python3-multidict (6.1.0-1) ... 64s Setting up python3-frozenlist (1.5.0-1) ... 64s Setting up python3-aiosignal (1.3.1-1) ... 64s Setting up python3-async-timeout (4.0.3-1) ... 64s Setting up python3-packaging (24.1-1) ... 65s Setting up python3-aiohappyeyeballs (2.4.3-1) ... 65s Setting up python3-pluggy (1.5.0-1) ... 65s Setting up python3-yarl (1.13.1-1build1) ... 65s Setting up python3-pytest (8.3.3-1) ... 65s Setting up python3-aiohttp (3.10.10-2build1) ... 65s Setting up python3-pytest-asyncio (0.20.3-1.3) ... 65s Setting up python3-jsonrpc-websocket (3.1.5-2) ... 65s Setting up autopkgtest-satdep (0) ... 65s Processing triggers for man-db (2.13.0-1) ... 68s (Reading database ... 75984 files and directories currently installed.) 68s Removing autopkgtest-satdep (0) ... 69s autopkgtest [17:05:29]: test run-tests: [----------------------- 69s ============================= test session starts ============================== 69s platform linux -- Python 3.12.7, pytest-8.3.3, pluggy-1.5.0 -- /usr/bin/python3.12 69s cachedir: .pytest_cache 69s rootdir: /tmp/autopkgtest.vmjsuE/autopkgtest_tmp 69s plugins: typeguard-4.4.1, asyncio-0.20.3 69s asyncio: mode=Mode.AUTO 69s collecting ... collected 20 items 69s 69s tests.py::test_pending_message_response PASSED [ 5%] 69s tests.py::test_internal_session PASSED [ 10%] 70s tests.py::test_send_message PASSED [ 15%] 70s tests.py::test_client_closed PASSED [ 20%] 70s tests.py::test_double_connect PASSED [ 25%] 70s tests.py::test_ws_error PASSED [ 30%] 70s tests.py::test_binary PASSED [ 35%] 70s tests.py::test_message_not_json PASSED [ 40%] 70s tests.py::test_message_binary_not_utf8 PASSED [ 45%] 70s tests.py::test_message_binary_not_json PASSED [ 50%] 70s tests.py::test_message_ping_ignored PASSED [ 55%] 70s tests.py::test_connection_timeout PASSED [ 60%] 70s tests.py::test_server_request PASSED [ 65%] 70s tests.py::test_server_async_request PASSED [ 70%] 70s tests.py::test_server_request_binary PASSED [ 75%] 70s tests.py::test_server_notification PASSED [ 80%] 70s tests.py::test_server_response_error PASSED [ 85%] 70s tests.py::test_calls PASSED [ 90%] 70s tests.py::test_simultaneous_calls PASSED [ 95%] 70s tests.py::test_notification PASSED [100%] 70s 70s =============================== warnings summary =============================== 70s tests.py::test_pending_message_response 70s tests.py:124: PytestWarning: The test is marked with '@pytest.mark.asyncio' but it is not an async function. Please remove asyncio marker. If the test is not marked explicitly, check for global markers applied via 'pytestmark'. 70s def test_pending_message_response(): 70s 70s tests.py::test_send_message 70s /usr/lib/python3/dist-packages/_pytest/unraisableexception.py:85: PytestUnraisableExceptionWarning: Exception ignored in: 70s 70s Traceback (most recent call last): 70s File "/usr/lib/python3/dist-packages/jsonrpc_websocket/jsonrpc.py", line 139, in wait 70s await self._event.wait() 70s File "/usr/lib/python3.12/asyncio/locks.py", line 212, in wait 70s await fut 70s asyncio.exceptions.CancelledError 70s 70s During handling of the above exception, another exception occurred: 70s 70s Traceback (most recent call last): 70s File "/usr/lib/python3.12/asyncio/queues.py", line 158, in get 70s await getter 70s GeneratorExit 70s 70s During handling of the above exception, another exception occurred: 70s 70s Traceback (most recent call last): 70s File "/usr/lib/python3/dist-packages/jsonrpc_websocket/jsonrpc.py", line 75, in _ws_loop 70s async for msg in self._client: 70s File "/usr/lib/python3/dist-packages/aiohttp/client_ws.py", line 384, in __anext__ 70s msg = await self.receive() 70s ^^^^^^^^^^^^^^^^^^^^ 70s File "/tmp/autopkgtest.vmjsuE/autopkgtest_tmp/tests.py", line 90, in receive 70s value = await self.receive_queue.get() 70s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 70s File "/usr/lib/python3.12/asyncio/queues.py", line 160, in get 70s getter.cancel() # Just in case getter is not done yet. 70s ^^^^^^^^^^^^^^^ 70s File "/usr/lib/python3.12/asyncio/base_events.py", line 794, in call_soon 70s self._check_closed() 70s File "/usr/lib/python3.12/asyncio/base_events.py", line 540, in _check_closed 70s raise RuntimeError('Event loop is closed') 70s RuntimeError: Event loop is closed 70s 70s warnings.warn(pytest.PytestUnraisableExceptionWarning(msg)) 70s 70s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 70s ======================== 20 passed, 2 warnings in 0.36s ======================== 70s autopkgtest [17:05:30]: test run-tests: -----------------------] 70s run-tests PASS 70s autopkgtest [17:05:30]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 71s autopkgtest [17:05:31]: @@@@@@@@@@@@@@@@@@@@ summary 71s run-tests PASS 84s virt: nova [W] Skipping flock for amd64 84s virt: Creating nova instance adt-plucky-amd64-python-jsonrpc-websocket-20241115-170419-juju-7f2275-prod-proposed-migration-environment-2-4596c70a-b7fe-4c8a-a6da-7d53c4417927 from image adt/ubuntu-plucky-amd64-server-20241115.img (UUID 9bb4dd9c-454f-45da-a125-77b9d27ed145)...