test 2024-08-15T17:07:31.787000Z TestFramework (INFO): PRNG seed is: 7629159114587971990 test 2024-08-15T17:07:31.787000Z TestFramework (DEBUG): Setting up network thread test 2024-08-15T17:07:31.787000Z TestFramework (INFO): Initializing test directory /tmp/bitcoin_func_test_fj812gkv test 2024-08-15T17:07:31.787000Z TestFramework (DEBUG): Copy cache directory /home/gloria/bitcoin/test/cache/node0 to node 0 test 2024-08-15T17:07:31.796000Z TestFramework.node0 (DEBUG): bitcoind started, waiting for RPC to come up node0 2024-08-15T17:07:31.799419Z [init] [init/common.cpp:149] [LogPackageVersion] Bitcoin Core version v27.99.0-faa1b9b0e6de-dirty (release build) node0 2024-08-15T17:07:31.799422Z [init] [init.cpp:742] [InitParameterInteraction] parameter interaction: -bind set -> setting -listen=1 node0 2024-08-15T17:07:31.799464Z [init] [node/chainstatemanager_args.cpp:57] [ApplyArgsManOptions] Script verification uses 11 additional threads node0 2024-08-15T17:07:31.799556Z [init] [kernel/context.cpp:20] [operator()] Using the 'sse4(1way),sse41(4way),avx2(8way)' SHA256 implementation node0 2024-08-15T17:07:31.919439Z [init] [random.cpp:114] [ReportHardwareRand] Using RdSeed as an additional entropy source node0 2024-08-15T17:07:31.919441Z [init] [random.cpp:117] [ReportHardwareRand] Using RdRand as an additional entropy source node0 2024-08-15T17:07:31.920964Z [init] [init/common.cpp:120] [StartLogging] Default data directory /home/gloria/.bitcoin node0 2024-08-15T17:07:31.920967Z [init] [init/common.cpp:121] [StartLogging] Using data directory /tmp/bitcoin_func_test_fj812gkv/node0/regtest node0 2024-08-15T17:07:31.920973Z [init] [init/common.cpp:126] [StartLogging] Config file: /tmp/bitcoin_func_test_fj812gkv/node0/bitcoin.conf node0 2024-08-15T17:07:31.920982Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: regtest="1" node0 2024-08-15T17:07:31.920987Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] bind="127.0.0.1" node0 2024-08-15T17:07:31.920991Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] connect="0" node0 2024-08-15T17:07:31.920995Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] deprecatedrpc="create_bdb" node0 2024-08-15T17:07:31.920998Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] discover="0" node0 2024-08-15T17:07:31.921001Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] dnsseed="0" node0 2024-08-15T17:07:31.921004Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] fallbackfee="0.0002" node0 2024-08-15T17:07:31.921007Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] fixedseeds="0" node0 2024-08-15T17:07:31.921010Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] keypool="1" node0 2024-08-15T17:07:31.921013Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] listenonion="0" node0 2024-08-15T17:07:31.921017Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] natpmp="0" node0 2024-08-15T17:07:31.921020Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] peertimeout="999999999" node0 2024-08-15T17:07:31.921023Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] port="15515" node0 2024-08-15T17:07:31.921027Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] printtoconsole="0" node0 2024-08-15T17:07:31.921031Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] rpcdoccheck="1" node0 2024-08-15T17:07:31.921034Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] rpcport="20515" node0 2024-08-15T17:07:31.921038Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] rpcservertimeout="99000" node0 2024-08-15T17:07:31.921041Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] server="1" node0 2024-08-15T17:07:31.921045Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] shrinkdebugfile="0" node0 2024-08-15T17:07:31.921048Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] unsafesqlitesync="1" node0 2024-08-15T17:07:31.921051Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] upnp="0" node0 2024-08-15T17:07:31.921054Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: blocksxor="1" node0 2024-08-15T17:07:31.921058Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: datacarriersize="100000" node0 2024-08-15T17:07:31.921062Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: datadir="/tmp/bitcoin_func_test_fj812gkv/node0" node0 2024-08-15T17:07:31.921065Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debug="" node0 2024-08-15T17:07:31.921068Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debugexclude="libevent" node0 2024-08-15T17:07:31.921075Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debugexclude="leveldb" node0 2024-08-15T17:07:31.921078Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debugexclude="rand" node0 2024-08-15T17:07:31.921081Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: disablewallet="" node0 2024-08-15T17:07:31.921084Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: fastprune="1" node0 2024-08-15T17:07:31.921087Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: loglevel="trace" node0 2024-08-15T17:07:31.921090Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: logsourcelocations="" node0 2024-08-15T17:07:31.921093Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: logthreadnames="" node0 2024-08-15T17:07:31.921096Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: logtimemicros="" node0 2024-08-15T17:07:31.921100Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: uacomment="testnode0" node0 2024-08-15T17:07:31.921103Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: v2transport="0" node0 2024-08-15T17:07:31.921106Z [init] [init.cpp:1158] [AppInitMain] Using at most 125 automatic connections (1024 file descriptors available) node0 2024-08-15T17:07:31.921177Z [init] [wallet/init.cpp:129] [Construct] Wallet disabled! node0 2024-08-15T17:07:31.921207Z [scheduler] [util/thread.cpp:20] [TraceThread] scheduler thread start node0 2024-08-15T17:07:31.922487Z [init] [httpserver.cpp:238] [InitHTTPAllowList] [http] Allowing HTTP connections from: 127.0.0.0/8 ::1/128 node0 2024-08-15T17:07:31.922529Z [init] [httpserver.cpp:384] [HTTPBindAddresses] Binding RPC on address ::1 port 20515 node0 2024-08-15T17:07:31.922596Z [init] [httpserver.cpp:384] [HTTPBindAddresses] Binding RPC on address 127.0.0.1 port 20515 node0 2024-08-15T17:07:31.922621Z [init] [httpserver.cpp:463] [InitHTTPServer] [http] Initialized HTTP server node0 2024-08-15T17:07:31.922627Z [init] [httpserver.cpp:465] [InitHTTPServer] [http] creating work queue of depth 16 node0 2024-08-15T17:07:31.922630Z [init] [rpc/server.cpp:298] [StartRPC] [rpc] Starting RPC node0 2024-08-15T17:07:31.922637Z [init] [httprpc.cpp:360] [StartHTTPRPC] [rpc] Starting HTTP RPC server node0 2024-08-15T17:07:31.922641Z [init] [httprpc.cpp:297] [InitRPCAuthentication] Using random cookie authentication. node0 2024-08-15T17:07:31.922685Z [init] [rpc/request.cpp:132] [GenerateAuthCookie] Generated RPC authentication cookie /tmp/bitcoin_func_test_fj812gkv/node0/regtest/.cookie node0 2024-08-15T17:07:31.922690Z [init] [rpc/request.cpp:133] [GenerateAuthCookie] Permissions used for cookie: rw------- node0 2024-08-15T17:07:31.922696Z [init] [httpserver.cpp:745] [RegisterHTTPHandler] [http] Registering HTTP handler for / (exactmatch 1) node0 2024-08-15T17:07:31.922700Z [init] [httpserver.cpp:745] [RegisterHTTPHandler] [http] Registering HTTP handler for /wallet/ (exactmatch 0) node0 2024-08-15T17:07:31.922704Z [init] [httpserver.cpp:488] [StartHTTPServer] Starting HTTP server with 4 worker threads node0 2024-08-15T17:07:31.922810Z [http] [httpserver.cpp:351] [ThreadHTTP] [http] Entering http event loop node0 2024-08-15T17:07:31.922826Z [init] [init.cpp:1265] [AppInitMain] Using /16 prefix for IP bucketing node0 2024-08-15T17:07:31.922833Z [init] [noui.cpp:57] [noui_InitMessage] init message: Loading P2P addresses… node0 2024-08-15T17:07:31.923106Z [init] [addrdb.cpp:206] [LoadAddrman] Creating peers.dat because the file was not found ("/tmp/bitcoin_func_test_fj812gkv/node0/regtest/peers.dat") node0 2024-08-15T17:07:31.943102Z [init] [noui.cpp:57] [noui_InitMessage] init message: Loading banlist… node0 2024-08-15T17:07:31.943123Z [init] [banman.cpp:42] [LoadBanlist] Recreating the banlist database node0 2024-08-15T17:07:31.943180Z [init] [banman.cpp:69] [DumpBanlist] [net] Flushed 0 banned node addresses/subnets to disk 0ms node0 2024-08-15T17:07:31.943198Z [init] [net.cpp:2360] [SetTryNewOutboundPeer] [net] setting try another outbound peer=false node0 2024-08-15T17:07:31.943203Z [init] [net.cpp:3136] [SetNetworkActive] SetNetworkActive: true node0 2024-08-15T17:07:31.943483Z [init] [policy/fees.cpp:560] [CBlockPolicyEstimator] /tmp/bitcoin_func_test_fj812gkv/node0/regtest/fee_estimates.dat is not found. Continue anyway. node0 2024-08-15T17:07:31.943570Z [init] [node/chainstatemanager_args.cpp:57] [ApplyArgsManOptions] Script verification uses 11 additional threads node0 2024-08-15T17:07:31.943578Z [init] [init.cpp:1517] [AppInitMain] Cache configuration: node0 2024-08-15T17:07:31.943589Z [init] [init.cpp:1518] [AppInitMain] * Using 2.0 MiB for block index database node0 2024-08-15T17:07:31.943592Z [init] [init.cpp:1526] [AppInitMain] * Using 8.0 MiB for chain state database node0 2024-08-15T17:07:31.943628Z [init] [init.cpp:1549] [AppInitMain] * Using 440.0 MiB for in-memory UTXO set (plus up to 286.1 MiB of unused mempool space) node0 2024-08-15T17:07:31.943894Z [init] [node/blockstorage.cpp:1162] [InitBlocksdirXorKey] fs::is_empty(opts.blocks_dir): 0 node0 2024-08-15T17:07:31.943902Z [init] [node/blockstorage.cpp:1163] [InitBlocksdirXorKey] fs::exists(xor_key_path): 0 node0 2024-08-15T17:07:31.943905Z [init] [node/blockstorage.cpp:1164] [InitBlocksdirXorKey] opts.use_xor: 1 node0 2024-08-15T17:07:31.943941Z [init] [node/blockstorage.cpp:1189] [InitBlocksdirXorKey] Using obfuscation key for blocksdir *.dat files (/tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks): '0000000000000000' node0 2024-08-15T17:07:31.948227Z [init] [script/sigcache.cpp:36] [SignatureCache] Using 16 MiB out of 16 MiB requested for signature cache, able to store 524288 elements node0 2024-08-15T17:07:31.952543Z [init] [validation.cpp:2125] [ValidationCache] Using 16 MiB out of 16 MiB requested for script execution cache, able to store 524288 elements node0 2024-08-15T17:07:31.952567Z [init] [noui.cpp:57] [noui_InitMessage] init message: Loading block index… node0 2024-08-15T17:07:31.952572Z [init] [node/chainstate.cpp:168] [LoadChainstate] Validating signatures for all blocks. node0 2024-08-15T17:07:31.952578Z [init] [node/chainstate.cpp:170] [LoadChainstate] Setting nMinimumChainWork=0000000000000000000000000000000000000000000000000000000000000000 node0 2024-08-15T17:07:31.952632Z [init] [dbwrapper.cpp:241] [CDBWrapper] Opening LevelDB in /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/index node0 2024-08-15T17:07:31.986701Z [init] [dbwrapper.cpp:249] [CDBWrapper] Opened LevelDB successfully node0 2024-08-15T17:07:31.986722Z [init] [dbwrapper.cpp:274] [CDBWrapper] Using obfuscation key for /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/index: 0000000000000000 node0 2024-08-15T17:07:31.987097Z [init] [node/blockstorage.cpp:508] [LoadBlockIndexDB] LoadBlockIndexDB: last block file = 0 node0 2024-08-15T17:07:31.987113Z [init] [node/blockstorage.cpp:512] [LoadBlockIndexDB] LoadBlockIndexDB: last block file info: CBlockFileInfo(blocks=200, size=52530, heights=0...199, time=2011-02-02...2011-02-02) node0 2024-08-15T17:07:31.987119Z [init] [node/blockstorage.cpp:523] [LoadBlockIndexDB] Checking all blk files are present... node0 2024-08-15T17:07:31.987187Z [init] [node/chainstate.cpp:108] [CompleteChainstateInitialization] Initializing chainstate Chainstate [ibd] @ height -1 (null) node0 2024-08-15T17:07:31.987200Z [init] [dbwrapper.cpp:241] [CDBWrapper] Opening LevelDB in /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate node0 2024-08-15T17:07:32.030489Z [init] [dbwrapper.cpp:249] [CDBWrapper] Opened LevelDB successfully node0 2024-08-15T17:07:32.030546Z [init] [dbwrapper.cpp:274] [CDBWrapper] Using obfuscation key for /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate: 8a556cbbe58ab7be node0 2024-08-15T17:07:32.030648Z [init] [validation.cpp:4695] [LoadChainTip] Loaded best chain: hashBestChain=39455d83324a3dbbb15712ab63dd2f96b45a30f271617aa16c12483a561f721c height=199 date=2011-02-02T23:17:17Z progress=1.000000 node0 2024-08-15T17:07:32.030695Z [init] [dbwrapper.cpp:241] [CDBWrapper] Opening LevelDB in /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate node0 2024-08-15T17:07:32.049024Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55182 node0 2024-08-15T17:07:32.049127Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockcount user=__cookie__ node0 2024-08-15T17:07:32.061459Z [init] [dbwrapper.cpp:249] [CDBWrapper] Opened LevelDB successfully node0 2024-08-15T17:07:32.061516Z [init] [dbwrapper.cpp:274] [CDBWrapper] Using obfuscation key for /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate: 8a556cbbe58ab7be node0 2024-08-15T17:07:32.061529Z [init] [validation.cpp:5560] [ResizeCoinsCaches] [Chainstate [ibd] @ height 199 (39455d83324a3dbbb15712ab63dd2f96b45a30f271617aa16c12483a561f721c)] resized coinsdb cache to 8.0 MiB node0 2024-08-15T17:07:32.061534Z [init] [validation.cpp:5562] [ResizeCoinsCaches] [Chainstate [ibd] @ height 199 (39455d83324a3dbbb15712ab63dd2f96b45a30f271617aa16c12483a561f721c)] resized coinstip cache to 440.0 MiB node0 2024-08-15T17:07:32.061543Z [init] [noui.cpp:57] [noui_InitMessage] init message: Verifying blocks… node0 2024-08-15T17:07:32.061550Z [init] [validation.cpp:4727] [VerifyDB] Verifying last 6 blocks at level 3 node0 2024-08-15T17:07:32.061574Z [init] [validation.cpp:4736] [VerifyDB] Verification progress: 0% node0 2024-08-15T17:07:32.061660Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 16% node0 2024-08-15T17:07:32.061701Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 33% node0 2024-08-15T17:07:32.061741Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 50% node0 2024-08-15T17:07:32.061779Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 66% node0 2024-08-15T17:07:32.061818Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 83% node0 2024-08-15T17:07:32.061858Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 99% node0 2024-08-15T17:07:32.061863Z [init] [validation.cpp:4838] [VerifyDB] Verification: No coin database inconsistencies in last 6 blocks (6 transactions) node0 2024-08-15T17:07:32.061867Z [init] [init.cpp:1611] [AppInitMain] block index 109ms node0 2024-08-15T17:07:32.061895Z [init] [init.cpp:1698] [AppInitMain] Setting NODE_NETWORK on non-prune mode node0 2024-08-15T17:07:32.061953Z [init] [init.cpp:1808] [AppInitMain] block tree size = 200 node0 2024-08-15T17:07:32.061958Z [init] [init.cpp:1821] [AppInitMain] nBestHeight = 199 node0 2024-08-15T17:07:32.062000Z [initload] [util/thread.cpp:20] [TraceThread] initload thread start node0 2024-08-15T17:07:32.062024Z [init] [net.cpp:3073] [BindListenPort] Bound to 127.0.0.1:15515 node0 2024-08-15T17:07:32.062033Z [init] [noui.cpp:57] [noui_InitMessage] init message: Starting network threads… node0 2024-08-15T17:07:32.062044Z [initload] [node/mempool_persist.cpp:47] [LoadMempool] Failed to open mempool file. Continuing anyway. node0 2024-08-15T17:07:32.062049Z [initload] [util/thread.cpp:22] [TraceThread] initload thread exit node0 2024-08-15T17:07:32.062056Z [init] [net.cpp:3297] [Start] DNS seeding disabled node0 2024-08-15T17:07:32.062089Z [net] [util/thread.cpp:20] [TraceThread] net thread start node0 2024-08-15T17:07:32.062117Z [init] [noui.cpp:57] [noui_InitMessage] init message: Done loading node0 2024-08-15T17:07:32.062124Z [msghand] [util/thread.cpp:20] [TraceThread] msghand thread start node0 2024-08-15T17:07:32.062150Z [addcon] [util/thread.cpp:20] [TraceThread] addcon thread start node0 2024-08-15T17:07:32.300272Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.300395Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockcount user=__cookie__ node0 2024-08-15T17:07:32.300782Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.300864Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getmempoolinfo user=__cookie__ test 2024-08-15T17:07:32.301000Z TestFramework.node0 (DEBUG): RPC successfully started test 2024-08-15T17:07:32.301000Z TestFramework (DEBUG): Generate a block with current time test 2024-08-15T17:07:32.301000Z TestFramework.node0 (DEBUG): TestNode.generate() dispatches `generate` call to `generatetoaddress` node0 2024-08-15T17:07:32.301311Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.301397Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.301844Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.301915Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=generatetoaddress user=__cookie__ node0 2024-08-15T17:07:32.301984Z [httpworker.1] [node/miner.cpp:158] [CreateNewBlock] CreateNewBlock(): block weight: 904 txs: 0 fees: 0 sigops 400 node0 2024-08-15T17:07:32.302015Z [httpworker.1] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.302040Z [httpworker.1] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.03ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.302047Z [httpworker.1] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 1 transactions: 0.01ms (0.007ms/tx, 0.000ms/txin) [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.302055Z [httpworker.1] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 0 txins: 0.01ms (0.000ms/txin) [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.302059Z [httpworker.1] [node/miner.cpp:177] [CreateNewBlock] [bench] CreateNewBlock() packages: 0.00ms (0 packages, 0 updated descendants), validity: 0.08ms (total 0.09ms) node0 2024-08-15T17:07:32.302071Z [httpworker.1] [validation.cpp:4393] [AcceptBlockHeader] [validation] Saw new header hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d height=200 node0 2024-08-15T17:07:32.302160Z [httpworker.1] [validation.cpp:3168] [ConnectTip] [bench] - Using cached block node0 2024-08-15T17:07:32.302166Z [httpworker.1] [validation.cpp:3178] [ConnectTip] [bench] - Load block from disk: 0.01ms node0 2024-08-15T17:07:32.302184Z [httpworker.1] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.302197Z [httpworker.1] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.01ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.302202Z [httpworker.1] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 1 transactions: 0.01ms (0.005ms/tx, 0.000ms/txin) [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.302236Z [httpworker.1] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 0 txins: 0.04ms (0.000ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.302257Z [httpworker.1] [validation.cpp:2733] [ConnectBlock] [bench] - Write undo data: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.302275Z [httpworker.1] [validation.cpp:2748] [ConnectBlock] [bench] - Index writing: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.302280Z [httpworker.1] [validationinterface.cpp:251] [BlockChecked] [validation] BlockChecked: block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d state=Valid node0 2024-08-15T17:07:32.302300Z [httpworker.1] [validation.cpp:3197] [ConnectTip] [bench] - Connect total: 0.13ms [0.00s (0.07ms/blk)] node0 2024-08-15T17:07:32.302315Z [httpworker.1] [validation.cpp:3206] [ConnectTip] [bench] - Flush: 0.01ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.302319Z [httpworker.1] [validation.cpp:3216] [ConnectTip] [bench] - Writing chainstate: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.302328Z [httpworker.1] [validationinterface.cpp:228] [MempoolTransactionsRemovedForBlock] [validation] Enqueuing MempoolTransactionsRemovedForBlock: block height=200 txs removed=0 node0 2024-08-15T17:07:32.302353Z [httpworker.1] [validation.cpp:2030] [IsInitialBlockDownload] Leaving InitialBlockDownload (latching to false) node0 2024-08-15T17:07:32.302368Z [httpworker.1] [validation.cpp:2979] [UpdateTipLog] UpdateTip: new best=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d height=200 version=0x30000000 log2_work=8.651052 tx=201 date='2024-08-15T17:07:32Z' progress=1.000000 cache=0.3MiB(1txo) node0 2024-08-15T17:07:32.302372Z [httpworker.1] [validation.cpp:3232] [ConnectTip] [bench] - Connect postprocess: 0.05ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.302375Z [httpworker.1] [validation.cpp:3236] [ConnectTip] [bench] - Connect block: 0.21ms [0.00s (0.11ms/blk)] node0 2024-08-15T17:07:32.302379Z [httpworker.1] [txmempool.cpp:664] [check] [mempool] Checking mempool with 0 transactions and 0 inputs node0 2024-08-15T17:07:32.302396Z [httpworker.1] [validationinterface.cpp:218] [BlockConnected] [validation] Enqueuing BlockConnected: block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d block height=200 node0 2024-08-15T17:07:32.302403Z [httpworker.1] [validationinterface.cpp:183] [UpdatedBlockTip] [validation] Enqueuing UpdatedBlockTip: new block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d fork block hash=39455d83324a3dbbb15712ab63dd2f96b45a30f271617aa16c12483a561f721c (in IBD=false) node0 2024-08-15T17:07:32.302415Z [httpworker.1] [validationinterface.cpp:188] [ActiveTipChange] [validation] ActiveTipChange: new block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d block height=200 node0 2024-08-15T17:07:32.302471Z [scheduler] [validationinterface.cpp:228] [operator()] [validation] MempoolTransactionsRemovedForBlock: block height=200 txs removed=0 node0 2024-08-15T17:07:32.302535Z [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-08-15T17:07:32.302550Z [scheduler] [validationinterface.cpp:218] [operator()] [validation] BlockConnected: block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d block height=200 node0 2024-08-15T17:07:32.303339Z [scheduler] [validationinterface.cpp:183] [operator()] [validation] UpdatedBlockTip: new block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d fork block hash=39455d83324a3dbbb15712ab63dd2f96b45a30f271617aa16c12483a561f721c (in IBD=false) node0 2024-08-15T17:07:32.303594Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.303678Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblock user=__cookie__ node0 2024-08-15T17:07:32.304093Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.304182Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=submitblock user=__cookie__ node0 2024-08-15T17:07:32.304536Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.304613Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.304977Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 test 2024-08-15T17:07:32.305000Z TestFramework (INFO): Mine some blocks, to create multiple blk*.dat/rev*.dat files node0 2024-08-15T17:07:32.305036Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ node0 2024-08-15T17:07:32.305243Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.305312Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.305663Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.305698Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ node0 2024-08-15T17:07:32.309283Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.309348Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=scantxoutset user=__cookie__ node0 2024-08-15T17:07:32.309438Z [httpworker.3] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.317961Z [httpworker.3] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (8.51ms) node0 2024-08-15T17:07:32.317987Z [httpworker.3] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.318704Z [httpworker.3] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.71ms) node0 2024-08-15T17:07:32.318717Z [httpworker.3] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (1 coins, 262kB) started node0 2024-08-15T17:07:32.318755Z [httpworker.3] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.318773Z [httpworker.3] [txdb.cpp:148] [BatchWrite] [coindb] Committed 1 changed transaction outputs (out of 1) to coin database... node0 2024-08-15T17:07:32.318802Z [httpworker.3] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (1 coins, 262kB) completed (0.08ms) node0 2024-08-15T17:07:32.318812Z [httpworker.3] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d node0 2024-08-15T17:07:32.318876Z [scheduler] [validationinterface.cpp:246] [operator()] [validation] ChainStateFlushed: block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d node0 2024-08-15T17:07:32.362813Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.362904Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.363349Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.363443Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.368838Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.370196Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=sendrawtransaction user=__cookie__ node0 2024-08-15T17:07:32.370879Z [httpworker.0] [validationinterface.cpp:199] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=dc09385e49e897b6092cf38d305b946138e6ea22a859b9e75af6a422eecfe487 wtxid=112f0c4f62ea151ecbf1c7aaa888b31a27e4a53caeb920f10462b697277d4cac node0 2024-08-15T17:07:32.370910Z [httpworker.0] [txmempool.cpp:664] [check] [mempool] Checking mempool with 1 transactions and 1 inputs node0 2024-08-15T17:07:32.370939Z [scheduler] [validationinterface.cpp:199] [operator()] [validation] TransactionAddedToMempool: txid=dc09385e49e897b6092cf38d305b946138e6ea22a859b9e75af6a422eecfe487 wtxid=112f0c4f62ea151ecbf1c7aaa888b31a27e4a53caeb920f10462b697277d4cac node0 2024-08-15T17:07:32.371621Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.372988Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=decoderawtransaction user=__cookie__ node0 2024-08-15T17:07:32.379554Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.379656Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.380227Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.380323Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=generatetodescriptor user=__cookie__ node0 2024-08-15T17:07:32.380383Z [httpworker.2] [node/miner.cpp:158] [CreateNewBlock] CreateNewBlock(): block weight: 80943 txs: 1 fees: 6000300 sigops 400 node0 2024-08-15T17:07:32.380455Z [httpworker.2] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.380485Z [httpworker.2] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.03ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.380517Z [httpworker.2] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.03ms (0.016ms/tx, 0.032ms/txin) [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.380523Z [httpworker.2] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.04ms (0.038ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.380527Z [httpworker.2] [node/miner.cpp:177] [CreateNewBlock] [bench] CreateNewBlock() packages: 0.01ms (1 packages, 0 updated descendants), validity: 0.15ms (total 0.16ms) node0 2024-08-15T17:07:32.380557Z [httpworker.2] [validation.cpp:4395] [AcceptBlockHeader] Saw new header hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 height=201 node0 2024-08-15T17:07:32.380584Z [httpworker.2] [validationinterface.cpp:256] [NewPoWValidBlock] [validation] NewPoWValidBlock: block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 node0 2024-08-15T17:07:32.380606Z [httpworker.2] [node/blockstorage.cpp:891] [FindNextBlockPos] [blockstorage] Leaving block file 0: CBlockFileInfo(blocks=201, size=52791, heights=0...200, time=2011-02-02...2024-08-15) (onto 1) (height 201) node0 2024-08-15T17:07:32.383827Z [httpworker.2] [flatfile.cpp:69] [Allocate] [validation] Pre-allocating up to position 0x8000 in blk00001.dat node0 2024-08-15T17:07:32.383929Z [httpworker.2] [validation.cpp:3168] [ConnectTip] [bench] - Using cached block node0 2024-08-15T17:07:32.383938Z [httpworker.2] [validation.cpp:3178] [ConnectTip] [bench] - Load block from disk: 0.01ms node0 2024-08-15T17:07:32.383954Z [httpworker.2] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.383980Z [httpworker.2] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.03ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.384007Z [httpworker.2] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.03ms (0.013ms/tx, 0.026ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.384012Z [httpworker.2] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.032ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.384031Z [httpworker.2] [flatfile.cpp:69] [Allocate] [validation] Pre-allocating up to position 0x100000 in rev00001.dat node0 2024-08-15T17:07:32.384059Z [httpworker.2] [validation.cpp:2733] [ConnectBlock] [bench] - Write undo data: 0.05ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.384063Z [httpworker.2] [validation.cpp:2748] [ConnectBlock] [bench] - Index writing: 0.01ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.384069Z [httpworker.2] [validationinterface.cpp:251] [BlockChecked] [validation] BlockChecked: block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 state=Valid node0 2024-08-15T17:07:32.384074Z [httpworker.2] [validation.cpp:3197] [ConnectTip] [bench] - Connect total: 0.14ms [0.00s (0.07ms/blk)] node0 2024-08-15T17:07:32.384089Z [httpworker.2] [validation.cpp:3206] [ConnectTip] [bench] - Flush: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.384094Z [httpworker.2] [validation.cpp:3216] [ConnectTip] [bench] - Writing chainstate: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.384102Z [httpworker.2] [txmempool.cpp:1039] [RemoveUnbroadcastTx] [mempool] Removed dc09385e49e897b6092cf38d305b946138e6ea22a859b9e75af6a422eecfe487 from set of unbroadcast txns before confirmation that txn was sent out node0 2024-08-15T17:07:32.384107Z [httpworker.2] [validationinterface.cpp:228] [MempoolTransactionsRemovedForBlock] [validation] Enqueuing MempoolTransactionsRemovedForBlock: block height=201 txs removed=1 node0 2024-08-15T17:07:32.384139Z [httpworker.2] [validation.cpp:2979] [UpdateTipLog] UpdateTip: new best=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 height=201 version=0x30000000 log2_work=8.658211 tx=203 date='2024-08-15T17:07:32Z' progress=1.000000 cache=0.3MiB(3txo) node0 2024-08-15T17:07:32.384144Z [httpworker.2] [validation.cpp:3232] [ConnectTip] [bench] - Connect postprocess: 0.05ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.384147Z [httpworker.2] [validation.cpp:3236] [ConnectTip] [bench] - Connect block: 0.21ms [0.00s (0.11ms/blk)] node0 2024-08-15T17:07:32.384151Z [httpworker.2] [txmempool.cpp:664] [check] [mempool] Checking mempool with 0 transactions and 0 inputs node0 2024-08-15T17:07:32.384168Z [httpworker.2] [validationinterface.cpp:218] [BlockConnected] [validation] Enqueuing BlockConnected: block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 block height=201 node0 2024-08-15T17:07:32.384173Z [scheduler] [validationinterface.cpp:228] [operator()] [validation] MempoolTransactionsRemovedForBlock: block height=201 txs removed=1 node0 2024-08-15T17:07:32.384243Z [httpworker.2] [validationinterface.cpp:183] [UpdatedBlockTip] [validation] Enqueuing UpdatedBlockTip: new block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 fork block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d (in IBD=false) node0 2024-08-15T17:07:32.384259Z [httpworker.2] [validationinterface.cpp:188] [ActiveTipChange] [validation] ActiveTipChange: new block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 block height=201 node0 2024-08-15T17:07:32.384268Z [scheduler] [policy/fees.cpp:702] [processBlock] [estimatefee] Blockpolicy first recorded height 201 node0 2024-08-15T17:07:32.384273Z [scheduler] [policy/fees.cpp:708] [processBlock] [estimatefee] Blockpolicy estimates updated by 1 of 1 block txs, since last block 1 of 1 tracked, mempool map size 0, max target 0 from current node0 2024-08-15T17:07:32.384332Z [scheduler] [validationinterface.cpp:218] [operator()] [validation] BlockConnected: block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 block height=201 node0 2024-08-15T17:07:32.384415Z [scheduler] [validationinterface.cpp:183] [operator()] [validation] UpdatedBlockTip: new block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 fork block hash=0cd442270ec056d8da6521876bb41c462a03e47459ba1c98ac649e369434e27d (in IBD=false) node0 2024-08-15T17:07:32.385002Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.385075Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=scantxoutset user=__cookie__ node0 2024-08-15T17:07:32.385143Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.386414Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (1.26ms) node0 2024-08-15T17:07:32.386424Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.387018Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.59ms) node0 2024-08-15T17:07:32.387029Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) started node0 2024-08-15T17:07:32.387045Z [httpworker.0] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.387060Z [httpworker.0] [txdb.cpp:148] [BatchWrite] [coindb] Committed 3 changed transaction outputs (out of 3) to coin database... node0 2024-08-15T17:07:32.387085Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) completed (0.05ms) node0 2024-08-15T17:07:32.387092Z [httpworker.0] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 node0 2024-08-15T17:07:32.387153Z [scheduler] [validationinterface.cpp:246] [operator()] [validation] ChainStateFlushed: block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 node0 2024-08-15T17:07:32.430709Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.430788Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.431194Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.431258Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ node0 2024-08-15T17:07:32.431579Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.431641Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.431986Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.432049Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ node0 2024-08-15T17:07:32.432487Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.432553Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.437664Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.439088Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=sendrawtransaction user=__cookie__ node0 2024-08-15T17:07:32.439702Z [httpworker.1] [validationinterface.cpp:199] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=a5df035741db3670da793ee5a77c074739b486d64994772836e1b3ec609aae1c wtxid=6f7101bc1f5a458b670a0a6d989657856fe2b06a4b7a07e34f2ccdde4a53938e node0 2024-08-15T17:07:32.439717Z [httpworker.1] [txmempool.cpp:664] [check] [mempool] Checking mempool with 1 transactions and 1 inputs node0 2024-08-15T17:07:32.439769Z [scheduler] [validationinterface.cpp:199] [operator()] [validation] TransactionAddedToMempool: txid=a5df035741db3670da793ee5a77c074739b486d64994772836e1b3ec609aae1c wtxid=6f7101bc1f5a458b670a0a6d989657856fe2b06a4b7a07e34f2ccdde4a53938e node0 2024-08-15T17:07:32.440391Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.441755Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=decoderawtransaction user=__cookie__ node0 2024-08-15T17:07:32.448332Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.448411Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.448971Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.449038Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=generatetodescriptor user=__cookie__ node0 2024-08-15T17:07:32.449101Z [httpworker.3] [node/miner.cpp:158] [CreateNewBlock] CreateNewBlock(): block weight: 80943 txs: 1 fees: 6000300 sigops 400 node0 2024-08-15T17:07:32.449170Z [httpworker.3] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.449200Z [httpworker.3] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.03ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.449228Z [httpworker.3] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.03ms (0.014ms/tx, 0.028ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.449233Z [httpworker.3] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.034ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.449238Z [httpworker.3] [node/miner.cpp:177] [CreateNewBlock] [bench] CreateNewBlock() packages: 0.01ms (1 packages, 0 updated descendants), validity: 0.15ms (total 0.15ms) node0 2024-08-15T17:07:32.449268Z [httpworker.3] [validation.cpp:4395] [AcceptBlockHeader] Saw new header hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b height=202 node0 2024-08-15T17:07:32.449291Z [httpworker.3] [validationinterface.cpp:256] [NewPoWValidBlock] [validation] NewPoWValidBlock: block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b node0 2024-08-15T17:07:32.449336Z [httpworker.3] [flatfile.cpp:69] [Allocate] [validation] Pre-allocating up to position 0xc000 in blk00001.dat node0 2024-08-15T17:07:32.449427Z [httpworker.3] [validation.cpp:3168] [ConnectTip] [bench] - Using cached block node0 2024-08-15T17:07:32.449433Z [httpworker.3] [validation.cpp:3178] [ConnectTip] [bench] - Load block from disk: 0.01ms node0 2024-08-15T17:07:32.449447Z [httpworker.3] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.00ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.449464Z [httpworker.3] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.02ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.449489Z [httpworker.3] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.02ms (0.012ms/tx, 0.024ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.449497Z [httpworker.3] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.032ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.449515Z [httpworker.3] [validation.cpp:2733] [ConnectBlock] [bench] - Write undo data: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.449519Z [httpworker.3] [validation.cpp:2748] [ConnectBlock] [bench] - Index writing: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.449524Z [httpworker.3] [validationinterface.cpp:251] [BlockChecked] [validation] BlockChecked: block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b state=Valid node0 2024-08-15T17:07:32.449529Z [httpworker.3] [validation.cpp:3197] [ConnectTip] [bench] - Connect total: 0.10ms [0.00s (0.06ms/blk)] node0 2024-08-15T17:07:32.449545Z [httpworker.3] [validation.cpp:3206] [ConnectTip] [bench] - Flush: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.449549Z [httpworker.3] [validation.cpp:3216] [ConnectTip] [bench] - Writing chainstate: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.449557Z [httpworker.3] [txmempool.cpp:1039] [RemoveUnbroadcastTx] [mempool] Removed a5df035741db3670da793ee5a77c074739b486d64994772836e1b3ec609aae1c from set of unbroadcast txns before confirmation that txn was sent out node0 2024-08-15T17:07:32.449561Z [httpworker.3] [validationinterface.cpp:228] [MempoolTransactionsRemovedForBlock] [validation] Enqueuing MempoolTransactionsRemovedForBlock: block height=202 txs removed=1 node0 2024-08-15T17:07:32.449576Z [httpworker.3] [validation.cpp:2979] [UpdateTipLog] UpdateTip: new best=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b height=202 version=0x30000000 log2_work=8.665336 tx=205 date='2024-08-15T17:07:32Z' progress=1.000000 cache=0.3MiB(3txo) node0 2024-08-15T17:07:32.449580Z [httpworker.3] [validation.cpp:3232] [ConnectTip] [bench] - Connect postprocess: 0.03ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.449584Z [httpworker.3] [validation.cpp:3236] [ConnectTip] [bench] - Connect block: 0.15ms [0.00s (0.10ms/blk)] node0 2024-08-15T17:07:32.449588Z [httpworker.3] [txmempool.cpp:664] [check] [mempool] Checking mempool with 0 transactions and 0 inputs node0 2024-08-15T17:07:32.449605Z [httpworker.3] [validationinterface.cpp:218] [BlockConnected] [validation] Enqueuing BlockConnected: block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b block height=202 node0 2024-08-15T17:07:32.449613Z [httpworker.3] [validationinterface.cpp:183] [UpdatedBlockTip] [validation] Enqueuing UpdatedBlockTip: new block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b fork block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 (in IBD=false) node0 2024-08-15T17:07:32.449620Z [httpworker.3] [validationinterface.cpp:188] [ActiveTipChange] [validation] ActiveTipChange: new block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b block height=202 node0 2024-08-15T17:07:32.449630Z [scheduler] [validationinterface.cpp:228] [operator()] [validation] MempoolTransactionsRemovedForBlock: block height=202 txs removed=1 node0 2024-08-15T17:07:32.449696Z [scheduler] [policy/fees.cpp:708] [processBlock] [estimatefee] Blockpolicy estimates updated by 1 of 1 block txs, since last block 1 of 1 tracked, mempool map size 0, max target 0 from current node0 2024-08-15T17:07:32.449710Z [scheduler] [validationinterface.cpp:218] [operator()] [validation] BlockConnected: block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b block height=202 node0 2024-08-15T17:07:32.449755Z [scheduler] [validationinterface.cpp:183] [operator()] [validation] UpdatedBlockTip: new block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b fork block hash=74b96a9b1ea439594f88cd6096ebee1af9c2a3983a48927fbbbc725a6d261069 (in IBD=false) node0 2024-08-15T17:07:32.450134Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.450214Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=scantxoutset user=__cookie__ node0 2024-08-15T17:07:32.450299Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.451297Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (0.99ms) node0 2024-08-15T17:07:32.451306Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.451930Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.62ms) node0 2024-08-15T17:07:32.451941Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) started node0 2024-08-15T17:07:32.451957Z [httpworker.1] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.451972Z [httpworker.1] [txdb.cpp:148] [BatchWrite] [coindb] Committed 3 changed transaction outputs (out of 3) to coin database... node0 2024-08-15T17:07:32.451994Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) completed (0.05ms) node0 2024-08-15T17:07:32.452001Z [httpworker.1] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b node0 2024-08-15T17:07:32.452063Z [scheduler] [validationinterface.cpp:246] [operator()] [validation] ChainStateFlushed: block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b node0 2024-08-15T17:07:32.494695Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.494779Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.495191Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.495256Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ node0 2024-08-15T17:07:32.495589Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.495653Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.495995Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.496060Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ node0 2024-08-15T17:07:32.496499Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.496560Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.501560Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.502928Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=sendrawtransaction user=__cookie__ node0 2024-08-15T17:07:32.503538Z [httpworker.0] [validationinterface.cpp:199] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=81ab724266dae3870c007e062f0f8530322854d4656b00761b914009f0a4a024 wtxid=ed95e05fb20f74955757ca6f6397a82d32dd545014310707d91d8d971230698d node0 2024-08-15T17:07:32.503553Z [httpworker.0] [txmempool.cpp:664] [check] [mempool] Checking mempool with 1 transactions and 1 inputs node0 2024-08-15T17:07:32.503605Z [scheduler] [validationinterface.cpp:199] [operator()] [validation] TransactionAddedToMempool: txid=81ab724266dae3870c007e062f0f8530322854d4656b00761b914009f0a4a024 wtxid=ed95e05fb20f74955757ca6f6397a82d32dd545014310707d91d8d971230698d node0 2024-08-15T17:07:32.504200Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.505520Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=decoderawtransaction user=__cookie__ node0 2024-08-15T17:07:32.512041Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.512121Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.512640Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.512708Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=generatetodescriptor user=__cookie__ node0 2024-08-15T17:07:32.512771Z [httpworker.2] [node/miner.cpp:158] [CreateNewBlock] CreateNewBlock(): block weight: 80943 txs: 1 fees: 6000300 sigops 400 node0 2024-08-15T17:07:32.512846Z [httpworker.2] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.512876Z [httpworker.2] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.03ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.512905Z [httpworker.2] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.03ms (0.014ms/tx, 0.028ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.512910Z [httpworker.2] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.034ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.512914Z [httpworker.2] [node/miner.cpp:177] [CreateNewBlock] [bench] CreateNewBlock() packages: 0.01ms (1 packages, 0 updated descendants), validity: 0.15ms (total 0.16ms) node0 2024-08-15T17:07:32.512944Z [httpworker.2] [validation.cpp:4395] [AcceptBlockHeader] Saw new header hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc height=203 node0 2024-08-15T17:07:32.512966Z [httpworker.2] [validationinterface.cpp:256] [NewPoWValidBlock] [validation] NewPoWValidBlock: block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc node0 2024-08-15T17:07:32.513013Z [httpworker.2] [flatfile.cpp:69] [Allocate] [validation] Pre-allocating up to position 0x10000 in blk00001.dat node0 2024-08-15T17:07:32.513103Z [httpworker.2] [validation.cpp:3168] [ConnectTip] [bench] - Using cached block node0 2024-08-15T17:07:32.513109Z [httpworker.2] [validation.cpp:3178] [ConnectTip] [bench] - Load block from disk: 0.01ms node0 2024-08-15T17:07:32.513123Z [httpworker.2] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.00ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.513141Z [httpworker.2] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.02ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.513166Z [httpworker.2] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.02ms (0.012ms/tx, 0.024ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.513171Z [httpworker.2] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.029ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.513189Z [httpworker.2] [validation.cpp:2733] [ConnectBlock] [bench] - Write undo data: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.513193Z [httpworker.2] [validation.cpp:2748] [ConnectBlock] [bench] - Index writing: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.513198Z [httpworker.2] [validationinterface.cpp:251] [BlockChecked] [validation] BlockChecked: block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc state=Valid node0 2024-08-15T17:07:32.513203Z [httpworker.2] [validation.cpp:3197] [ConnectTip] [bench] - Connect total: 0.10ms [0.00s (0.06ms/blk)] node0 2024-08-15T17:07:32.513219Z [httpworker.2] [validation.cpp:3206] [ConnectTip] [bench] - Flush: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.513223Z [httpworker.2] [validation.cpp:3216] [ConnectTip] [bench] - Writing chainstate: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.513231Z [httpworker.2] [txmempool.cpp:1039] [RemoveUnbroadcastTx] [mempool] Removed 81ab724266dae3870c007e062f0f8530322854d4656b00761b914009f0a4a024 from set of unbroadcast txns before confirmation that txn was sent out node0 2024-08-15T17:07:32.513235Z [httpworker.2] [validationinterface.cpp:228] [MempoolTransactionsRemovedForBlock] [validation] Enqueuing MempoolTransactionsRemovedForBlock: block height=203 txs removed=1 node0 2024-08-15T17:07:32.513254Z [httpworker.2] [validation.cpp:2979] [UpdateTipLog] UpdateTip: new best=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc height=203 version=0x30000000 log2_work=8.672425 tx=207 date='2024-08-15T17:07:32Z' progress=1.000000 cache=0.3MiB(3txo) node0 2024-08-15T17:07:32.513258Z [httpworker.2] [validation.cpp:3232] [ConnectTip] [bench] - Connect postprocess: 0.04ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.513262Z [httpworker.2] [validation.cpp:3236] [ConnectTip] [bench] - Connect block: 0.16ms [0.00s (0.09ms/blk)] node0 2024-08-15T17:07:32.513266Z [httpworker.2] [txmempool.cpp:664] [check] [mempool] Checking mempool with 0 transactions and 0 inputs node0 2024-08-15T17:07:32.513283Z [httpworker.2] [validationinterface.cpp:218] [BlockConnected] [validation] Enqueuing BlockConnected: block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc block height=203 node0 2024-08-15T17:07:32.513290Z [httpworker.2] [validationinterface.cpp:183] [UpdatedBlockTip] [validation] Enqueuing UpdatedBlockTip: new block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc fork block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b (in IBD=false) node0 2024-08-15T17:07:32.513297Z [httpworker.2] [validationinterface.cpp:188] [ActiveTipChange] [validation] ActiveTipChange: new block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc block height=203 node0 2024-08-15T17:07:32.513309Z [scheduler] [validationinterface.cpp:228] [operator()] [validation] MempoolTransactionsRemovedForBlock: block height=203 txs removed=1 node0 2024-08-15T17:07:32.513373Z [scheduler] [policy/fees.cpp:708] [processBlock] [estimatefee] Blockpolicy estimates updated by 1 of 1 block txs, since last block 1 of 1 tracked, mempool map size 0, max target 1 from current node0 2024-08-15T17:07:32.513386Z [scheduler] [validationinterface.cpp:218] [operator()] [validation] BlockConnected: block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc block height=203 node0 2024-08-15T17:07:32.513432Z [scheduler] [validationinterface.cpp:183] [operator()] [validation] UpdatedBlockTip: new block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc fork block hash=2f65f1606c32f84936484cb6316506eb1dfe498f17d9feba569492e23c5c0c8b (in IBD=false) node0 2024-08-15T17:07:32.513899Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.513969Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=scantxoutset user=__cookie__ node0 2024-08-15T17:07:32.514035Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.515083Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (1.04ms) node0 2024-08-15T17:07:32.515093Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.515714Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.61ms) node0 2024-08-15T17:07:32.515725Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) started node0 2024-08-15T17:07:32.515741Z [httpworker.0] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.515757Z [httpworker.0] [txdb.cpp:148] [BatchWrite] [coindb] Committed 3 changed transaction outputs (out of 3) to coin database... node0 2024-08-15T17:07:32.515779Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) completed (0.05ms) node0 2024-08-15T17:07:32.515787Z [httpworker.0] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc node0 2024-08-15T17:07:32.515850Z [scheduler] [validationinterface.cpp:246] [operator()] [validation] ChainStateFlushed: block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc node0 2024-08-15T17:07:32.558712Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.558794Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.559214Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.559281Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ node0 2024-08-15T17:07:32.559613Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.559675Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.560014Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.560075Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ node0 2024-08-15T17:07:32.560516Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.560577Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.565578Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.567001Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=sendrawtransaction user=__cookie__ node0 2024-08-15T17:07:32.567625Z [httpworker.1] [validationinterface.cpp:199] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=63890d00b634d79d028cdfb48ab2982bc78e4255b30724819799871430a26e95 wtxid=6f7ced0c98328eeb15dad24857135d2b8f776f4007db5788bc6c4d8a30aaaf3f node0 2024-08-15T17:07:32.567640Z [httpworker.1] [txmempool.cpp:664] [check] [mempool] Checking mempool with 1 transactions and 1 inputs node0 2024-08-15T17:07:32.567691Z [scheduler] [validationinterface.cpp:199] [operator()] [validation] TransactionAddedToMempool: txid=63890d00b634d79d028cdfb48ab2982bc78e4255b30724819799871430a26e95 wtxid=6f7ced0c98328eeb15dad24857135d2b8f776f4007db5788bc6c4d8a30aaaf3f node0 2024-08-15T17:07:32.568301Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.569631Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=decoderawtransaction user=__cookie__ node0 2024-08-15T17:07:32.576153Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.576231Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.576795Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.576866Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=generatetodescriptor user=__cookie__ node0 2024-08-15T17:07:32.576930Z [httpworker.3] [node/miner.cpp:158] [CreateNewBlock] CreateNewBlock(): block weight: 80943 txs: 1 fees: 6000300 sigops 400 node0 2024-08-15T17:07:32.577000Z [httpworker.3] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.577031Z [httpworker.3] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.03ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.577060Z [httpworker.3] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.03ms (0.014ms/tx, 0.028ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.577065Z [httpworker.3] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.034ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.577070Z [httpworker.3] [node/miner.cpp:177] [CreateNewBlock] [bench] CreateNewBlock() packages: 0.01ms (1 packages, 0 updated descendants), validity: 0.15ms (total 0.16ms) node0 2024-08-15T17:07:32.577106Z [httpworker.3] [validation.cpp:4395] [AcceptBlockHeader] Saw new header hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a height=204 node0 2024-08-15T17:07:32.577130Z [httpworker.3] [validationinterface.cpp:256] [NewPoWValidBlock] [validation] NewPoWValidBlock: block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a node0 2024-08-15T17:07:32.577149Z [httpworker.3] [node/blockstorage.cpp:891] [FindNextBlockPos] [blockstorage] Leaving block file 1: CBlockFileInfo(blocks=3, size=60900, heights=201...203, time=2024-08-15...2024-08-15) (onto 2) (height 204) node0 2024-08-15T17:07:32.578498Z [httpworker.3] [flatfile.cpp:69] [Allocate] [validation] Pre-allocating up to position 0x8000 in blk00002.dat node0 2024-08-15T17:07:32.578595Z [httpworker.3] [validation.cpp:3168] [ConnectTip] [bench] - Using cached block node0 2024-08-15T17:07:32.578603Z [httpworker.3] [validation.cpp:3178] [ConnectTip] [bench] - Load block from disk: 0.01ms node0 2024-08-15T17:07:32.578618Z [httpworker.3] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.00ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.578641Z [httpworker.3] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.02ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.578668Z [httpworker.3] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.03ms (0.013ms/tx, 0.026ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.578673Z [httpworker.3] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.032ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.578692Z [httpworker.3] [flatfile.cpp:69] [Allocate] [validation] Pre-allocating up to position 0x100000 in rev00002.dat node0 2024-08-15T17:07:32.578721Z [httpworker.3] [validation.cpp:2733] [ConnectBlock] [bench] - Write undo data: 0.05ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.578726Z [httpworker.3] [validation.cpp:2748] [ConnectBlock] [bench] - Index writing: 0.01ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.578731Z [httpworker.3] [validationinterface.cpp:251] [BlockChecked] [validation] BlockChecked: block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a state=Valid node0 2024-08-15T17:07:32.578737Z [httpworker.3] [validation.cpp:3197] [ConnectTip] [bench] - Connect total: 0.14ms [0.00s (0.06ms/blk)] node0 2024-08-15T17:07:32.578752Z [httpworker.3] [validation.cpp:3206] [ConnectTip] [bench] - Flush: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.578757Z [httpworker.3] [validation.cpp:3216] [ConnectTip] [bench] - Writing chainstate: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.578765Z [httpworker.3] [txmempool.cpp:1039] [RemoveUnbroadcastTx] [mempool] Removed 63890d00b634d79d028cdfb48ab2982bc78e4255b30724819799871430a26e95 from set of unbroadcast txns before confirmation that txn was sent out node0 2024-08-15T17:07:32.578770Z [httpworker.3] [validationinterface.cpp:228] [MempoolTransactionsRemovedForBlock] [validation] Enqueuing MempoolTransactionsRemovedForBlock: block height=204 txs removed=1 node0 2024-08-15T17:07:32.578785Z [httpworker.3] [validation.cpp:2979] [UpdateTipLog] UpdateTip: new best=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a height=204 version=0x30000000 log2_work=8.679480 tx=209 date='2024-08-15T17:07:32Z' progress=1.000000 cache=0.3MiB(3txo) node0 2024-08-15T17:07:32.578789Z [httpworker.3] [validation.cpp:3232] [ConnectTip] [bench] - Connect postprocess: 0.03ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.578793Z [httpworker.3] [validation.cpp:3236] [ConnectTip] [bench] - Connect block: 0.19ms [0.00s (0.09ms/blk)] node0 2024-08-15T17:07:32.578797Z [httpworker.3] [txmempool.cpp:664] [check] [mempool] Checking mempool with 0 transactions and 0 inputs node0 2024-08-15T17:07:32.578820Z [httpworker.3] [validationinterface.cpp:218] [BlockConnected] [validation] Enqueuing BlockConnected: block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a block height=204 node0 2024-08-15T17:07:32.578827Z [httpworker.3] [validationinterface.cpp:183] [UpdatedBlockTip] [validation] Enqueuing UpdatedBlockTip: new block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a fork block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc (in IBD=false) node0 2024-08-15T17:07:32.578837Z [httpworker.3] [validationinterface.cpp:188] [ActiveTipChange] [validation] ActiveTipChange: new block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a block height=204 node0 2024-08-15T17:07:32.578852Z [scheduler] [validationinterface.cpp:228] [operator()] [validation] MempoolTransactionsRemovedForBlock: block height=204 txs removed=1 node0 2024-08-15T17:07:32.578916Z [scheduler] [policy/fees.cpp:708] [processBlock] [estimatefee] Blockpolicy estimates updated by 1 of 1 block txs, since last block 1 of 1 tracked, mempool map size 0, max target 1 from current node0 2024-08-15T17:07:32.578930Z [scheduler] [validationinterface.cpp:218] [operator()] [validation] BlockConnected: block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a block height=204 node0 2024-08-15T17:07:32.578975Z [scheduler] [validationinterface.cpp:183] [operator()] [validation] UpdatedBlockTip: new block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a fork block hash=50682e28d3887fed84a7b5b1cf1735b8f655e49ba9bd25b7eb234a343e4916bc (in IBD=false) node0 2024-08-15T17:07:32.579505Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.579576Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=scantxoutset user=__cookie__ node0 2024-08-15T17:07:32.579645Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.580973Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (1.32ms) node0 2024-08-15T17:07:32.580982Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.581613Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.62ms) node0 2024-08-15T17:07:32.581624Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) started node0 2024-08-15T17:07:32.581641Z [httpworker.1] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.581658Z [httpworker.1] [txdb.cpp:148] [BatchWrite] [coindb] Committed 3 changed transaction outputs (out of 3) to coin database... node0 2024-08-15T17:07:32.581683Z [httpworker.1] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) completed (0.05ms) node0 2024-08-15T17:07:32.581690Z [httpworker.1] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a node0 2024-08-15T17:07:32.581752Z [scheduler] [validationinterface.cpp:246] [operator()] [validation] ChainStateFlushed: block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a node0 2024-08-15T17:07:32.626732Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.626816Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.627219Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.627282Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ node0 2024-08-15T17:07:32.627619Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.627681Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.628020Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.628084Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ node0 2024-08-15T17:07:32.628530Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.628601Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.633587Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.634942Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=sendrawtransaction user=__cookie__ node0 2024-08-15T17:07:32.635572Z [httpworker.0] [validationinterface.cpp:199] [TransactionAddedToMempool] [validation] Enqueuing TransactionAddedToMempool: txid=8366b9a5f3e98dd099c4c4ac90fb271c5413f07e9cd3ea2e6ceb4bdfdcbe1641 wtxid=bf2113141354a9ee94e48330bfa6fc72b0f07a632c44a265a60b0c70056ea280 node0 2024-08-15T17:07:32.635586Z [httpworker.0] [txmempool.cpp:664] [check] [mempool] Checking mempool with 1 transactions and 1 inputs node0 2024-08-15T17:07:32.635639Z [scheduler] [validationinterface.cpp:199] [operator()] [validation] TransactionAddedToMempool: txid=8366b9a5f3e98dd099c4c4ac90fb271c5413f07e9cd3ea2e6ceb4bdfdcbe1641 wtxid=bf2113141354a9ee94e48330bfa6fc72b0f07a632c44a265a60b0c70056ea280 node0 2024-08-15T17:07:32.636234Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.637536Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=decoderawtransaction user=__cookie__ node0 2024-08-15T17:07:32.643952Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.644034Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getblockchaininfo user=__cookie__ node0 2024-08-15T17:07:32.644543Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.644611Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=generatetodescriptor user=__cookie__ node0 2024-08-15T17:07:32.644674Z [httpworker.2] [node/miner.cpp:158] [CreateNewBlock] CreateNewBlock(): block weight: 80943 txs: 1 fees: 6000300 sigops 400 node0 2024-08-15T17:07:32.644743Z [httpworker.2] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.644776Z [httpworker.2] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.03ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.644804Z [httpworker.2] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.03ms (0.014ms/tx, 0.028ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.644810Z [httpworker.2] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.034ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.644814Z [httpworker.2] [node/miner.cpp:177] [CreateNewBlock] [bench] CreateNewBlock() packages: 0.01ms (1 packages, 0 updated descendants), validity: 0.15ms (total 0.16ms) node0 2024-08-15T17:07:32.644848Z [httpworker.2] [validation.cpp:4395] [AcceptBlockHeader] Saw new header hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 height=205 node0 2024-08-15T17:07:32.644871Z [httpworker.2] [validationinterface.cpp:256] [NewPoWValidBlock] [validation] NewPoWValidBlock: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 node0 2024-08-15T17:07:32.644918Z [httpworker.2] [flatfile.cpp:69] [Allocate] [validation] Pre-allocating up to position 0xc000 in blk00002.dat node0 2024-08-15T17:07:32.645007Z [httpworker.2] [validation.cpp:3168] [ConnectTip] [bench] - Using cached block node0 2024-08-15T17:07:32.645013Z [httpworker.2] [validation.cpp:3178] [ConnectTip] [bench] - Load block from disk: 0.01ms node0 2024-08-15T17:07:32.645028Z [httpworker.2] [validation.cpp:2507] [ConnectBlock] [bench] - Sanity checks: 0.00ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.645047Z [httpworker.2] [validation.cpp:2609] [ConnectBlock] [bench] - Fork checks: 0.02ms [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.645072Z [httpworker.2] [validation.cpp:2701] [ConnectBlock] [bench] - Connect 2 transactions: 0.02ms (0.012ms/tx, 0.024ms/txin) [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.645080Z [httpworker.2] [validation.cpp:2719] [ConnectBlock] [bench] - Verify 1 txins: 0.03ms (0.033ms/txin) [0.00s (0.03ms/blk)] node0 2024-08-15T17:07:32.645098Z [httpworker.2] [validation.cpp:2733] [ConnectBlock] [bench] - Write undo data: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.645102Z [httpworker.2] [validation.cpp:2748] [ConnectBlock] [bench] - Index writing: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.645107Z [httpworker.2] [validationinterface.cpp:251] [BlockChecked] [validation] BlockChecked: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 state=Valid node0 2024-08-15T17:07:32.645112Z [httpworker.2] [validation.cpp:3197] [ConnectTip] [bench] - Connect total: 0.10ms [0.00s (0.06ms/blk)] node0 2024-08-15T17:07:32.645128Z [httpworker.2] [validation.cpp:3206] [ConnectTip] [bench] - Flush: 0.02ms [0.00s (0.01ms/blk)] node0 2024-08-15T17:07:32.645132Z [httpworker.2] [validation.cpp:3216] [ConnectTip] [bench] - Writing chainstate: 0.00ms [0.00s (0.00ms/blk)] node0 2024-08-15T17:07:32.645140Z [httpworker.2] [txmempool.cpp:1039] [RemoveUnbroadcastTx] [mempool] Removed 8366b9a5f3e98dd099c4c4ac90fb271c5413f07e9cd3ea2e6ceb4bdfdcbe1641 from set of unbroadcast txns before confirmation that txn was sent out node0 2024-08-15T17:07:32.645145Z [httpworker.2] [validationinterface.cpp:228] [MempoolTransactionsRemovedForBlock] [validation] Enqueuing MempoolTransactionsRemovedForBlock: block height=205 txs removed=1 node0 2024-08-15T17:07:32.645159Z [httpworker.2] [validation.cpp:2979] [UpdateTipLog] UpdateTip: new best=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 height=205 version=0x30000000 log2_work=8.686501 tx=211 date='2024-08-15T17:07:32Z' progress=1.000000 cache=0.3MiB(3txo) node0 2024-08-15T17:07:32.645163Z [httpworker.2] [validation.cpp:3232] [ConnectTip] [bench] - Connect postprocess: 0.03ms [0.00s (0.02ms/blk)] node0 2024-08-15T17:07:32.645167Z [httpworker.2] [validation.cpp:3236] [ConnectTip] [bench] - Connect block: 0.16ms [0.00s (0.09ms/blk)] node0 2024-08-15T17:07:32.645171Z [httpworker.2] [txmempool.cpp:664] [check] [mempool] Checking mempool with 0 transactions and 0 inputs node0 2024-08-15T17:07:32.645189Z [httpworker.2] [validationinterface.cpp:218] [BlockConnected] [validation] Enqueuing BlockConnected: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 block height=205 node0 2024-08-15T17:07:32.645196Z [httpworker.2] [validationinterface.cpp:183] [UpdatedBlockTip] [validation] Enqueuing UpdatedBlockTip: new block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 fork block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a (in IBD=false) node0 2024-08-15T17:07:32.645203Z [httpworker.2] [validationinterface.cpp:188] [ActiveTipChange] [validation] ActiveTipChange: new block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 block height=205 node0 2024-08-15T17:07:32.645213Z [scheduler] [validationinterface.cpp:228] [operator()] [validation] MempoolTransactionsRemovedForBlock: block height=205 txs removed=1 node0 2024-08-15T17:07:32.645277Z [scheduler] [policy/fees.cpp:708] [processBlock] [estimatefee] Blockpolicy estimates updated by 1 of 1 block txs, since last block 1 of 1 tracked, mempool map size 0, max target 2 from current node0 2024-08-15T17:07:32.645292Z [scheduler] [validationinterface.cpp:218] [operator()] [validation] BlockConnected: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 block height=205 node0 2024-08-15T17:07:32.645337Z [scheduler] [validationinterface.cpp:183] [operator()] [validation] UpdatedBlockTip: new block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 fork block hash=4ae6f4e9878b8cd05d43a4e81ec4eaf9b9982eaaef82a2a729f36a0c4c709d3a (in IBD=false) node0 2024-08-15T17:07:32.645803Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.645846Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=scantxoutset user=__cookie__ node0 2024-08-15T17:07:32.645918Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.646927Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (1.00ms) node0 2024-08-15T17:07:32.646937Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.647570Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.63ms) node0 2024-08-15T17:07:32.647581Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) started node0 2024-08-15T17:07:32.647597Z [httpworker.0] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.647614Z [httpworker.0] [txdb.cpp:148] [BatchWrite] [coindb] Committed 3 changed transaction outputs (out of 3) to coin database... node0 2024-08-15T17:07:32.647635Z [httpworker.0] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (3 coins, 262kB) completed (0.05ms) node0 2024-08-15T17:07:32.647643Z [httpworker.0] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 node0 2024-08-15T17:07:32.647705Z [scheduler] [validationinterface.cpp:246] [operator()] [validation] ChainStateFlushed: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 node0 2024-08-15T17:07:32.690745Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.690828Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ node0 2024-08-15T17:07:32.691244Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.691307Z [httpworker.1] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getbestblockhash user=__cookie__ node0 2024-08-15T17:07:32.691640Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.691704Z [httpworker.2] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=getrawmempool user=__cookie__ test 2024-08-15T17:07:32.692000Z TestFramework (INFO): Shut down node and un-XOR block/undo files manually test 2024-08-15T17:07:32.692000Z TestFramework.node0 (DEBUG): Stopping node node0 2024-08-15T17:07:32.692041Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.692103Z [httpworker.0] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=syncwithvalidationinterfacequeue user=__cookie__ node0 2024-08-15T17:07:32.693138Z [http] [httpserver.cpp:305] [http_request_cb] [http] Received a POST request for / from 127.0.0.1:55196 node0 2024-08-15T17:07:32.693204Z [httpworker.3] [rpc/request.cpp:232] [parse] [rpc] ThreadRPCServer method=stop user=__cookie__ node0 2024-08-15T17:07:32.693234Z [init] [httpserver.cpp:498] [InterruptHTTPServer] [http] Interrupting HTTP server node0 2024-08-15T17:07:32.693252Z [init] [httprpc.cpp:378] [InterruptHTTPRPC] [rpc] Interrupting HTTP RPC server node0 2024-08-15T17:07:32.693258Z [init] [rpc/server.cpp:308] [operator()] [rpc] Interrupting RPC node0 2024-08-15T17:07:32.693303Z [init] [init.cpp:273] [Shutdown] Shutdown: In progress... node0 2024-08-15T17:07:32.693311Z [shutoff] [httprpc.cpp:383] [StopHTTPRPC] [rpc] Stopping HTTP RPC server node0 2024-08-15T17:07:32.693318Z [addcon] [util/thread.cpp:22] [TraceThread] addcon thread exit node0 2024-08-15T17:07:32.693370Z [shutoff] [httpserver.cpp:760] [UnregisterHTTPHandler] [http] Unregistering HTTP handler for / (exactmatch 1) node0 2024-08-15T17:07:32.693382Z [shutoff] [httpserver.cpp:760] [UnregisterHTTPHandler] [http] Unregistering HTTP handler for /wallet/ (exactmatch 0) node0 2024-08-15T17:07:32.693387Z [shutoff] [rpc/server.cpp:320] [operator()] [rpc] Stopping RPC node0 2024-08-15T17:07:32.693436Z [shutoff] [init.cpp:441] [OnRPCStopped] [rpc] RPC stopped. node0 2024-08-15T17:07:32.693442Z [shutoff] [httpserver.cpp:510] [StopHTTPServer] [http] Stopping HTTP server node0 2024-08-15T17:07:32.693461Z [shutoff] [httpserver.cpp:512] [StopHTTPServer] [http] Waiting for HTTP worker threads to exit node0 2024-08-15T17:07:32.693488Z [http] [httpserver.cpp:354] [ThreadHTTP] [http] Exited http event loop node0 2024-08-15T17:07:32.693498Z [shutoff] [httpserver.cpp:540] [StopHTTPServer] [http] Waiting for HTTP event thread to exit node0 2024-08-15T17:07:32.693530Z [shutoff] [httpserver.cpp:546] [StopHTTPServer] [http] Stopped HTTP server node0 2024-08-15T17:07:32.713273Z [net] [util/thread.cpp:22] [TraceThread] net thread exit node0 2024-08-15T17:07:32.745510Z [msghand] [util/thread.cpp:22] [TraceThread] msghand thread exit node0 2024-08-15T17:07:32.746660Z [shutoff] [net.cpp:2328] [DumpAddresses] [net] Flushed 0 addresses to peers.dat 1ms node0 2024-08-15T17:07:32.746721Z [scheduler] [util/thread.cpp:22] [TraceThread] scheduler thread exit node0 2024-08-15T17:07:32.746985Z [shutoff] [node/mempool_persist.cpp:189] [DumpMempool] Writing 0 mempool transactions to file... node0 2024-08-15T17:07:32.746992Z [shutoff] [node/mempool_persist.cpp:199] [DumpMempool] Writing 0 unbroadcast transactions to file. node0 2024-08-15T17:07:32.747699Z [shutoff] [node/mempool_persist.cpp:213] [DumpMempool] Dumped mempool: 0.000s to copy, 0.001s to dump, 27 bytes dumped to file node0 2024-08-15T17:07:32.747708Z [shutoff] [policy/fees.cpp:1058] [FlushUnconfirmed] [estimatefee] Recorded 0 unconfirmed txs from mempool in 0.000s node0 2024-08-15T17:07:32.748559Z [shutoff] [policy/fees.cpp:956] [FlushFeeEstimates] Flushed fee estimates to fee_estimates.dat. node0 2024-08-15T17:07:32.748573Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.749360Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (0.78ms) node0 2024-08-15T17:07:32.749370Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.749993Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.62ms) node0 2024-08-15T17:07:32.750005Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (0 coins, 262kB) started node0 2024-08-15T17:07:32.750020Z [shutoff] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.750036Z [shutoff] [txdb.cpp:148] [BatchWrite] [coindb] Committed 0 changed transaction outputs (out of 0) to coin database... node0 2024-08-15T17:07:32.750056Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (0 coins, 262kB) completed (0.05ms) node0 2024-08-15T17:07:32.750066Z [shutoff] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 node0 2024-08-15T17:07:32.750073Z [shutoff] [validationinterface.cpp:246] [operator()] [validation] ChainStateFlushed: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 node0 2024-08-15T17:07:32.750085Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk started node0 2024-08-15T17:07:32.750827Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block and undo data to disk completed (0.74ms) node0 2024-08-15T17:07:32.750836Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk started node0 2024-08-15T17:07:32.751456Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write block index to disk completed (0.61ms) node0 2024-08-15T17:07:32.751466Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (0 coins, 262kB) started node0 2024-08-15T17:07:32.751479Z [shutoff] [txdb.cpp:146] [BatchWrite] [coindb] Writing final batch of 0.00 MiB node0 2024-08-15T17:07:32.751493Z [shutoff] [txdb.cpp:148] [BatchWrite] [coindb] Committed 0 changed transaction outputs (out of 0) to coin database... node0 2024-08-15T17:07:32.751510Z [shutoff] [logging/timer.h:58] [Log] [bench] FlushStateToDisk: write coins cache to disk (0 coins, 262kB) completed (0.04ms) node0 2024-08-15T17:07:32.751519Z [shutoff] [validationinterface.cpp:246] [ChainStateFlushed] [validation] Enqueuing ChainStateFlushed: block hash=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 node0 2024-08-15T17:07:32.753206Z [shutoff] [init.cpp:387] [Shutdown] Shutdown: done test 2024-08-15T17:07:32.793000Z TestFramework.node0 (DEBUG): Node stopped test 2024-08-15T17:07:32.793000Z TestFramework (DEBUG): Rewriting file /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/blk00000.dat... test 2024-08-15T17:07:32.802000Z TestFramework (DEBUG): Rewriting file /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/blk00001.dat... test 2024-08-15T17:07:32.811000Z TestFramework (DEBUG): Rewriting file /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/blk00002.dat... test 2024-08-15T17:07:32.817000Z TestFramework (DEBUG): Rewriting file /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/rev00000.dat... test 2024-08-15T17:07:32.818000Z TestFramework (DEBUG): Rewriting file /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/rev00001.dat... test 2024-08-15T17:07:32.819000Z TestFramework (DEBUG): Rewriting file /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/rev00002.dat... test 2024-08-15T17:07:32.953000Z TestFramework (INFO): Check that restarting with 'blocksxor=0' fails if XOR key is present test 2024-08-15T17:07:32.953000Z TestFramework.node0 (DEBUG): bitcoind started, waiting for RPC to come up node0 2024-08-15T17:07:32.956504Z [init] [init/common.cpp:149] [LogPackageVersion] Bitcoin Core version v27.99.0-faa1b9b0e6de-dirty (release build) node0 2024-08-15T17:07:32.956507Z [init] [init.cpp:742] [InitParameterInteraction] parameter interaction: -bind set -> setting -listen=1 node0 2024-08-15T17:07:32.956552Z [init] [node/chainstatemanager_args.cpp:57] [ApplyArgsManOptions] Script verification uses 11 additional threads node0 2024-08-15T17:07:32.956667Z [init] [kernel/context.cpp:20] [operator()] Using the 'sse4(1way),sse41(4way),avx2(8way)' SHA256 implementation node0 2024-08-15T17:07:33.075239Z [init] [random.cpp:114] [ReportHardwareRand] Using RdSeed as an additional entropy source node0 2024-08-15T17:07:33.075242Z [init] [random.cpp:117] [ReportHardwareRand] Using RdRand as an additional entropy source node0 2024-08-15T17:07:33.076721Z [init] [init/common.cpp:120] [StartLogging] Default data directory /home/gloria/.bitcoin node0 2024-08-15T17:07:33.076724Z [init] [init/common.cpp:121] [StartLogging] Using data directory /tmp/bitcoin_func_test_fj812gkv/node0/regtest node0 2024-08-15T17:07:33.076730Z [init] [init/common.cpp:126] [StartLogging] Config file: /tmp/bitcoin_func_test_fj812gkv/node0/bitcoin.conf node0 2024-08-15T17:07:33.076738Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: regtest="1" node0 2024-08-15T17:07:33.076743Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] bind="127.0.0.1" node0 2024-08-15T17:07:33.076747Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] connect="0" node0 2024-08-15T17:07:33.076751Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] deprecatedrpc="create_bdb" node0 2024-08-15T17:07:33.076754Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] discover="0" node0 2024-08-15T17:07:33.076757Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] dnsseed="0" node0 2024-08-15T17:07:33.076760Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] fallbackfee="0.0002" node0 2024-08-15T17:07:33.076763Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] fixedseeds="0" node0 2024-08-15T17:07:33.076766Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] keypool="1" node0 2024-08-15T17:07:33.076769Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] listenonion="0" node0 2024-08-15T17:07:33.076772Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] natpmp="0" node0 2024-08-15T17:07:33.076775Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] peertimeout="999999999" node0 2024-08-15T17:07:33.076779Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] port="15515" node0 2024-08-15T17:07:33.076782Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] printtoconsole="0" node0 2024-08-15T17:07:33.076786Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] rpcdoccheck="1" node0 2024-08-15T17:07:33.076790Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] rpcport="20515" node0 2024-08-15T17:07:33.076794Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] rpcservertimeout="99000" node0 2024-08-15T17:07:33.076797Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] server="1" node0 2024-08-15T17:07:33.076800Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] shrinkdebugfile="0" node0 2024-08-15T17:07:33.076804Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] unsafesqlitesync="1" node0 2024-08-15T17:07:33.076807Z [init] [common/args.cpp:825] [logArgsPrefix] Config file arg: [regtest] upnp="0" node0 2024-08-15T17:07:33.076810Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: blocksxor="0" node0 2024-08-15T17:07:33.076814Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: datadir="/tmp/bitcoin_func_test_fj812gkv/node0" node0 2024-08-15T17:07:33.076821Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debug="" node0 2024-08-15T17:07:33.076824Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debugexclude="libevent" node0 2024-08-15T17:07:33.076828Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debugexclude="leveldb" node0 2024-08-15T17:07:33.076831Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: debugexclude="rand" node0 2024-08-15T17:07:33.076833Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: disablewallet="" node0 2024-08-15T17:07:33.076837Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: loglevel="trace" node0 2024-08-15T17:07:33.076840Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: logsourcelocations="" node0 2024-08-15T17:07:33.076843Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: logthreadnames="" node0 2024-08-15T17:07:33.076846Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: logtimemicros="" node0 2024-08-15T17:07:33.076850Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: uacomment="testnode0" node0 2024-08-15T17:07:33.076853Z [init] [common/args.cpp:825] [logArgsPrefix] Command-line arg: v2transport="0" node0 2024-08-15T17:07:33.076856Z [init] [init.cpp:1158] [AppInitMain] Using at most 125 automatic connections (1024 file descriptors available) node0 2024-08-15T17:07:33.076907Z [init] [wallet/init.cpp:129] [Construct] Wallet disabled! node0 2024-08-15T17:07:33.076956Z [scheduler] [util/thread.cpp:20] [TraceThread] scheduler thread start node0 2024-08-15T17:07:33.078138Z [init] [httpserver.cpp:238] [InitHTTPAllowList] [http] Allowing HTTP connections from: 127.0.0.0/8 ::1/128 node0 2024-08-15T17:07:33.078179Z [init] [httpserver.cpp:384] [HTTPBindAddresses] Binding RPC on address ::1 port 20515 node0 2024-08-15T17:07:33.078253Z [init] [httpserver.cpp:384] [HTTPBindAddresses] Binding RPC on address 127.0.0.1 port 20515 node0 2024-08-15T17:07:33.078278Z [init] [httpserver.cpp:463] [InitHTTPServer] [http] Initialized HTTP server node0 2024-08-15T17:07:33.078284Z [init] [httpserver.cpp:465] [InitHTTPServer] [http] creating work queue of depth 16 node0 2024-08-15T17:07:33.078287Z [init] [rpc/server.cpp:298] [StartRPC] [rpc] Starting RPC node0 2024-08-15T17:07:33.078297Z [init] [httprpc.cpp:360] [StartHTTPRPC] [rpc] Starting HTTP RPC server node0 2024-08-15T17:07:33.078301Z [init] [httprpc.cpp:297] [InitRPCAuthentication] Using random cookie authentication. node0 2024-08-15T17:07:33.078346Z [init] [rpc/request.cpp:132] [GenerateAuthCookie] Generated RPC authentication cookie /tmp/bitcoin_func_test_fj812gkv/node0/regtest/.cookie node0 2024-08-15T17:07:33.078351Z [init] [rpc/request.cpp:133] [GenerateAuthCookie] Permissions used for cookie: rw------- node0 2024-08-15T17:07:33.078358Z [init] [httpserver.cpp:745] [RegisterHTTPHandler] [http] Registering HTTP handler for / (exactmatch 1) node0 2024-08-15T17:07:33.078361Z [init] [httpserver.cpp:745] [RegisterHTTPHandler] [http] Registering HTTP handler for /wallet/ (exactmatch 0) node0 2024-08-15T17:07:33.078365Z [init] [httpserver.cpp:488] [StartHTTPServer] Starting HTTP server with 4 worker threads node0 2024-08-15T17:07:33.078456Z [init] [init.cpp:1265] [AppInitMain] Using /16 prefix for IP bucketing node0 2024-08-15T17:07:33.078464Z [http] [httpserver.cpp:351] [ThreadHTTP] [http] Entering http event loop node0 2024-08-15T17:07:33.078532Z [init] [noui.cpp:57] [noui_InitMessage] init message: Loading P2P addresses… node0 2024-08-15T17:07:33.078699Z [init] [logging/timer.h:58] [Log] [addrman] CheckAddrman: new 0, tried 0, total 0 started node0 2024-08-15T17:07:33.078746Z [init] [logging/timer.h:58] [Log] [addrman] CheckAddrman: completed (0.04ms) node0 2024-08-15T17:07:33.078752Z [init] [addrdb.cpp:202] [LoadAddrman] Loaded 0 addresses from peers.dat 0ms node0 2024-08-15T17:07:33.078918Z [init] [noui.cpp:57] [noui_InitMessage] init message: Loading banlist… node0 2024-08-15T17:07:33.078953Z [init] [banman.cpp:40] [LoadBanlist] [net] Loaded 0 banned node addresses/subnets 0ms node0 2024-08-15T17:07:33.078969Z [init] [net.cpp:2360] [SetTryNewOutboundPeer] [net] setting try another outbound peer=false node0 2024-08-15T17:07:33.078974Z [init] [net.cpp:3136] [SetNetworkActive] SetNetworkActive: true node0 2024-08-15T17:07:33.079743Z [init] [policy/fees.cpp:470] [Read] [estimatefee] Reading estimates: 190 buckets counting confirms up to 48 blocks node0 2024-08-15T17:07:33.079861Z [init] [policy/fees.cpp:470] [Read] [estimatefee] Reading estimates: 190 buckets counting confirms up to 12 blocks node0 2024-08-15T17:07:33.080240Z [init] [policy/fees.cpp:470] [Read] [estimatefee] Reading estimates: 190 buckets counting confirms up to 1008 blocks node0 2024-08-15T17:07:33.080342Z [init] [node/chainstatemanager_args.cpp:57] [ApplyArgsManOptions] Script verification uses 11 additional threads node0 2024-08-15T17:07:33.080350Z [init] [init.cpp:1517] [AppInitMain] Cache configuration: node0 2024-08-15T17:07:33.080356Z [init] [init.cpp:1518] [AppInitMain] * Using 2.0 MiB for block index database node0 2024-08-15T17:07:33.080360Z [init] [init.cpp:1526] [AppInitMain] * Using 8.0 MiB for chain state database node0 2024-08-15T17:07:33.080394Z [init] [init.cpp:1549] [AppInitMain] * Using 440.0 MiB for in-memory UTXO set (plus up to 286.1 MiB of unused mempool space) node0 2024-08-15T17:07:33.080692Z [init] [node/blockstorage.cpp:1162] [InitBlocksdirXorKey] fs::is_empty(opts.blocks_dir): 0 node0 2024-08-15T17:07:33.080714Z [init] [node/blockstorage.cpp:1163] [InitBlocksdirXorKey] fs::exists(xor_key_path): 1 node0 2024-08-15T17:07:33.080717Z [init] [node/blockstorage.cpp:1164] [InitBlocksdirXorKey] opts.use_xor: 0 node0 2024-08-15T17:07:33.080727Z [init] [node/blockstorage.cpp:1189] [InitBlocksdirXorKey] Using obfuscation key for blocksdir *.dat files (/tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks): '0000000000000000' node0 2024-08-15T17:07:33.085030Z [init] [script/sigcache.cpp:36] [SignatureCache] Using 16 MiB out of 16 MiB requested for signature cache, able to store 524288 elements node0 2024-08-15T17:07:33.089216Z [init] [validation.cpp:2125] [ValidationCache] Using 16 MiB out of 16 MiB requested for script execution cache, able to store 524288 elements node0 2024-08-15T17:07:33.089241Z [init] [noui.cpp:57] [noui_InitMessage] init message: Loading block index… node0 2024-08-15T17:07:33.089246Z [init] [node/chainstate.cpp:168] [LoadChainstate] Validating signatures for all blocks. node0 2024-08-15T17:07:33.089252Z [init] [node/chainstate.cpp:170] [LoadChainstate] Setting nMinimumChainWork=0000000000000000000000000000000000000000000000000000000000000000 node0 2024-08-15T17:07:33.089309Z [init] [dbwrapper.cpp:241] [CDBWrapper] Opening LevelDB in /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/index node0 2024-08-15T17:07:33.125527Z [init] [dbwrapper.cpp:249] [CDBWrapper] Opened LevelDB successfully node0 2024-08-15T17:07:33.125545Z [init] [dbwrapper.cpp:274] [CDBWrapper] Using obfuscation key for /tmp/bitcoin_func_test_fj812gkv/node0/regtest/blocks/index: 0000000000000000 node0 2024-08-15T17:07:33.125956Z [init] [node/blockstorage.cpp:508] [LoadBlockIndexDB] LoadBlockIndexDB: last block file = 2 node0 2024-08-15T17:07:33.125979Z [init] [node/blockstorage.cpp:512] [LoadBlockIndexDB] LoadBlockIndexDB: last block file info: CBlockFileInfo(blocks=2, size=40600, heights=204...205, time=2024-08-15...2024-08-15) node0 2024-08-15T17:07:33.125987Z [init] [node/blockstorage.cpp:523] [LoadBlockIndexDB] Checking all blk files are present... node0 2024-08-15T17:07:33.126056Z [init] [node/chainstate.cpp:108] [CompleteChainstateInitialization] Initializing chainstate Chainstate [ibd] @ height -1 (null) node0 2024-08-15T17:07:33.126068Z [init] [dbwrapper.cpp:241] [CDBWrapper] Opening LevelDB in /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate node0 2024-08-15T17:07:33.128906Z [init] [dbwrapper.cpp:249] [CDBWrapper] Opened LevelDB successfully node0 2024-08-15T17:07:33.128942Z [init] [dbwrapper.cpp:274] [CDBWrapper] Using obfuscation key for /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate: 8a556cbbe58ab7be node0 2024-08-15T17:07:33.129036Z [init] [validation.cpp:4695] [LoadChainTip] Loaded best chain: hashBestChain=3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124 height=205 date=2024-08-15T17:07:32Z progress=1.000000 node0 2024-08-15T17:07:33.129078Z [init] [dbwrapper.cpp:241] [CDBWrapper] Opening LevelDB in /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate node0 2024-08-15T17:07:33.131151Z [init] [dbwrapper.cpp:249] [CDBWrapper] Opened LevelDB successfully node0 2024-08-15T17:07:33.131186Z [init] [dbwrapper.cpp:274] [CDBWrapper] Using obfuscation key for /tmp/bitcoin_func_test_fj812gkv/node0/regtest/chainstate: 8a556cbbe58ab7be node0 2024-08-15T17:07:33.131196Z [init] [validation.cpp:5560] [ResizeCoinsCaches] [Chainstate [ibd] @ height 205 (3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124)] resized coinsdb cache to 8.0 MiB node0 2024-08-15T17:07:33.131202Z [init] [validation.cpp:5562] [ResizeCoinsCaches] [Chainstate [ibd] @ height 205 (3450b3c4cf92ee0ce2cec05463b28c668519eb7c70f398bd10e37949de611124)] resized coinstip cache to 440.0 MiB node0 2024-08-15T17:07:33.131211Z [init] [noui.cpp:57] [noui_InitMessage] init message: Verifying blocks… node0 2024-08-15T17:07:33.131216Z [init] [validation.cpp:4727] [VerifyDB] Verifying last 6 blocks at level 3 node0 2024-08-15T17:07:33.131236Z [init] [validation.cpp:4736] [VerifyDB] Verification progress: 0% node0 2024-08-15T17:07:33.131456Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 16% node0 2024-08-15T17:07:33.131633Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 33% node0 2024-08-15T17:07:33.131809Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 50% node0 2024-08-15T17:07:33.131979Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 66% node0 2024-08-15T17:07:33.132153Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 83% node0 2024-08-15T17:07:33.132195Z [init] [validation.cpp:4744] [VerifyDB] Verification progress: 99% node0 2024-08-15T17:07:33.132200Z [init] [validation.cpp:4838] [VerifyDB] Verification: No coin database inconsistencies in last 6 blocks (11 transactions) node0 2024-08-15T17:07:33.132210Z [init] [init.cpp:1611] [AppInitMain] block index 42ms node0 2024-08-15T17:07:33.132237Z [init] [init.cpp:1698] [AppInitMain] Setting NODE_NETWORK on non-prune mode node0 2024-08-15T17:07:33.132288Z [init] [init.cpp:1808] [AppInitMain] block tree size = 206 node0 2024-08-15T17:07:33.132292Z [init] [init.cpp:1821] [AppInitMain] nBestHeight = 205 node0 2024-08-15T17:07:33.132333Z [initload] [util/thread.cpp:20] [TraceThread] initload thread start node0 2024-08-15T17:07:33.132367Z [initload] [node/mempool_persist.cpp:73] [LoadMempool] Loading 0 mempool transactions from file... node0 2024-08-15T17:07:33.132372Z [initload] [node/mempool_persist.cpp:145] [LoadMempool] Imported mempool transactions from file: 0 succeeded, 0 failed, 0 expired, 0 already there, 0 waiting for initial broadcast node0 2024-08-15T17:07:33.132377Z [initload] [util/thread.cpp:22] [TraceThread] initload thread exit node0 2024-08-15T17:07:33.132383Z [init] [net.cpp:3073] [BindListenPort] Bound to 127.0.0.1:15515 node0 2024-08-15T17:07:33.132396Z [init] [noui.cpp:57] [noui_InitMessage] init message: Starting network threads… node0 2024-08-15T17:07:33.132424Z [init] [net.cpp:3297] [Start] DNS seeding disabled node0 2024-08-15T17:07:33.132458Z [net] [util/thread.cpp:20] [TraceThread] net thread start node0 2024-08-15T17:07:33.132539Z [msghand] [util/thread.cpp:20] [TraceThread] msghand thread start node0 2024-08-15T17:07:33.132586Z [init] [noui.cpp:57] [noui_InitMessage] init message: Done loading node0 2024-08-15T17:07:33.132629Z [addcon] [util/thread.cpp:20] [TraceThread] addcon thread start node0 2024-08-15T17:08:18.132719Z [scheduler] [net.cpp:2365] [StartExtraBlockRelayPeers] [net] enabling extra block-relay-only peers test 2024-08-15T17:08:32.954000Z TestFramework (ERROR): Assertion failed Traceback (most recent call last): File "/home/gloria/bitcoin/test/functional/test_framework/test_node.py", line 652, in assert_start_raises_init_error ret = self.process.wait(timeout=self.rpc_timeout) File "/usr/lib/python3.10/subprocess.py", line 1209, in wait return self._wait(timeout=timeout) File "/usr/lib/python3.10/subprocess.py", line 1951, in _wait raise TimeoutExpired(self.args, timeout) subprocess.TimeoutExpired: Command '['/home/gloria/bitcoin/src/bitcoind', '-datadir=/tmp/bitcoin_func_test_fj812gkv/node0', '-logtimemicros', '-debug', '-debugexclude=libevent', '-debugexclude=leveldb', '-debugexclude=rand', '-uacomment=testnode0', '-disablewallet', '-logthreadnames', '-logsourcelocations', '-loglevel=trace', '-v2transport=0', '-blocksxor=0']' timed out after 60 seconds During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/home/gloria/bitcoin/test/functional/test_framework/test_framework.py", line 132, in main self.run_test() File "/home/gloria/bitcoin/test/functional/feature_blocksxor.py", line 52, in run_test node.assert_start_raises_init_error(['-blocksxor=0'], File "/home/gloria/bitcoin/test/functional/test_framework/test_node.py", line 682, in assert_start_raises_init_error self._raise_assertion_error(assert_msg) File "/home/gloria/bitcoin/test/functional/test_framework/test_node.py", line 192, in _raise_assertion_error raise AssertionError(self._node_msg(msg)) AssertionError: [node 0] bitcoind should have exited within 60s with expected error The blocksdir XOR-key can not be disabled when a random key was already stored! test 2024-08-15T17:08:32.955000Z TestFramework (DEBUG): Closing down network thread test 2024-08-15T17:08:33.005000Z TestFramework (INFO): Stopping nodes test 2024-08-15T17:08:33.005000Z TestFramework (WARNING): Not cleaning up dir /tmp/bitcoin_func_test_fj812gkv test 2024-08-15T17:08:33.005000Z TestFramework (ERROR): Test failed. Test logging available at /tmp/bitcoin_func_test_fj812gkv/test_framework.log test 2024-08-15T17:08:33.005000Z TestFramework (ERROR): test 2024-08-15T17:08:33.005000Z TestFramework (ERROR): Hint: Call /home/gloria/bitcoin/test/functional/combine_logs.py '/tmp/bitcoin_func_test_fj812gkv' to consolidate all logs test 2024-08-15T17:08:33.005000Z TestFramework (ERROR): test 2024-08-15T17:08:33.005000Z TestFramework (ERROR): If this failure happened unexpectedly or intermittently, please file a bug and provide a link or upload of the combined log. test 2024-08-15T17:08:33.005000Z TestFramework (ERROR): https://github.com/bitcoin/bitcoin/issues test 2024-08-15T17:08:33.006000Z TestFramework (ERROR):