Was able to easily reproduce the issue.
C:\Users\hodlinator\bitcoin>py build\test\functional\feature_framework_startup_failures.py
2025-03-28T13:09:19.459000Z TestFramework (INFO): PRNG seed is: 4595347679033165165
2025-03-28T13:09:19.519000Z TestFramework (INFO): Initializing test directory C:\Users\HODLIN1\AppData\Local\Temp\bitcoin_func_test_4hxul9x2
2025-03-28T13:09:19.519000Z TestFramework (INFO): Verifying timeout in connecting to bitcoind’s RPC interface results in only one exception.
2025-03-28T13:09:19.788000Z TestFramework (INFO): Verifying inability to connect to bitcoind’s RPC interface due to wrong port results in one exception containing at least one OSError.
2025-03-28T13:09:21.378000Z TestFramework (ERROR): Found 0/1 occurrences of the specific exception: AssertionError: [node 0] Unable to connect to bitcoind after \d+s (ignored errors: {[^}]‘OSError \w+’?: \d+[^}]}, latest error: \w+([^)]+))
2025-03-28T13:09:21.378000Z TestFramework (ERROR): Assertion failed
Traceback (most recent call last):
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\test_framework.py”, line 135, in main
self.run_test()
~~~~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\feature_framework_startup_failures.py”, line 111, in run_test
self.test_wrong_rpc_port()
~~~~~~~~~~~~~~~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\feature_framework_startup_failures.py”, line 85, in test_wrong_rpc_port
self._run_test_internal(
~~~~~~~~~~~~~~~~~~~~~~~^
# Lower the timeout so we don’t wait that long.
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
…<4 lines>…
r"AssertionError: [node 0] Unable to connect to bitcoind after \d+s (ignored errors: {[^}]‘OSError \w+’?: \d+[^}]}, latest error: \w+([^)]+))"
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
)
^
File “C:\Users\hodlinator\bitcoin\build\test\functional\feature_framework_startup_failures.py”, line 74, in _run_test_internal
raise AssertionError(f"Child test didn’t contain (only) expected errors.\n:\n{result.stdout}\n\n")
AssertionError: Child test didn’t contain (only) expected errors.
:
2025-03-28T13:09:19.929000Z TestFramework (INFO): PRNG seed is: 1755806780689552109
2025-03-28T13:09:19.952000Z TestFramework (INFO): Initializing test directory C:\Users\HODLIN1\AppData\Local\Temp\bitcoin_func_test_zahuvbto
2025-03-28T13:09:21.234000Z TestFramework (ERROR): Unexpected exception caught during testing
Traceback (most recent call last):
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\test_framework.py”, line 131, in main
self.setup()
~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\test_framework.py”, line 313, in setup
self.setup_network()
~~~~~~~~~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\feature_framework_startup_failures.py”, line 47, in setup_network
BitcoinTestFramework.setup_network(self)
~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\test_framework.py”, line 404, in setup_network
self.setup_nodes()
~~~~~~~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\test_framework.py”, line 426, in setup_nodes
self.start_nodes()
~~~~~~~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\test_framework.py”, line 585, in start_nodes
node.wait_for_rpc_connection()
~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\test_node.py”, line 286, in wait_for_rpc_connection
rpc.getblockcount()
~~~~~~~~~~~~~~~~~^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\coverage.py”, line 50, in call
return_val = self.auth_service_proxy_instance.call(*args, **kwargs)
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\authproxy.py”, line 127, in call
response, status = self._request(‘POST’, self.__url.path, postdata.encode(‘utf-8’))
~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “C:\Users\hodlinator\bitcoin\build\test\functional\test_framework\authproxy.py”, line 105, in _request
self.__conn.request(method, path, postdata, headers)
~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\http\client.py”, line 1336, in request
self._send_request(method, url, body, headers, encode_chunked)
~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\http\client.py”, line 1382, in _send_request
self.endheaders(body, encode_chunked=encode_chunked)
~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\http\client.py”, line 1331, in endheaders
self._send_output(message_body, encode_chunked=encode_chunked)
~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\http\client.py”, line 1091, in _send_output
self.send(msg)
~~~~~~~~~^^^^^
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\http\client.py”, line 1035, in send
self.connect()
~~~~~~~~~~~~^^
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\http\client.py”, line 1001, in connect
self.sock = self._create_connection(
~~~~~~~~~~~~~~~~~~~~~~~^
(self.host,self.port), self.timeout, self.source_address)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\socket.py”, line 864, in create_connection
raise exceptions[0]
File “C:\Users\hodlinator\AppData\Local\Programs\Python\Python313\Lib\socket.py”, line 849, in create_connection
sock.connect(sa)
~~~~~~~~~~~~^^^^
TimeoutError: timed out
2025-03-28T13:09:21.344000Z TestFramework (INFO): Not stopping nodes as test failed. The dangling processes will be cleaned up later.
2025-03-28T13:09:21.345000Z TestFramework (WARNING): Not cleaning up dir C:\Users\HODLIN1\AppData\Local\Temp\bitcoin_func_test_zahuvbto
2025-03-28T13:09:21.345000Z TestFramework (ERROR): Test failed. Test logging available at C:\Users\HODLIN1\AppData\Local\Temp\bitcoin_func_test_zahuvbto/test_framework.log
2025-03-28T13:09:21.345000Z TestFramework (ERROR):
2025-03-28T13:09:21.345000Z TestFramework (ERROR): Hint: Call C:\Users\hodlinator\bitcoin\build\test\functional\combine_logs.py ‘C:\Users\HODLIN~1\AppData\Local\Temp\bitcoin_func_test_zahuvbto’ to consolidate all logs
2025-03-28T13:09:21.346000Z TestFramework (ERROR):
2025-03-28T13:09:21.346000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2025-03-28T13:09:21.346000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2025-03-28T13:09:21.346000Z TestFramework (ERROR):
[node 0] Cleaning up leftover process
2025-03-28T13:09:21.431000Z TestFramework (INFO): Not stopping nodes as test failed. The dangling processes will be cleaned up later.
2025-03-28T13:09:21.431000Z TestFramework (WARNING): Not cleaning up dir C:\Users\HODLIN1\AppData\Local\Temp\bitcoin_func_test_4hxul9x2
2025-03-28T13:09:21.431000Z TestFramework (ERROR): Test failed. Test logging available at C:\Users\HODLIN1\AppData\Local\Temp\bitcoin_func_test_4hxul9x2/test_framework.log
2025-03-28T13:09:21.431000Z TestFramework (ERROR):
2025-03-28T13:09:21.432000Z TestFramework (ERROR): Hint: Call C:\Users\hodlinator\bitcoin\build\test\functional\combine_logs.py ‘C:\Users\HODLIN~1\AppData\Local\Temp\bitcoin_func_test_4hxul9x2’ to consolidate all logs
2025-03-28T13:09:21.432000Z TestFramework (ERROR):
2025-03-28T13:09:21.432000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log.
2025-03-28T13:09:21.432000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues
2025-03-28T13:09:21.432000Z TestFramework (ERROR):
My hypothesis is that we trigger the issue through the first test setting a 0 rpc_timeout:
https://github.com/bitcoin/bitcoin/blob/6bbfb3eeef2e35560dd357bb2a8a7378e2b49eb5/test/functional/feature_framework_startup_failures.py#L76-L81
Attempting to use the network stack with a 0 timeout seems to have different meanings on Linux and Windows. It’s not really a realistic test. I think the next test covers a superset of the one above, so could probably remove the first one.
https://github.com/bitcoin/bitcoin/blob/6bbfb3eeef2e35560dd357bb2a8a7378e2b49eb5/test/functional/feature_framework_startup_failures.py#L83-L92
