2025-12-29T03:24:57.451Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959418, "block": "b20548a432a02d0829fb1c99075c834ebac4d6a4ed8d72c6a84b6b8de1bbb7d6"} 2025-12-29T03:24:57.451Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959418, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.451Z DEBUG miner/protocol_receive.go:130 verify block processed {"round": 5959418, "block": "b20548a432a02d0829fb1c99075c834ebac4d6a4ed8d72c6a84b6b8de1bbb7d6"} 2025-12-29T03:24:57.451Z DEBUG miner/protocol_round.go:759 update prev block notarization, already notarized {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.451Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959418, "block hash": "b20548a432a02d0829fb1c99075c834ebac4d6a4ed8d72c6a84b6b8de1bbb7d6", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.451Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959418, "block": "b20548a432a02d0829fb1c99075c834ebac4d6a4ed8d72c6a84b6b8de1bbb7d6", "prev_block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "state_hash": "d75144dd58f16b69189b4d10d760f36375a188bd39ac29d27b121d8e8fcf82a5", "weight": 1} 2025-12-29T03:24:57.451Z INFO miner/protocol_round.go:613 handle verify block - added block for ticket verification {"round": 5959418, "block": "b20548a432a02d0829fb1c99075c834ebac4d6a4ed8d72c6a84b6b8de1bbb7d6", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.451Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 0, "current_round": 5959418, "round": 5959418} 2025-12-29T03:24:57.450Z DEBUG miner/protocol_receive.go:169 verify block {"round": 5959418, "block": "b20548a432a02d0829fb1c99075c834ebac4d6a4ed8d72c6a84b6b8de1bbb7d6"} 2025-12-29T03:24:57.450Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "82.661µs"} 2025-12-29T03:24:57.450Z DEBUG miner/worker.go:53 message {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:57.448Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.447Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959418, "block": "ffc15f712f8488d697c2390787a0b0bba9119e8089004f1bf81a21e808047116"} 2025-12-29T03:24:57.447Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959418, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.447Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959418, "block hash": "ffc15f712f8488d697c2390787a0b0bba9119e8089004f1bf81a21e808047116", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.447Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959418, "block": "ffc15f712f8488d697c2390787a0b0bba9119e8089004f1bf81a21e808047116", "prev_block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "state_hash": "9af41cf0b31cd0a3440ecd5c4fa5f0eee1f82f640ff25c914d012f4d310a4124", "weight": 1} 2025-12-29T03:24:57.447Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959418, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.447Z DEBUG statecache/statecache.go:131 statecache - commit {"block": "ffc15f712f8488d697c2390787a0b0bba9119e8089004f1bf81a21e808047116", "bc_hits": 123, "bc_miss": 0, "sc_hits": 884859374, "sc_miss": 448360, "duration": "612.319µs"} 2025-12-29T03:24:57.446Z INFO miner/protocol_block.go:1316 generate block (assemble+update+sign) {"round": 5959418, "block_size": 2, "reused_txns": 0, "reused_txns": 0, "time": "21.688912ms", "block": "ffc15f712f8488d697c2390787a0b0bba9119e8089004f1bf81a21e808047116", "prev_block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "begin_state_hash": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285", "block_state_hash": "9af41cf0b31cd0a3440ecd5c4fa5f0eee1f82f640ff25c914d012f4d310a4124", "computed_state_hash": "9af41cf0b31cd0a3440ecd5c4fa5f0eee1f82f640ff25c914d012f4d310a4124", "changes": 18, "state_status": 4, "iteration_count": 0} 2025-12-29T03:24:57.446Z DEBUG miner/protocol_block.go:1305 generate block (assemble+update) {"round": 5959418, "txns": 2, "time": "21.441298ms"} 2025-12-29T03:24:57.446Z DEBUG miner/protocol_block.go:1282 generate block (assemble) {"round": 5959418, "txns": 2, "time": "21.382457ms"} 2025-12-29T03:24:57.445Z DEBUG chain/state.go:961 Updating nonce {"client": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "new_nonce": 3056247} 2025-12-29T03:24:57.444Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.444Z INFO chain/state.go:533 SC executed {"client id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "block": "", "round": 5959418, "prev_state_hash": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285", "txn_hash": "656920a88c507cace7fe5aebfe0fd003dd606a0441990a56c4c3f024805a2c55", "txn_nonce": 3056247, "txn_func": "generate_challenge", "txn_status": 0, "txn_exec_time": "496.307µs", "begin client state": "a059665fdf2a0b390e6f4471bf98d876b028a15a725ca15300845956d44df349", "current_root": "a059665fdf2a0b390e6f4471bf98d876b028a15a725ca15300845956d44df349", "mpt_cache_hit": 15, "mpt_cache_miss": 0, "output": "OpenChallenges generated"} 2025-12-29T03:24:57.444Z INFO storagesc/challenge.go:1191 skipping generate challenge: empty blobber challenge partition 2025-12-29T03:24:57.443Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "eaf4195ad8a6c0bd8f90e9b28f4ba3b4938ddc60155952285997f669446bb502"} 2025-12-29T03:24:57.443Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.443Z DEBUG chain/protocol_view_change.go:424 [mvc] nonce, set nonce in getCurrentSelfNonce {"nonce": 3056246} 2025-12-29T03:24:57.442Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.442Z DEBUG chain/protocol_view_change.go:431 [mvc] nonce, get current miner nonce {"minerId": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea"} 2025-12-29T03:24:57.440Z DEBUG chain/state.go:961 Updating nonce {"client": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "new_nonce": 3056246} 2025-12-29T03:24:57.439Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.439Z INFO chain/state.go:533 SC executed {"client id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "block": "", "round": 5959418, "prev_state_hash": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285", "txn_hash": "b3ed42e3553ea3622af68b77f00b7c2bd44dc3bf4ef0429e4fc93c11179ac8e9", "txn_nonce": 3056246, "txn_func": "payFees", "txn_status": 0, "txn_exec_time": "9.684283ms", "begin client state": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285", "current_root": "b2fc41e0f26710432365619b00f377473429c2b60d6da99843368ad6e846c59a", "mpt_cache_hit": 72, "mpt_cache_miss": 0, "output": ""} 2025-12-29T03:24:57.438Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.438Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.437Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.436Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.430Z ERROR minersc/global_node.go:372 failed to get current magic block {"error": "value not present"} 0chain.net/smartcontract/minersc.(*GlobalNode).prevMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/global_node.go:372 0chain.net/smartcontract/minersc.getRegisterShardersInMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:659 0chain.net/smartcontract/minersc.(*MinerSmartContract).payFees /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:519 0chain.net/smartcontract/minersc.(*MinerSmartContract).Execute /0chain/code/go/0chain.net/smartcontract/minersc/sc.go:139 0chain.net/chaincore/smartcontract.ExecuteWithStats /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:52 0chain.net/chaincore/smartcontract.ExecuteSmartContract /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:67 0chain.net/chaincore/chain.(*Chain).ExecuteSmartContract.func1 /0chain/code/go/0chain.net/chaincore/chain/state.go:158 2025-12-29T03:24:57.430Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9d2208fe938b7465b9c5dc571053986cf8dbf3585fb36399f98ea569c42f50b76"} 2025-12-29T03:24:57.430Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9b5e8a1650666fdd3a9de7855b6da63ab629b1701f23738477e2325f7ca2150ac"} 2025-12-29T03:24:57.430Z DEBUG minersc/fees.go:480 pay_fees, got miner id successfully {"miner id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "round": 5959418, "block": ""} 2025-12-29T03:24:57.429Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.429Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.429Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.429Z DEBUG chain/protocol_view_change.go:424 [mvc] nonce, set nonce in getCurrentSelfNonce {"nonce": 3056245} 2025-12-29T03:24:57.429Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "ߧ\ufffd\ufffd\u0018Mb\ufffdD&\ufffd\ufffdD\ufffd\ufffdZk\ufffd\ufffd\u0000d\u0015\u001dc{\ufffd\ufffd\ufffd\ufffd\ufffd\u0007"} 2025-12-29T03:24:57.429Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "7c\ufffdC'\ufffd(\ufffdt\ufffd\ufffd\ufffd\ufffdR\ufffd\u0018i8=\u0018\ufffde\ufffd\ufffda\u0011\u0006\u001f\ufffd\ufffdtk"} 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd2P7\ufffdS\ufffd\ufffd`\u0004\ufffd\ufffd*Ya\ufffdH\ufffd\ufffd\ufffd\ufffd\ufffd\ufffdF\ufffd\ufffd\u0006\ufffd\ufffd\ufffd"} 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "4_\ufffd\ufffd\u0003\ufffd\ufffd\ufffd\ufffd<\ufffd\ufffdh}\u0012R\u0010\ufffd\ufffd\ufffd\ufffd\ufffd\ufffdi\ufffd\u001d\u0017\ufffd\u0010i\ufffd\u0019"} 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdO+\rI:\ufffd;\ufffd\ufffd\ufffd\ufffd\ufffd\ufffdӡϸ*i1\ufffd\u0007żA\u0018\ufffd\ufffdfV\ufffd"} 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "[H\u0004v\ufffd2\ufffd\ufffd\ufffdJ\u0019һ\ufffdMd\ufffd\ufffd\u0015ePA\ufffd@\ufffd\ufffd\ufffd\ufffdfH\u001e\ufffd"} 2025-12-29T03:24:57.428Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959418, "below_round": 5959365, "before": 1766978637, "total": 304, "count": 0} 2025-12-29T03:24:57.428Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "-_qY$\ufffdzr\u0011\ufffdN\u001c\ufffds\ufffd~\u0010|ю\ufffd*\ufffd\ufffdX\ufffd\ufffdw\ufffd\ufffd/0"} 2025-12-29T03:24:57.428Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.428Z INFO chain/protocol_round.go:326 finalize round - latest finalized round {"round": 5959417, "lfb round": 5959416, "lfb block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.428Z INFO chain/protocol_round.go:199 finalize round {"round": 5959417, "plfb_round": 5959414, "num_round_notarized": 1, "num_chain_notarized": 3} 2025-12-29T03:24:57.428Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959418, "below_round": 5959364, "before": 1766978637, "total": 304, "count": 0} 2025-12-29T03:24:57.428Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "W;η\ufffdk!\ufffd\ufffdm\u0019\ufffd6U]b\ufffd\ufffd]%3\ufffdi)F7\ufffd\ufffdٱ\u001a\ufffd"} 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\u0012\ufffdG̔\ufffdC\ufffd\ufffd\ufffdTW\ufffd-\ufffdcb?\u0000\ufffd했\ufffd\ufffdQ_\ufffd\ufffd\ufffd"} 2025-12-29T03:24:57.428Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.428Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.428Z INFO chain/protocol_round.go:402 finalize round - finalize block success {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "duration": "5.951789ms"} 2025-12-29T03:24:57.428Z DEBUG chain/protocol_view_change.go:431 [mvc] nonce, get current miner nonce {"minerId": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea"} 2025-12-29T03:24:57.428Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.427Z DEBUG chain/worker.go:230 finalize block processed {"round": 5959414, "duration": "5.906247ms"} 2025-12-29T03:24:57.427Z DEBUG chain/protocol_block.go:612 finalized block - done {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "duration": "5.697205ms"} 2025-12-29T03:24:57.427Z INFO chain/entity.go:2366 config has been updated successfully {"start of round": 5959414} 2025-12-29T03:24:57.427Z INFO miner/protocol_block.go:1112 generate block starting iteration {"round": 5959418, "prev_block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "prev_state_hash": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285"} 2025-12-29T03:24:57.427Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdBr\\\ufffd\u0014!\ufffdK\ufffd/\ufffd;\u001c\ufffdC\r\ufffdC\ufffd\ufffd\u0015\ufffd\u0008\ufffd\ufffd'\ufffd$ \u000bC"} 2025-12-29T03:24:57.427Z DEBUG statecache/statecache.go:173 state cache - see gap {"block": "95ca3b3cd06bbb4242b9c93b7d97443dde54f64ab413a558c94c77e427e687dc"} 2025-12-29T03:24:57.427Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdR\ufffdu\ufffdX\ufffdj\ufffd\ufffd\ufffd\ufffd\ufffd\u001ce~}\ufffd\ufffdڊ\\ң\ufffd/\ufffd\ufffd\ufffd>C"} 2025-12-29T03:24:57.427Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0006'\u0012\ufffdu\tWg\ufffdt\ufffd\ufffd\r\ufffd\ufffd\ufffd\u0007ݏ\ufffd\ufffd3\u0015i\ufffd\ufffd\ufffd\ufffd\ufffdkZy"} 2025-12-29T03:24:57.427Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0014\ufffd\ufffd2U\u0015\ufffd(9\ufffd\ufffd\ufffdj\ufffd\u0003\ufffd E甠{\ufffd\ufffd\ufffd\ufffd\u0014b\ufffd"} 2025-12-29T03:24:57.427Z DEBUG transaction/worker.go:253 [mvc] see no old txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056243} 2025-12-29T03:24:57.427Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\ufffd\ufffdK\ufffd7|(H\ufffd\ufffdɲ!a\ufffdQU:f\ufffdm&\u000f\ufffd\ufffd\ufffd\ufffd\t\ufffd\ufffd"} 2025-12-29T03:24:57.426Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.426Z DEBUG transaction/worker.go:221 [mvc] remove old nonce txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056243} 2025-12-29T03:24:57.426Z DEBUG chain/lfb_round_node.go:33 [mvc] store lfb {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "mb number": 1} 2025-12-29T03:24:57.426Z DEBUG chain/worker.go:443 BC is moving {"current_lfb_round": 5959414, "last_round": 5959413} 2025-12-29T03:24:57.426Z DEBUG chain/entity.go:2269 set lfb {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "state_computed": true} 2025-12-29T03:24:57.426Z DEBUG chain/state.go:128 finalize round - rebased current state db {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "state hash": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188"} 2025-12-29T03:24:57.426Z DEBUG util/mpt_nodedb.go:556 LevelNodeDB rebase db 2025-12-29T03:24:57.426Z INFO transaction/worker.go:164 cleaning transactions {"collection": "collection.txn:0afc093ffb509f059c55478bc1a60351cef7b4e9c008a53a6cc8241ca8617dfe", "missing_count": 2, "txns": ["6fd627ed4b371ab9ca85b9fb0b2b7c686e8abeceb6bedca83b5d88e599817390", "273b03573f4d312742ed47b5443ca5b86847ea9cac079a72b98216b1ef2a548f"], "past txns": []} 2025-12-29T03:24:57.426Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.426Z DEBUG statecache/statecache.go:173 state cache - see gap {"block": "95ca3b3cd06bbb4242b9c93b7d97443dde54f64ab413a558c94c77e427e687dc"} 2025-12-29T03:24:57.426Z DEBUG chain/protocol_block.go:424 finalize block - record dead nodes {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "num dead nodes": 23} 2025-12-29T03:24:57.426Z DEBUG transaction/worker.go:124 cleaning past transactions 2025-12-29T03:24:57.426Z DEBUG miner/round.go:238 Round clear - cancel verification 2025-12-29T03:24:57.425Z DEBUG miner/chain.go:313 delete txns {"txns": ["6fd627ed4b371ab9ca85b9fb0b2b7c686e8abeceb6bedca83b5d88e599817390", "273b03573f4d312742ed47b5443ca5b86847ea9cac079a72b98216b1ef2a548f"]} 2025-12-29T03:24:57.425Z INFO miner/protocol_block.go:619 update finalized block (debug transaction) {"txn": "273b03573f4d312742ed47b5443ca5b86847ea9cac079a72b98216b1ef2a548f", "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:57.425Z INFO miner/protocol_block.go:619 update finalized block (debug transaction) {"txn": "6fd627ed4b371ab9ca85b9fb0b2b7c686e8abeceb6bedca83b5d88e599817390", "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:57.425Z INFO miner/protocol_block.go:613 update finalized block {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "lf_round": 5959413, "current_round": 5959418, "weight": 1} 2025-12-29T03:24:57.425Z DEBUG chain/protocol_block.go:409 MPT state node stat - inc {"node num": -18147774, "change num": 18, "delete num": 23} 2025-12-29T03:24:57.425Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "173.742µs"} 2025-12-29T03:24:57.425Z INFO miner/protocol_bls.go:357 Ignoring VRFShare. Already at threshold {"Round": 5959418, "VRF_Shares": 2, "bls_threshold": 2} 2025-12-29T03:24:57.425Z DEBUG miner/protocol_receive.go:26 handle vrf share {"round": 5959418, "vrf_timeout_count": 0, "sender_index": 0} 2025-12-29T03:24:57.425Z DEBUG miner/worker.go:53 message {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:57.424Z INFO block/entity.go:1195 save state {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "block_size": 2, "changes": 18, "client_state": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188", "duration": "2.496017ms"} 2025-12-29T03:24:57.424Z INFO miner/protocol_bls.go:481 DKG ThresholdNumSigReceived VRF is already completed. {"round": 5959418} 2025-12-29T03:24:57.424Z INFO round/entity.go:675 add_vrf_share already at threshold. Returning false. 2025-12-29T03:24:57.424Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959418, "node_id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa", "from": "1c3227e496fdf3c9aa604f3470a2bbd1", "message": "59594180ddf09718d123651"} 2025-12-29T03:24:57.424Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959418, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.424Z DEBUG miner/protocol_round.go:467 Setting LFMB round/hash for a block {"rn": 5959418, "mc.crn": 5959418, "rnoff": 5959393, "nvc": 0, "r": 0, "h": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "b.lfmbr": 0, "b.lfmbh": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4"} 2025-12-29T03:24:57.424Z DEBUG util/merkle_patricia_trie.go:272 MPT save changes success {"duration": "1.953459ms"} 2025-12-29T03:24:57.424Z INFO miner/protocol_round.go:830 Starting round verification {"round": 5959418, "delta": "180ms"} 2025-12-29T03:24:57.424Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959418, "r.minerPerm": [2, 1, 0]} 2025-12-29T03:24:57.424Z DEBUG util/mpt_node_change.go:156 MPT - update changes done {"changes": 18} 2025-12-29T03:24:57.424Z INFO miner/protocol_round.go:340 *** TOC_FIX starting round block generation *** {"round": 5959418, "index": 2, "rank": 0, "timeout_count": 0, "random_seed": 899085639225023966, "lf_round": 5959413} 2025-12-29T03:24:57.424Z INFO miner/protocol_bls.go:591 Starting round with vrf {"round": 5959418, "roundtimeout": 0, "rseed": 899085639225023966, "prev_round": 5959417, "Prev_rseed": 0} 2025-12-29T03:24:57.424Z DEBUG miner/protocol_bls.go:542 DKG computeRBO {"round": 5959418} 2025-12-29T03:24:57.424Z INFO miner/protocol_bls.go:524 receive bls sign {"round": 5959418, "group_signature": "1 ac5355a1e4a7330e87489971c9483b440699df3a9801738f4702dde4d1a0a28 18331cb83a4a87fb25b1a420a94a94e9f9df5e242d53e2ddc3ecb0b159dda7a4", "rboOutput": "0c7a31a014a99dde4d9d37c32895f546cf11b500cc1b53917b5d9c51c3753a46", "dkg_starting_round": 0, "dkg_mb_number": 1} 2025-12-29T03:24:57.423Z DEBUG bls/dkg.go:396 dkg verify {"id": "1c3227e496fdf3c9aa604f3470a2bbd1", "key": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "msg": "59594180ddf09718d123651", "sig": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.423Z DEBUG miner/protocol_bls.go:496 VRF Hurray we've threshold BLS shares {"round": 5959418, "round pointer": "0xc0850e3cb0"} 2025-12-29T03:24:57.423Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959418, "round_vrf_num": 2, "threshold": 2} 2025-12-29T03:24:57.423Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959418, "node_id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa", "from": "1c3227e496fdf3c9aa604f3470a2bbd1", "message": "59594180ddf09718d123651"} 2025-12-29T03:24:57.423Z DEBUG util/mpt_node_change.go:150 MPT - update changes {"changes": 18} 2025-12-29T03:24:57.423Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959418, "round_timeout": 0, "prev_rseed": 999528025602274897, "prev round vrf random seed": "ddf09718d123651", "bls_msg": "59594180ddf09718d123651"} 2025-12-29T03:24:57.423Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959418, "round_vrf_num": 1, "threshold": 2, "sender": 2, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.423Z DEBUG bls/dkg.go:396 dkg verify {"id": "1c3227e496fdf3c9aa604f3470a2bbd1", "key": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "msg": "59594180ddf09718d123651", "sig": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.423Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "1.163327ms"} 2025-12-29T03:24:57.422Z INFO miner/protocol_round.go:78 add_my_vrf_share {"round": 5959418, "round_timeout": 0, "dkg_starting_round": 0, "dkg_mb_number": 1, "pr_seed": 999528025602274897, "pr_vrf_seed": "ddf09718d123651", "share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.422Z INFO miner/protocol_bls.go:489 Not yet reached threshold {"vrfShares_num": 1, "threshold": 2, "round": 5959418} 2025-12-29T03:24:57.422Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959418, "round_vrf_num": 1, "threshold": 2} 2025-12-29T03:24:57.422Z DEBUG miner/protocol_bls.go:305 get_bls_share {"round": 5959418, "rtc": 0, "dkg_pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "dkg_sr": 0, "mb_sr": 0, "share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.422Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959418, "node_id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "share": "1 194cb14e1bc7fa11099fc554552b9039fbf05a0c3390cc863cd964bcd9f573d0 f0d242dd188b66ce96514f5d36d18c578288e3010a34af3dff55c929da86a38", "from": "196b96df7631e00d339f9a25131927c4", "message": "59594180ddf09718d123651"} 2025-12-29T03:24:57.422Z DEBUG bls/dkg.go:356 dkg sign {"key": "21d97039e984194d599f2bf60c03d185c67ff756eba44a56ac9cf108c33c814a", "pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053"} 2025-12-29T03:24:57.422Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959418, "round_timeout": 0, "prev_rseed": 999528025602274897, "prev round vrf random seed": "ddf09718d123651", "bls_msg": "59594180ddf09718d123651"} 2025-12-29T03:24:57.422Z DEBUG miner/protocol_bls.go:293 Sign msg with dkg {"msg": "59594180ddf09718d123651", "round": 5959418, "dkg starting round": 0} 2025-12-29T03:24:57.422Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959418, "round_vrf_num": 0, "threshold": 2, "sender": 2, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.422Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959418, "round_timeout": 0, "prev_rseed": 999528025602274897, "prev round vrf random seed": "ddf09718d123651", "bls_msg": "59594180ddf09718d123651"} 2025-12-29T03:24:57.422Z INFO miner/protocol_bls.go:283 DKG getBlsShare {"Round Number": 5959418} 2025-12-29T03:24:57.422Z INFO miner/protocol_round.go:244 StartNextRound - add VRF {"round": 5959418} 2025-12-29T03:24:57.422Z INFO miner/protocol_round.go:78 add_my_vrf_share {"round": 5959418, "round_timeout": 0, "dkg_starting_round": 0, "dkg_mb_number": 1, "pr_seed": 999528025602274897, "pr_vrf_seed": "ddf09718d123651", "share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.422Z DEBUG chain/protocol_round.go:160 finalize round {"round": 5959417, "lf_round": 5959413} 2025-12-29T03:24:57.422Z DEBUG miner/protocol_bls.go:305 get_bls_share {"round": 5959418, "rtc": 0, "dkg_pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "dkg_sr": 0, "mb_sr": 0, "share": "1 349299a81f2acb3dedd33aca72b0d8d3222603bc06a4a8d893b95b239700830 14f4043bc21a98b6a48bad3da285d5a4fae7a1ed54399a86297f1f83637427aa"} 2025-12-29T03:24:57.422Z DEBUG chain/protocol_round.go:156 finalize_round: already finalizing {"round": 5959417} 2025-12-29T03:24:57.422Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959414, "r.minerPerm": [2, 1, 0]} 2025-12-29T03:24:57.422Z INFO chain/protocol_block.go:363 finalize block -- round {"round": {"number":5959414,"round_random_seed":5212347040463786258,"block_hash":"0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7","vrf_output":"4855fa0460e71112a7c75d4fb546ff370d215e7c3e8e65c70b9e3d085ac1c84d"}, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:57.422Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959417} 2025-12-29T03:24:57.422Z DEBUG miner/protocol_round.go:210 finalizedRound - cancel round verification {"round": 5959417} 2025-12-29T03:24:57.422Z INFO chain/protocol_block.go:328 finalize block {"round": 5959414, "current_round": 5959418, "lf_round": 5959413, "hash": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "round_rank": 0, "state": 7} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_round.go:154 [wait not ahead] [2] not ahead, can move on {"round": 5959417, "lfb tk round": 5959413} 2025-12-29T03:24:57.422Z DEBUG bls/dkg.go:396 dkg verify {"id": "196b96df7631e00d339f9a25131927c4", "key": "1 1f8c72cc3a6b74f6ad7528172b64afe3dc42ee1987495659fe2cf34e749caa7c 20957b4d25e7b5fb1e32d4e3b93e2b73dad7f118efb6dae28715e4ad627b0fff 14259a6ddf4b44c83c1389bd8ed1fc47c1b099c5986bb33923bbcbb25d30054a 2100bb7434dadde1a5ed8545748d0958955bcd21e06cf170f78dbda35a9e04b7", "msg": "59594180ddf09718d123651", "sig": "1 194cb14e1bc7fa11099fc554552b9039fbf05a0c3390cc863cd964bcd9f573d0 f0d242dd188b66ce96514f5d36d18c578288e3010a34af3dff55c929da86a38"} 2025-12-29T03:24:57.422Z DEBUG chain/worker.go:262 start to finalize block {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "prev block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5"} 2025-12-29T03:24:57.421Z INFO chain/protocol_round.go:359 finalize round {"round": 5959414, "lfb round": 5959416, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:57.421Z DEBUG bls/dkg.go:356 dkg sign {"key": "21d97039e984194d599f2bf60c03d185c67ff756eba44a56ac9cf108c33c814a", "pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053"} 2025-12-29T03:24:57.421Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959418, "round_timeout": 0, "prev_rseed": 999528025602274897, "prev round vrf random seed": "ddf09718d123651", "bls_msg": "59594180ddf09718d123651"} 2025-12-29T03:24:57.421Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959418, "round_vrf_num": 0, "threshold": 2, "sender": 1, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 194cb14e1bc7fa11099fc554552b9039fbf05a0c3390cc863cd964bcd9f573d0 f0d242dd188b66ce96514f5d36d18c578288e3010a34af3dff55c929da86a38"} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_receive.go:26 handle vrf share {"round": 5959418, "vrf_timeout_count": 0, "sender_index": 1} 2025-12-29T03:24:57.421Z DEBUG miner/worker.go:53 message {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_bls.go:293 Sign msg with dkg {"msg": "59594180ddf09718d123651", "round": 5959418, "dkg starting round": 0} 2025-12-29T03:24:57.421Z INFO chain/protocol_round.go:326 finalize round - latest finalized round {"round": 5959417, "lfb round": 5959416, "lfb block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.421Z INFO chain/protocol_round.go:199 finalize round {"round": 5959417, "plfb_round": 5959413, "num_round_notarized": 1, "num_chain_notarized": 3} 2025-12-29T03:24:57.421Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959418, "round_timeout": 0, "prev_rseed": 999528025602274897, "prev round vrf random seed": "ddf09718d123651", "bls_msg": "59594180ddf09718d123651"} 2025-12-29T03:24:57.421Z DEBUG chain/protocol_round.go:160 finalize round {"round": 5959417, "lf_round": 5959413} 2025-12-29T03:24:57.421Z INFO miner/protocol_bls.go:283 DKG getBlsShare {"Round Number": 5959418} 2025-12-29T03:24:57.421Z INFO miner/protocol_round.go:244 StartNextRound - add VRF {"round": 5959418} 2025-12-29T03:24:57.421Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959417} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_round.go:210 finalizedRound - cancel round verification {"round": 5959417} 2025-12-29T03:24:57.421Z INFO chain/entity.go:2016 Moving to the next round {"next_round": 5959418} 2025-12-29T03:24:57.421Z INFO miner/protocol_receive.go:370 process notarization success {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "duration": "1.901638ms"} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_round.go:123 [wait not ahead] 2025-12-29T03:24:57.421Z DEBUG miner/protocol_round.go:154 [wait not ahead] [2] not ahead, can move on {"round": 5959417, "lfb tk round": 5959413} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_round.go:991 verification_complete {"top_verified_rank": 0} 2025-12-29T03:24:57.421Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959417} 2025-12-29T03:24:57.421Z INFO miner/protocol_receive.go:456 process notarization - start next round {"new round": 5959418} 2025-12-29T03:24:57.421Z INFO miner/protocol_round.go:986 verification_complete {"round": 5959417, "verified_blocks": 0} 2025-12-29T03:24:57.421Z DEBUG round/entity.go:311 add notarized block - block already exist, merge tickets {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.421Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.421Z INFO chain/entity.go:1571 Adding a notarized block for current round {"Round": 5959417} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_round.go:123 [wait not ahead] 2025-12-29T03:24:57.421Z INFO miner/round.go:44 Cancelling block generation {"round": 5959417} 2025-12-29T03:24:57.421Z INFO miner/round.go:230 Cancelling verification {"round": 5959417} 2025-12-29T03:24:57.421Z INFO miner/protocol_receive.go:456 process notarization - start next round {"new round": 5959418} 2025-12-29T03:24:57.421Z DEBUG miner/protocol_round.go:1149 check block notarization - block notarized {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.421Z DEBUG round/entity.go:344 reached notarization {"round": 5959417} 2025-12-29T03:24:57.421Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.421Z INFO chain/entity.go:1571 Adding a notarized block for current round {"Round": 5959417} 2025-12-29T03:24:57.420Z INFO chain/protocol_block.go:319 reached notarization - merging tickets {"round": 5959417, "current_round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "tickets_num": 2} 2025-12-29T03:24:57.419Z DEBUG miner/protocol_receive.go:428 process notarization - merge notarization block {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.419Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "72.191µs"} 2025-12-29T03:24:57.419Z DEBUG miner/worker.go:53 message {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:57.416Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959417} 2025-12-29T03:24:57.399Z DEBUG miner/protocol_round.go:943 verifyAndSend - finished successfully {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.399Z INFO miner/protocol_round.go:1127 checkBlockNotarization -- block is not Notarized. Returning {"round": 5959417, "block hash": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.399Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 1, "current_round": 5959417, "round": 5959417} 2025-12-29T03:24:57.399Z INFO miner/protocol_round.go:920 verifyAndSend - sending verification ticket {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "block_rank": 0, "RRS": 999528025602274897} 2025-12-29T03:24:57.399Z DEBUG miner/protocol_round.go:1040 verify round block {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.399Z DEBUG miner/protocol_round.go:865 verifyAndSend - started {"block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.399Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.399Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.398Z INFO miner/protocol_round.go:951 Started main verification loop {"round": 5959417} 2025-12-29T03:24:57.354Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959413, "latest.Round": 5959413} 2025-12-29T03:24:57.351Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959413, "latest.Round": 5959413} 2025-12-29T03:24:57.315Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959417} 2025-12-29T03:24:57.242Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959417, "block": "8fb37621038d76e19dcc9463636940c16fc80ffd7b36090a1f98673b3d2f6259"} 2025-12-29T03:24:57.242Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.242Z DEBUG miner/protocol_receive.go:130 verify block processed {"round": 5959417, "block": "8fb37621038d76e19dcc9463636940c16fc80ffd7b36090a1f98673b3d2f6259"} 2025-12-29T03:24:57.242Z DEBUG miner/protocol_round.go:759 update prev block notarization, already notarized {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.242Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959417, "block hash": "8fb37621038d76e19dcc9463636940c16fc80ffd7b36090a1f98673b3d2f6259", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.242Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959417, "block": "8fb37621038d76e19dcc9463636940c16fc80ffd7b36090a1f98673b3d2f6259", "prev_block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "state_hash": "c11d10b8723ad5463c7e19413312fba6510063f9f9a7ee0d15e75d1e3753b6f6", "weight": 1} 2025-12-29T03:24:57.242Z INFO miner/protocol_round.go:613 handle verify block - added block for ticket verification {"round": 5959417, "block": "8fb37621038d76e19dcc9463636940c16fc80ffd7b36090a1f98673b3d2f6259", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.241Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 0, "current_round": 5959417, "round": 5959417} 2025-12-29T03:24:57.241Z DEBUG miner/protocol_receive.go:169 verify block {"round": 5959417, "block": "8fb37621038d76e19dcc9463636940c16fc80ffd7b36090a1f98673b3d2f6259"} 2025-12-29T03:24:57.241Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "73.331µs"} 2025-12-29T03:24:57.240Z DEBUG miner/worker.go:53 message {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:57.237Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704"} 2025-12-29T03:24:57.237Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.237Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959417, "block hash": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.237Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959417, "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "prev_block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "state_hash": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285", "weight": 1} 2025-12-29T03:24:57.237Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.236Z DEBUG statecache/statecache.go:131 statecache - commit {"block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "bc_hits": 90, "bc_miss": 0, "sc_hits": 884859251, "sc_miss": 448360, "duration": "586.868µs"} 2025-12-29T03:24:57.236Z INFO miner/protocol_block.go:1316 generate block (assemble+update+sign) {"round": 5959417, "block_size": 1, "reused_txns": 0, "reused_txns": 0, "time": "17.295516ms", "block": "983c0a58f9e9685282b73c8e38735bc5665caccdbb2560a88a90f7adb2ddc704", "prev_block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "begin_state_hash": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2", "block_state_hash": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285", "computed_state_hash": "dce112ec47cc94a74395949a5457872df663623f008eed9688fc9f515fd5e285", "changes": 18, "state_status": 4, "iteration_count": 0} 2025-12-29T03:24:57.236Z DEBUG miner/protocol_block.go:1305 generate block (assemble+update) {"round": 5959417, "txns": 1, "time": "17.079693ms"} 2025-12-29T03:24:57.236Z DEBUG miner/protocol_block.go:1282 generate block (assemble) {"round": 5959417, "txns": 1, "time": "17.023992ms"} 2025-12-29T03:24:57.233Z DEBUG chain/state.go:961 Updating nonce {"client": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "new_nonce": 3056245} 2025-12-29T03:24:57.232Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.232Z INFO chain/state.go:533 SC executed {"client id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "block": "", "round": 5959417, "prev_state_hash": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2", "txn_hash": "981a2b40b06f4492b4770814af9674d8a062cee5388fc6b1826dc7f9d79a1b33", "txn_nonce": 3056245, "txn_func": "payFees", "txn_status": 0, "txn_exec_time": "9.724505ms", "begin client state": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2", "current_root": "11aa25675036819147cdf2856e0b7352998dd8b23a8502e2c5746420eb5d644f", "mpt_cache_hit": 72, "mpt_cache_miss": 0, "output": ""} 2025-12-29T03:24:57.231Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.231Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.230Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.229Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.223Z ERROR minersc/global_node.go:372 failed to get current magic block {"error": "value not present"} 0chain.net/smartcontract/minersc.(*GlobalNode).prevMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/global_node.go:372 0chain.net/smartcontract/minersc.getRegisterShardersInMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:659 0chain.net/smartcontract/minersc.(*MinerSmartContract).payFees /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:519 0chain.net/smartcontract/minersc.(*MinerSmartContract).Execute /0chain/code/go/0chain.net/smartcontract/minersc/sc.go:139 0chain.net/chaincore/smartcontract.ExecuteWithStats /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:52 0chain.net/chaincore/smartcontract.ExecuteSmartContract /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:67 0chain.net/chaincore/chain.(*Chain).ExecuteSmartContract.func1 /0chain/code/go/0chain.net/chaincore/chain/state.go:158 2025-12-29T03:24:57.223Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9d2208fe938b7465b9c5dc571053986cf8dbf3585fb36399f98ea569c42f50b76"} 2025-12-29T03:24:57.223Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9b5e8a1650666fdd3a9de7855b6da63ab629b1701f23738477e2325f7ca2150ac"} 2025-12-29T03:24:57.223Z DEBUG minersc/fees.go:480 pay_fees, got miner id successfully {"miner id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "round": 5959417, "block": ""} 2025-12-29T03:24:57.222Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.222Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.222Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.222Z DEBUG chain/protocol_view_change.go:424 [mvc] nonce, set nonce in getCurrentSelfNonce {"nonce": 3056244} 2025-12-29T03:24:57.222Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd9\u0004H\ufffd<0\ufffd\ufffd\u001d\ufffdI\ufffd\ufffd\u0001\ufffd\ufffd&r\u0013I\ufffd\ufffd\ufffdšKp~k\ufffd"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd'sG&~\ufffd\ufffd\ufffd\u0006\u0008u\ufffd1\ufffdw\ufffd\u001f\ufffd\ufffd\ufffd\u001a\ufffd\ufffd\u00146>zN\u001b.@"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "B\ufffd\ufffd\ufffd\u0015\ufffd\u0003\ufffd\ufffdp\ufffd\u0019\ufffd\ufffd5\ufffd\"\ufffdA\ufffd\ufffdaj\ufffd\ufffd\ufffd\u000f\ufffdӻ\"\ufffd"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "L\ufffd\ufffd\tQ\ufffd>\ufffdD\ufffd\ufffdm`3\ufffd\ufffd\n\ufffdg?\u000b\ufffd\ufffd\ufffdrFek\ufffd+\ufffd\ufffd"} 2025-12-29T03:24:57.221Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959417, "below_round": 5959363, "before": 1766978637, "total": 303, "count": 0} 2025-12-29T03:24:57.221Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.221Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.221Z INFO chain/protocol_round.go:402 finalize round - finalize block success {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "duration": "6.0954ms"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdO+\rI:\ufffd;\ufffd\ufffd\ufffd\ufffd\ufffd\ufffdӡϸ*i1\ufffd\u0007żA\u0018\ufffd\ufffdfV\ufffd"} 2025-12-29T03:24:57.221Z DEBUG chain/worker.go:230 finalize block processed {"round": 5959413, "duration": "6.031589ms"} 2025-12-29T03:24:57.221Z DEBUG chain/protocol_block.go:612 finalized block - done {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "duration": "5.825776ms"} 2025-12-29T03:24:57.221Z INFO chain/entity.go:2366 config has been updated successfully {"start of round": 5959413} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "[H\u0004v\ufffd2\ufffd\ufffd\ufffdJ\u0019һ\ufffdMd\ufffd\ufffd\u0015ePA\ufffd@\ufffd\ufffd\ufffd\ufffdfH\u001e\ufffd"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdBr\\\ufffd\u0014!\ufffdK\ufffd/\ufffd;\u001c\ufffdC\r\ufffdC\ufffd\ufffd\u0015\ufffd\u0008\ufffd\ufffd'\ufffd$ \u000bC"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "-_qY$\ufffdzr\u0011\ufffdN\u001c\ufffds\ufffd~\u0010|ю\ufffd*\ufffd\ufffdX\ufffd\ufffdw\ufffd\ufffd/0"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdR\ufffdu\ufffdX\ufffdj\ufffd\ufffd\ufffd\ufffd\ufffd\u001ce~}\ufffd\ufffdڊ\\ң\ufffd/\ufffd\ufffd\ufffd>C"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "W;η\ufffdk!\ufffd\ufffdm\u0019\ufffd6U]b\ufffd\ufffd]%3\ufffdi)F7\ufffd\ufffdٱ\u001a\ufffd"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd2E$\ufffd@g\ufffd\u0003ǝ\ufffd\u0001\ufffd\u0013\ufffd\ufffd\ufffd'\ufffd\ufffd3\ufffd\u001b\n\ufffde0\ufffd\ufffd"} 2025-12-29T03:24:57.221Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.220Z DEBUG chain/protocol_view_change.go:431 [mvc] nonce, get current miner nonce {"minerId": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea"} 2025-12-29T03:24:57.220Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0006'\u0012\ufffdu\tWg\ufffdt\ufffd\ufffd\r\ufffd\ufffd\ufffd\u0007ݏ\ufffd\ufffd3\u0015i\ufffd\ufffd\ufffd\ufffd\ufffdkZy"} 2025-12-29T03:24:57.220Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.220Z INFO miner/protocol_block.go:1112 generate block starting iteration {"round": 5959417, "prev_block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "prev_state_hash": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2"} 2025-12-29T03:24:57.220Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0014\ufffd\ufffd2U\u0015\ufffd(9\ufffd\ufffd\ufffdj\ufffd\u0003\ufffd E甠{\ufffd\ufffd\ufffd\ufffd\u0014b\ufffd"} 2025-12-29T03:24:57.220Z INFO miner/m_handler.go:242 Reject VRFShare: VRF is complete for this round {"vrfs_round_num": 5959417, "vrfs_sender_index": 1, "vrfs_round_num": 5959417} 2025-12-29T03:24:57.220Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\ufffd\ufffdK\ufffd7|(H\ufffd\ufffdɲ!a\ufffdQU:f\ufffdm&\u000f\ufffd\ufffd\ufffd\ufffd\t\ufffd\ufffd"} 2025-12-29T03:24:57.220Z DEBUG transaction/worker.go:253 [mvc] see no old txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056241} 2025-12-29T03:24:57.220Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.220Z DEBUG statecache/statecache.go:173 state cache - see gap {"block": "95ca3b3cd06bbb4242b9c93b7d97443dde54f64ab413a558c94c77e427e687dc"} 2025-12-29T03:24:57.220Z INFO miner/m_handler.go:242 Reject VRFShare: VRF is complete for this round {"vrfs_round_num": 5959417, "vrfs_sender_index": 1, "vrfs_round_num": 5959417} 2025-12-29T03:24:57.220Z DEBUG transaction/worker.go:221 [mvc] remove old nonce txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056241} 2025-12-29T03:24:57.220Z DEBUG chain/lfb_round_node.go:33 [mvc] store lfb {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "mb number": 1} 2025-12-29T03:24:57.219Z DEBUG chain/worker.go:443 BC is moving {"current_lfb_round": 5959413, "last_round": 5959412} 2025-12-29T03:24:57.219Z DEBUG chain/entity.go:2269 set lfb {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "state_computed": true} 2025-12-29T03:24:57.219Z DEBUG chain/state.go:128 finalize round - rebased current state db {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "state hash": "f48fc52f4db4562178d57f870fc45185d338af442888b0f72a7e2285c3ebe587"} 2025-12-29T03:24:57.219Z DEBUG util/mpt_nodedb.go:556 LevelNodeDB rebase db 2025-12-29T03:24:57.219Z INFO transaction/worker.go:164 cleaning transactions {"collection": "collection.txn:0afc093ffb509f059c55478bc1a60351cef7b4e9c008a53a6cc8241ca8617dfe", "missing_count": 1, "txns": ["3a04de7c8e084cb20e77f93abbe17dac6ee9eea68d0d0911eeae97301077d41d"], "past txns": []} 2025-12-29T03:24:57.219Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.219Z DEBUG chain/protocol_block.go:424 finalize block - record dead nodes {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "num dead nodes": 18} 2025-12-29T03:24:57.219Z DEBUG transaction/worker.go:124 cleaning past transactions 2025-12-29T03:24:57.219Z DEBUG miner/round.go:238 Round clear - cancel verification 2025-12-29T03:24:57.219Z DEBUG miner/chain.go:313 delete txns {"txns": ["3a04de7c8e084cb20e77f93abbe17dac6ee9eea68d0d0911eeae97301077d41d"]} 2025-12-29T03:24:57.219Z INFO miner/protocol_block.go:619 update finalized block (debug transaction) {"txn": "3a04de7c8e084cb20e77f93abbe17dac6ee9eea68d0d0911eeae97301077d41d", "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5"} 2025-12-29T03:24:57.219Z INFO miner/protocol_block.go:613 update finalized block {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "lf_round": 5959412, "current_round": 5959417, "weight": 1} 2025-12-29T03:24:57.219Z DEBUG chain/protocol_block.go:409 MPT state node stat - inc {"node num": -18147792, "change num": 18, "delete num": 18} 2025-12-29T03:24:57.218Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959417, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.218Z DEBUG miner/protocol_round.go:467 Setting LFMB round/hash for a block {"rn": 5959417, "mc.crn": 5959417, "rnoff": 5959392, "nvc": 0, "r": 0, "h": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "b.lfmbr": 0, "b.lfmbh": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4"} 2025-12-29T03:24:57.218Z INFO miner/protocol_round.go:830 Starting round verification {"round": 5959417, "delta": "180ms"} 2025-12-29T03:24:57.218Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959417, "r.minerPerm": [1, 2, 0]} 2025-12-29T03:24:57.218Z INFO miner/protocol_round.go:340 *** TOC_FIX starting round block generation *** {"round": 5959417, "index": 2, "rank": 0, "timeout_count": 0, "random_seed": 999528025602274897, "lf_round": 5959412} 2025-12-29T03:24:57.218Z INFO miner/protocol_bls.go:591 Starting round with vrf {"round": 5959417, "roundtimeout": 0, "rseed": 999528025602274897, "prev_round": 5959416, "Prev_rseed": 0} 2025-12-29T03:24:57.218Z DEBUG miner/protocol_bls.go:542 DKG computeRBO {"round": 5959417} 2025-12-29T03:24:57.218Z INFO miner/protocol_bls.go:524 receive bls sign {"round": 5959417, "group_signature": "1 2285a1980b97903beef8cdbd83cccdbb8c1fe6fdf37366b24d77d31afff6113 a29362dd35118bb918550b3e0dd018c6ea353bf6761623b746f423a2358473e", "rboOutput": "0ddf09718d1236510a5480e23fee8369a6faec5c8471bc0f02b70ec72049324f", "dkg_starting_round": 0, "dkg_mb_number": 1} 2025-12-29T03:24:57.218Z INFO block/entity.go:1195 save state {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "block_size": 1, "changes": 18, "client_state": "f48fc52f4db4562178d57f870fc45185d338af442888b0f72a7e2285c3ebe587", "duration": "2.465287ms"} 2025-12-29T03:24:57.218Z DEBUG util/merkle_patricia_trie.go:272 MPT save changes success {"duration": "1.988429ms"} 2025-12-29T03:24:57.218Z DEBUG miner/protocol_bls.go:496 VRF Hurray we've threshold BLS shares {"round": 5959417, "round pointer": "0xc0862923f0"} 2025-12-29T03:24:57.218Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959417, "round_vrf_num": 2, "threshold": 2} 2025-12-29T03:24:57.217Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959417, "node_id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "share": "1 1f76e2a326efc0af1e2636ae1e99b219d50efb4d85248cd04372dd5c01a1bd9d 19e0b7d95bb520e8255884d6684bff571b4caeb1d807045911dafc8372fda96f", "from": "1c3227e496fdf3c9aa604f3470a2bbd1", "message": "59594170-743e586f73b9910a"} 2025-12-29T03:24:57.217Z DEBUG util/mpt_node_change.go:156 MPT - update changes done {"changes": 18} 2025-12-29T03:24:57.217Z DEBUG bls/dkg.go:396 dkg verify {"id": "1c3227e496fdf3c9aa604f3470a2bbd1", "key": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "msg": "59594170-743e586f73b9910a", "sig": "1 1f76e2a326efc0af1e2636ae1e99b219d50efb4d85248cd04372dd5c01a1bd9d 19e0b7d95bb520e8255884d6684bff571b4caeb1d807045911dafc8372fda96f"} 2025-12-29T03:24:57.217Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959417, "round_vrf_num": 1, "threshold": 2} 2025-12-29T03:24:57.216Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959417, "node_id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "share": "1 a4e6899ee933b690c996650dc423718efcd79cd21056709a5c1b6ecef7d270d 15a12ba8543e0680ec098d867e4d72ba5587ba8c5f025d2a5d047d1c86268a0b", "from": "18f9a15b9551f8552a19cf4750ef14bf", "message": "59594170-743e586f73b9910a"} 2025-12-29T03:24:57.216Z DEBUG util/mpt_node_change.go:150 MPT - update changes {"changes": 18} 2025-12-29T03:24:57.216Z DEBUG bls/dkg.go:396 dkg verify {"id": "18f9a15b9551f8552a19cf4750ef14bf", "key": "1 15981a99909f8d701bca846be62c295e8958436831d3087ded78a1719a92f9a9 1bc96c0fea12a9ee4451600417977d1e987b252b469ea7c7be9d40e681fceff4 164089f6538d2b42ac3b37af7bc35e2550212af1e8ccedfeec4bde4f472dd384 aa502eaa78fb0cc02bd92538e258b55b1495cdc393205378d4ead83eb106f33", "msg": "59594170-743e586f73b9910a", "sig": "1 a4e6899ee933b690c996650dc423718efcd79cd21056709a5c1b6ecef7d270d 15a12ba8543e0680ec098d867e4d72ba5587ba8c5f025d2a5d047d1c86268a0b"} 2025-12-29T03:24:57.215Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959417, "round_timeout": 0, "prev_rseed": -8376229592661856522, "prev round vrf random seed": "-743e586f73b9910a", "bls_msg": "59594170-743e586f73b9910a"} 2025-12-29T03:24:57.215Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959417, "round_vrf_num": 0, "threshold": 2, "sender": 2, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 1f76e2a326efc0af1e2636ae1e99b219d50efb4d85248cd04372dd5c01a1bd9d 19e0b7d95bb520e8255884d6684bff571b4caeb1d807045911dafc8372fda96f"} 2025-12-29T03:24:57.215Z INFO miner/protocol_round.go:78 add_my_vrf_share {"round": 5959417, "round_timeout": 0, "dkg_starting_round": 0, "dkg_mb_number": 1, "pr_seed": -8376229592661856522, "pr_vrf_seed": "-743e586f73b9910a", "share": "1 1f76e2a326efc0af1e2636ae1e99b219d50efb4d85248cd04372dd5c01a1bd9d 19e0b7d95bb520e8255884d6684bff571b4caeb1d807045911dafc8372fda96f"} 2025-12-29T03:24:57.215Z INFO miner/protocol_receive.go:370 process notarization success {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "duration": "3.542493ms"} 2025-12-29T03:24:57.215Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959413, "r.minerPerm": [1, 2, 0]} 2025-12-29T03:24:57.215Z DEBUG round/entity.go:311 add notarized block - block already exist, merge tickets {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.215Z INFO chain/protocol_block.go:363 finalize block -- round {"round": {"number":5959413,"round_random_seed":2270528570148294512,"block_hash":"614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5","vrf_output":"1f8289b46c108f7082f7e45b31a27862bebf663f2a3bc917c6490ee35837b4de"}, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5"} 2025-12-29T03:24:57.215Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959416, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.215Z DEBUG miner/protocol_round.go:1149 check block notarization - block notarized {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.215Z INFO chain/protocol_block.go:328 finalize block {"round": 5959413, "current_round": 5959417, "lf_round": 5959412, "hash": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "round_rank": 0, "state": 7} 2025-12-29T03:24:57.215Z DEBUG round/entity.go:311 add notarized block - block already exist, merge tickets {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.215Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959416, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.215Z INFO chain/protocol_block.go:319 reached notarization - merging tickets {"round": 5959416, "current_round": 5959417, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "tickets_num": 3} 2025-12-29T03:24:57.215Z DEBUG miner/protocol_bls.go:305 get_bls_share {"round": 5959417, "rtc": 0, "dkg_pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "dkg_sr": 0, "mb_sr": 0, "share": "1 1f76e2a326efc0af1e2636ae1e99b219d50efb4d85248cd04372dd5c01a1bd9d 19e0b7d95bb520e8255884d6684bff571b4caeb1d807045911dafc8372fda96f"} 2025-12-29T03:24:57.215Z DEBUG chain/worker.go:262 start to finalize block {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "prev block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6"} 2025-12-29T03:24:57.215Z INFO chain/protocol_round.go:359 finalize round {"round": 5959413, "lfb round": 5959415, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5"} 2025-12-29T03:24:57.215Z INFO chain/protocol_round.go:326 finalize round - latest finalized round {"round": 5959416, "lfb round": 5959415, "lfb block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:57.215Z INFO chain/protocol_round.go:199 finalize round {"round": 5959416, "plfb_round": 5959412, "num_round_notarized": 1, "num_chain_notarized": 3} 2025-12-29T03:24:57.215Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959417} 2025-12-29T03:24:57.215Z DEBUG bls/dkg.go:356 dkg sign {"key": "21d97039e984194d599f2bf60c03d185c67ff756eba44a56ac9cf108c33c814a", "pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053"} 2025-12-29T03:24:57.215Z DEBUG chain/protocol_round.go:160 finalize round {"round": 5959416, "lf_round": 5959412} 2025-12-29T03:24:57.215Z DEBUG miner/protocol_bls.go:293 Sign msg with dkg {"msg": "59594170-743e586f73b9910a", "round": 5959417, "dkg starting round": 0} 2025-12-29T03:24:57.215Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959417, "round_timeout": 0, "prev_rseed": -8376229592661856522, "prev round vrf random seed": "-743e586f73b9910a", "bls_msg": "59594170-743e586f73b9910a"} 2025-12-29T03:24:57.215Z INFO miner/protocol_bls.go:283 DKG getBlsShare {"Round Number": 5959417} 2025-12-29T03:24:57.215Z INFO miner/protocol_round.go:244 StartNextRound - add VRF {"round": 5959417} 2025-12-29T03:24:57.215Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959416} 2025-12-29T03:24:57.215Z DEBUG miner/protocol_round.go:210 finalizedRound - cancel round verification {"round": 5959416} 2025-12-29T03:24:57.215Z INFO chain/entity.go:2016 Moving to the next round {"next_round": 5959417} 2025-12-29T03:24:57.215Z DEBUG miner/protocol_round.go:154 [wait not ahead] [2] not ahead, can move on {"round": 5959416, "lfb tk round": 5959412} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_round.go:123 [wait not ahead] 2025-12-29T03:24:57.214Z DEBUG miner/protocol_round.go:991 verification_complete {"top_verified_rank": 0} 2025-12-29T03:24:57.214Z INFO miner/protocol_round.go:986 verification_complete {"round": 5959416, "verified_blocks": 0} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_round.go:943 verifyAndSend - finished successfully {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.214Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959416} 2025-12-29T03:24:57.214Z INFO miner/round.go:230 Cancelling verification {"round": 5959416} 2025-12-29T03:24:57.214Z INFO miner/protocol_receive.go:456 process notarization - start next round {"new round": 5959417} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_round.go:1149 check block notarization - block notarized {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.214Z DEBUG round/entity.go:344 reached notarization {"round": 5959416} 2025-12-29T03:24:57.214Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959416, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.214Z INFO chain/entity.go:1571 Adding a notarized block for current round {"Round": 5959416} 2025-12-29T03:24:57.214Z INFO chain/protocol_block.go:303 reached notarization - add tickets {"round": 5959416, "current_round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "tickets_num": 2} 2025-12-29T03:24:57.214Z INFO miner/protocol_round.go:920 verifyAndSend - sending verification ticket {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "block_rank": 0, "RRS": -8376229592661856522} 2025-12-29T03:24:57.214Z INFO chain/protocol_block.go:319 reached notarization - merging tickets {"round": 5959415, "current_round": 5959416, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "tickets_num": 3} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_round.go:1069 verify round block - finished verification {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.214Z INFO miner/protocol_block.go:376 verify block successful {"round": 5959416, "block_size": 2, "time": "21.999736ms", "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "prev_block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "state_hash": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2", "state_status": 4} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_block.go:374 SignBlock finished {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "214.883µs"} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_receive.go:428 process notarization - merge notarization block {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_block.go:367 VerifyBlockMagicBlock finished {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "570ns"} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_block.go:360 verifySmartContracts finished {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "5.01µs"} 2025-12-29T03:24:57.214Z DEBUG miner/protocol_block.go:351 verify block - ComputeState finished {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "16.854489ms"} 2025-12-29T03:24:57.214Z INFO block/entity.go:1034 compute state successful {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "block ptr": "0xc04de5f0e0", "block_size": 2, "duration": "16.783449ms", "changes": 17, "begin_client_state": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156", "computed_state_hash": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2", "block_state_hash": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2", "prev_block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "prev_block_client_state": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156"} 2025-12-29T03:24:57.214Z DEBUG statecache/statecache.go:131 statecache - commit {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "bc_hits": 123, "bc_miss": 0, "sc_hits": 884859161, "sc_miss": 448360, "duration": "599.169µs"} 2025-12-29T03:24:57.213Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "99.661µs"} 2025-12-29T03:24:57.213Z DEBUG miner/protocol_receive.go:33 received VRF share for the future round, caching it {"current_round": 5959416, "vrf_share_round": 5959417} 2025-12-29T03:24:57.213Z DEBUG miner/protocol_receive.go:26 handle vrf share {"round": 5959417, "vrf_timeout_count": 0, "sender_index": 0} 2025-12-29T03:24:57.213Z DEBUG miner/worker.go:53 message {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:57.212Z DEBUG chain/state.go:961 Updating nonce {"client": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "new_nonce": 3054633} 2025-12-29T03:24:57.212Z DEBUG miner/protocol_receive.go:413 Computing state for block we received notarization for 2025-12-29T03:24:57.212Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "60.251µs"} 2025-12-29T03:24:57.212Z DEBUG miner/worker.go:53 message {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:57.211Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.211Z INFO chain/state.go:533 SC executed {"client id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "round": 5959416, "prev_state_hash": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156", "txn_hash": "fa1085cd59c4916403ffbaaf961e991297c347b35b1decea7916a5cd819d3fc6", "txn_nonce": 3054633, "txn_func": "generate_challenge", "txn_status": 1, "txn_exec_time": "474.377µs", "begin client state": "02cc3121ce63a320e3f848ec4c141c3d63223bdde5a12a6001552a3d6942ff85", "current_root": "02cc3121ce63a320e3f848ec4c141c3d63223bdde5a12a6001552a3d6942ff85", "mpt_cache_hit": 15, "mpt_cache_miss": 0, "output": "OpenChallenges generated"} 2025-12-29T03:24:57.211Z INFO storagesc/challenge.go:1191 skipping generate challenge: empty blobber challenge partition 2025-12-29T03:24:57.211Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "eaf4195ad8a6c0bd8f90e9b28f4ba3b4938ddc60155952285997f669446bb502"} 2025-12-29T03:24:57.210Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.208Z DEBUG chain/state.go:961 Updating nonce {"client": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "new_nonce": 3054632} 2025-12-29T03:24:57.207Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.207Z INFO chain/state.go:533 SC executed {"client id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "round": 5959416, "prev_state_hash": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156", "txn_hash": "1e9e260edac66eb3a9594781a3fa5150bdfb6b6fedc0675586f561a11cd89ea3", "txn_nonce": 3054632, "txn_func": "payFees", "txn_status": 1, "txn_exec_time": "9.757945ms", "begin client state": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156", "current_root": "d8ac7bf392e4d4fba9509dfcaf0cccf545a85486324aa7c7a693826173a0e59f", "mpt_cache_hit": 72, "mpt_cache_miss": 0, "output": ""} 2025-12-29T03:24:57.206Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.206Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.205Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.204Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:57.198Z ERROR minersc/global_node.go:372 failed to get current magic block {"error": "value not present"} 0chain.net/smartcontract/minersc.(*GlobalNode).prevMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/global_node.go:372 0chain.net/smartcontract/minersc.getRegisterShardersInMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:659 0chain.net/smartcontract/minersc.(*MinerSmartContract).payFees /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:519 0chain.net/smartcontract/minersc.(*MinerSmartContract).Execute /0chain/code/go/0chain.net/smartcontract/minersc/sc.go:139 0chain.net/chaincore/smartcontract.ExecuteWithStats /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:52 0chain.net/chaincore/smartcontract.ExecuteSmartContract /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:67 0chain.net/chaincore/chain.(*Chain).ExecuteSmartContract.func1 /0chain/code/go/0chain.net/chaincore/chain/state.go:158 2025-12-29T03:24:57.198Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9d2208fe938b7465b9c5dc571053986cf8dbf3585fb36399f98ea569c42f50b76"} 2025-12-29T03:24:57.198Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9b5e8a1650666fdd3a9de7855b6da63ab629b1701f23738477e2325f7ca2150ac"} 2025-12-29T03:24:57.198Z DEBUG minersc/fees.go:480 pay_fees, got miner id successfully {"miner id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.198Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.197Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:57.197Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:57.197Z DEBUG miner/protocol_block.go:339 ValidateBlockCost {"round": 5959416, "hash": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "calculated cost": 100} 2025-12-29T03:24:57.196Z DEBUG statecache/statecache.go:173 state cache - see gap {"block": "95ca3b3cd06bbb4242b9c93b7d97443dde54f64ab413a558c94c77e427e687dc"} 2025-12-29T03:24:57.195Z DEBUG miner/protocol_block.go:305 ValidateTransactions finished {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "2.137432ms"} 2025-12-29T03:24:57.194Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"verification_ticket","value":"Verification Ticket"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "1.469251ms"} 2025-12-29T03:24:57.194Z INFO miner/protocol_round.go:1127 checkBlockNotarization -- block is not Notarized. Returning {"round": 5959416, "block hash": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.194Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 1, "current_round": 5959416, "round": 5959416} 2025-12-29T03:24:57.194Z DEBUG miner/protocol_receive.go:298 handle vt. msg - verify ticket successfully {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "verifier": 1} 2025-12-29T03:24:57.193Z DEBUG miner/protocol_block.go:299 GetPreviousBlock finished {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "1.38µs"} 2025-12-29T03:24:57.193Z DEBUG miner/protocol_block.go:292 VerifyBlockMagicBlockReference finished {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "2.921µs"} 2025-12-29T03:24:57.193Z DEBUG miner/protocol_block.go:286 Validating finished {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "spent": "921.954µs"} 2025-12-29T03:24:57.193Z DEBUG miner/worker.go:53 message {"msg": {"code":"verification_ticket","value":"Verification Ticket"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:57.192Z DEBUG miner/protocol_round.go:1067 verify round block - started verification {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.192Z DEBUG miner/protocol_round.go:1040 verify round block {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.192Z DEBUG miner/m_handler.go:365 handle vt. msg - verification ticket {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.192Z DEBUG miner/protocol_round.go:865 verifyAndSend - started {"block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.192Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959416, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.192Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959416, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.192Z INFO miner/protocol_round.go:951 Started main verification loop {"round": 5959416} 2025-12-29T03:24:57.188Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959412, "latest.Round": 5959412} 2025-12-29T03:24:57.132Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959412, "latest.Round": 5959412} 2025-12-29T03:24:57.115Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959416} 2025-12-29T03:24:57.035Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959416, "block": "26779b4b36592278c7a89103e698d347fd352cc5f168031fd805853f518cd00f"} 2025-12-29T03:24:57.035Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959416, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.035Z DEBUG miner/protocol_receive.go:130 verify block processed {"round": 5959416, "block": "26779b4b36592278c7a89103e698d347fd352cc5f168031fd805853f518cd00f"} 2025-12-29T03:24:57.035Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959416, "block hash": "26779b4b36592278c7a89103e698d347fd352cc5f168031fd805853f518cd00f", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.035Z DEBUG miner/protocol_round.go:759 update prev block notarization, already notarized {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:57.035Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959416, "block": "26779b4b36592278c7a89103e698d347fd352cc5f168031fd805853f518cd00f", "prev_block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "state_hash": "0d3f2dac2a55a7431b6ee94fb3d025dd059f0454f4e58287f016c2b1057ccebf", "weight": 1} 2025-12-29T03:24:57.035Z INFO miner/protocol_round.go:613 handle verify block - added block for ticket verification {"round": 5959416, "block": "26779b4b36592278c7a89103e698d347fd352cc5f168031fd805853f518cd00f", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.035Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 0, "current_round": 5959416, "round": 5959416} 2025-12-29T03:24:57.034Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.034Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959416, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.034Z DEBUG miner/protocol_receive.go:130 verify block processed {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.034Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959416, "block hash": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.034Z DEBUG miner/protocol_round.go:759 update prev block notarization, already notarized {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:57.034Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "prev_block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "state_hash": "b8324524e14067ee03c79df201d213bd7fc9d6277f8886338f1b0ab1653095d2", "weight": 1} 2025-12-29T03:24:57.034Z INFO miner/protocol_round.go:613 handle verify block - added block for ticket verification {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:57.034Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 0, "current_round": 5959416, "round": 5959416} 2025-12-29T03:24:57.034Z DEBUG miner/protocol_receive.go:169 verify block {"round": 5959416, "block": "26779b4b36592278c7a89103e698d347fd352cc5f168031fd805853f518cd00f"} 2025-12-29T03:24:57.034Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "51.351µs"} 2025-12-29T03:24:57.034Z DEBUG miner/worker.go:53 message {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:57.033Z DEBUG miner/protocol_receive.go:169 verify block {"round": 5959416, "block": "2872415d21b76c02f6f219d5e7224c969c80a6eb48e6fd35b03ba8dddb3cbb98"} 2025-12-29T03:24:57.033Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "67.241µs"} 2025-12-29T03:24:57.033Z DEBUG miner/worker.go:53 message {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:57.015Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959416, "below_round": 5959363, "before": 1766978637, "total": 302, "count": 0} 2025-12-29T03:24:57.015Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.015Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.015Z INFO chain/protocol_round.go:326 finalize round - latest finalized round {"round": 5959415, "lfb round": 5959414, "lfb block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:57.015Z INFO chain/protocol_round.go:199 finalize round {"round": 5959415, "plfb_round": 5959412, "num_round_notarized": 1, "num_chain_notarized": 3} 2025-12-29T03:24:57.015Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959416, "below_round": 5959362, "before": 1766978637, "total": 306, "count": 4} 2025-12-29T03:24:57.015Z DEBUG chain/entity.go:1714 found block to delete {"round": 5959362, "block_round": 5959126, "current_round": 5959416, "lf_round": 5959412} 2025-12-29T03:24:57.015Z DEBUG chain/entity.go:1714 found block to delete {"round": 5959362, "block_round": 5959124, "current_round": 5959416, "lf_round": 5959412} 2025-12-29T03:24:57.015Z DEBUG chain/entity.go:1714 found block to delete {"round": 5959362, "block_round": 5959125, "current_round": 5959416, "lf_round": 5959412} 2025-12-29T03:24:57.015Z DEBUG chain/entity.go:1714 found block to delete {"round": 5959362, "block_round": 5959123, "current_round": 5959416, "lf_round": 5959412} 2025-12-29T03:24:57.014Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.014Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:57.014Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959416} 2025-12-29T03:24:57.014Z INFO chain/protocol_round.go:402 finalize round - finalize block success {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "duration": "6.384915ms"} 2025-12-29T03:24:57.014Z DEBUG chain/worker.go:230 finalize block processed {"round": 5959412, "duration": "6.341874ms"} 2025-12-29T03:24:57.014Z DEBUG chain/protocol_block.go:612 finalized block - done {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "duration": "6.246183ms"} 2025-12-29T03:24:57.014Z INFO chain/entity.go:2366 config has been updated successfully {"start of round": 5959412} 2025-12-29T03:24:57.014Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdBr\\\ufffd\u0014!\ufffdK\ufffd/\ufffd;\u001c\ufffdC\r\ufffdC\ufffd\ufffd\u0015\ufffd\u0008\ufffd\ufffd'\ufffd$ \u000bC"} 2025-12-29T03:24:57.014Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdR\ufffdu\ufffdX\ufffdj\ufffd\ufffd\ufffd\ufffd\ufffd\u001ce~}\ufffd\ufffdڊ\\ң\ufffd/\ufffd\ufffd\ufffd>C"} 2025-12-29T03:24:57.014Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0006'\u0012\ufffdu\tWg\ufffdt\ufffd\ufffd\r\ufffd\ufffd\ufffd\u0007ݏ\ufffd\ufffd3\u0015i\ufffd\ufffd\ufffd\ufffd\ufffdkZy"} 2025-12-29T03:24:57.014Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0014\ufffd\ufffd2U\u0015\ufffd(9\ufffd\ufffd\ufffdj\ufffd\u0003\ufffd E甠{\ufffd\ufffd\ufffd\ufffd\u0014b\ufffd"} 2025-12-29T03:24:57.013Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\ufffd\ufffdK\ufffd7|(H\ufffd\ufffdɲ!a\ufffdQU:f\ufffdm&\u000f\ufffd\ufffd\ufffd\ufffd\t\ufffd\ufffd"} 2025-12-29T03:24:57.013Z DEBUG chain/lfb_round_node.go:33 [mvc] store lfb {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "mb number": 1} 2025-12-29T03:24:57.013Z DEBUG chain/worker.go:443 BC is moving {"current_lfb_round": 5959412, "last_round": 5959411} 2025-12-29T03:24:57.013Z DEBUG chain/entity.go:2269 set lfb {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "state_computed": true} 2025-12-29T03:24:57.013Z DEBUG transaction/worker.go:253 [mvc] see no old txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056240} 2025-12-29T03:24:57.013Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.013Z DEBUG chain/state.go:128 finalize round - rebased current state db {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "state hash": "cbad06fff4564f6e15ca18fa0d5d848372876f57d0ea22dfbdcf88d09cbf6c02"} 2025-12-29T03:24:57.013Z DEBUG util/mpt_nodedb.go:556 LevelNodeDB rebase db 2025-12-29T03:24:57.013Z DEBUG transaction/worker.go:221 [mvc] remove old nonce txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056240} 2025-12-29T03:24:57.013Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "Q\ufffdR\ufffd={ԍ\ufffd\ufffd\ufffd\ufffd\ufffd\u0003\ufffdPU\ufffdx{\ufffd\ufffd\ufffdc\u000f\u0012ч\ufffd\ufffd\\\ufffd"} 2025-12-29T03:24:57.013Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdp\ufffdĉi'YЄN\ufffd\ufffd\ufffdt\u00050\u0014Ȩ\u0006׺\u001a\ufffdo&\ufffd\ufffd\u0008N\ufffd"} 2025-12-29T03:24:57.012Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd?\ufffd\u001d\ufffdK\ufffd\ufffdi\ufffd\ufffd4a\u000e\ufffdD\ufffd۱\ufffd\u0014^\ufffd\ufffd\ufffd\u0008ֶ\u0019S\ufffd"} 2025-12-29T03:24:57.012Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\ufffd\u001f\ufffd\ufffdI@\ufffd\ufffdB\ufffdM7\ufffd)\ufffdʪ\ufffd%\ufffd1\u0008\u001a[@`\ufffd\u0017*\ufffd"} 2025-12-29T03:24:57.012Z DEBUG chain/protocol_block.go:424 finalize block - record dead nodes {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "num dead nodes": 24} 2025-12-29T03:24:57.012Z INFO transaction/worker.go:164 cleaning transactions {"collection": "collection.txn:0afc093ffb509f059c55478bc1a60351cef7b4e9c008a53a6cc8241ca8617dfe", "missing_count": 2, "txns": ["d828e285749db74e66fce0dc4d5c23dc4e41ed0b351c14a197a3006872e52936", "2443a4df6e9d2fd3749bab30473d81cd36f47a1dcd0e2dc4850a77ad3163a0af"], "past txns": []} 2025-12-29T03:24:57.012Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:57.012Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "3.363949ms"} 2025-12-29T03:24:57.012Z INFO miner/protocol_bls.go:481 DKG ThresholdNumSigReceived VRF is already completed. {"round": 5959416} 2025-12-29T03:24:57.012Z INFO round/entity.go:675 add_vrf_share already at threshold. Returning false. 2025-12-29T03:24:57.012Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959416, "node_id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "share": "1 177bfe50344394edb8a9fa6cd34c8e5364d5012423b4e27cc84832c1255693 231cdb6140b1fc1248a6b27dee5d8cf82680558a0b7c48af322e037fd81bd4b4", "from": "196b96df7631e00d339f9a25131927c4", "message": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.012Z DEBUG transaction/worker.go:124 cleaning past transactions 2025-12-29T03:24:57.012Z DEBUG miner/round.go:238 Round clear - cancel verification 2025-12-29T03:24:57.012Z DEBUG miner/chain.go:313 delete txns {"txns": ["d828e285749db74e66fce0dc4d5c23dc4e41ed0b351c14a197a3006872e52936", "2443a4df6e9d2fd3749bab30473d81cd36f47a1dcd0e2dc4850a77ad3163a0af"]} 2025-12-29T03:24:57.012Z INFO miner/protocol_block.go:619 update finalized block (debug transaction) {"txn": "2443a4df6e9d2fd3749bab30473d81cd36f47a1dcd0e2dc4850a77ad3163a0af", "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6"} 2025-12-29T03:24:57.011Z INFO miner/protocol_block.go:619 update finalized block (debug transaction) {"txn": "d828e285749db74e66fce0dc4d5c23dc4e41ed0b351c14a197a3006872e52936", "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6"} 2025-12-29T03:24:57.011Z INFO miner/protocol_block.go:613 update finalized block {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "lf_round": 5959411, "current_round": 5959416, "weight": 1} 2025-12-29T03:24:57.011Z DEBUG chain/protocol_block.go:409 MPT state node stat - inc {"node num": -18147810, "change num": 18, "delete num": 24} 2025-12-29T03:24:57.011Z DEBUG bls/dkg.go:396 dkg verify {"id": "196b96df7631e00d339f9a25131927c4", "key": "1 1f8c72cc3a6b74f6ad7528172b64afe3dc42ee1987495659fe2cf34e749caa7c 20957b4d25e7b5fb1e32d4e3b93e2b73dad7f118efb6dae28715e4ad627b0fff 14259a6ddf4b44c83c1389bd8ed1fc47c1b099c5986bb33923bbcbb25d30054a 2100bb7434dadde1a5ed8545748d0958955bcd21e06cf170f78dbda35a9e04b7", "msg": "5959416076cc81c5c81e8252", "sig": "1 177bfe50344394edb8a9fa6cd34c8e5364d5012423b4e27cc84832c1255693 231cdb6140b1fc1248a6b27dee5d8cf82680558a0b7c48af322e037fd81bd4b4"} 2025-12-29T03:24:57.011Z INFO miner/protocol_bls.go:481 DKG ThresholdNumSigReceived VRF is already completed. {"round": 5959416} 2025-12-29T03:24:57.011Z INFO round/entity.go:675 add_vrf_share already at threshold. Returning false. 2025-12-29T03:24:57.011Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959416, "node_id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335", "from": "1c3227e496fdf3c9aa604f3470a2bbd1", "message": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.011Z INFO miner/protocol_round.go:830 Starting round verification {"round": 5959416, "delta": "180ms"} 2025-12-29T03:24:57.011Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959416, "r.minerPerm": [1, 0, 2]} 2025-12-29T03:24:57.011Z INFO miner/protocol_round.go:322 TOC_FIX Not a generator {"round": 5959416, "index": 2, "rank": 2, "timeout_count": 0, "random_seed": -8376229592661856522} 2025-12-29T03:24:57.011Z INFO miner/protocol_bls.go:591 Starting round with vrf {"round": 5959416, "roundtimeout": 0, "rseed": -8376229592661856522, "prev_round": 5959415, "Prev_rseed": 0} 2025-12-29T03:24:57.011Z DEBUG miner/protocol_bls.go:542 DKG computeRBO {"round": 5959416} 2025-12-29T03:24:57.011Z INFO miner/protocol_bls.go:524 receive bls sign {"round": 5959416, "group_signature": "1 179c9375b2b8e23988d92d86406a5fd91bf226a2493ee6aff0c8db573e81a3a6 8c4361646fc890c760d3ef46878bad87a6fc568d98095a185da986b081532f5", "rboOutput": "8bc1a7908c466ef6ddd61a714bfcffaef172e5e2159bb3161f7e145d582945ca", "dkg_starting_round": 0, "dkg_mb_number": 1} 2025-12-29T03:24:57.011Z INFO block/entity.go:1195 save state {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "block_size": 2, "changes": 18, "client_state": "cbad06fff4564f6e15ca18fa0d5d848372876f57d0ea22dfbdcf88d09cbf6c02", "duration": "2.269213ms"} 2025-12-29T03:24:57.010Z DEBUG miner/protocol_bls.go:496 VRF Hurray we've threshold BLS shares {"round": 5959416, "round pointer": "0xc0706a7320"} 2025-12-29T03:24:57.010Z DEBUG bls/dkg.go:396 dkg verify {"id": "1c3227e496fdf3c9aa604f3470a2bbd1", "key": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "msg": "5959416076cc81c5c81e8252", "sig": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.010Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959416, "round_vrf_num": 2, "threshold": 2} 2025-12-29T03:24:57.010Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959416, "node_id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335", "from": "1c3227e496fdf3c9aa604f3470a2bbd1", "message": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.010Z DEBUG util/merkle_patricia_trie.go:272 MPT save changes success {"duration": "1.753415ms"} 2025-12-29T03:24:57.010Z DEBUG util/mpt_node_change.go:156 MPT - update changes done {"changes": 18} 2025-12-29T03:24:57.009Z DEBUG bls/dkg.go:396 dkg verify {"id": "1c3227e496fdf3c9aa604f3470a2bbd1", "key": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "msg": "5959416076cc81c5c81e8252", "sig": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.009Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959416, "round_vrf_num": 1, "threshold": 2} 2025-12-29T03:24:57.009Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959416, "node_id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "share": "1 6e2495bf1b0f13b2502f4018390169b3b1f9ac883fb509d47cdef11d144dbc2 22c8c125de4ae7d41cc07f5a098debf0e02f397eb7f228a7d2b29c1901159d91", "from": "18f9a15b9551f8552a19cf4750ef14bf", "message": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.009Z DEBUG util/mpt_node_change.go:150 MPT - update changes {"changes": 18} 2025-12-29T03:24:57.009Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959416, "round_timeout": 0, "prev_rseed": 8560359678190453330, "prev round vrf random seed": "76cc81c5c81e8252", "bls_msg": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.009Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959416, "round_vrf_num": 0, "threshold": 2, "sender": 1, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 177bfe50344394edb8a9fa6cd34c8e5364d5012423b4e27cc84832c1255693 231cdb6140b1fc1248a6b27dee5d8cf82680558a0b7c48af322e037fd81bd4b4"} 2025-12-29T03:24:57.009Z DEBUG miner/protocol_receive.go:26 handle vrf share {"round": 5959416, "vrf_timeout_count": 0, "sender_index": 1} 2025-12-29T03:24:57.009Z DEBUG miner/worker.go:53 message {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:57.009Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959416, "round_timeout": 0, "prev_rseed": 8560359678190453330, "prev round vrf random seed": "76cc81c5c81e8252", "bls_msg": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.009Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959416, "round_vrf_num": 0, "threshold": 2, "sender": 2, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.009Z INFO miner/protocol_round.go:78 add_my_vrf_share {"round": 5959416, "round_timeout": 0, "dkg_starting_round": 0, "dkg_mb_number": 1, "pr_seed": 8560359678190453330, "pr_vrf_seed": "76cc81c5c81e8252", "share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.009Z DEBUG miner/protocol_bls.go:305 get_bls_share {"round": 5959416, "rtc": 0, "dkg_pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "dkg_sr": 0, "mb_sr": 0, "share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.008Z DEBUG bls/dkg.go:396 dkg verify {"id": "18f9a15b9551f8552a19cf4750ef14bf", "key": "1 15981a99909f8d701bca846be62c295e8958436831d3087ded78a1719a92f9a9 1bc96c0fea12a9ee4451600417977d1e987b252b469ea7c7be9d40e681fceff4 164089f6538d2b42ac3b37af7bc35e2550212af1e8ccedfeec4bde4f472dd384 aa502eaa78fb0cc02bd92538e258b55b1495cdc393205378d4ead83eb106f33", "msg": "5959416076cc81c5c81e8252", "sig": "1 6e2495bf1b0f13b2502f4018390169b3b1f9ac883fb509d47cdef11d144dbc2 22c8c125de4ae7d41cc07f5a098debf0e02f397eb7f228a7d2b29c1901159d91"} 2025-12-29T03:24:57.008Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959416, "round_timeout": 0, "prev_rseed": 8560359678190453330, "prev round vrf random seed": "76cc81c5c81e8252", "bls_msg": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.008Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959416, "round_vrf_num": 0, "threshold": 2, "sender": 2, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.008Z INFO miner/protocol_round.go:78 add_my_vrf_share {"round": 5959416, "round_timeout": 0, "dkg_starting_round": 0, "dkg_mb_number": 1, "pr_seed": 8560359678190453330, "pr_vrf_seed": "76cc81c5c81e8252", "share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.008Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959412, "r.minerPerm": [0, 1, 2]} 2025-12-29T03:24:57.008Z DEBUG miner/protocol_bls.go:305 get_bls_share {"round": 5959416, "rtc": 0, "dkg_pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "dkg_sr": 0, "mb_sr": 0, "share": "1 64499eb19400fb7591c1f5ce10a83fa4ca1dc53b4452e237f1c22309c413f18 15d3751299cf4db3c202e24ed3dbb242baa3d25ee2653f3b664bda4d33e50335"} 2025-12-29T03:24:57.008Z INFO chain/protocol_block.go:363 finalize block -- round {"round": {"number":5959412,"round_random_seed":6791936131168774639,"block_hash":"465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6","vrf_output":"5e41cded1374a9efcf2799ad1eacfb2a7424427d60b616a78aded42a2d4dbaa6"}, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6"} 2025-12-29T03:24:57.008Z DEBUG bls/dkg.go:356 dkg sign {"key": "21d97039e984194d599f2bf60c03d185c67ff756eba44a56ac9cf108c33c814a", "pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053"} 2025-12-29T03:24:57.008Z INFO chain/protocol_block.go:328 finalize block {"round": 5959412, "current_round": 5959416, "lf_round": 5959411, "hash": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "round_rank": 0, "state": 7} 2025-12-29T03:24:57.008Z DEBUG chain/worker.go:262 start to finalize block {"round": 5959412, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6", "prev block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c"} 2025-12-29T03:24:57.008Z DEBUG miner/protocol_bls.go:293 Sign msg with dkg {"msg": "5959416076cc81c5c81e8252", "round": 5959416, "dkg starting round": 0} 2025-12-29T03:24:57.008Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959416, "round_timeout": 0, "prev_rseed": 8560359678190453330, "prev round vrf random seed": "76cc81c5c81e8252", "bls_msg": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.008Z INFO chain/protocol_round.go:359 finalize round {"round": 5959412, "lfb round": 5959414, "block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6"} 2025-12-29T03:24:57.008Z INFO chain/protocol_round.go:326 finalize round - latest finalized round {"round": 5959415, "lfb round": 5959414, "lfb block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:57.008Z INFO miner/protocol_bls.go:283 DKG getBlsShare {"Round Number": 5959416} 2025-12-29T03:24:57.008Z INFO chain/protocol_round.go:199 finalize round {"round": 5959415, "plfb_round": 5959411, "num_round_notarized": 1, "num_chain_notarized": 3} 2025-12-29T03:24:57.008Z DEBUG chain/protocol_round.go:160 finalize round {"round": 5959415, "lf_round": 5959411} 2025-12-29T03:24:57.008Z DEBUG chain/protocol_round.go:156 finalize_round: already finalizing {"round": 5959415} 2025-12-29T03:24:57.008Z INFO miner/protocol_round.go:244 StartNextRound - add VRF {"round": 5959416} 2025-12-29T03:24:57.008Z DEBUG chain/protocol_round.go:160 finalize round {"round": 5959415, "lf_round": 5959411} 2025-12-29T03:24:57.008Z DEBUG bls/dkg.go:356 dkg sign {"key": "21d97039e984194d599f2bf60c03d185c67ff756eba44a56ac9cf108c33c814a", "pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053"} 2025-12-29T03:24:57.008Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959415} 2025-12-29T03:24:57.008Z DEBUG miner/protocol_bls.go:293 Sign msg with dkg {"msg": "5959416076cc81c5c81e8252", "round": 5959416, "dkg starting round": 0} 2025-12-29T03:24:57.008Z DEBUG miner/protocol_round.go:210 finalizedRound - cancel round verification {"round": 5959415} 2025-12-29T03:24:57.008Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959416, "round_timeout": 0, "prev_rseed": 8560359678190453330, "prev round vrf random seed": "76cc81c5c81e8252", "bls_msg": "5959416076cc81c5c81e8252"} 2025-12-29T03:24:57.008Z INFO miner/protocol_bls.go:283 DKG getBlsShare {"Round Number": 5959416} 2025-12-29T03:24:57.008Z INFO miner/protocol_round.go:244 StartNextRound - add VRF {"round": 5959416} 2025-12-29T03:24:57.007Z INFO miner/protocol_receive.go:370 process notarization success {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "duration": "2.032209ms"} 2025-12-29T03:24:57.008Z DEBUG miner/protocol_round.go:154 [wait not ahead] [2] not ahead, can move on {"round": 5959415, "lfb tk round": 5959411} 2025-12-29T03:24:57.008Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959415} 2025-12-29T03:24:57.008Z DEBUG miner/protocol_round.go:210 finalizedRound - cancel round verification {"round": 5959415} 2025-12-29T03:24:57.008Z INFO chain/entity.go:2016 Moving to the next round {"next_round": 5959416} 2025-12-29T03:24:57.007Z DEBUG miner/protocol_round.go:123 [wait not ahead] 2025-12-29T03:24:57.007Z DEBUG miner/protocol_round.go:154 [wait not ahead] [2] not ahead, can move on {"round": 5959415, "lfb tk round": 5959411} 2025-12-29T03:24:57.007Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959415} 2025-12-29T03:24:57.007Z INFO miner/protocol_receive.go:456 process notarization - start next round {"new round": 5959416} 2025-12-29T03:24:57.007Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "166.413µs"} 2025-12-29T03:24:57.007Z DEBUG round/entity.go:311 add notarized block - block already exist, merge tickets {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:57.007Z DEBUG miner/protocol_receive.go:33 received VRF share for the future round, caching it {"current_round": 5959415, "vrf_share_round": 5959416} 2025-12-29T03:24:57.007Z DEBUG miner/protocol_receive.go:26 handle vrf share {"round": 5959416, "vrf_timeout_count": 0, "sender_index": 0} 2025-12-29T03:24:57.007Z DEBUG miner/worker.go:53 message {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:57.007Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.007Z INFO chain/entity.go:1571 Adding a notarized block for current round {"Round": 5959415} 2025-12-29T03:24:57.007Z DEBUG miner/protocol_round.go:991 verification_complete {"top_verified_rank": 0} 2025-12-29T03:24:57.007Z INFO miner/protocol_round.go:986 verification_complete {"round": 5959415, "verified_blocks": 0} 2025-12-29T03:24:57.007Z DEBUG miner/protocol_round.go:123 [wait not ahead] 2025-12-29T03:24:57.007Z INFO miner/round.go:44 Cancelling block generation {"round": 5959415} 2025-12-29T03:24:57.007Z INFO miner/round.go:230 Cancelling verification {"round": 5959415} 2025-12-29T03:24:57.007Z INFO miner/protocol_receive.go:456 process notarization - start next round {"new round": 5959416} 2025-12-29T03:24:57.007Z DEBUG miner/protocol_round.go:1149 check block notarization - block notarized {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:57.007Z DEBUG round/entity.go:344 reached notarization {"round": 5959415} 2025-12-29T03:24:57.007Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:57.007Z INFO chain/entity.go:1571 Adding a notarized block for current round {"Round": 5959415} 2025-12-29T03:24:57.007Z INFO chain/protocol_block.go:319 reached notarization - merging tickets {"round": 5959415, "current_round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "tickets_num": 2} 2025-12-29T03:24:57.006Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "114.391µs"} 2025-12-29T03:24:57.006Z DEBUG miner/worker.go:53 message {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 1, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:57.005Z DEBUG miner/protocol_receive.go:428 process notarization - merge notarization block {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:57.005Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "81.111µs"} 2025-12-29T03:24:57.005Z DEBUG miner/worker.go:53 message {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:56.988Z DEBUG miner/protocol_round.go:943 verifyAndSend - finished successfully {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:56.988Z INFO miner/protocol_round.go:1127 checkBlockNotarization -- block is not Notarized. Returning {"round": 5959415, "block hash": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:56.988Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 1, "current_round": 5959415, "round": 5959415} 2025-12-29T03:24:56.988Z INFO miner/protocol_round.go:920 verifyAndSend - sending verification ticket {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "block_rank": 0, "RRS": 8560359678190453330} 2025-12-29T03:24:56.988Z DEBUG miner/protocol_round.go:1040 verify round block {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:56.988Z DEBUG miner/protocol_round.go:865 verifyAndSend - started {"block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:56.988Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.988Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.988Z INFO miner/protocol_round.go:951 Started main verification loop {"round": 5959415} 2025-12-29T03:24:56.958Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959411, "latest.Round": 5959411} 2025-12-29T03:24:56.914Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959415} 2025-12-29T03:24:56.909Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959411, "latest.Round": 5959411} 2025-12-29T03:24:56.886Z INFO miner/worker.go:187 round worker: got_timeout {"next": 1500} 2025-12-29T03:24:56.886Z INFO miner/protocol_round.go:1322 nextTimeout {"tick": 1500} 2025-12-29T03:24:56.826Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e"} 2025-12-29T03:24:56.826Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.826Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959415, "block hash": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:56.826Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959415, "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "prev_block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "state_hash": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156", "weight": 1} 2025-12-29T03:24:56.826Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.826Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959415, "block": "876023943993ef601a694df8e9c6d14e47ca9a4c91abd872deaf6bf076627369"} 2025-12-29T03:24:56.825Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.825Z DEBUG statecache/statecache.go:131 statecache - commit {"block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "bc_hits": 90, "bc_miss": 0, "sc_hits": 884859038, "sc_miss": 448360, "duration": "694.17µs"} 2025-12-29T03:24:56.825Z DEBUG miner/protocol_receive.go:130 verify block processed {"round": 5959415, "block": "876023943993ef601a694df8e9c6d14e47ca9a4c91abd872deaf6bf076627369"} 2025-12-29T03:24:56.825Z DEBUG miner/protocol_round.go:759 update prev block notarization, already notarized {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.825Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959415, "block hash": "876023943993ef601a694df8e9c6d14e47ca9a4c91abd872deaf6bf076627369", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:56.825Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959415, "block": "876023943993ef601a694df8e9c6d14e47ca9a4c91abd872deaf6bf076627369", "prev_block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "state_hash": "a0cc0e9e27763b411afd76f31c395ba39deef1987dc0f8fa58d3eefdcf7def31", "weight": 1} 2025-12-29T03:24:56.825Z INFO miner/protocol_round.go:613 handle verify block - added block for ticket verification {"round": 5959415, "block": "876023943993ef601a694df8e9c6d14e47ca9a4c91abd872deaf6bf076627369", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:56.825Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 0, "current_round": 5959415, "round": 5959415} 2025-12-29T03:24:56.825Z INFO miner/protocol_block.go:1316 generate block (assemble+update+sign) {"round": 5959415, "block_size": 1, "reused_txns": 0, "reused_txns": 0, "time": "17.855354ms", "block": "09a63710b4f60bcd0c11d063004aab4c5a3410de017b98eaba7431ed8606897e", "prev_block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "begin_state_hash": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188", "block_state_hash": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156", "computed_state_hash": "b860cf0ed2ebba1ec9baa713436e898e3a71e4e3e11b9eee964781357a157156", "changes": 18, "state_status": 4, "iteration_count": 0} 2025-12-29T03:24:56.824Z DEBUG miner/protocol_block.go:1305 generate block (assemble+update) {"round": 5959415, "txns": 1, "time": "17.6086ms"} 2025-12-29T03:24:56.824Z DEBUG miner/protocol_block.go:1282 generate block (assemble) {"round": 5959415, "txns": 1, "time": "17.553719ms"} 2025-12-29T03:24:56.824Z DEBUG miner/protocol_receive.go:169 verify block {"round": 5959415, "block": "876023943993ef601a694df8e9c6d14e47ca9a4c91abd872deaf6bf076627369"} 2025-12-29T03:24:56.824Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "70.621µs"} 2025-12-29T03:24:56.824Z DEBUG miner/worker.go:53 message {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:56.822Z DEBUG chain/state.go:961 Updating nonce {"client": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "new_nonce": 3056244} 2025-12-29T03:24:56.821Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.821Z INFO chain/state.go:533 SC executed {"client id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "block": "", "round": 5959415, "prev_state_hash": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188", "txn_hash": "3c8130c7421b7828c9a6e11a9aa22322dec347fea4f1f91f37bef60b50b65c7b", "txn_nonce": 3056244, "txn_func": "payFees", "txn_status": 0, "txn_exec_time": "9.693953ms", "begin client state": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188", "current_root": "42eef1f765a194e822efa3fff58df56bb08501c954b61ee41b206a0ddfd98b4d", "mpt_cache_hit": 72, "mpt_cache_miss": 0, "output": ""} 2025-12-29T03:24:56.820Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:56.820Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:56.819Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:56.818Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:56.813Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959415} 2025-12-29T03:24:56.812Z ERROR minersc/global_node.go:372 failed to get current magic block {"error": "value not present"} 0chain.net/smartcontract/minersc.(*GlobalNode).prevMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/global_node.go:372 0chain.net/smartcontract/minersc.getRegisterShardersInMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:659 0chain.net/smartcontract/minersc.(*MinerSmartContract).payFees /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:519 0chain.net/smartcontract/minersc.(*MinerSmartContract).Execute /0chain/code/go/0chain.net/smartcontract/minersc/sc.go:139 0chain.net/chaincore/smartcontract.ExecuteWithStats /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:52 0chain.net/chaincore/smartcontract.ExecuteSmartContract /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:67 0chain.net/chaincore/chain.(*Chain).ExecuteSmartContract.func1 /0chain/code/go/0chain.net/chaincore/chain/state.go:158 2025-12-29T03:24:56.812Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9d2208fe938b7465b9c5dc571053986cf8dbf3585fb36399f98ea569c42f50b76"} 2025-12-29T03:24:56.812Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9b5e8a1650666fdd3a9de7855b6da63ab629b1701f23738477e2325f7ca2150ac"} 2025-12-29T03:24:56.812Z DEBUG minersc/fees.go:480 pay_fees, got miner id successfully {"miner id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "round": 5959415, "block": ""} 2025-12-29T03:24:56.811Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:56.811Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:56.811Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.810Z DEBUG chain/protocol_view_change.go:424 [mvc] nonce, set nonce in getCurrentSelfNonce {"nonce": 3056243} 2025-12-29T03:24:56.810Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "x5\u0018\ufffdO\u001eb\ufffdj\u0006\ufffd\ufffdN$\ufffdY\ufffd\ufffd\ufffd\ufffd=\u001f\ufffd\ufffd\ufffd<\u0019\ufffd7\ufffd!J"} 2025-12-29T03:24:56.810Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959415, "below_round": 5959362, "before": 1766978636, "total": 305, "count": 0} 2025-12-29T03:24:56.810Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffdګ\ufffd^ f9\ufffd\ufffd\ufffd:\ufffd\ufffd\ufffd*\ufffd\ufffd0\ufffd'\ufffd\u0011\ufffd\ufffd\ufffd\u001cx_\u0011\u0016"} 2025-12-29T03:24:56.810Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:56.810Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:56.810Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffddVp\u0006\u001b\ufffdmXT\ufffdJ\ufffd\ufffd\ufffd~\u0004_\ufffd\ufffd\ufffd\u0001z\ufffd\ufffd\ufffd\ufffd\ufffdR\ufffd\ufffd"} 2025-12-29T03:24:56.810Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd`\u0005\ufffd\ufffd\u0013\ufffd\u000eL\ufffd\u0019\ufffd\ufffdu C"} 2025-12-29T03:24:56.809Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0006'\u0012\ufffdu\tWg\ufffdt\ufffd\ufffd\r\ufffd\ufffd\ufffd\u0007ݏ\ufffd\ufffd3\u0015i\ufffd\ufffd\ufffd\ufffd\ufffdkZy"} 2025-12-29T03:24:56.809Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "W;η\ufffdk!\ufffd\ufffdm\u0019\ufffd6U]b\ufffd\ufffd]%3\ufffdi)F7\ufffd\ufffdٱ\u001a\ufffd"} 2025-12-29T03:24:56.809Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd[\ufffd4c`\ufffdI\u0006\u0016\ufffd\ufffdk\t\ufffd\u0004\ufffd\u000c\ufffd\u000cμݏ\ufffd\u0010\u0014H=1\ufffd"} 2025-12-29T03:24:56.809Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.809Z DEBUG chain/protocol_view_change.go:431 [mvc] nonce, get current miner nonce {"minerId": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea"} 2025-12-29T03:24:56.809Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:56.809Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0014\ufffd\ufffd2U\u0015\ufffd(9\ufffd\ufffd\ufffdj\ufffd\u0003\ufffd E甠{\ufffd\ufffd\ufffd\ufffd\u0014b\ufffd"} 2025-12-29T03:24:56.809Z INFO miner/protocol_block.go:1112 generate block starting iteration {"round": 5959415, "prev_block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "prev_state_hash": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188"} 2025-12-29T03:24:56.809Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\ufffd\ufffdK\ufffd7|(H\ufffd\ufffdɲ!a\ufffdQU:f\ufffdm&\u000f\ufffd\ufffd\ufffd\ufffd\t\ufffd\ufffd"} 2025-12-29T03:24:56.809Z DEBUG transaction/worker.go:253 [mvc] see no old txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056240} 2025-12-29T03:24:56.809Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:56.809Z DEBUG transaction/worker.go:221 [mvc] remove old nonce txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056240} 2025-12-29T03:24:56.808Z DEBUG chain/lfb_round_node.go:33 [mvc] store lfb {"round": 5959411, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "mb number": 1} 2025-12-29T03:24:56.808Z DEBUG chain/worker.go:443 BC is moving {"current_lfb_round": 5959411, "last_round": 5959410} 2025-12-29T03:24:56.808Z DEBUG chain/entity.go:2269 set lfb {"round": 5959411, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "state_computed": true} 2025-12-29T03:24:56.808Z DEBUG statecache/statecache.go:173 state cache - see gap {"block": "95ca3b3cd06bbb4242b9c93b7d97443dde54f64ab413a558c94c77e427e687dc"} 2025-12-29T03:24:56.808Z DEBUG chain/state.go:128 finalize round - rebased current state db {"round": 5959411, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "state hash": "598681d7ddd5c688ce4ce70efd7d267d07512569076b2187a7d0a247aeaabdc8"} 2025-12-29T03:24:56.808Z INFO transaction/worker.go:164 cleaning transactions {"collection": "collection.txn:0afc093ffb509f059c55478bc1a60351cef7b4e9c008a53a6cc8241ca8617dfe", "missing_count": 1, "txns": ["346666044b4332e7241bf0feb4bf2c36be333e89c2e7bc26dfd651bea56a197e"], "past txns": []} 2025-12-29T03:24:56.808Z DEBUG util/mpt_nodedb.go:556 LevelNodeDB rebase db 2025-12-29T03:24:56.808Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:56.808Z DEBUG chain/protocol_block.go:424 finalize block - record dead nodes {"round": 5959411, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "num dead nodes": 18} 2025-12-29T03:24:56.808Z DEBUG transaction/worker.go:124 cleaning past transactions 2025-12-29T03:24:56.808Z DEBUG miner/round.go:238 Round clear - cancel verification 2025-12-29T03:24:56.808Z INFO miner/protocol_bls.go:481 DKG ThresholdNumSigReceived VRF is already completed. {"round": 5959415} 2025-12-29T03:24:56.808Z INFO round/entity.go:675 add_vrf_share already at threshold. Returning false. 2025-12-29T03:24:56.808Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959415, "node_id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4", "from": "1c3227e496fdf3c9aa604f3470a2bbd1", "message": "595941504855fa0460e71112"} 2025-12-29T03:24:56.808Z DEBUG miner/chain.go:313 delete txns {"txns": ["346666044b4332e7241bf0feb4bf2c36be333e89c2e7bc26dfd651bea56a197e"]} 2025-12-29T03:24:56.808Z INFO miner/protocol_block.go:619 update finalized block (debug transaction) {"txn": "346666044b4332e7241bf0feb4bf2c36be333e89c2e7bc26dfd651bea56a197e", "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c"} 2025-12-29T03:24:56.808Z INFO miner/protocol_block.go:613 update finalized block {"round": 5959411, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "lf_round": 5959410, "current_round": 5959415, "weight": 1} 2025-12-29T03:24:56.808Z DEBUG chain/protocol_block.go:409 MPT state node stat - inc {"node num": -18147828, "change num": 18, "delete num": 18} 2025-12-29T03:24:56.807Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "3.456461ms"} 2025-12-29T03:24:56.807Z INFO block/entity.go:1195 save state {"round": 5959411, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "block_size": 1, "changes": 18, "client_state": "598681d7ddd5c688ce4ce70efd7d267d07512569076b2187a7d0a247aeaabdc8", "duration": "2.491577ms"} 2025-12-29T03:24:56.807Z DEBUG bls/dkg.go:396 dkg verify {"id": "1c3227e496fdf3c9aa604f3470a2bbd1", "key": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "msg": "595941504855fa0460e71112", "sig": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.807Z INFO miner/protocol_bls.go:481 DKG ThresholdNumSigReceived VRF is already completed. {"round": 5959415} 2025-12-29T03:24:56.807Z INFO round/entity.go:675 add_vrf_share already at threshold. Returning false. 2025-12-29T03:24:56.807Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959415, "node_id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "share": "1 11eee9701b82ff38831f1a7e3cc7f41743c856954d4fbf512c55edbcc2d16bea 74f41283ca1bf819cf83657da1355677b8c206158a7242d1e4b5b268de4472a", "from": "196b96df7631e00d339f9a25131927c4", "message": "595941504855fa0460e71112"} 2025-12-29T03:24:56.807Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959415, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.807Z DEBUG miner/protocol_round.go:467 Setting LFMB round/hash for a block {"rn": 5959415, "mc.crn": 5959415, "rnoff": 5959390, "nvc": 0, "r": 0, "h": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "b.lfmbr": 0, "b.lfmbh": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4"} 2025-12-29T03:24:56.807Z INFO miner/protocol_round.go:830 Starting round verification {"round": 5959415, "delta": "180ms"} 2025-12-29T03:24:56.807Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959415, "r.minerPerm": [1, 2, 0]} 2025-12-29T03:24:56.807Z DEBUG util/merkle_patricia_trie.go:272 MPT save changes success {"duration": "1.939889ms"} 2025-12-29T03:24:56.807Z INFO miner/protocol_round.go:340 *** TOC_FIX starting round block generation *** {"round": 5959415, "index": 2, "rank": 0, "timeout_count": 0, "random_seed": 8560359678190453330, "lf_round": 5959410} 2025-12-29T03:24:56.807Z DEBUG util/mpt_node_change.go:156 MPT - update changes done {"changes": 18} 2025-12-29T03:24:56.807Z INFO miner/protocol_bls.go:591 Starting round with vrf {"round": 5959415, "roundtimeout": 0, "rseed": 8560359678190453330, "prev_round": 5959414, "Prev_rseed": 0} 2025-12-29T03:24:56.806Z DEBUG miner/protocol_bls.go:542 DKG computeRBO {"round": 5959415} 2025-12-29T03:24:56.806Z INFO miner/protocol_bls.go:524 receive bls sign {"round": 5959415, "group_signature": "1 23f07358ca17ffed5ed7a337f5a2607bf8f2ad55bdcc7463436adec9ddcd6a29 75cffd401aa9c1acb2e53e2b265c7638f98c7c08041bb95d6b64cb9e96ede3a", "rboOutput": "76cc81c5c81e825233fa7f1394006c524b41edc3010c493642045d0b46ece544", "dkg_starting_round": 0, "dkg_mb_number": 1} 2025-12-29T03:24:56.806Z DEBUG bls/dkg.go:396 dkg verify {"id": "196b96df7631e00d339f9a25131927c4", "key": "1 1f8c72cc3a6b74f6ad7528172b64afe3dc42ee1987495659fe2cf34e749caa7c 20957b4d25e7b5fb1e32d4e3b93e2b73dad7f118efb6dae28715e4ad627b0fff 14259a6ddf4b44c83c1389bd8ed1fc47c1b099c5986bb33923bbcbb25d30054a 2100bb7434dadde1a5ed8545748d0958955bcd21e06cf170f78dbda35a9e04b7", "msg": "595941504855fa0460e71112", "sig": "1 11eee9701b82ff38831f1a7e3cc7f41743c856954d4fbf512c55edbcc2d16bea 74f41283ca1bf819cf83657da1355677b8c206158a7242d1e4b5b268de4472a"} 2025-12-29T03:24:56.806Z DEBUG miner/protocol_bls.go:496 VRF Hurray we've threshold BLS shares {"round": 5959415, "round pointer": "0xc0759445a0"} 2025-12-29T03:24:56.806Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959415, "round_vrf_num": 2, "threshold": 2} 2025-12-29T03:24:56.806Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959415, "node_id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4", "from": "1c3227e496fdf3c9aa604f3470a2bbd1", "message": "595941504855fa0460e71112"} 2025-12-29T03:24:56.805Z DEBUG util/mpt_node_change.go:150 MPT - update changes {"changes": 18} 2025-12-29T03:24:56.805Z DEBUG bls/dkg.go:396 dkg verify {"id": "1c3227e496fdf3c9aa604f3470a2bbd1", "key": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "msg": "595941504855fa0460e71112", "sig": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.805Z DEBUG round/entity.go:684 add_vrf_share {"round": 5959415, "round_vrf_num": 1, "threshold": 2} 2025-12-29T03:24:56.805Z INFO miner/protocol_bls.go:430 verified vrf {"round": 5959415, "node_id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "share": "1 147bdb59e7dc830705e529daa1b3221d0a2cb1677326097aeb7779c407e1b7f7 13ca8d7a69ca7fa37f042f7498448891e505f8cf3d853fd2d3af288c405c8186", "from": "18f9a15b9551f8552a19cf4750ef14bf", "message": "595941504855fa0460e71112"} 2025-12-29T03:24:56.805Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959415, "round_timeout": 0, "prev_rseed": 5212347040463786258, "prev round vrf random seed": "4855fa0460e71112", "bls_msg": "595941504855fa0460e71112"} 2025-12-29T03:24:56.805Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959415, "round_vrf_num": 0, "threshold": 2, "sender": 2, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.805Z INFO miner/protocol_round.go:78 add_my_vrf_share {"round": 5959415, "round_timeout": 0, "dkg_starting_round": 0, "dkg_mb_number": 1, "pr_seed": 5212347040463786258, "pr_vrf_seed": "4855fa0460e71112", "share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.805Z DEBUG miner/protocol_bls.go:305 get_bls_share {"round": 5959415, "rtc": 0, "dkg_pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "dkg_sr": 0, "mb_sr": 0, "share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.805Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959415, "round_timeout": 0, "prev_rseed": 5212347040463786258, "prev round vrf random seed": "4855fa0460e71112", "bls_msg": "595941504855fa0460e71112"} 2025-12-29T03:24:56.805Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959415, "round_vrf_num": 0, "threshold": 2, "sender": 2, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.805Z INFO miner/protocol_round.go:78 add_my_vrf_share {"round": 5959415, "round_timeout": 0, "dkg_starting_round": 0, "dkg_mb_number": 1, "pr_seed": 5212347040463786258, "pr_vrf_seed": "4855fa0460e71112", "share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_bls.go:305 get_bls_share {"round": 5959415, "rtc": 0, "dkg_pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053", "dkg_sr": 0, "mb_sr": 0, "share": "1 bcfb4f91e363a5d3aef700f8a413c8caa6c9b509cbab022d81b77a095fd9904 1d5575e6c5b9c937c4042f6660d64c273535aeab0d5b344ef227a4241c3f59a4"} 2025-12-29T03:24:56.804Z DEBUG bls/dkg.go:356 dkg sign {"key": "21d97039e984194d599f2bf60c03d185c67ff756eba44a56ac9cf108c33c814a", "pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053"} 2025-12-29T03:24:56.804Z INFO round/entity.go:618 get miners by rank {"num_miners": 3, "round": 5959411, "r.minerPerm": [1, 2, 0]} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_bls.go:293 Sign msg with dkg {"msg": "595941504855fa0460e71112", "round": 5959415, "dkg starting round": 0} 2025-12-29T03:24:56.804Z INFO chain/protocol_block.go:363 finalize block -- round {"round": {"number":5959411,"round_random_seed":-3681364024823543118,"block_hash":"756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c","vrf_output":"cce92aec937d8ab26276e21abf6d62bd9e4c80af6f5f58e79954d7c185b2a282"}, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c"} 2025-12-29T03:24:56.804Z INFO chain/protocol_block.go:328 finalize block {"round": 5959411, "current_round": 5959415, "lf_round": 5959410, "hash": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "round_rank": 0, "state": 7} 2025-12-29T03:24:56.804Z DEBUG chain/worker.go:262 start to finalize block {"round": 5959411, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c", "prev block": "7785a7db38b34d165011bd3f959a89e41ec1c0e3a3bbf54ae01afc1a83203564"} 2025-12-29T03:24:56.804Z DEBUG chain/protocol_round.go:160 finalize round {"round": 5959414, "lf_round": 5959410} 2025-12-29T03:24:56.804Z DEBUG chain/protocol_round.go:156 finalize_round: already finalizing {"round": 5959414} 2025-12-29T03:24:56.804Z INFO chain/protocol_round.go:359 finalize round {"round": 5959411, "lfb round": 5959413, "block": "756fa6e210bdd6fcbf231fdb6fad7b67046a1d72fed7f3d6939b18477006a17c"} 2025-12-29T03:24:56.804Z INFO chain/protocol_round.go:326 finalize round - latest finalized round {"round": 5959414, "lfb round": 5959413, "lfb block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5"} 2025-12-29T03:24:56.804Z INFO chain/protocol_round.go:199 finalize round {"round": 5959414, "plfb_round": 5959410, "num_round_notarized": 1, "num_chain_notarized": 3} 2025-12-29T03:24:56.804Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959415, "round_timeout": 0, "prev_rseed": 5212347040463786258, "prev round vrf random seed": "4855fa0460e71112", "bls_msg": "595941504855fa0460e71112"} 2025-12-29T03:24:56.804Z INFO miner/protocol_bls.go:283 DKG getBlsShare {"Round Number": 5959415} 2025-12-29T03:24:56.804Z INFO miner/protocol_round.go:244 StartNextRound - add VRF {"round": 5959415} 2025-12-29T03:24:56.804Z DEBUG bls/dkg.go:396 dkg verify {"id": "18f9a15b9551f8552a19cf4750ef14bf", "key": "1 15981a99909f8d701bca846be62c295e8958436831d3087ded78a1719a92f9a9 1bc96c0fea12a9ee4451600417977d1e987b252b469ea7c7be9d40e681fceff4 164089f6538d2b42ac3b37af7bc35e2550212af1e8ccedfeec4bde4f472dd384 aa502eaa78fb0cc02bd92538e258b55b1495cdc393205378d4ead83eb106f33", "msg": "595941504855fa0460e71112", "sig": "1 147bdb59e7dc830705e529daa1b3221d0a2cb1677326097aeb7779c407e1b7f7 13ca8d7a69ca7fa37f042f7498448891e505f8cf3d853fd2d3af288c405c8186"} 2025-12-29T03:24:56.804Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959414} 2025-12-29T03:24:56.804Z DEBUG bls/dkg.go:356 dkg sign {"key": "21d97039e984194d599f2bf60c03d185c67ff756eba44a56ac9cf108c33c814a", "pi": "1 6603e20478fac2c72a61b85056977b92696b8fffd408d8acac0e1f47aed3f96 1501242ed9000d9c6a02764160261164a52360a692c34ed452ff097bc90ee9b3 7f8aaa178d31fa4cbd0c1974768dfa4921e29405754ebf8d2c163a774b741a2 24515e04bd685a5559260f5d1868e5ea11abaf21f55a56aee52c15d9f3c2b053"} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_bls.go:293 Sign msg with dkg {"msg": "595941504855fa0460e71112", "round": 5959415, "dkg starting round": 0} 2025-12-29T03:24:56.804Z DEBUG chain/protocol_round.go:160 finalize round {"round": 5959414, "lf_round": 5959410} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_round.go:210 finalizedRound - cancel round verification {"round": 5959414} 2025-12-29T03:24:56.804Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959415, "round_timeout": 0, "prev_rseed": 5212347040463786258, "prev round vrf random seed": "4855fa0460e71112", "bls_msg": "595941504855fa0460e71112"} 2025-12-29T03:24:56.804Z INFO miner/protocol_bls.go:265 BLS sign VRF share calculated for {"round": 5959415, "round_timeout": 0, "prev_rseed": 5212347040463786258, "prev round vrf random seed": "4855fa0460e71112", "bls_msg": "595941504855fa0460e71112"} 2025-12-29T03:24:56.804Z INFO miner/protocol_bls.go:283 DKG getBlsShare {"Round Number": 5959415} 2025-12-29T03:24:56.804Z INFO miner/protocol_bls.go:364 DKG AddVRFShare {"round": 5959415, "round_vrf_num": 0, "threshold": 2, "sender": 0, "round_timeout_count": 0, "vrf_timeout_count": 0, "vrf_share": "1 11eee9701b82ff38831f1a7e3cc7f41743c856954d4fbf512c55edbcc2d16bea 74f41283ca1bf819cf83657da1355677b8c206158a7242d1e4b5b268de4472a"} 2025-12-29T03:24:56.804Z INFO miner/protocol_round.go:244 StartNextRound - add VRF {"round": 5959415} 2025-12-29T03:24:56.804Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959414} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_round.go:210 finalizedRound - cancel round verification {"round": 5959414} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_receive.go:26 handle vrf share {"round": 5959415, "vrf_timeout_count": 0, "sender_index": 0} 2025-12-29T03:24:56.804Z INFO chain/entity.go:2016 Moving to the next round {"next_round": 5959415} 2025-12-29T03:24:56.804Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71", "duration": "407.996µs"} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_receive.go:33 received VRF share for the future round, caching it {"current_round": 5959414, "vrf_share_round": 5959415} 2025-12-29T03:24:56.804Z DEBUG miner/protocol_round.go:154 [wait not ahead] [2] not ahead, can move on {"round": 5959414, "lfb tk round": 5959410} 2025-12-29T03:24:56.803Z DEBUG miner/protocol_receive.go:26 handle vrf share {"round": 5959415, "vrf_timeout_count": 0, "sender_index": 0} 2025-12-29T03:24:56.803Z DEBUG miner/protocol_round.go:154 [wait not ahead] [2] not ahead, can move on {"round": 5959414, "lfb tk round": 5959410} 2025-12-29T03:24:56.804Z DEBUG miner/worker.go:53 message {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:56.803Z DEBUG miner/protocol_round.go:123 [wait not ahead] 2025-12-29T03:24:56.803Z INFO miner/protocol_receive.go:370 process notarization success {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "duration": "2.465666ms"} 2025-12-29T03:24:56.803Z INFO miner/round.go:40 Try to cancel block generation that have not been started yet {"round": 5959414} 2025-12-29T03:24:56.803Z INFO miner/protocol_receive.go:456 process notarization - start next round {"new round": 5959415} 2025-12-29T03:24:56.803Z DEBUG round/entity.go:311 add notarized block - block already exist, merge tickets {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.803Z DEBUG miner/protocol_round.go:991 verification_complete {"top_verified_rank": 0} 2025-12-29T03:24:56.803Z DEBUG miner/worker.go:53 message {"msg": {"code":"vrf_share","value":"VRF Share"}, "sender_index": 0, "id": "8f9a15b9551f8552a19cf4750ef14bff3bbac83bc1decd4d5a013a7f36f7ef71"} 2025-12-29T03:24:56.803Z INFO miner/protocol_round.go:986 verification_complete {"round": 5959414, "verified_blocks": 0} 2025-12-29T03:24:56.803Z DEBUG miner/protocol_round.go:123 [wait not ahead] 2025-12-29T03:24:56.803Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959414, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.803Z INFO chain/entity.go:1571 Adding a notarized block for current round {"Round": 5959414} 2025-12-29T03:24:56.803Z INFO miner/round.go:44 Cancelling block generation {"round": 5959414} 2025-12-29T03:24:56.803Z INFO miner/round.go:230 Cancelling verification {"round": 5959414} 2025-12-29T03:24:56.803Z INFO miner/protocol_receive.go:456 process notarization - start next round {"new round": 5959415} 2025-12-29T03:24:56.803Z DEBUG miner/protocol_round.go:1149 check block notarization - block notarized {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.803Z DEBUG round/entity.go:344 reached notarization {"round": 5959414} 2025-12-29T03:24:56.803Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959414, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.803Z INFO chain/entity.go:1571 Adding a notarized block for current round {"Round": 5959414} 2025-12-29T03:24:56.803Z INFO chain/protocol_block.go:319 reached notarization - merging tickets {"round": 5959414, "current_round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "tickets_num": 2} 2025-12-29T03:24:56.801Z DEBUG miner/protocol_receive.go:428 process notarization - merge notarization block {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.801Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "55.991µs"} 2025-12-29T03:24:56.801Z DEBUG miner/worker.go:53 message {"msg": {"code":"notarization","value":"Notarization"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:56.775Z DEBUG miner/protocol_round.go:943 verifyAndSend - finished successfully {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.775Z INFO miner/protocol_round.go:1127 checkBlockNotarization -- block is not Notarized. Returning {"round": 5959414, "block hash": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.775Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 1, "current_round": 5959414, "round": 5959414} 2025-12-29T03:24:56.775Z INFO miner/protocol_round.go:920 verifyAndSend - sending verification ticket {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "block_rank": 0, "RRS": 5212347040463786258} 2025-12-29T03:24:56.775Z DEBUG miner/protocol_round.go:1040 verify round block {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.775Z DEBUG miner/protocol_round.go:865 verifyAndSend - started {"block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.775Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959414, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.775Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959414, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.775Z INFO miner/protocol_round.go:951 Started main verification loop {"round": 5959414} 2025-12-29T03:24:56.737Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959410, "latest.Round": 5959410} 2025-12-29T03:24:56.713Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959414} 2025-12-29T03:24:56.691Z DEBUG chain/protocol_lfb_ticket.go:386 update lfb ticket - ticket.Round <= latest.Round {"ticket.Round": 5959410, "latest.Round": 5959410} 2025-12-29T03:24:56.620Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959414, "block": "ecc5bf3eda1ec6905a1411627c4fb8a9e32ed9cdd2e524c3a6443359f2ad3ec7"} 2025-12-29T03:24:56.620Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959414, "randk": 1, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.620Z DEBUG miner/protocol_receive.go:130 verify block processed {"round": 5959414, "block": "ecc5bf3eda1ec6905a1411627c4fb8a9e32ed9cdd2e524c3a6443359f2ad3ec7"} 2025-12-29T03:24:56.620Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959414, "block hash": "ecc5bf3eda1ec6905a1411627c4fb8a9e32ed9cdd2e524c3a6443359f2ad3ec7", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:56.620Z DEBUG miner/protocol_round.go:759 update prev block notarization, already notarized {"round": 5959413, "block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5"} 2025-12-29T03:24:56.620Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959414, "block": "ecc5bf3eda1ec6905a1411627c4fb8a9e32ed9cdd2e524c3a6443359f2ad3ec7", "prev_block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "state_hash": "617e5f9bfc050ec9f35a2ab82a723eb224aef800b3f5fe40b8dd22f55612f3c7", "weight": 1} 2025-12-29T03:24:56.620Z INFO miner/protocol_round.go:613 handle verify block - added block for ticket verification {"round": 5959414, "block": "ecc5bf3eda1ec6905a1411627c4fb8a9e32ed9cdd2e524c3a6443359f2ad3ec7", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:56.620Z INFO chain/protocol_block.go:225 not reached notarization {"mb_sr": 0, "active_miners": 3, "threshold": 2, "num_signatures": 0, "current_round": 5959414, "round": 5959414} 2025-12-29T03:24:56.619Z DEBUG miner/protocol_receive.go:169 verify block {"round": 5959414, "block": "ecc5bf3eda1ec6905a1411627c4fb8a9e32ed9cdd2e524c3a6443359f2ad3ec7"} 2025-12-29T03:24:56.619Z DEBUG miner/worker.go:73 message (done) {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341", "duration": "52.071µs"} 2025-12-29T03:24:56.619Z DEBUG miner/worker.go:53 message {"msg": {"code":"verify_block","value":"Verify Block"}, "sender_index": 0, "id": "96b96df7631e00d339f9a25131927c43cba9f130b075f4af09e8934c21173341"} 2025-12-29T03:24:56.616Z DEBUG miner/protocol_round.go:1028 verify block - pending {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7"} 2025-12-29T03:24:56.616Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959414, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.616Z DEBUG miner/round.go:143 Adding block to verifyChannel {"round": 5959414, "block hash": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "magic block": "ed79cae70d439c11258236da1dfa6fc550f7cc569768304623e8fbd7d70efae4", "magic block round": 0} 2025-12-29T03:24:56.616Z INFO miner/protocol_round.go:803 adding block to verify {"round": 5959414, "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "prev_block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "state_hash": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188", "weight": 1} 2025-12-29T03:24:56.616Z DEBUG chain/entity.go:2056 [mvc] set round rank {"round": 5959414, "randk": 0, "mb_round": 0, "mb_number": 1, "mb_hash": "506391f07cabd7bfee330f979ba9baab498b721b748eb869ba2b5c054932c8bd"} 2025-12-29T03:24:56.616Z DEBUG statecache/statecache.go:131 statecache - commit {"block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "bc_hits": 123, "bc_miss": 0, "sc_hits": 884858948, "sc_miss": 448360, "duration": "659.38µs"} 2025-12-29T03:24:56.615Z INFO miner/protocol_block.go:1316 generate block (assemble+update+sign) {"round": 5959414, "block_size": 2, "reused_txns": 0, "reused_txns": 0, "time": "21.0488ms", "block": "0457fe7f730215604a7229b26b1f8859e52117572376a34e488b45e87cc8e0e7", "prev_block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "begin_state_hash": "f48fc52f4db4562178d57f870fc45185d338af442888b0f72a7e2285c3ebe587", "block_state_hash": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188", "computed_state_hash": "84e15be4346360a4490616e4a26b09ff04e80cde0ccebcdd8fc41014483d3188", "changes": 18, "state_status": 4, "iteration_count": 0} 2025-12-29T03:24:56.615Z DEBUG miner/protocol_block.go:1305 generate block (assemble+update) {"round": 5959414, "txns": 2, "time": "20.754686ms"} 2025-12-29T03:24:56.615Z DEBUG miner/protocol_block.go:1282 generate block (assemble) {"round": 5959414, "txns": 2, "time": "20.693755ms"} 2025-12-29T03:24:56.614Z DEBUG chain/state.go:961 Updating nonce {"client": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "new_nonce": 3056243} 2025-12-29T03:24:56.613Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.613Z INFO chain/state.go:533 SC executed {"client id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "block": "", "round": 5959414, "prev_state_hash": "f48fc52f4db4562178d57f870fc45185d338af442888b0f72a7e2285c3ebe587", "txn_hash": "273b03573f4d312742ed47b5443ca5b86847ea9cac079a72b98216b1ef2a548f", "txn_nonce": 3056243, "txn_func": "generate_challenge", "txn_status": 0, "txn_exec_time": "474.477µs", "begin client state": "d153f16ebf9a63c383b56a550a34a29b4b16190b0c281c0a787e2b06769f1b70", "current_root": "d153f16ebf9a63c383b56a550a34a29b4b16190b0c281c0a787e2b06769f1b70", "mpt_cache_hit": 15, "mpt_cache_miss": 0, "output": "OpenChallenges generated"} 2025-12-29T03:24:56.613Z INFO storagesc/challenge.go:1191 skipping generate challenge: empty blobber challenge partition 2025-12-29T03:24:56.613Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "eaf4195ad8a6c0bd8f90e9b28f4ba3b4938ddc60155952285997f669446bb502"} 2025-12-29T03:24:56.612Z DEBUG chain/entity.go:440 process block, no block in buffer {"current round": 5959414} 2025-12-29T03:24:56.612Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.612Z DEBUG chain/protocol_view_change.go:424 [mvc] nonce, set nonce in getCurrentSelfNonce {"nonce": 3056242} 2025-12-29T03:24:56.612Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.612Z DEBUG chain/protocol_view_change.go:431 [mvc] nonce, get current miner nonce {"minerId": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea"} 2025-12-29T03:24:56.609Z DEBUG chain/state.go:961 Updating nonce {"client": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "new_nonce": 3056242} 2025-12-29T03:24:56.609Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.608Z INFO chain/state.go:533 SC executed {"client id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "block": "", "round": 5959414, "prev_state_hash": "f48fc52f4db4562178d57f870fc45185d338af442888b0f72a7e2285c3ebe587", "txn_hash": "6fd627ed4b371ab9ca85b9fb0b2b7c686e8abeceb6bedca83b5d88e599817390", "txn_nonce": 3056242, "txn_func": "payFees", "txn_status": 0, "txn_exec_time": "9.540711ms", "begin client state": "f48fc52f4db4562178d57f870fc45185d338af442888b0f72a7e2285c3ebe587", "current_root": "4a8cdc4f074e5248859b529bd0eb3ce6c7d5fe7427f366637c7b77bf4b21bc80", "mpt_cache_hit": 72, "mpt_cache_miss": 0, "output": ""} 2025-12-29T03:24:56.607Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:56.607Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:56.606Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:56.606Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:jason"} 2025-12-29T03:24:56.600Z ERROR minersc/global_node.go:372 failed to get current magic block {"error": "value not present"} 0chain.net/smartcontract/minersc.(*GlobalNode).prevMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/global_node.go:372 0chain.net/smartcontract/minersc.getRegisterShardersInMagicBlock /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:659 0chain.net/smartcontract/minersc.(*MinerSmartContract).payFees /0chain/code/go/0chain.net/smartcontract/minersc/fees.go:519 0chain.net/smartcontract/minersc.(*MinerSmartContract).Execute /0chain/code/go/0chain.net/smartcontract/minersc/sc.go:139 0chain.net/chaincore/smartcontract.ExecuteWithStats /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:52 0chain.net/chaincore/smartcontract.ExecuteSmartContract /0chain/code/go/0chain.net/chaincore/smartcontract/handler.go:67 0chain.net/chaincore/chain.(*Chain).ExecuteSmartContract.func1 /0chain/code/go/0chain.net/chaincore/chain/state.go:158 2025-12-29T03:24:56.600Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9d2208fe938b7465b9c5dc571053986cf8dbf3585fb36399f98ea569c42f50b76"} 2025-12-29T03:24:56.599Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "6dba10422e368813802877a85039d3985d96760ed844092319743fb3a76712d9b5e8a1650666fdd3a9de7855b6da63ab629b1701f23738477e2325f7ca2150ac"} 2025-12-29T03:24:56.599Z DEBUG minersc/fees.go:480 pay_fees, got miner id successfully {"miner id": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "round": 5959414, "block": ""} 2025-12-29T03:24:56.599Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:56.599Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:hermes"} 2025-12-29T03:24:56.598Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.598Z DEBUG chain/protocol_view_change.go:424 [mvc] nonce, set nonce in getCurrentSelfNonce {"nonce": 3056241} 2025-12-29T03:24:56.598Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0012=z\u0005\ufffd\t\ufffd;:\ufffd\ufffd\ufffd\ufffdL;6`Pn\ufffd\ufffdo\ufffd\ufffd\ufffdk\ufffd\ufffdw\ufffd>"} 2025-12-29T03:24:56.598Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdń\ufffd\ufffd\u0014\ufffd\u0019\ufffd\ufffd9\u0011Zh\ufffdS\ufffd\ufffd]*\u0003Y\ufffd႑T\ufffd\u0010\ufffd\ufffd\ufffd"} 2025-12-29T03:24:56.598Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\u00189v\ufffd\u0007xz\ufffd:C\ufffd\ufffd2D>O,s\ufffd\ufffdYr\ufffd\ufffd\ufffdG\ufffd&\ufffd\ufffd"} 2025-12-29T03:24:56.598Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd,\ufffd?bo\u001b7\u0014\ufffd4\ufffdl\ufffd=\ufffd%\ufffd=\ufffd\ufffd3m\ufffdr\ufffd\u0017<3#\ufffd1"} 2025-12-29T03:24:56.598Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdO+\rI:\ufffd;\ufffd\ufffd\ufffd\ufffd\ufffd\ufffdӡϸ*i1\ufffd\u0007żA\u0018\ufffd\ufffdfV\ufffd"} 2025-12-29T03:24:56.598Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "[H\u0004v\ufffd2\ufffd\ufffd\ufffdJ\u0019һ\ufffdMd\ufffd\ufffd\u0015ePA\ufffd@\ufffd\ufffd\ufffd\ufffdfH\u001e\ufffd"} 2025-12-29T03:24:56.597Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "-_qY$\ufffdzr\u0011\ufffdN\u001c\ufffds\ufffd~\u0010|ю\ufffd*\ufffd\ufffdX\ufffd\ufffdw\ufffd\ufffd/0"} 2025-12-29T03:24:56.597Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959414, "below_round": 5959361, "before": 1766978636, "total": 304, "count": 0} 2025-12-29T03:24:56.597Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:56.597Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:56.597Z INFO chain/protocol_round.go:326 finalize round - latest finalized round {"round": 5959413, "lfb round": 5959412, "lfb block": "465baf14a6173c9540445114cf3b61550f0bfd905787d357017187587df79dc6"} 2025-12-29T03:24:56.597Z INFO chain/protocol_round.go:199 finalize round {"round": 5959413, "plfb_round": 5959410, "num_round_notarized": 1, "num_chain_notarized": 3} 2025-12-29T03:24:56.597Z INFO chain/entity.go:1721 delete blocks below round {"round": 5959414, "below_round": 5959360, "before": 1766978636, "total": 304, "count": 0} 2025-12-29T03:24:56.597Z INFO miner/m_handler.go:242 Reject VRFShare: VRF is complete for this round {"vrfs_round_num": 5959414, "vrfs_sender_index": 1, "vrfs_round_num": 5959414} 2025-12-29T03:24:56.597Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:56.597Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "W;η\ufffdk!\ufffd\ufffdm\u0019\ufffd6U]b\ufffd\ufffd]%3\ufffdi)F7\ufffd\ufffdٱ\u001a\ufffd"} 2025-12-29T03:24:56.597Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\ufffd/M\ufffdV!x\ufffd\ufffd\u000f\ufffdQ\ufffd\ufffd8\ufffdD(\ufffd\ufffd\ufffd*~\"\ufffd\ufffd\ufffd\ufffd\ufffd"} 2025-12-29T03:24:56.597Z DEBUG chain/protocol_round.go:334 finalize round - block has less than 3 confirmation 2025-12-29T03:24:56.597Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "hardfork:Medea"} 2025-12-29T03:24:56.597Z INFO chain/protocol_round.go:402 finalize round - finalize block success {"round": 5959410, "block": "7785a7db38b34d165011bd3f959a89e41ec1c0e3a3bbf54ae01afc1a83203564", "duration": "5.929198ms"} 2025-12-29T03:24:56.597Z DEBUG chain/protocol_view_change.go:431 [mvc] nonce, get current miner nonce {"minerId": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea"} 2025-12-29T03:24:56.597Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:56.597Z DEBUG chain/worker.go:230 finalize block processed {"round": 5959410, "duration": "5.878467ms"} 2025-12-29T03:24:56.597Z DEBUG chain/protocol_block.go:612 finalized block - done {"round": 5959410, "block": "7785a7db38b34d165011bd3f959a89e41ec1c0e3a3bbf54ae01afc1a83203564", "duration": "5.752855ms"} 2025-12-29T03:24:56.597Z INFO chain/entity.go:2366 config has been updated successfully {"start of round": 5959410} 2025-12-29T03:24:56.597Z INFO miner/protocol_block.go:1112 generate block starting iteration {"round": 5959414, "prev_block": "614195828f3aeff88b1d7516158920c6ceb364093ef1c5b048357a05d13295a5", "prev_state_hash": "f48fc52f4db4562178d57f870fc45185d338af442888b0f72a7e2285c3ebe587"} 2025-12-29T03:24:56.597Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdBr\\\ufffd\u0014!\ufffdK\ufffd/\ufffd;\u001c\ufffdC\r\ufffdC\ufffd\ufffd\u0015\ufffd\u0008\ufffd\ufffd'\ufffd$ \u000bC"} 2025-12-29T03:24:56.596Z DEBUG statecache/statecache.go:173 state cache - see gap {"block": "95ca3b3cd06bbb4242b9c93b7d97443dde54f64ab413a558c94c77e427e687dc"} 2025-12-29T03:24:56.596Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffdR\ufffdu\ufffdX\ufffdj\ufffd\ufffd\ufffd\ufffd\ufffd\u001ce~}\ufffd\ufffdڊ\\ң\ufffd/\ufffd\ufffd\ufffd>C"} 2025-12-29T03:24:56.596Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:56.596Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0006'\u0012\ufffdu\tWg\ufffdt\ufffd\ufffd\r\ufffd\ufffd\ufffd\u0007ݏ\ufffd\ufffd3\u0015i\ufffd\ufffd\ufffd\ufffd\ufffdkZy"} 2025-12-29T03:24:56.596Z DEBUG transaction/worker.go:253 [mvc] see no old txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056239} 2025-12-29T03:24:56.596Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:56.596Z DEBUG transaction/worker.go:221 [mvc] remove old nonce txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056239} 2025-12-29T03:24:56.596Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0014\ufffd\ufffd2U\u0015\ufffd(9\ufffd\ufffd\ufffdj\ufffd\u0003\ufffd E甠{\ufffd\ufffd\ufffd\ufffd\u0014b\ufffd"} 2025-12-29T03:24:56.596Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd)D\ufffdFce(\ufffd\ufffd\ufffd\u0002\r\ufffd2\ufffd\u001aa\ufffdT[\ufffdl54X\ufffd\u000c\ufffd\rC"} 2025-12-29T03:24:56.393Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0006'\u0012\ufffdu\tWg\ufffdt\ufffd\ufffd\r\ufffd\ufffd\ufffd\u0007ݏ\ufffd\ufffd3\u0015i\ufffd\ufffd\ufffd\ufffd\ufffdkZy"} 2025-12-29T03:24:56.392Z DEBUG statecache/statecache.go:173 state cache - see gap {"block": "95ca3b3cd06bbb4242b9c93b7d97443dde54f64ab413a558c94c77e427e687dc"} 2025-12-29T03:24:56.392Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\u0014\ufffd\ufffd2U\u0015\ufffd(9\ufffd\ufffd\ufffdj\ufffd\u0003\ufffd E甠{\ufffd\ufffd\ufffd\ufffd\u0014b\ufffd"} 2025-12-29T03:24:56.392Z DEBUG statecache/statecache.go:147 state cache get - key not found {"key": "\ufffd\ufffd\ufffd\ufffdK\ufffd7|(H\ufffd\ufffdɲ!a\ufffdQU:f\ufffdm&\u000f\ufffd\ufffd\ufffd\ufffd\t\ufffd\ufffd"} 2025-12-29T03:24:56.392Z DEBUG transaction/worker.go:253 [mvc] see no old txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056239} 2025-12-29T03:24:56.392Z INFO memorystore/collection.go:65 Redis returned 0 rows after seclect 2025-12-29T03:24:56.392Z DEBUG chain/lfb_round_node.go:33 [mvc] store lfb {"round": 5959409, "block": "fb362c34da326b373b94f6c5c6b25ccff1275556442e37067e28ec9920c81498", "mb number": 1} 2025-12-29T03:24:56.392Z DEBUG transaction/worker.go:221 [mvc] remove old nonce txns {"clientID": "c3227e496fdf3c9aa604f3470a2bbd1249f00142150bc067996cd0d6ffcd77ea", "nonce": 3056239} 2025-12-29T03:24:56.392Z DEBUG chain/worker.go:443 BC is moving {"current_lfb_round": 5959409, "last_round": 5959408} 2025-12-29T03:24:56.392Z DEBUG chain/entity.go:2269 set lfb {"round": 5959409, "block": "fb362c34da326b373b94f6c5c6b25ccff1275556442e37067e28ec9920c81498", "state_computed": true}