Intermittent failure in rpc_gettxspendingprevout.py, line 98, assert_equal(node0.gettxspendingprevout([..]), [..]), JSONRPCException: No spending tx for the outpoint :0 in mempool, and txospenderindex is unavailable #34735

issue maflcko openend this issue on March 4, 2026
maflcko commented at 8:14 pm on March 4, 2026: member
 0test  2026-03-04T09:42:14.819801Z TestFramework (INFO): Find transactions spending outputs 
 1 node0 2026-03-04T09:42:14.820045Z [http] [httpserver.cpp:232] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:49896 
 2 node0 2026-03-04T09:42:14.820093Z [http_pool_1] [rpc/request.cpp:243] [parse] [rpc] ThreadRPCServer method=gettxspendingprevout user=__cookie__ 
 3 node1 2026-03-04T09:42:14.820445Z [http] [httpserver.cpp:232] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54094 
 4 node1 2026-03-04T09:42:14.820506Z [http_pool_0] [rpc/request.cpp:243] [parse] [rpc] ThreadRPCServer method=gettxspendingprevout user=__cookie__ 
 5 node2 2026-03-04T09:42:14.820866Z [http] [httpserver.cpp:232] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54088 
 6 node2 2026-03-04T09:42:14.820930Z [http_pool_0] [rpc/request.cpp:243] [parse] [rpc] ThreadRPCServer method=gettxspendingprevout user=__cookie__ 
 7 test  2026-03-04T09:42:14.821144Z TestFramework (INFO): Find transaction spending multiple outputs 
 8 node0 2026-03-04T09:42:14.821360Z [http] [httpserver.cpp:232] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:49896 
 9 node0 2026-03-04T09:42:14.821407Z [http_pool_0] [rpc/request.cpp:243] [parse] [rpc] ThreadRPCServer method=gettxspendingprevout user=__cookie__ 
