ci: wallet_listtransactions.py --legacy-wallet failure

fanquake commented at 11:59 am on September 5, 2023: member

https://github.com/bitcoin/bitcoin/actions/runs/6082441277/job/16500239670"

  0Remaining jobs: [wallet_listtransactions.py --legacy-wallet]
  1274/274 - wallet_listtransactions.py --legacy-wallet failed, Duration: 2686 s
  2
  3stdout:
  42023-09-05T11:07:42.589000Z TestFramework (INFO): PRNG seed is: 4023633279228776754
  5
  62023-09-05T11:07:42.589000Z TestFramework (INFO): Initializing test directory D:\a\_temp\test_runner_₿_🏃_20230905_092624\wallet_listtransactions_222
  7
  82023-09-05T11:52:26.642000Z TestFramework (ERROR): Assertion failed
  9
 10Traceback (most recent call last):
 11
 12  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 552, in start_nodes
 13
 14    node.wait_for_rpc_connection()
 15
 16  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 296, in wait_for_rpc_connection
 17
 18    self._raise_assertion_error("Unable to connect to bitcoind after {}s".format(self.rpc_timeout))
 19
 20  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 177, in _raise_assertion_error
 21
 22    raise AssertionError(self._node_msg(msg))
 23
 24AssertionError: [node 1] Unable to connect to bitcoind after 2400s
 25
 26
 27
 28During handling of the above exception, another exception occurred:
 29
 30
 31
 32Traceback (most recent call last):
 33
 34  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 130, in main
 35
 36    self.setup()
 37
 38  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 295, in setup
 39
 40    self.setup_network()
 41
 42  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 389, in setup_network
 43
 44    self.setup_nodes()
 45
 46  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 411, in setup_nodes
 47
 48    self.start_nodes()
 49
 50  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 555, in start_nodes
 51
 52    self.stop_nodes()
 53
 54  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 570, in stop_nodes
 55
 56    node.stop_node(wait=wait, wait_until_stopped=False)
 57
 58  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 357, in stop_node
 59
 60    self.stop(wait=wait)
 61
 62    ^^^^^^^^^
 63
 64  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 194, in __getattr__
 65
 66    assert self.rpc_connected and self.rpc is not None, self._node_msg("Error: no RPC connection")
 67
 68                                  ^^^^^^^^^^^^^^^^^^^^
 69
 70AssertionError: [node 1] Error: no RPC connection
 71
 722023-09-05T11:52:26.703000Z TestFramework (INFO): Stopping nodes
 73
 74[node 2] Cleaning up leftover process
 75
 76[node 1] Cleaning up leftover process
 77
 78[node 0] Cleaning up leftover process
 79
 80
 81
 82stderr:
 83Traceback (most recent call last):
 84
 85  File "D:\a\bitcoin\bitcoin/test/functional/wallet_listtransactions.py", line 304, in <module>
 86
 87    ListTransactionsTest().main()
 88
 89  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 154, in main
 90
 91    exit_code = self.shutdown()
 92
 93                ^^^^^^^^^^^^^^^
 94
 95  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 311, in shutdown
 96
 97    self.stop_nodes()
 98
 99  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 570, in stop_nodes
