Intermittent issue in p2p_i2p_ports.py AssertionError: [node 0] Expected messages "['Error connecting to [...].b32.i2p:0: Cannot connect to 127.0.0.1:60000']" does not partially match log: #30030

issue maflcko opened this issue on May 3, 2024
  1. maflcko commented at 9:53 AM on May 3, 2024: member

    https://cirrus-ci.com/task/4526270581571584?logs=ci#L3634

     test  2024-04-28T17:46:07.431000Z TestFramework.node0 (DEBUG): RPC successfully started 
 test  2024-04-28T17:46:07.431000Z TestFramework (DEBUG): Generate a block with current time 
 test  2024-04-28T17:46:07.431000Z TestFramework.node0 (DEBUG): TestNode.generate() dispatches `generate` call to `generatetoaddress` 
 node0 2024-04-28T17:46:07.431322Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.431380Z [httpworker.1] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ 
 node0 2024-04-28T17:46:07.431901Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.431963Z [httpworker.0] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=generatetoaddress user=__cookie__ 
 node0 2024-04-28T17:46:07.432032Z [httpworker.0] [node/miner.cpp:163] [CreateNewBlock] CreateNewBlock(): block weight: 904 txs: 0 fees: 0 sigops 400 
 node0 2024-04-28T17:46:07.432073Z [httpworker.0] [validation.cpp:2337] [ConnectBlock] [bench]     - Sanity checks: 0.00ms [0.00s (0.00ms/blk)] 
 node0 2024-04-28T17:46:07.432094Z [httpworker.0] [validation.cpp:2439] [ConnectBlock] [bench]     - Fork checks: 0.02ms [0.00s (0.02ms/blk)] 
 node0 2024-04-28T17:46:07.432108Z [httpworker.0] [validation.cpp:2531] [ConnectBlock] [bench]       - Connect 1 transactions: 0.01ms (0.011ms/tx, 0.000ms/txin) [0.00s (0.01ms/blk)] 
 node0 2024-04-28T17:46:07.432123Z [httpworker.0] [validation.cpp:2549] [ConnectBlock] [bench]     - Verify 0 txins: 0.03ms (0.000ms/txin) [0.00s (0.03ms/blk)] 
 node0 2024-04-28T17:46:07.432133Z [httpworker.0] [node/miner.cpp:182] [CreateNewBlock] [bench] CreateNewBlock() packages: 0.01ms (0 packages, 0 updated descendants), validity: 0.11ms (total 0.12ms) 
 node0 2024-04-28T17:46:07.432148Z [httpworker.0] [validation.cpp:4209] [AcceptBlockHeader] [validation] Saw new header hash=3d85c0577c39dd26e5317925700277c541e6f13a6c7a53df98364cdb2180c6f5 height=200 
 node0 2024-04-28T17:46:07.432383Z [httpworker.0] [validation.cpp:3007] [ConnectTip] [bench]   - Using cached block 
 node0 2024-04-28T17:46:07.432396Z [httpworker.0] [validation.cpp:3017] [ConnectTip] [bench]   - Load block from disk: 0.01ms 
 node0 2024-04-28T17:46:07.432424Z [httpworker.0] [validation.cpp:2337] [ConnectBlock] [bench]     - Sanity checks: 0.00ms [0.00s (0.00ms/blk)] 
 node0 2024-04-28T17:46:07.432441Z [httpworker.0] [validation.cpp:2439] [ConnectBlock] [bench]     - Fork checks: 0.02ms [0.00s (0.02ms/blk)] 
 node0 2024-04-28T17:46:07.432456Z [httpworker.0] [validation.cpp:2531] [ConnectBlock] [bench]       - Connect 1 transactions: 0.01ms (0.013ms/tx, 0.000ms/txin) [0.00s (0.01ms/blk)] 
 node0 2024-04-28T17:46:07.432468Z [httpworker.0] [validation.cpp:2549] [ConnectBlock] [bench]     - Verify 0 txins: 0.03ms (0.000ms/txin) [0.00s (0.03ms/blk)] 
 node0 2024-04-28T17:46:07.432517Z [httpworker.0] [validation.cpp:2563] [ConnectBlock] [bench]     - Write undo data: 0.05ms [0.00s (0.02ms/blk)] 
 node0 2024-04-28T17:46:07.432529Z [httpworker.0] [validation.cpp:2578] [ConnectBlock] [bench]     - Index writing: 0.01ms [0.00s (0.01ms/blk)] 
 node0 2024-04-28T17:46:07.432541Z [httpworker.0] [validationinterface.cpp:245] [BlockChecked] [validation] BlockChecked: block hash=3d85c0577c39dd26e5317925700277c541e6f13a6c7a53df98364cdb2180c6f5 state=Valid 
 node0 2024-04-28T17:46:07.432555Z [httpworker.0] [validation.cpp:3036] [ConnectTip] [bench]   - Connect total: 0.16ms [0.00s (0.08ms/blk)] 
 node0 2024-04-28T17:46:07.432575Z [httpworker.0] [validation.cpp:3045] [ConnectTip] [bench]   - Flush: 0.02ms [0.00s (0.01ms/blk)] 
 node0 2024-04-28T17:46:07.432588Z [httpworker.0] [validation.cpp:3055] [ConnectTip] [bench]   - Writing chainstate: 0.01ms [0.00s (0.01ms/blk)] 
 node0 2024-04-28T17:46:07.432604Z [httpworker.0] [validationinterface.cpp:222] [MempoolTransactionsRemovedForBlock] [validation] Enqueuing MempoolTransactionsRemovedForBlock: block height=200 txs removed=0 
 node0 2024-04-28T17:46:07.432637Z [httpworker.0] [validation.cpp:1848] [IsInitialBlockDownload] Leaving InitialBlockDownload (latching to false) 
 node0 2024-04-28T17:46:07.432664Z [scheduler] [validationinterface.cpp:222] [operator()] [validation] MempoolTransactionsRemovedForBlock: block height=200 txs removed=0 
 node0 2024-04-28T17:46:07.432764Z [scheduler] [policy/fees.cpp:708] [processBlock] [estimatefee] Blockpolicy estimates updated by 0 of 0 block txs, since last block 0 of 0 tracked, mempool map size 0, max target 0 from current 
 node0 2024-04-28T17:46:07.432790Z [httpworker.0] [validation.cpp:2814] [UpdateTipLog] UpdateTip: new best=3d85c0577c39dd26e5317925700277c541e6f13a6c7a53df98364cdb2180c6f5 height=200 version=0x30000000 log2_work=8.651052 tx=201 date='2024-04-28T17:46:07Z' progress=1.000000 cache=0.3MiB(1txo) 
 node0 2024-04-28T17:46:07.432802Z [httpworker.0] [validation.cpp:3071] [ConnectTip] [bench]   - Connect postprocess: 0.21ms [0.00s (0.11ms/blk)] 
 node0 2024-04-28T17:46:07.432821Z [httpworker.0] [validation.cpp:3075] [ConnectTip] [bench] - Connect block: 0.42ms [0.00s (0.21ms/blk)] 
 node0 2024-04-28T17:46:07.432833Z [httpworker.0] [txmempool.cpp:663] [check] [mempool] Checking mempool with 0 transactions and 0 inputs 
 node0 2024-04-28T17:46:07.432850Z [httpworker.0] [validationinterface.cpp:212] [BlockConnected] [validation] Enqueuing BlockConnected: block hash=3d85c0577c39dd26e5317925700277c541e6f13a6c7a53df98364cdb2180c6f5 block height=200 
 node0 2024-04-28T17:46:07.432862Z [httpworker.0] [validationinterface.cpp:183] [UpdatedBlockTip] [validation] Enqueuing UpdatedBlockTip: new block hash=3d85c0577c39dd26e5317925700277c541e6f13a6c7a53df98364cdb2180c6f5 fork block hash=39455d83324a3dbbb15712ab63dd2f96b45a30f271617aa16c12483a561f721c (in IBD=false) 
 node0 2024-04-28T17:46:07.433048Z [scheduler] [validationinterface.cpp:212] [operator()] [validation] BlockConnected: block hash=3d85c0577c39dd26e5317925700277c541e6f13a6c7a53df98364cdb2180c6f5 block height=200 
 node0 2024-04-28T17:46:07.433075Z [scheduler] [validationinterface.cpp:183] [operator()] [validation] UpdatedBlockTip: new block hash=3d85c0577c39dd26e5317925700277c541e6f13a6c7a53df98364cdb2180c6f5 fork block hash=39455d83324a3dbbb15712ab63dd2f96b45a30f271617aa16c12483a561f721c (in IBD=false) 
 node0 2024-04-28T17:46:07.433547Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.433614Z [httpworker.3] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=getblock user=__cookie__ 
 node0 2024-04-28T17:46:07.434149Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.434213Z [httpworker.2] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=submitblock user=__cookie__ 
 node0 2024-04-28T17:46:07.434652Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.434704Z [httpworker.1] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ 
 node0 2024-04-28T17:46:07.435105Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.435140Z [httpworker.0] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ 
 node0 2024-04-28T17:46:07.435480Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.435529Z [httpworker.3] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ 
 node0 2024-04-28T17:46:07.435898Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.435951Z [httpworker.2] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ 
 test  2024-04-28T17:46:07.436000Z TestFramework (INFO): Ensure we don't try to connect if port!=0 
 node0 2024-04-28T17:46:07.436599Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.436652Z [httpworker.1] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=addnode user=__cookie__ 
 node0 2024-04-28T17:46:07.436691Z [httpworker.1] [net.cpp:410] [ConnectNode] [net] trying v1 connection zsxwyo6qcn3chqzwxnseusqgsnuw3maqnztkiypyfxtya4snkoka.b32.i2p:8333 lastseen=0.0hrs 
 node0 2024-04-28T17:46:07.436711Z [httpworker.1] [i2p.cpp:289] [Log] [i2p] Error connecting to zsxwyo6qcn3chqzwxnseusqgsnuw3maqnztkiypyfxtya4snkoka.b32.i2p:8333, connection refused due to arbitrary port 8333 
 test  2024-04-28T17:46:07.437000Z TestFramework (INFO): Ensure we try to connect if port=0 and get an error due to missing I2P proxy 
 node0 2024-04-28T17:46:07.437631Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638 
 node0 2024-04-28T17:46:07.437669Z [httpworker.0] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=addnode user=__cookie__ 
 node0 2024-04-28T17:46:07.437708Z [httpworker.0] [net.cpp:410] [ConnectNode] [net] trying v1 connection h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0 lastseen=0.0hrs 
 node0 2024-04-28T17:46:07.437733Z [httpworker.0] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session ef0bda29ed with 127.0.0.1:60000 
 node0 2024-04-28T17:46:07.437887Z [httpworker.0] [i2p.cpp:289] [Log] [i2p] Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Missing RESULT= in the reply to "HELLO VERSION MIN=3.1 MAX=3.1": "HELLO VERSION MIN=3.1 MAX=3.1" 
 node0 2024-04-28T17:46:08.362998Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session d00fa05b26 with 127.0.0.1:60000 
 node0 2024-04-28T17:46:08.363157Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:08.363230Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:10.363358Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session e6c846052b with 127.0.0.1:60000 
 node0 2024-04-28T17:46:10.363457Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:10.363506Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:13.363629Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 52392d7213 with 127.0.0.1:60000 
 node0 2024-04-28T17:46:13.363758Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:13.363815Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:17.363936Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 7b4ca1e901 with 127.0.0.1:60000 
 node0 2024-04-28T17:46:17.364079Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:17.364140Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:22.364266Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 71fd5d01e7 with 127.0.0.1:60000 
 node0 2024-04-28T17:46:22.364418Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:22.364476Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:28.364604Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 456c2be19d with 127.0.0.1:60000 
 node0 2024-04-28T17:46:28.364747Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:28.364801Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:35.364979Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session fe9c34d0c4 with 127.0.0.1:60000 
 node0 2024-04-28T17:46:35.365142Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:35.365204Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:43.365375Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 259947762e with 127.0.0.1:60000 
 node0 2024-04-28T17:46:43.365547Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:43.365610Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:46:52.351012Z [scheduler] [net.cpp:2345] [StartExtraBlockRelayPeers] [net] enabling extra block-relay-only peers 
 node0 2024-04-28T17:46:52.365791Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 9382e16d70 with 127.0.0.1:60000 
 node0 2024-04-28T17:46:52.365952Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:46:52.366015Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:47:02.366195Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 6e43de2025 with 127.0.0.1:60000 
 node0 2024-04-28T17:47:02.366391Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:47:02.366454Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:47:13.366620Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 84a7e1afb0 with 127.0.0.1:60000 
 node0 2024-04-28T17:47:13.366787Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:47:13.366860Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 node0 2024-04-28T17:47:25.367036Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session ba0e7156d5 with 127.0.0.1:60000 
 node0 2024-04-28T17:47:25.367240Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111) 
 node0 2024-04-28T17:47:25.367302Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000 
 test  2024-04-28T17:47:27.449000Z TestFramework (ERROR): Assertion failed 
                                   Traceback (most recent call last):
                                     File "/ci_container_base/ci/scratch/build/bitcoin-x86_64-pc-linux-gnu/test/functional/test_framework/test_framework.py", line 132, in main
                                       self.run_test()
                                     File "/ci_container_base/ci/scratch/build/bitcoin-x86_64-pc-linux-gnu/test/functional/p2p_i2p_ports.py", line 30, in run_test
                                       with node.assert_debug_log(expected_msgs=[f"Error connecting to {addr}: Cannot connect to {PROXY}"]):
                                     File "/usr/lib/python3.10/contextlib.py", line 142, in __exit__
                                       next(self.gen)
                                     File "/ci_container_base/ci/scratch/build/bitcoin-x86_64-pc-linux-gnu/test/functional/test_framework/test_node.py", line 490, in assert_debug_log
                                       self._raise_assertion_error('Expected messages "{}" does not partially match log:\n\n{}\n\n'.format(str(expected_msgs), print_log))
                                     File "/ci_container_base/ci/scratch/build/bitcoin-x86_64-pc-linux-gnu/test/functional/test_framework/test_node.py", line 188, in _raise_assertion_error
                                       raise AssertionError(self._node_msg(msg))
                                   AssertionError: [node 0] Expected messages "['Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Cannot connect to 127.0.0.1:60000']" does not partially match log:
                                    - 2024-04-28T17:46:07.437631Z [http] [httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37638
                                    - 2024-04-28T17:46:07.437669Z [httpworker.0] [rpc/request.cpp:187] [parse] [rpc] ThreadRPCServer method=addnode user=__cookie__
                                    - 2024-04-28T17:46:07.437708Z [httpworker.0] [net.cpp:410] [ConnectNode] [net] trying v1 connection h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0 lastseen=0.0hrs
                                    - 2024-04-28T17:46:07.437733Z [httpworker.0] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session ef0bda29ed with 127.0.0.1:60000
                                    - 2024-04-28T17:46:07.437887Z [httpworker.0] [i2p.cpp:289] [Log] [i2p] Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Missing RESULT= in the reply to "HELLO VERSION MIN=3.1 MAX=3.1": "HELLO VERSION MIN=3.1 MAX=3.1"
                                    - 2024-04-28T17:46:08.362998Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session d00fa05b26 with 127.0.0.1:60000
                                    - 2024-04-28T17:46:08.363157Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:08.363230Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:10.363358Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session e6c846052b with 127.0.0.1:60000
                                    - 2024-04-28T17:46:10.363457Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:10.363506Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:13.363629Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 52392d7213 with 127.0.0.1:60000
                                    - 2024-04-28T17:46:13.363758Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:13.363815Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:17.363936Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 7b4ca1e901 with 127.0.0.1:60000
                                    - 2024-04-28T17:46:17.364079Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:17.364140Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:22.364266Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 71fd5d01e7 with 127.0.0.1:60000
                                    - 2024-04-28T17:46:22.364418Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:22.364476Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:28.364604Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 456c2be19d with 127.0.0.1:60000
                                    - 2024-04-28T17:46:28.364747Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:28.364801Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:35.364979Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session fe9c34d0c4 with 127.0.0.1:60000
                                    - 2024-04-28T17:46:35.365142Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:35.365204Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:43.365375Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 259947762e with 127.0.0.1:60000
                                    - 2024-04-28T17:46:43.365547Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:43.365610Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:46:52.351012Z [scheduler] [net.cpp:2345] [StartExtraBlockRelayPeers] [net] enabling extra block-relay-only peers
                                    - 2024-04-28T17:46:52.365791Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 9382e16d70 with 127.0.0.1:60000
                                    - 2024-04-28T17:46:52.365952Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:46:52.366015Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:47:02.366195Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 6e43de2025 with 127.0.0.1:60000
                                    - 2024-04-28T17:47:02.366391Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:47:02.366454Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:47:13.366620Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session 84a7e1afb0 with 127.0.0.1:60000
                                    - 2024-04-28T17:47:13.366787Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:47:13.366860Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
                                    - 2024-04-28T17:47:25.367036Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Creating persistent SAM session ba0e7156d5 with 127.0.0.1:60000
                                    - 2024-04-28T17:47:25.367240Z [i2paccept] [netbase.cpp:549] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2024-04-28T17:47:25.367302Z [i2paccept] [i2p.cpp:289] [Log] [i2p] Error listening: Cannot connect to 127.0.0.1:60000
  2. maflcko added the label Tests on May 3, 2024
  3. maflcko added the label CI failed on May 3, 2024
  4. ceffikhan referenced this in commit 65ed73b996 on Jun 26, 2024
  5. ceffikhan referenced this in commit 3c3eaa9f9f on Jun 27, 2024
  6. brunoerg commented at 8:57 PM on July 1, 2024: contributor

    See #30345 (comment)

    It seems there is something listening at port 60000 in parallel.

  7. maflcko commented at 6:41 AM on July 2, 2024: member

    The test is run inside a docker,

    https://github.com/bitcoin/bitcoin/blob/ca76d180d2a30d063154be8101bce64e308866ac/ci/test/02_run_container.sh#L50-L59

    , so I think it needs to be something inside the docker container. Also, I am not aware of a process inside the CI env that would echo back what it got.

  8. fanquake commented at 5:42 PM on July 11, 2024: member

    https://cirrus-ci.com/task/5777533663182848?logs=ci#L9969

  9. maflcko commented at 11:08 AM on July 12, 2024: member

    , so I think it needs to be something inside the docker container. Also, I am not aware of a process inside the CI env that would echo back what it got.

    Also interesting that no one reproduced this locally yet

  11. maflcko commented at 11:43 AM on November 18, 2024: member

    https://cirrus-ci.com/task/5063913392308224?logs=ci#L6167

  12. fanquake commented at 11:23 AM on November 19, 2024: member

    https://github.com/bitcoin/bitcoin/pull/31279/checks#step:6:7681

  13. maflcko commented at 10:14 AM on December 16, 2024: member

    Now also on Windows:

    https://github.com/bitcoin/bitcoin/actions/runs/12318851093/job/34384513871#step:12:338

     node0 2024-12-13T16:36:24.993446Z [httpworker.0] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=getblock user=__cookie__ 
 test  2024-12-13T16:36:24.994000Z TestFramework (INFO): Ensure we don't try to connect if port!=0 
 test  2024-12-13T16:36:24.994000Z TestFramework (INFO): Ensure we try to connect if port=0 and get an error due to missing I2P proxy 
 node0 2024-12-13T16:36:24.994136Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523 
 node0 2024-12-13T16:36:24.994215Z [httpworker.3] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=submitblock user=__cookie__ 
 node0 2024-12-13T16:36:24.994729Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523 
 node0 2024-12-13T16:36:24.994801Z [httpworker.2] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ 
 node0 2024-12-13T16:36:24.995270Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523 
 node0 2024-12-13T16:36:24.995345Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ 
 node0 2024-12-13T16:36:24.995818Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523 
 node0 2024-12-13T16:36:24.995900Z [httpworker.0] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ 
 node0 2024-12-13T16:36:24.996400Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523 
 node0 2024-12-13T16:36:24.996474Z [httpworker.3] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ 
 node0 2024-12-13T16:36:24.997283Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523 
 node0 2024-12-13T16:36:24.997356Z [httpworker.2] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=addnode user=__cookie__ 
 node0 2024-12-13T16:36:24.997434Z [httpworker.2] [D:\a\bitcoin\bitcoin\src\net.cpp:414] [ConnectNode] [net] trying v1 connection zsxwyo6qcn3chqzwxnseusqgsnuw3maqnztkiypyfxtya4snkoka.b32.i2p:8333 lastseen=0.0hrs 
 node0 2024-12-13T16:36:24.997467Z [httpworker.2] [D:\a\bitcoin\bitcoin\src\i2p.cpp:226] [Connect] [i2p] Error connecting to zsxwyo6qcn3chqzwxnseusqgsnuw3maqnztkiypyfxtya4snkoka.b32.i2p:8333, connection refused due to arbitrary port 8333 
 node0 2024-12-13T16:36:24.998687Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523 
 node0 2024-12-13T16:36:24.998774Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=addnode user=__cookie__ 
 node0 2024-12-13T16:36:24.998828Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\net.cpp:414] [ConnectNode] [net] trying v1 connection h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0 lastseen=0.0hrs 
 node0 2024-12-13T16:37:09.896819Z [scheduler] [D:\a\bitcoin\bitcoin\src\net.cpp:2372] [StartExtraBlockRelayPeers] [net] enabling extra block-relay-only peers 
 node0 2024-12-13T16:39:24.891666Z [i2paccept] [D:\a\bitcoin\bitcoin\src\i2p.cpp:151] [Listen] [i2p:error] Couldn't listen: Receive timeout (received 0 bytes without terminator before that) 
 node0 2024-12-13T16:39:24.891768Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\i2p.cpp:417] [CreateIfNotCreatedAlready] [i2p] Creating persistent SAM session 61f5bdf8c4 with 127.0.0.1:60000 
 test  2024-12-13T16:42:24.895000Z TestFramework (ERROR): Assertion failed 
                                   Traceback (most recent call last):
                                     File "D:\a\bitcoin\bitcoin\build\test\functional\test_framework\test_framework.py", line 135, in main
                                       self.run_test()
                                     File "D:\a\bitcoin\bitcoin\build\test\functional\p2p_i2p_ports.py", line 30, in run_test
                                       with node.assert_debug_log(expected_msgs=[f"Error connecting to {addr}: Cannot connect to {PROXY}"]):
                                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
                                     File "C:\hostedtoolcache\windows\Python\3.12.8\x64\Lib\contextlib.py", line 144, in __exit__
                                       next(self.gen)
                                     File "D:\a\bitcoin\bitcoin\build\test\functional\test_framework\test_node.py", line 520, in assert_debug_log
                                       self._raise_assertion_error('Expected messages "{}" does not partially match log:\n\n{}\n\n'.format(str(expected_msgs), print_log))
                                     File "D:\a\bitcoin\bitcoin\build\test\functional\test_framework\test_node.py", line 198, in _raise_assertion_error
                                       raise AssertionError(self._node_msg(msg))
                                   AssertionError: [node 0] Expected messages "['Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Cannot connect to 127.0.0.1:60000']" does not partially match log:
                                    - 2024-12-13T16:36:24.998687Z [http] [D:\a\bitcoin\bitcoin\src\httpserver.cpp:306] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:54523
                                    - 2024-12-13T16:36:24.998774Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\rpc\request.cpp:241] [parse] [rpc] ThreadRPCServer method=addnode user=__cookie__
                                    - 2024-12-13T16:36:24.998828Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\net.cpp:414] [ConnectNode] [net] trying v1 connection h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0 lastseen=0.0hrs
                                    - 2024-12-13T16:37:09.896819Z [scheduler] [D:\a\bitcoin\bitcoin\src\net.cpp:2372] [StartExtraBlockRelayPeers] [net] enabling extra block-relay-only peers
                                    - 2024-12-13T16:39:24.891666Z [i2paccept] [D:\a\bitcoin\bitcoin\src\i2p.cpp:151] [Listen] [i2p:error] Couldn't listen: Receive timeout (received 0 bytes without terminator before that)
                                    - 2024-12-13T16:39:24.891768Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\i2p.cpp:417] [CreateIfNotCreatedAlready] [i2p] Creating persistent SAM session 61f5bdf8c4 with 127.0.0.1:60000
                                    - 2024-12-13T16:42:24.895618Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\i2p.cpp:274] [Connect] [i2p] Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Receive timeout (received 0 bytes without terminator before that)
 node0 2024-12-13T16:42:24.895618Z [httpworker.1] [D:\a\bitcoin\bitcoin\src\i2p.cpp:274] [Connect] [i2p] Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Receive timeout (received 0 bytes without terminator before that) 
 test  2024-12-13T16:42:24.926000Z TestFramework (DEBUG): Closing down network thread 
 test  2024-12-13T16:42:24.981000Z TestFramework (INFO): Stopping nodes 
 test  2024-12-13T16:42:24.981000Z TestFramework.node0 (DEBUG): Stopping node
  14. maflcko commented at 9:31 AM on January 20, 2025: member

    Reproduced locally:

    sh-5.2# history 
    1  curl -L -O "https://drahtbot.space/temp_scratch/p2p_i2p_ports_14.tar.zstd"
    2  tar -xvf ./p2p_i2p_ports_14.tar.zstd 
    3  ./test/functional/combine_logs.py ./p2p_i2p_ports_14 

     test  2025-01-16T23:02:00.696000Z TestFramework (ERROR): Assertion failed 
                                   Traceback (most recent call last):
                                     File "/ci_container_base/test/functional/test_framework/test_framework.py", line 135, in main
                                       self.run_test()
                                     File "/ci_container_base/ci/scratch/build-i686-pc-linux-gnu/test/functional/p2p_i2p_ports.py", line 31, in run_test
                                       node.addnode(node=addr, command="onetry")
                                     File "/usr/lib64/python3.9/contextlib.py", line 126, in __exit__
                                       next(self.gen)
                                     File "/ci_container_base/test/functional/test_framework/test_node.py", line 519, in assert_debug_log
                                       self._raise_assertion_error('Expected messages "{}" does not partially match log:\n\n{}\n\n'.format(str(expected_msgs), print_log))
                                     File "/ci_container_base/test/functional/test_framework/test_node.py", line 198, in _raise_assertion_error
                                       raise AssertionError(self._node_msg(msg))
                                   AssertionError: [node 0] Expected messages "['Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Cannot connect to 127.0.0.1:60000']" does not partially match log:
                                    - 2025-01-16T22:46:00.652396Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:37518
                                    - 2025-01-16T22:46:00.652586Z [httpworker.6] [rpc/request.cpp:241] [parse] [rpc] ThreadRPCServer method=addnode user=__cookie__
                                    - 2025-01-16T22:46:00.652669Z [httpworker.6] [net.cpp:411] [ConnectNode] [net] trying v1 connection h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0 lastseen=0.0hrs
                                    - 2025-01-16T22:46:00.652706Z [httpworker.6] [i2p.cpp:417] [CreateIfNotCreatedAlready] [i2p] Creating persistent SAM session 54bc19b9e6 with 127.0.0.1:60000
                                    - 2025-01-16T22:46:00.652966Z [httpworker.6] [i2p.cpp:274] [Connect] [i2p] Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Missing RESULT= in the reply to "HELLO VERSION MIN=3.1 MAX=3.1": "HELLO VERSION MIN=3.1 MAX=3.1"
                                    - 2025-01-16T22:46:01.547910Z [i2paccept] [i2p.cpp:417] [CreateIfNotCreatedAlready] [i2p] Creating persistent SAM session 8220ac868b with 127.0.0.1:60000
                                    - 2025-01-16T22:46:01.548174Z [i2paccept] [netbase.cpp:564] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2025-01-16T22:46:01.548258Z [i2paccept] [i2p.cpp:151] [Listen] [i2p:error] Couldn't listen: Cannot connect to 127.0.0.1:60000
                                    - 2025-01-16T22:46:03.548479Z [i2paccept] [i2p.cpp:417] [CreateIfNotCreatedAlready] [i2p] Creating persistent SAM session 5fda738994 with 127.0.0.1:60000
                                    - 2025-01-16T22:46:03.548987Z [i2paccept] [netbase.cpp:564] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2025-01-16T22:46:03.549067Z [i2paccept] [i2p.cpp:151] [Listen] [i2p:error] Couldn't listen: Cannot connect to 127.0.0.1:60000
                                    - 2025-01-16T22:46:06.555760Z [i2paccept] [i2p.cpp:417] [CreateIfNotCreatedAlready] [i2p] Creating persistent SAM session 06966aa761 with 127.0.0.1:60000
                                    - 2025-01-16T22:46:06.555965Z [i2paccept] [netbase.cpp:564] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
                                    - 2025-01-16T22:46:06.556042Z [i2paccept] [i2p.cpp:151] [Listen] [i2p:error] Couldn't listen: Cannot connect to 127.0.0.1:60000
                                    - 2025-01-16T22:46:10.556284Z [i2paccept] [i2p.cpp:417] [CreateIfNotCreatedAlready] [i2p] Creating persistent SAM session 8b9e54fa71 with 127.0.0.1:60000
                                    - 2025-01-16T22:46:10.556581Z [i2paccept] [netbase.cpp:564] [LogConnectFailure] connect() to 127.0.0.1:60000 failed after wait: Connection refused (111)
  15. maflcko commented at 11:49 AM on February 19, 2025: member

    https://cirrus-ci.com/task/6088386139652096?logs=ci#L3797

  16. maflcko commented at 12:45 PM on February 19, 2025: member

    Not sure what to do here. Let's print the offending program name? See https://github.com/bitcoin/bitcoin/pull/31903

  17. maflcko commented at 3:57 PM on February 19, 2025: member

    So the issue is internal to the functional tests themselves. See https://cirrus-ci.com/task/5346278168592384?logs=ci#L4358 (commit https://github.com/bitcoin/bitcoin/commit/fa780b31ab54febd7e56a0fc165683e056fc2500) which prints:

    COMMAND    PID USER   FD   TYPE    DEVICE SIZE/OFF NODE NAME