10 test  2026-03-04T09:42:14.821596Z TestFramework (INFO): Find no transaction when output is unspent 
11 node0 2026-03-04T09:42:14.821776Z [http] [httpserver.cpp:232] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:49896 
12 node0 2026-03-04T09:42:14.821811Z [http_pool_1] [rpc/request.cpp:243] [parse] [rpc] ThreadRPCServer method=gettxspendingprevout user=__cookie__ 
13 test  2026-03-04T09:42:14.822031Z TestFramework (ERROR): Unexpected exception: 
14                                   Traceback (most recent call last):
15                                     File "/home/runner/work/_temp/test/functional/test_framework/test_framework.py", line 142, in main
16                                       self.run_test()
17                                       ~~~~~~~~~~~~~^^
18                                     File "/home/runner/work/_temp/build/test/functional/rpc_gettxspendingprevout.py", line 98, in run_test
19                                       assert_equal(node0.gettxspendingprevout([prevout(txidH, vout=0)]), [unspent_out(txidH, vout=0)])
20                                                    ~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^
21                                     File "/home/runner/work/_temp/test/functional/test_framework/coverage.py", line 50, in __call__
22                                       return_val = self.auth_service_proxy_instance.__call__(*args, **kwargs)
23                                     File "/home/runner/work/_temp/test/functional/test_framework/authproxy.py", line 152, in __call__
24                                       raise JSONRPCException(response['error'], status)
25                                   test_framework.authproxy.JSONRPCException: {'code': -1, 'message': 'No spending tx for the outpoint 1cdcfcb69bd749b9440fe8ad038024c4779e8315c533ad9a1604189776771124:0 in mempool, and txospenderindex is unavailable.'} [http_status=200]
26 test  2026-03-04T09:42:14.824301Z TestFramework (DEBUG): Closing down network thread 
27 node0 2026-03-04T09:42:14.832250Z [msghand] [net.cpp:4076] [PushMessage] [net] sending inv (73 bytes) peer=0 
28 node1 2026-03-04T09:42:14.832392Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: inv (73 bytes) peer=0 
29 node1 2026-03-04T09:42:14.832428Z [msghand] [net_processing.cpp:4178] [ProcessMessage] [net] got inv: wtx f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b  new peer=0 
30 node1 2026-03-04T09:42:14.832439Z [msghand] [net_processing.cpp:4178] [ProcessMessage] [net] got inv: wtx 6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec  new peer=0 
31 node1 2026-03-04T09:42:14.832456Z [msghand] [node/txdownloadman_impl.cpp:275] [GetRequestsToSend] [net] Requesting wtx f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b peer=0 
32 node1 2026-03-04T09:42:14.832462Z [msghand] [node/txdownloadman_impl.cpp:275] [GetRequestsToSend] [net] Requesting wtx 6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec peer=0 
33 node1 2026-03-04T09:42:14.832467Z [msghand] [net.cpp:4076] [PushMessage] [net] sending getdata (73 bytes) peer=0 
34 node0 2026-03-04T09:42:14.832545Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: getdata (73 bytes) peer=0 
35 node0 2026-03-04T09:42:14.832557Z [msghand] [net_processing.cpp:4225] [ProcessMessage] [net] received getdata (2 invsz) peer=0 
36 node0 2026-03-04T09:42:14.832565Z [msghand] [net_processing.cpp:4228] [ProcessMessage] [net] received getdata for: wtx f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b peer=0 
37 node0 2026-03-04T09:42:14.832573Z [msghand] [net.cpp:4076] [PushMessage] [net] sending tx (133 bytes) peer=0 
38 node0 2026-03-04T09:42:14.832599Z [msghand] [txmempool.cpp:789] [RemoveUnbroadcastTx] [mempool] Removed 36890766ac6c5223cb40ab63657304ca800acb46df7e7338b865fb1ae00c4c57 from set of unbroadcast txns 
39 node0 2026-03-04T09:42:14.832619Z [msghand] [net.cpp:4076] [PushMessage] [net] sending tx (211 bytes) peer=0 
40 node1 2026-03-04T09:42:14.832631Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: tx (133 bytes) peer=0 
41 node0 2026-03-04T09:42:14.832638Z [msghand] [txmempool.cpp:789] [RemoveUnbroadcastTx] [mempool] Removed 1cdcfcb69bd749b9440fe8ad038024c4779e8315c533ad9a1604189776771124 from set of unbroadcast txns 
42 node1 2026-03-04T09:42:14.832946Z [msghand] [validationinterface.cpp:203] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=36890766ac6c5223cb40ab63657304ca800acb46df7e7338b865fb1ae00c4c57 wtxid=f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b 
43 node1 2026-03-04T09:42:14.832959Z [msghand] [txmempool.cpp:441] [check] [mempool] Checking mempool with 7 transactions and 7 inputs 
44 node1 2026-03-04T09:42:14.832999Z [msghand] [net_processing.cpp:3157] [ProcessValidTx] [mempool] AcceptToMemoryPool: peer=0: accepted 36890766ac6c5223cb40ab63657304ca800acb46df7e7338b865fb1ae00c4c57 (wtxid=f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b) (poolsz 7 txn, 5 kB) 
45 node1 2026-03-04T09:42:14.833016Z [msghand] [net.cpp:4076] [PushMessage] [net] sending inv (37 bytes) peer=1 
46 node1 2026-03-04T09:42:14.833064Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: tx (211 bytes) peer=0 
47 node2 2026-03-04T09:42:14.833117Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: inv (37 bytes) peer=0 
48 node2 2026-03-04T09:42:14.833152Z [msghand] [net_processing.cpp:4178] [ProcessMessage] [net] got inv: wtx f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b  new peer=0 
49 node2 2026-03-04T09:42:14.833167Z [msghand] [node/txdownloadman_impl.cpp:275] [GetRequestsToSend] [net] Requesting wtx f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b peer=0 
50 node2 2026-03-04T09:42:14.833173Z [msghand] [net.cpp:4076] [PushMessage] [net] sending getdata (37 bytes) peer=0 
51 node1 2026-03-04T09:42:14.833521Z [msghand] [validationinterface.cpp:203] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=1cdcfcb69bd749b9440fe8ad038024c4779e8315c533ad9a1604189776771124 wtxid=6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec 
52 node1 2026-03-04T09:42:14.833529Z [msghand] [txmempool.cpp:441] [check] [mempool] Checking mempool with 8 transactions and 9 inputs 
53 node1 2026-03-04T09:42:14.833566Z [msghand] [net_processing.cpp:3157] [ProcessValidTx] [mempool] AcceptToMemoryPool: peer=0: accepted 1cdcfcb69bd749b9440fe8ad038024c4779e8315c533ad9a1604189776771124 (wtxid=6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec) (poolsz 8 txn, 6 kB) 
54 node1 2026-03-04T09:42:14.833587Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: getdata (37 bytes) peer=1 
55 node1 2026-03-04T09:42:14.833593Z [msghand] [net_processing.cpp:4225] [ProcessMessage] [net] received getdata (1 invsz) peer=1 
56 node1 2026-03-04T09:42:14.833599Z [msghand] [net_processing.cpp:4228] [ProcessMessage] [net] received getdata for: wtx f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b peer=1 
57 node1 2026-03-04T09:42:14.833605Z [msghand] [net.cpp:4076] [PushMessage] [net] sending tx (133 bytes) peer=1 
58 node1 2026-03-04T09:42:14.833640Z [msghand] [net.cpp:4076] [PushMessage] [net] sending inv (37 bytes) peer=1 
59 node2 2026-03-04T09:42:14.833665Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: tx (133 bytes) peer=0 
60 node2 2026-03-04T09:42:14.833954Z [msghand] [validationinterface.cpp:203] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=36890766ac6c5223cb40ab63657304ca800acb46df7e7338b865fb1ae00c4c57 wtxid=f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b 
61 node2 2026-03-04T09:42:14.833965Z [msghand] [txmempool.cpp:441] [check] [mempool] Checking mempool with 7 transactions and 7 inputs 
62 node2 2026-03-04T09:42:14.834002Z [msghand] [net_processing.cpp:3157] [ProcessValidTx] [mempool] AcceptToMemoryPool: peer=0: accepted 36890766ac6c5223cb40ab63657304ca800acb46df7e7338b865fb1ae00c4c57 (wtxid=f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b) (poolsz 7 txn, 5 kB) 
63 node2 2026-03-04T09:42:14.834013Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: inv (37 bytes) peer=0 
64 node2 2026-03-04T09:42:14.834024Z [msghand] [net_processing.cpp:4178] [ProcessMessage] [net] got inv: wtx 6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec  new peer=0 
65 node2 2026-03-04T09:42:14.834032Z [msghand] [node/txdownloadman_impl.cpp:275] [GetRequestsToSend] [net] Requesting wtx 6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec peer=0 
66 node2 2026-03-04T09:42:14.834036Z [msghand] [net.cpp:4076] [PushMessage] [net] sending getdata (37 bytes) peer=0 
67 node1 2026-03-04T09:42:14.834111Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: getdata (37 bytes) peer=1 
68 node1 2026-03-04T09:42:14.834123Z [msghand] [net_processing.cpp:4225] [ProcessMessage] [net] received getdata (1 invsz) peer=1 
69 node1 2026-03-04T09:42:14.834128Z [msghand] [net_processing.cpp:4228] [ProcessMessage] [net] received getdata for: wtx 6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec peer=1 
70 node1 2026-03-04T09:42:14.834135Z [msghand] [net.cpp:4076] [PushMessage] [net] sending tx (211 bytes) peer=1 
71 node2 2026-03-04T09:42:14.834193Z [msghand] [net_processing.cpp:3582] [ProcessMessage] [net] received: tx (211 bytes) peer=0 
72 node2 2026-03-04T09:42:14.834666Z [msghand] [validationinterface.cpp:203] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=1cdcfcb69bd749b9440fe8ad038024c4779e8315c533ad9a1604189776771124 wtxid=6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec 
73 node2 2026-03-04T09:42:14.834676Z [msghand] [txmempool.cpp:441] [check] [mempool] Checking mempool with 8 transactions and 9 inputs 
74 node2 2026-03-04T09:42:14.834715Z [msghand] [net_processing.cpp:3157] [ProcessValidTx] [mempool] AcceptToMemoryPool: peer=0: accepted 1cdcfcb69bd749b9440fe8ad038024c4779e8315c533ad9a1604189776771124 (wtxid=6732b4eae65e985118d8764b9f3045ebc8b51421dd2b25d82ddfce2b94490bec) (poolsz 8 txn, 6 kB) 
75 node1 2026-03-04T09:42:14.861988Z [scheduler] [validationinterface.cpp:203] [operator()] [validation] TransactionAddedToMempool: txid=36890766ac6c5223cb40ab63657304ca800acb46df7e7338b865fb1ae00c4c57 wtxid=f1a26c5103ff7a7050ad019fe36cfabe84f0f8731d7e8aa9c44128338e7d409b 
76 test  2026-03-04T09:42:14.874610Z TestFramework (INFO): Not stopping nodes as test failed. The dangling processes will be cleaned up later.
Probably a missing sync … ?
maflcko added the label CI failed on Mar 4, 2026
fanquake added this to the milestone 31.0 on Mar 4, 2026
fjahr commented at 4:52 pm on March 5, 2026: contributor

@maflcko do you have the link to the failed run? I would like to see if it’s the same Windows runner that where we saw the unit test issues.
achow101 commented at 6:48 pm on March 5, 2026: member

Probably a missing sync … ?

I don’t think it is. There’s an earlier check that each transaction is in node0’s mempool.

Edit: I think it’s a race with the txospender index syncing
furszy commented at 7:30 pm on March 5, 2026: member

Edit: I think it’s a race with the txospender index syncing

Yes.
fanquake closed this on Mar 6, 2026
fanquake referenced this in commit d97df29d5d on Mar 6, 2026
Contributors
maflcko fjahr achow101 furszy
Labels
CI failed
Milestone
31.0