Not sure why this hits this long timeout. The logs are https://github.com/bitcoin/bitcoin/actions/runs/20618562716/job/59216177088?pr=33775#step:11:3901:
test 2025-12-31T12:48:06.476897Z TestFramework (INFO): test echoipc (testing spawned process in multiprocess build)
node0 2025-12-31T12:48:06.477022Z [http] [httpserver.cpp:307] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:50326
node0 2025-12-31T12:48:06.477055Z [httpworker.0] [rpc/request.cpp:243] [parse] [rpc] ThreadRPCServer method=echoipc user=__cookie__
node0 2025-12-31T12:48:06.478285Z [httpworker.0] [ipc/interfaces.cpp:64] [spawnProcess] [ipc] Process bitcoin-node pid 17479 launched
node0 2025-12-31T12:48:06.478605Z [httpworker.0] [ipc/capnp/protocol.cpp:53] [IpcLogFn] ipc: {bitcoin-node-17465/b-httpworker.0-17468} IPC client first request from current thread, constructing waiter
node0 2025-12-31T12:48:06.478652Z [capnp-loop] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {bitcoin-node-17465/b-httpworker.0-17468} IPC client send Init.construct$Params
node0 2025-12-31T12:48:06.478677Z [capnp-loop] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] send data: (threadMap = <external capability>)
node0 2025-12-31T12:48:50.987744Z [scheduler] [net.cpp:2439] [StartExtraBlockRelayPeers] [net] enabling extra block-relay-only peers
node0 2025-12-31T12:52:04.064058Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
node0 2025-12-31T12:52:04.064130Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 completed (7μs)
node0 2025-12-31T12:52:42.016032Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
node0 2025-12-31T12:52:42.016107Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 completed (8μs)
node0 2025-12-31T12:53:06.019385Z [addcon] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:2924 started
node0 2025-12-31T12:53:06.019463Z [addcon] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:2924 completed (7μs)
node0 2025-12-31T12:59:30.084915Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
node0 2025-12-31T12:59:30.084991Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 completed (7μs)
node0 2025-12-31T13:01:13.165088Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
node0 2025-12-31T13:01:13.165160Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 completed (7μs)
node0 2025-12-31T13:03:05.989149Z [scheduler] [net.cpp:2401] [DumpAddresses] [net] Flushed 0 addresses to peers.dat 1ms
node0 2025-12-31T13:04:09.110919Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
node0 2025-12-31T13:04:09.110995Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 completed (7μs)
node0 2025-12-31T13:04:39.009903Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
node0 2025-12-31T13:04:39.009991Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 completed (8μs)
test 2025-12-31T13:08:06.577668Z TestFramework (ERROR): Unexpected exception
Traceback (most recent call last):
File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 174, in _get_response
http_response = self.__conn.getresponse()
^^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/lib/python3.12/http/client.py", line 1430, in getresponse
response.begin()
File "/usr/lib/python3.12/http/client.py", line 331, in begin
version, status, reason = self._read_status()
^^^^^^^^^^^^^^^^^^^
File "/usr/lib/python3.12/http/client.py", line 292, in _read_status
line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/usr/lib/python3.12/socket.py", line 720, in readinto
return self._sock.recv_into(b)
^^^^^^^^^^^^^^^^^^^^^^^
TimeoutError: timed out
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/test_framework.py", line 142, in main
self.run_test()
File "/home/admin/actions-runner/_work/_temp/build/test/functional/rpc_misc.py", line 96, in run_test
assert_equal(node.echoipc("hello"), "hello")
^^^^^^^^^^^^^^^^^^^^^
File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/coverage.py", line 50, in __call__
return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 137, in __call__
response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 111, in _request
return self._get_response()
^^^^^^^^^^^^^^^^^^^^
File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 176, in _get_response
raise JSONRPCException({
test_framework.authproxy.JSONRPCException: 'echoipc' RPC took longer than 1200.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
