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:
0test 2025-12-31T12:48:06.476897Z TestFramework (INFO): test echoipc (testing spawned process in multiprocess build)
1 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
2 node0 2025-12-31T12:48:06.477055Z [httpworker.0] [rpc/request.cpp:243] [parse] [rpc] ThreadRPCServer method=echoipc user=__cookie__
3 node0 2025-12-31T12:48:06.478285Z [httpworker.0] [ipc/interfaces.cpp:64] [spawnProcess] [ipc] Process bitcoin-node pid 17479 launched
4 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
5 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
6 node0 2025-12-31T12:48:06.478677Z [capnp-loop] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] send data: (threadMap = <external capability>)
7 node0 2025-12-31T12:48:50.987744Z [scheduler] [net.cpp:2439] [StartExtraBlockRelayPeers] [net] enabling extra block-relay-only peers
8 node0 2025-12-31T12:52:04.064058Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
9 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)
10 node0 2025-12-31T12:52:42.016032Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
11 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)
12 node0 2025-12-31T12:53:06.019385Z [addcon] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:2924 started
13 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)
14 node0 2025-12-31T12:59:30.084915Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
15 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)
16 node0 2025-12-31T13:01:13.165088Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
17 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)
18 node0 2025-12-31T13:03:05.989149Z [scheduler] [net.cpp:2401] [DumpAddresses] [net] Flushed 0 addresses to peers.dat 1ms
19 node0 2025-12-31T13:04:09.110919Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
20 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)
21 node0 2025-12-31T13:04:39.009903Z [net] [logging/timer.h:58] [Log] [lock] Enter: lock contention m_nodes_mutex, ./net.cpp:1919 started
22 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)
23 test 2025-12-31T13:08:06.577668Z TestFramework (ERROR): Unexpected exception
24 Traceback (most recent call last):
25 File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 174, in _get_response
26 http_response = self.__conn.getresponse()
27 ^^^^^^^^^^^^^^^^^^^^^^^^^
28 File "/usr/lib/python3.12/http/client.py", line 1430, in getresponse
29 response.begin()
30 File "/usr/lib/python3.12/http/client.py", line 331, in begin
31 version, status, reason = self._read_status()
32 ^^^^^^^^^^^^^^^^^^^
33 File "/usr/lib/python3.12/http/client.py", line 292, in _read_status
34 line = str(self.fp.readline(_MAXLINE + 1), "iso-8859-1")
35 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
36 File "/usr/lib/python3.12/socket.py", line 720, in readinto
37 return self._sock.recv_into(b)
38 ^^^^^^^^^^^^^^^^^^^^^^^
39 TimeoutError: timed out
40 During handling of the above exception, another exception occurred:
41 Traceback (most recent call last):
42 File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/test_framework.py", line 142, in main
43 self.run_test()
44 File "/home/admin/actions-runner/_work/_temp/build/test/functional/rpc_misc.py", line 96, in run_test
45 assert_equal(node.echoipc("hello"), "hello")
46 ^^^^^^^^^^^^^^^^^^^^^
47 File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/coverage.py", line 50, in __call__
48 return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
49 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
50 File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 137, in __call__
51 response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
52 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
53 File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 111, in _request
54 return self._get_response()
55 ^^^^^^^^^^^^^^^^^^^^
56 File "/home/admin/actions-runner/_work/_temp/test/functional/test_framework/authproxy.py", line 176, in _get_response
57 raise JSONRPCException({
58 test_framework.authproxy.JSONRPCException: 'echoipc' RPC took longer than 1200.000000 seconds. Consider using larger timeout for calls that take longer to return. (-344)