python3  50589 root   11u  IPv4 179599797      0t0  TCP localhost:60000->localhost:19216 (ESTABLISHED)
bitcoind 50594 root   21u  IPv4 179704903      0t0  TCP localhost:19216->localhost:60000 (ESTABLISHED)

    It would be good to fix the tests themselves.

  18. maflcko commented at 12:38 PM on May 27, 2025: member

    https://cirrus-ci.com/task/4713767570767872?logs=ci#L3220

  19. fanquake commented at 3:02 PM on October 15, 2025: member

    Ran into this locally (https://github.com/bitcoin/bitcoin/commit/e14451ac87339ed61b8c872f027184a978dd96eb):

    276/277 - p2p_i2p_ports.py failed, Duration: 81 s

stdout:
2025-10-15T14:58:36.321373Z TestFramework (INFO): PRNG seed is: 5343123104196887417
2025-10-15T14:58:36.321958Z TestFramework (INFO): Initializing test directory /ci_container_base/ci/scratch/test_runner/test_runner_₿_🏃_20251015_145347/p2p_i2p_ports_16
2025-10-15T14:58:37.956907Z TestFramework (INFO): Ensure we don't try to connect if port!=0
2025-10-15T14:58:37.959989Z TestFramework (INFO): Ensure we try to connect if port=0 and get an error due to missing I2P proxy
2025-10-15T14:59:57.984209Z TestFramework (ERROR): Unexpected exception
Traceback (most recent call last):
  File "/ci_container_base/test/functional/test_framework/test_framework.py", line 198, in main
    self.run_test()
  File "/ci_container_base/ci/scratch/build-x86_64-pc-linux-gnu/test/functional/p2p_i2p_ports.py", line 30, in run_test
    with node.assert_debug_log(expected_msgs=[f"Error connecting to {addr}: Cannot connect to {PROXY}"]):
  File "/usr/lib/python3.12/contextlib.py", line 144, in __exit__
    next(self.gen)
  File "/ci_container_base/test/functional/test_framework/test_node.py", line 572, in assert_debug_log
    self._raise_assertion_error('Expected messages "{}" does not partially match log:\n\n{}\n\n'.format(str(expected_msgs), print_log))
  File "/ci_container_base/test/functional/test_framework/test_node.py", line 225, in _raise_assertion_error
    raise AssertionError(self._node_msg(msg))
AssertionError: [node 0] Expected messages "['Error connecting to h3r6bkn46qxftwja53pxiykntegfyfjqtnzbm6iv6r5mungmqgmq.b32.i2p:0: Cannot connect to 127.0.0.1:60000']" does not partially match log:

 - 2025-10-15T14:58:37.960430Z [http] [httpserver.cpp:308] [void http_request_cb(struct evhttp_request *, void *)] [http] Received a POST request for / from 127.0.0.1:57134
 - 2025-10-15T14:58:37.960573Z [httpworker.14] [rpc/request.cpp:243] [void JSONRPCRequest::parse(const UniValue &)] [rpc] ThreadRPCServer method=addnode user=__cookie__
  20. maflcko commented at 8:07 AM on October 22, 2025: member

    Just to give another data point: 60000 falls into the ephemeral port range, which normally is avoided by the functional tests, as they start using ports starting from around 11000. However, I can't explain how and if the ephemeral port range itself was related to the issue here.

    To check it, you can run:

    cat /proc/sys/net/ipv4/ip_local_port_range

32768	60999

    So a more trivial fix could be to change 60000 to 61000, but #33670 seems more natural.

  21. fanquake closed this on Oct 22, 2025
  22. fanquake referenced this in commit c211d18322 on Oct 22, 2025
Contributors
maflckobrunoergfanquake
Labels
TestsCI failed
Linked (view graph)
#30345 test: fix debug log assertion in p2p_i2p_ports test#33670 test: Use unassigned p2p_port instead of hardcoded 60000 in p2p_i2p_ports.py
github-metadata-mirror

This is a metadata mirror of the GitHub repository bitcoin/bitcoin. This site is not affiliated with GitHub. Content is generated from a GitHub metadata backup.
generated: 2026-04-24 09:14 UTC
This site is hosted by @0xB10C
More mirrored repositories can be found on mirror.b10c.me