100
101    node.stop_node(wait=wait, wait_until_stopped=False)
102
103  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 357, in stop_node
104
105    self.stop(wait=wait)
106
107  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\coverage.py", line 50, in __call__
108
109    return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
110
111                 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
112
113  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\authproxy.py", line 127, in __call__
114
115    response, status = self._request('POST', self.__url.path, postdata.encode('utf-8'))
116
117                       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
118
119  File "D:\a\bitcoin\bitcoin\test\functional\test_framework\authproxy.py", line 105, in _request
120
121    self.__conn.request(method, path, postdata, headers)
122
123  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\http\client.py", line 1286, in request
124
125    self._send_request(method, url, body, headers, encode_chunked)
126
127  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\http\client.py", line 1332, in _send_request
128
129    self.endheaders(body, encode_chunked=encode_chunked)
130
131  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\http\client.py", line 1281, in endheaders
132
133    self._send_output(message_body, encode_chunked=encode_chunked)
134
135  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\http\client.py", line 1041, in _send_output
136
137    self.send(msg)
138
139  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\http\client.py", line 979, in send
140
141    self.connect()
142
143  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\http\client.py", line 945, in connect
144
145    self.sock = self._create_connection(
146
147                ^^^^^^^^^^^^^^^^^^^^^^^^
148
149  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\socket.py", line 851, in create_connection
150
151    raise exceptions[0]
152
153  File "C:\hostedtoolcache\windows\Python\3.11.4\x64\Lib\socket.py", line 836, in create_connection
154
155    sock.connect(sa)
156
157ConnectionRefusedError: [WinError 10061] No connection could be made because the target machine actively refused it
158
159
160
161Combine the logs and print the last 99999999 lines ...

 0  test  2023-09-05T11:52:26.642000Z TestFramework.node0 (DEBUG): Stopping node 
 1 test  2023-09-05T11:52:26.642000Z TestFramework.node1 (DEBUG): Stopping node 
 2 test  2023-09-05T11:52:26.642000Z TestFramework (ERROR): Assertion failed 
 3                                   Traceback (most recent call last):
 4                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 552, in start_nodes
 5                                       node.wait_for_rpc_connection()
 6                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 296, in wait_for_rpc_connection
 7                                       self._raise_assertion_error("Unable to connect to bitcoind after {}s".format(self.rpc_timeout))
 8                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 177, in _raise_assertion_error
 9                                       raise AssertionError(self._node_msg(msg))
10                                   AssertionError: [node 1] Unable to connect to bitcoind after 2400s
11                                   During handling of the above exception, another exception occurred:
12                                   Traceback (most recent call last):
13                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 130, in main
14                                       self.setup()
15                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 295, in setup
16                                       self.setup_network()
17                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 389, in setup_network
18                                       self.setup_nodes()
19                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 411, in setup_nodes
20                                       self.start_nodes()
21                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 555, in start_nodes
22                                       self.stop_nodes()
23                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_framework.py", line 570, in stop_nodes
24                                       node.stop_node(wait=wait, wait_until_stopped=False)
25                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 357, in stop_node
26                                       self.stop(wait=wait)
27                                       ^^^^^^^^^
28                                     File "D:\a\bitcoin\bitcoin\test\functional\test_framework\test_node.py", line 194, in __getattr__
29                                       assert self.rpc_connected and self.rpc is not None, self._node_msg("Error: no RPC connection")
30                                                                     ^^^^^^^^^^^^^^^^^^^^
31                                   AssertionError: [node 1] Error: no RPC connection
32 test  2023-09-05T11:52:26.642000Z TestFramework (DEBUG): Closing down network thread 
33 node0 2023-09-05T11:52:26.644582Z [D:\a\bitcoin\bitcoin\src\httpserver.cpp:255] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54455

maflcko added the label Tests on Sep 5, 2023

maflcko commented at 12:03 pm on September 5, 2023: member

Looks like the known GHA Windows issue, no?

fanquake commented at 12:05 pm on September 5, 2023: member

Looks like the known GHA Windows issue, no?

Is there an issue open for that, can close this is favour. Is the issue that the Native Windows GitHub Actions are just randomly broken?

hebasto commented at 12:08 pm on September 5, 2023: member

We have been faced this issue in GHA since beginning. #28384 has improved situation but not resolved.

hebasto commented at 12:11 pm on September 5, 2023: member

Suggesting to rename this issue using:

0ConnectionRefusedError: [WinError 10061] No connection could be made because the target machine actively refused it

maflcko commented at 9:15 am on September 6, 2023: member

https://github.com/bitcoin/bitcoin/actions/runs/6087472870/job/16516084926?pr=28246#step:28:369

maflcko commented at 9:15 am on September 6, 2023: member

Suggesting to rename this issue using:

0ConnectionRefusedError: [WinError 10061] No connection could be made because the target machine actively refused it

I can’t find this in the logs, only AssertionError: [node 1] Unable to connect to bitcoind after 2400s.

hebasto commented at 7:00 pm on September 6, 2023: member

Suggesting to rename this issue using:
0ConnectionRefusedError: [WinError 10061] No connection could be made because the target machine actively refused it
I can’t find this in the logs, only AssertionError: [node 1] Unable to connect to bitcoind after 2400s.

From https://github.com/bitcoin/bitcoin/actions/runs/6082441277/job/16500239670#step:28:228:

0ConnectionRefusedError: [WinError 10061] No connection could be made because the target machine actively refused it

EDIT: The link has been updated.

maflcko commented at 4:36 pm on September 10, 2023: member

Not sure what you are linking to, but I get {"count":11,"value":"Uri expired"}

hebasto commented at 9:31 am on September 12, 2023: member

Not sure what you are linking to…

It was a raw log of the job mentioned in the top comment.

This one should work: https://github.com/bitcoin/bitcoin/actions/runs/6082441277/job/16500239670#step:28:228

hebasto commented at 7:21 pm on September 18, 2023: member

I’ve re-read logs from this issue and other similar ones. The combined logs miss entries for nodes that are supposed to run.

Could subprocess.Popen just stuck on Windows?

For example: https://github.com/python/cpython/issues/88693.

maflcko added the label CI failed on Jan 24, 2024

ci: wallet_listtransactions.py –legacy-wallet failure #28411