Alaya开发网节点full模式追块会出现oom，日志报panic

[AndreaHan]

System information

Alaya version: 0.16.2 OS & Version: Windows/Linux/OSX Commit hash : (if develop)

新建开放节点采用full模式追块出现oom，日志捕获panic关键字，进程停止，以下是相关部分日志信息

日志信息： DEBUG[12-15|08:01:15.057|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=dfd358…2a309e num=16892811 INFO [12-15|08:01:15.057|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892811 hash=0xdfd358f35d08d580c159794557b16c0bc01a7cce53d16de9ad8c2fdee92a309e DEBUG[12-15|08:01:15.057|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892811 hash=dfd358…2a309e base= type=snapshotdbCurrentHighestBlock DEBUG[12-15|08:01:15.057|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892814 hash=5f22d8…b48fb0 parentHash=8cae27…d9e01a INFO [12-15|08:01:15.058|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892814 hash=5f22d8…b48fb0 parent=8cae27…d9e01a DEBUG[12-15|08:01:15.058|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892814 blockHash=5f22d8…b48fb0 nodeId=a192ebbb32c0810d value=14 DEBUG[12-15|08:01:15.058|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=5f22d8…b48fb0 blockNumber=16892814 root=ac2846…301572 pointer=0xc1cb819c80 DEBUG[12-15|08:01:15.058|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892814 parentHash=8cae27…d9e01a current hash=5f22d8…b48fb0 nonce=033481290fece4d6e3680edcafacd2ed6f9f95d2ac30f8fa51b2addcf43d0284187e2dfca943f9a3ecd2600d10558afe8f5d0b33dc183d5e41c6f3a557054d8fbc67ad8627ff330e263471875340686dfc DEBUG[12-15|08:01:15.058|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892814 parentHash=8cae27…d9e01a current hash=5f22d8…b48fb0 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.058|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892814 parentHash=8cae27…d9e01a current hash=5f22d8…b48fb0 valueLength=101 MaxValidators=101 nonce=033481290fece4d6e3680edcafacd2ed6f9f95d2ac30f8fa51b2addcf43d0284187e2dfca943f9a3ecd2600d10558afe8f5d0b33dc183d5e41c6f3a557054d8fbc67ad8627ff330e263471875340686dfc firstNonce=ee57cac9f3e1bd6579813a87562fda5601d69f5ebe1cb04d15706f90ffcb7ddb lastNonce=3481290fece4d6e3680edcafacd2ed6f9f95d2ac30f8fa51b2addcf43d028418 DEBUG[12-15|08:01:15.058|core/blockchain_reactor.go:322] Store ppos hash blockHash=5f22d8…b48fb0 blockNumber=16892814 pposHash=1d86f0bb2c1ed0d7d6a12c99a77455108e5340c119c144d9a9e4a81768e92b2c DEBUG[12-15|08:01:15.059|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=5f22d8…b48fb0 blockNumber=16892814 root=dfa6ef…ecd356 pointer=0xc1cb819c80 DEBUG[12-15|08:01:15.059|eth/handler.go:749] Received a message[NewBlockHashesMsg]------------ receiveAt=1639555275 peerId=48f9ebd7559b7849 hash=2219c2…41faa7 number=21926194 DEBUG[12-15|08:01:15.059|eth/handler.go:759] Unknown block hash=2219c2…41faa7 number=21926194 DEBUG[12-15|08:01:15.059|consensus/cbft/cbft.go:1047] Cbft status epoch=67572 view=6 start=1 DEBUG[12-15|08:01:15.059|eth/fetcher/fetcher.go:350] Peer discarded announcement peer=48f9ebd7559b7849 number=21926194 hash=2219c2…41faa7 distance=5033381 DEBUG[12-15|08:01:15.059|consensus/cbft/cbft.go:1047] Cbft status epoch=67572 view=6 start=1 DEBUG[12-15|08:01:15.059|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892814 blockHash=5f22d8…b48fb0 DEBUG[12-15|08:01:15.059|core/blockchain.go:1578] Execute block time blockNumber=16892814 blockHash=5f22d8…b48fb0 time=2.467213ms DEBUG[12-15|08:01:15.059|core/blockchain_cache.go:300] Execute block number=16892814 hash=5f22d8…b48fb0 parentNumber=16892813 parentHash=8cae27…d9e01a duration=2.491823ms makeState=43.147µs err=nil INFO [12-15|08:01:15.059|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=8eca72…671c42 blockNum=16892814 DEBUG[12-15|08:01:15.057|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=115.247µs gcnodes=15 gcsize=4.81KiB gctime=27.119µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.059|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=15.526µs gcnodes=7 gcsize=2.05KiB gctime=15.154µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.059|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892811 blockHash=0xdfd358f35d08d580c159794557b16c0bc01a7cce53d16de9ad8c2fdee92a309e root=0x1cd8c18a011357d4dd786151140768583f8c688b3845b42f841bbcddcb5649f5 DEBUG[12-15|08:01:15.059|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=dfd358…2a309e number=16892811 INFO [12-15|08:01:15.060|miner/worker.go:730] Successfully write new block hash=dfd358…2a309e number=16892811 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966770030 root=1cd8c1…5649f5 DEBUG[12-15|08:01:15.060|miner/worker.go:420] Clear Pending number=16892811 DEBUG[12-15|08:01:15.060|miner/worker.go:424] received a event of ChainHeadEvent hash=dfd358…2a309e number=16892811 parentHash=c5389f…3964f0 INFO [12-15|08:01:15.061|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=6 qc={Epoch:67572,ViewNumber:6,Hash:5f22d8…b48fb0,Number:16892814,Index:3,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.062|core/tx_pool.go:463] call Reset() hash=5f22d8…b48fb0 number=16892814 parentHash=8cae27…d9e01a DEBUG[12-15|08:01:15.062|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=8eca72…671c42 DEBUG[12-15|08:01:15.062|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892813 oldHash=8cae27…d9e01a newNumber=16892814 newHash=5f22d8…b48fb0 count=0 elapsed=10.805µs DEBUG[12-15|08:01:15.062|core/tx_pool.go:473] call Reset elapse time hash=5f22d8…b48fb0 number=16892814 parentHash=8cae27…d9e01a elapseTime=414.99µs INFO [12-15|08:01:15.062|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=6 number=16892812 hash=f8e53d…25d32f DEBUG[12-15|08:01:15.062|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=6 len=0 view={Epoch:67572,ViewNumber:6} INFO [12-15|08:01:15.062|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=6 qcBlock={Epoch:67572,ViewNumber:6,Hash:5f22d8…b48fb0,Number:16892814,Index:3,ValidatorSet:BA{6:xxxxx}} INFO [12-15|08:01:15.062|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=6 number=16892815 hash=dce125…33c858 time="2021-12-15 08:01:15.062" DEBUG[12-15|08:01:15.062|core/blockchain_cache.go:289] Start execute block hash=dce125…33c858 number=16892815 sealHash=1a5194…3fee56 INFO [12-15|08:01:15.062|core/blockchain_cache.go:212] Make stateDB hash=5f22d8…b48fb0 number=16892814 root=dfa6ef…ecd356 DEBUG[12-15|08:01:15.062|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=8eca72…671c42 INFO [12-15|08:01:15.062|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892812 blockHash=f8e53d…25d32f DEBUG[12-15|08:01:15.062|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=f8e53d…25d32f num=16892812 INFO [12-15|08:01:15.062|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892812 hash=0xf8e53dd997a7ccbd3b19e09b075d7c5775b080e1257683f440fcdbb7cb25d32f DEBUG[12-15|08:01:15.062|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892815 current hash=dce125…33c858 parentHash=5f22d8…b48fb0 proof=0254baf59650698bb626a4b71620e01f3b3f76e923ded4a3c565d6c62dffb97552f34f9a65860ce7c779261ba70890f7853f5de783d47d005998774fe452b0622ce2a55ab5b658b591e97c86d1f5211acc DEBUG[12-15|08:01:15.063|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892815 parentHash=5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0 DEBUG[12-15|08:01:15.062|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892812 hash=f8e53d…25d32f base= type=snapshotdbCurrentHighestBlock DEBUG[12-15|08:01:15.062|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=6 hash=f8e53d…25d32f number=16892812 DEBUG[12-15|08:01:15.063|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.063|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=7fdd37…3e2335 DEBUG[12-15|08:01:15.063|miner/worker.go:684] Block is packaged by other hash=f8e53d…25d32f number=16892812 len(Receipts)=0 blockRoot=053d87…3494b7 stateIsNil=false DEBUG[12-15|08:01:15.063|miner/worker.go:693] Cbft consensus successful hash=f8e53d…25d32f number=16892812 timestamp=1639555275063 DEBUG[12-15|08:01:15.063|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.063|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892812, blockHash:0xf8e53dd997a7ccbd3b19e09b075d7c5775b080e1257683f440fcdbb7cb25d32f, qcNumber:16892812, qcHash:0xf8e53dd997a7ccbd3b19e09b075d7c5775b080e1257683f440fcdbb7cb25d32f]" lockState="[blockNumber:16892813, blockHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a, qcNumber:16892813, qcHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a]" qcState="[blockNumber:16892814, blockHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0, qcNumber:16892814, qcHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0]" DEBUG[12-15|08:01:15.063|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.063|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892812, blockHash:0xf8e53dd997a7ccbd3b19e09b075d7c5775b080e1257683f440fcdbb7cb25d32f, qcNumber:16892812, qcHash:0xf8e53dd997a7ccbd3b19e09b075d7c5775b080e1257683f440fcdbb7cb25d32f]" lockState="[blockNumber:16892813, blockHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a, qcNumber:16892813, qcHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a]" qcState="[blockNumber:16892814, blockHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0, qcNumber:16892814, qcHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0]" elapsed=95.498µs DEBUG[12-15|08:01:15.063|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=91.859µs gcnodes=7 gcsize=2.05KiB gctime=15.154µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.063|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=12.437µs gcnodes=7 gcsize=2.05KiB gctime=12.22µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.063|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892812 blockHash=0xf8e53dd997a7ccbd3b19e09b075d7c5775b080e1257683f440fcdbb7cb25d32f root=0x053d87f9267be6807fc50b49de3db71cb5feb041f601486c5e185765593494b7 DEBUG[12-15|08:01:15.063|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=f8e53d…25d32f number=16892812 INFO [12-15|08:01:15.064|miner/worker.go:730] Successfully write new block hash=f8e53d…25d32f number=16892812 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966771134 root=053d87…3494b7 DEBUG[12-15|08:01:15.064|miner/worker.go:420] Clear Pending number=16892812 DEBUG[12-15|08:01:15.064|miner/worker.go:424] received a event of ChainHeadEvent hash=f8e53d…25d32f number=16892812 parentHash=dfd358…2a309e DEBUG[12-15|08:01:15.064|eth/handler.go:772] Received a message[NewBlockMsg]------------ receiveAt=1639555275 peerId=c4d723c6a227346d hash=2219c2…41faa7 number=21926194 DEBUG[12-15|08:01:15.064|consensus/cbft/cbft.go:437] Currently syncing, consensus message pause, discard sync msg epoch=67572 view=6 DEBUG[12-15|08:01:15.064|consensus/cbft/cbft.go:1047] Cbft status epoch=67572 view=6 start=1 DEBUG[12-15|08:01:15.064|eth/fetcher/fetcher.go:643] Discarded propagated block, too far away peer=c4d723c6a227346d number=21926194 hash=2219c2…41faa7 distance=5033380 DEBUG[12-15|08:01:15.064|consensus/cbft/cbft.go:1047] Cbft status epoch=67572 view=6 start=1 INFO [12-15|08:01:15.064|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892815 current hash=dce125…33c858 parentHash=5f22d8…b48fb0 proof=0254baf59650698bb626a4b71620e01f3b3f76e923ded4a3c565d6c62dffb97552f34f9a65860ce7c779261ba70890f7853f5de783d47d005998774fe452b0622ce2a55ab5b658b591e97c86d1f5211acc input=3481290fece4d6e3680edcafacd2ed6f9f95d2ac30f8fa51b2addcf43d028418 DEBUG[12-15|08:01:15.064|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892815 hash=dce125…33c858 parentHash=5f22d8…b48fb0 INFO [12-15|08:01:15.064|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892815 hash=dce125…33c858 parent=5f22d8…b48fb0 DEBUG[12-15|08:01:15.064|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892815 blockHash=dce125…33c858 nodeId=a192ebbb32c0810d value=15 DEBUG[12-15|08:01:15.064|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=dce125…33c858 blockNumber=16892815 root=dfa6ef…ecd356 pointer=0xc1cb62b980 DEBUG[12-15|08:01:15.064|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892815 parentHash=5f22d8…b48fb0 current hash=dce125…33c858 nonce=0254baf59650698bb626a4b71620e01f3b3f76e923ded4a3c565d6c62dffb97552f34f9a65860ce7c779261ba70890f7853f5de783d47d005998774fe452b0622ce2a55ab5b658b591e97c86d1f5211acc DEBUG[12-15|08:01:15.064|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892815 parentHash=5f22d8…b48fb0 current hash=dce125…33c858 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.064|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892815 parentHash=5f22d8…b48fb0 current hash=dce125…33c858 valueLength=101 MaxValidators=101 nonce=0254baf59650698bb626a4b71620e01f3b3f76e923ded4a3c565d6c62dffb97552f34f9a65860ce7c779261ba70890f7853f5de783d47d005998774fe452b0622ce2a55ab5b658b591e97c86d1f5211acc firstNonce=d7a18957533a982faec671c07dbb4fd8e52db70e42b2193b8f136dcb0eb38a77 lastNonce=54baf59650698bb626a4b71620e01f3b3f76e923ded4a3c565d6c62dffb97552 DEBUG[12-15|08:01:15.064|core/blockchain_reactor.go:322] Store ppos hash blockHash=dce125…33c858 blockNumber=16892815 pposHash=22b98361b5c0fae26efc4762e9efdb285e5cddc3522133397852f3c5ab4d7060 DEBUG[12-15|08:01:15.065|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=dce125…33c858 blockNumber=16892815 root=fe01e9…01d13a pointer=0xc1cb62b980 DEBUG[12-15|08:01:15.065|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892815 blockHash=dce125…33c858 DEBUG[12-15|08:01:15.065|core/blockchain.go:1578] Execute block time blockNumber=16892815 blockHash=dce125…33c858 time=2.370648ms DEBUG[12-15|08:01:15.065|core/blockchain_cache.go:300] Execute block number=16892815 hash=dce125…33c858 parentNumber=16892814 parentHash=5f22d8…b48fb0 duration=2.390996ms makeState=48.957µs err=nil INFO [12-15|08:01:15.065|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=1a5194…3fee56 blockNum=16892815 INFO [12-15|08:01:15.068|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=6 qc={Epoch:67572,ViewNumber:6,Hash:dce125…33c858,Number:16892815,Index:4,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.068|core/tx_pool.go:463] call Reset() hash=dce125…33c858 number=16892815 parentHash=5f22d8…b48fb0 DEBUG[12-15|08:01:15.068|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=1a5194…3fee56 DEBUG[12-15|08:01:15.068|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892814 oldHash=5f22d8…b48fb0 newNumber=16892815 newHash=dce125…33c858 count=0 elapsed=9.409µs DEBUG[12-15|08:01:15.068|core/tx_pool.go:473] call Reset elapse time hash=dce125…33c858 number=16892815 parentHash=5f22d8…b48fb0 elapseTime=409.126µs INFO [12-15|08:01:15.068|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=6 number=16892813 hash=8cae27…d9e01a DEBUG[12-15|08:01:15.068|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=6 len=0 view={Epoch:67572,ViewNumber:6} INFO [12-15|08:01:15.068|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=6 qcBlock={Epoch:67572,ViewNumber:6,Hash:dce125…33c858,Number:16892815,Index:4,ValidatorSet:BA{6:xxxxx}} INFO [12-15|08:01:15.068|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=6 number=16892816 hash=9528ea…ebb44f time="2021-12-15 08:01:15.068" DEBUG[12-15|08:01:15.068|core/blockchain_cache.go:289] Start execute block hash=9528ea…ebb44f number=16892816 sealHash=8f2200…fa8552 INFO [12-15|08:01:15.068|core/blockchain_cache.go:212] Make stateDB hash=dce125…33c858 number=16892815 root=fe01e9…01d13a DEBUG[12-15|08:01:15.068|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=1a5194…3fee56 INFO [12-15|08:01:15.069|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892813 blockHash=8cae27…d9e01a DEBUG[12-15|08:01:15.069|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=8cae27…d9e01a num=16892813 INFO [12-15|08:01:15.069|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892813 hash=0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a DEBUG[12-15|08:01:15.069|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892816 current hash=9528ea…ebb44f parentHash=dce125…33c858 proof=03119145ece5222b97ec442c64d853c7275d7ffa1aeb7615eec9a74de1a276aeabe36444021b5da6e660281c72d8e2cfe9dceaa399aa31785fc294bafbe4d0fddc1d1ee1d387bb3e1397c920c1c475b42a DEBUG[12-15|08:01:15.069|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892816 parentHash=dce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858 DEBUG[12-15|08:01:15.069|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892813 hash=8cae27…d9e01a base= type=snapshotdbCurrentHighestBlock DEBUG[12-15|08:01:15.068|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=6 hash=8cae27…d9e01a number=16892813 DEBUG[12-15|08:01:15.069|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.069|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=c01b06…805d16 DEBUG[12-15|08:01:15.069|miner/worker.go:684] Block is packaged by other hash=8cae27…d9e01a number=16892813 len(Receipts)=0 blockRoot=ac2846…301572 stateIsNil=false DEBUG[12-15|08:01:15.069|miner/worker.go:693] Cbft consensus successful hash=8cae27…d9e01a number=16892813 timestamp=1639555275069 DEBUG[12-15|08:01:15.069|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.069|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892813, blockHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a, qcNumber:16892813, qcHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a]" lockState="[blockNumber:16892814, blockHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0, qcNumber:16892814, qcHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0]" qcState="[blockNumber:16892815, blockHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858, qcNumber:16892815, qcHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858]" DEBUG[12-15|08:01:15.069|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.069|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892813, blockHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a, qcNumber:16892813, qcHash:0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a]" lockState="[blockNumber:16892814, blockHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0, qcNumber:16892814, qcHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0]" qcState="[blockNumber:16892815, blockHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858, qcNumber:16892815, qcHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858]" elapsed=71.458µs INFO [12-15|08:01:15.070|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892816 current hash=9528ea…ebb44f parentHash=dce125…33c858 proof=03119145ece5222b97ec442c64d853c7275d7ffa1aeb7615eec9a74de1a276aeabe36444021b5da6e660281c72d8e2cfe9dceaa399aa31785fc294bafbe4d0fddc1d1ee1d387bb3e1397c920c1c475b42a input=54baf59650698bb626a4b71620e01f3b3f76e923ded4a3c565d6c62dffb97552 DEBUG[12-15|08:01:15.070|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892816 hash=9528ea…ebb44f parentHash=dce125…33c858 INFO [12-15|08:01:15.070|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892816 hash=9528ea…ebb44f parent=dce125…33c858 DEBUG[12-15|08:01:15.070|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892816 blockHash=9528ea…ebb44f nodeId=a192ebbb32c0810d value=16 DEBUG[12-15|08:01:15.070|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=9528ea…ebb44f blockNumber=16892816 root=fe01e9…01d13a pointer=0xc1cb62bc80 DEBUG[12-15|08:01:15.071|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892816 parentHash=dce125…33c858 current hash=9528ea…ebb44f nonce=03119145ece5222b97ec442c64d853c7275d7ffa1aeb7615eec9a74de1a276aeabe36444021b5da6e660281c72d8e2cfe9dceaa399aa31785fc294bafbe4d0fddc1d1ee1d387bb3e1397c920c1c475b42a DEBUG[12-15|08:01:15.071|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892816 parentHash=dce125…33c858 current hash=9528ea…ebb44f valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.071|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892816 parentHash=dce125…33c858 current hash=9528ea…ebb44f valueLength=101 MaxValidators=101 nonce=03119145ece5222b97ec442c64d853c7275d7ffa1aeb7615eec9a74de1a276aeabe36444021b5da6e660281c72d8e2cfe9dceaa399aa31785fc294bafbe4d0fddc1d1ee1d387bb3e1397c920c1c475b42a firstNonce=65a4be90751191095a5dd3747b77a9911e40f53dedea742548e974bc859f1759 lastNonce=119145ece5222b97ec442c64d853c7275d7ffa1aeb7615eec9a74de1a276aeab DEBUG[12-15|08:01:15.071|core/blockchain_reactor.go:322] Store ppos hash blockHash=9528ea…ebb44f blockNumber=16892816 pposHash=9f915cdf97a680f05aa8f9b46b979b9ff0729016941f0eedb41411847e49aa65 DEBUG[12-15|08:01:15.071|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=9528ea…ebb44f blockNumber=16892816 root=00e6b1…af906c pointer=0xc1cb62bc80 DEBUG[12-15|08:01:15.071|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892816 blockHash=9528ea…ebb44f DEBUG[12-15|08:01:15.071|core/blockchain.go:1578] Execute block time blockNumber=16892816 blockHash=9528ea…ebb44f time=2.828576ms DEBUG[12-15|08:01:15.071|core/blockchain_cache.go:300] Execute block number=16892816 hash=9528ea…ebb44f parentNumber=16892815 parentHash=dce125…33c858 duration=2.906514ms makeState=48.524µs err=nil INFO [12-15|08:01:15.072|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=8f2200…fa8552 blockNum=16892816 DEBUG[12-15|08:01:15.073|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=112.26µs gcnodes=7 gcsize=2.05KiB gctime=12.22µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.073|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=11.414µs gcnodes=7 gcsize=2.05KiB gctime=11.211µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.073|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892813 blockHash=0x8cae273ea073b4a9788ec41788f2d571addf056a081a9d3a21aab9ca1dd9e01a root=0xac2846c1b381465987506a2f319ccbe0cdfec90ff65eb4a620df4078a3301572 DEBUG[12-15|08:01:15.073|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=8cae27…d9e01a number=16892813 INFO [12-15|08:01:15.073|miner/worker.go:730] Successfully write new block hash=8cae27…d9e01a number=16892813 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966772239 root=ac2846…301572 DEBUG[12-15|08:01:15.073|miner/worker.go:420] Clear Pending number=16892813 DEBUG[12-15|08:01:15.073|miner/worker.go:424] received a event of ChainHeadEvent hash=8cae27…d9e01a number=16892813 parentHash=f8e53d…25d32f DEBUG[12-15|08:01:15.074|eth/handler.go:749] Received a message[NewBlockHashesMsg]------------ receiveAt=1639555275 peerId=01bdfadca94b4525 hash=2219c2…41faa7 number=21926194 DEBUG[12-15|08:01:15.074|eth/handler.go:759] Unknown block hash=2219c2…41faa7 number=21926194 DEBUG[12-15|08:01:15.074|consensus/cbft/cbft.go:1047] Cbft status epoch=67572 view=6 start=1 DEBUG[12-15|08:01:15.074|eth/fetcher/fetcher.go:350] Peer discarded announcement peer=01bdfadca94b4525 number=21926194 hash=2219c2…41faa7 distance=5033379 DEBUG[12-15|08:01:15.074|consensus/cbft/cbft.go:1047] Cbft status epoch=67572 view=6 start=1 INFO [12-15|08:01:15.075|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=6 qc={Epoch:67572,ViewNumber:6,Hash:9528ea…ebb44f,Number:16892816,Index:5,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.075|core/tx_pool.go:463] call Reset() hash=9528ea…ebb44f number=16892816 parentHash=dce125…33c858 DEBUG[12-15|08:01:15.075|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=8f2200…fa8552 DEBUG[12-15|08:01:15.075|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892815 oldHash=dce125…33c858 newNumber=16892816 newHash=9528ea…ebb44f count=0 elapsed=9.539µs DEBUG[12-15|08:01:15.075|core/tx_pool.go:473] call Reset elapse time hash=9528ea…ebb44f number=16892816 parentHash=dce125…33c858 elapseTime=412.674µs INFO [12-15|08:01:15.075|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=6 number=16892814 hash=5f22d8…b48fb0 DEBUG[12-15|08:01:15.075|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=6 len=0 view={Epoch:67572,ViewNumber:6} INFO [12-15|08:01:15.075|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=6 qcBlock={Epoch:67572,ViewNumber:6,Hash:9528ea…ebb44f,Number:16892816,Index:5,ValidatorSet:BA{6:xxxxx}} INFO [12-15|08:01:15.075|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=6 number=16892817 hash=7c4d27…767e45 time="2021-12-15 08:01:15.075" DEBUG[12-15|08:01:15.075|core/blockchain_cache.go:289] Start execute block hash=7c4d27…767e45 number=16892817 sealHash=d92c31…748278 INFO [12-15|08:01:15.075|core/blockchain_cache.go:212] Make stateDB hash=9528ea…ebb44f number=16892816 root=00e6b1…af906c DEBUG[12-15|08:01:15.075|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=8f2200…fa8552 INFO [12-15|08:01:15.075|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892814 blockHash=5f22d8…b48fb0 DEBUG[12-15|08:01:15.076|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=5f22d8…b48fb0 num=16892814 INFO [12-15|08:01:15.076|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892814 hash=0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0 DEBUG[12-15|08:01:15.076|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892817 current hash=7c4d27…767e45 parentHash=9528ea…ebb44f proof=032084abfc3fab1ede6f3f47c6bd623c662f244512281887d75a12e2236d24c76c3cf68046605847a30c5e5ee9e57c7a02f985cad6ef85f14606bc6cfe2492c62db27e49c034efc3e6f51016a16f546d72 DEBUG[12-15|08:01:15.076|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892817 parentHash=9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f DEBUG[12-15|08:01:15.076|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892814 hash=5f22d8…b48fb0 base= type=snapshotdbCurrentHighestBlock DEBUG[12-15|08:01:15.075|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=6 hash=5f22d8…b48fb0 number=16892814 DEBUG[12-15|08:01:15.076|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.076|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=8eca72…671c42 DEBUG[12-15|08:01:15.076|miner/worker.go:684] Block is packaged by other hash=5f22d8…b48fb0 number=16892814 len(Receipts)=0 blockRoot=dfa6ef…ecd356 stateIsNil=false DEBUG[12-15|08:01:15.076|miner/worker.go:693] Cbft consensus successful hash=5f22d8…b48fb0 number=16892814 timestamp=1639555275076 DEBUG[12-15|08:01:15.076|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.076|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892814, blockHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0, qcNumber:16892814, qcHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0]" lockState="[blockNumber:16892815, blockHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858, qcNumber:16892815, qcHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858]" qcState="[blockNumber:16892816, blockHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f, qcNumber:16892816, qcHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f]" DEBUG[12-15|08:01:15.076|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.076|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892814, blockHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0, qcNumber:16892814, qcHash:0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0]" lockState="[blockNumber:16892815, blockHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858, qcNumber:16892815, qcHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858]" qcState="[blockNumber:16892816, blockHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f, qcNumber:16892816, qcHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f]" elapsed=93.172µs DEBUG[12-15|08:01:15.077|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=107.114µs gcnodes=7 gcsize=2.05KiB gctime=11.211µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.077|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=12.083µs gcnodes=7 gcsize=2.05KiB gctime=11.856µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.077|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892814 blockHash=0x5f22d822ce23b9c93e41ee1f3d7a37906330777ec0cb971189f11f059fb48fb0 root=0xdfa6ef617316e911a3af0e1ee57a7db0b24b6dce66e142161c59f4dcd9ecd356 DEBUG[12-15|08:01:15.077|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=5f22d8…b48fb0 number=16892814 INFO [12-15|08:01:15.077|miner/worker.go:730] Successfully write new block hash=5f22d8…b48fb0 number=16892814 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966773345 root=dfa6ef…ecd356 DEBUG[12-15|08:01:15.077|miner/worker.go:420] Clear Pending number=16892814 DEBUG[12-15|08:01:15.077|miner/worker.go:424] received a event of ChainHeadEvent hash=5f22d8…b48fb0 number=16892814 parentHash=8cae27…d9e01a INFO [12-15|08:01:15.077|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892817 current hash=7c4d27…767e45 parentHash=9528ea…ebb44f proof=032084abfc3fab1ede6f3f47c6bd623c662f244512281887d75a12e2236d24c76c3cf68046605847a30c5e5ee9e57c7a02f985cad6ef85f14606bc6cfe2492c62db27e49c034efc3e6f51016a16f546d72 input=119145ece5222b97ec442c64d853c7275d7ffa1aeb7615eec9a74de1a276aeab DEBUG[12-15|08:01:15.077|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892817 hash=7c4d27…767e45 parentHash=9528ea…ebb44f INFO [12-15|08:01:15.078|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892817 hash=7c4d27…767e45 parent=9528ea…ebb44f DEBUG[12-15|08:01:15.078|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892817 blockHash=7c4d27…767e45 nodeId=a192ebbb32c0810d value=17 DEBUG[12-15|08:01:15.078|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=7c4d27…767e45 blockNumber=16892817 root=00e6b1…af906c pointer=0xc1cbe0c000 DEBUG[12-15|08:01:15.078|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892817 parentHash=9528ea…ebb44f current hash=7c4d27…767e45 nonce=032084abfc3fab1ede6f3f47c6bd623c662f244512281887d75a12e2236d24c76c3cf68046605847a30c5e5ee9e57c7a02f985cad6ef85f14606bc6cfe2492c62db27e49c034efc3e6f51016a16f546d72 DEBUG[12-15|08:01:15.078|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892817 parentHash=9528ea…ebb44f current hash=7c4d27…767e45 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.078|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892817 parentHash=9528ea…ebb44f current hash=7c4d27…767e45 valueLength=101 MaxValidators=101 nonce=032084abfc3fab1ede6f3f47c6bd623c662f244512281887d75a12e2236d24c76c3cf68046605847a30c5e5ee9e57c7a02f985cad6ef85f14606bc6cfe2492c62db27e49c034efc3e6f51016a16f546d72 firstNonce=e515844ccf68f7de4f2a93c5cd7291872512623bc68142ea135ad3e6ef6e989f lastNonce=2084abfc3fab1ede6f3f47c6bd623c662f244512281887d75a12e2236d24c76c DEBUG[12-15|08:01:15.078|core/blockchain_reactor.go:322] Store ppos hash blockHash=7c4d27…767e45 blockNumber=16892817 pposHash=c06b1d92fa559e5adb0c3c1967245e2e5d76f58fd64de60559e57670df56f9aa DEBUG[12-15|08:01:15.079|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=7c4d27…767e45 blockNumber=16892817 root=bcdeb5…e1bd15 pointer=0xc1cbe0c000 DEBUG[12-15|08:01:15.079|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892817 blockHash=7c4d27…767e45 DEBUG[12-15|08:01:15.079|core/blockchain.go:1578] Execute block time blockNumber=16892817 blockHash=7c4d27…767e45 time=3.187032ms DEBUG[12-15|08:01:15.079|core/blockchain_cache.go:300] Execute block number=16892817 hash=7c4d27…767e45 parentNumber=16892816 parentHash=9528ea…ebb44f duration=3.302738ms makeState=63.966µs err=nil INFO [12-15|08:01:15.079|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=d92c31…748278 blockNum=16892817 INFO [12-15|08:01:15.082|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=6 qc={Epoch:67572,ViewNumber:6,Hash:7c4d27…767e45,Number:16892817,Index:6,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.082|core/tx_pool.go:463] call Reset() hash=7c4d27…767e45 number=16892817 parentHash=9528ea…ebb44f DEBUG[12-15|08:01:15.082|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=d92c31…748278 DEBUG[12-15|08:01:15.082|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892816 oldHash=9528ea…ebb44f newNumber=16892817 newHash=7c4d27…767e45 count=0 elapsed=9.593µs DEBUG[12-15|08:01:15.082|core/tx_pool.go:473] call Reset elapse time hash=7c4d27…767e45 number=16892817 parentHash=9528ea…ebb44f elapseTime=396.357µs INFO [12-15|08:01:15.082|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=6 number=16892815 hash=dce125…33c858 DEBUG[12-15|08:01:15.083|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=6 len=0 view={Epoch:67572,ViewNumber:6} INFO [12-15|08:01:15.083|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=6 qcBlock={Epoch:67572,ViewNumber:6,Hash:7c4d27…767e45,Number:16892817,Index:6,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.083|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=6 hash=dce125…33c858 number=16892815 DEBUG[12-15|08:01:15.083|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.083|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=1a5194…3fee56 DEBUG[12-15|08:01:15.083|miner/worker.go:684] Block is packaged by other hash=dce125…33c858 number=16892815 len(Receipts)=0 blockRoot=fe01e9…01d13a stateIsNil=false DEBUG[12-15|08:01:15.083|miner/worker.go:693] Cbft consensus successful hash=dce125…33c858 number=16892815 timestamp=1639555275083 DEBUG[12-15|08:01:15.083|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.083|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892815, blockHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858, qcNumber:16892815, qcHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858]" lockState="[blockNumber:16892816, blockHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f, qcNumber:16892816, qcHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f]" qcState="[blockNumber:16892817, blockHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45, qcNumber:16892817, qcHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45]" DEBUG[12-15|08:01:15.083|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.083|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892815, blockHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858, qcNumber:16892815, qcHash:0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858]" lockState="[blockNumber:16892816, blockHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f, qcNumber:16892816, qcHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f]" qcState="[blockNumber:16892817, blockHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45, qcNumber:16892817, qcHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45]" elapsed=102.074µs INFO [12-15|08:01:15.083|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=6 number=16892818 hash=27de4c…ae8bd5 time="2021-12-15 08:01:15.083" DEBUG[12-15|08:01:15.083|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=107.332µs gcnodes=7 gcsize=2.05KiB gctime=11.856µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.083|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=13.098µs gcnodes=7 gcsize=2.05KiB gctime=12.865µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.083|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892815 blockHash=0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858 root=0xfe01e9e19f19053b48d276f457620b04968d825d078f79563486a8934201d13a DEBUG[12-15|08:01:15.083|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=dce125…33c858 number=16892815 INFO [12-15|08:01:15.083|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892815 blockHash=dce125…33c858 DEBUG[12-15|08:01:15.084|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=dce125…33c858 num=16892815 INFO [12-15|08:01:15.084|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892815 hash=0xdce125c9f3f9982b1690286e403a001f9aeecdbcf6e91a03f4f789dbf433c858 DEBUG[12-15|08:01:15.083|core/blockchain_cache.go:289] Start execute block hash=27de4c…ae8bd5 number=16892818 sealHash=cb1c17…f21c2d INFO [12-15|08:01:15.084|core/blockchain_cache.go:212] Make stateDB hash=7c4d27…767e45 number=16892817 root=bcdeb5…e1bd15 DEBUG[12-15|08:01:15.084|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=d92c31…748278 DEBUG[12-15|08:01:15.084|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892818 current hash=27de4c…ae8bd5 parentHash=7c4d27…767e45 proof=0257c8c9c1f99b59c5552000eea00b836ee31a4698facf8f8089d2d6c8ecd9b70d38c02072fc8a79f62861d8f854c2671238d71d7c99831417f429fb0c00eeb9138dfc8076ee31bdf60c25f61d458b9324 DEBUG[12-15|08:01:15.084|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892818 parentHash=7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45 DEBUG[12-15|08:01:15.084|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892815 hash=dce125…33c858 base= type=snapshotdbCurrentHighestBlock INFO [12-15|08:01:15.084|miner/worker.go:730] Successfully write new block hash=dce125…33c858 number=16892815 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966774448 root=fe01e9…01d13a DEBUG[12-15|08:01:15.085|miner/worker.go:420] Clear Pending number=16892815 DEBUG[12-15|08:01:15.085|miner/worker.go:424] received a event of ChainHeadEvent hash=dce125…33c858 number=16892815 parentHash=5f22d8…b48fb0 INFO [12-15|08:01:15.085|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892818 current hash=27de4c…ae8bd5 parentHash=7c4d27…767e45 proof=0257c8c9c1f99b59c5552000eea00b836ee31a4698facf8f8089d2d6c8ecd9b70d38c02072fc8a79f62861d8f854c2671238d71d7c99831417f429fb0c00eeb9138dfc8076ee31bdf60c25f61d458b9324 input=2084abfc3fab1ede6f3f47c6bd623c662f244512281887d75a12e2236d24c76c DEBUG[12-15|08:01:15.085|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892818 hash=27de4c…ae8bd5 parentHash=7c4d27…767e45 INFO [12-15|08:01:15.086|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892818 hash=27de4c…ae8bd5 parent=7c4d27…767e45 DEBUG[12-15|08:01:15.086|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892818 blockHash=27de4c…ae8bd5 nodeId=a192ebbb32c0810d value=18 DEBUG[12-15|08:01:15.086|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=27de4c…ae8bd5 blockNumber=16892818 root=bcdeb5…e1bd15 pointer=0xc1cbd9ca80 DEBUG[12-15|08:01:15.086|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892818 parentHash=7c4d27…767e45 current hash=27de4c…ae8bd5 nonce=0257c8c9c1f99b59c5552000eea00b836ee31a4698facf8f8089d2d6c8ecd9b70d38c02072fc8a79f62861d8f854c2671238d71d7c99831417f429fb0c00eeb9138dfc8076ee31bdf60c25f61d458b9324 DEBUG[12-15|08:01:15.086|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892818 parentHash=7c4d27…767e45 current hash=27de4c…ae8bd5 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.086|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892818 parentHash=7c4d27…767e45 current hash=27de4c…ae8bd5 valueLength=101 MaxValidators=101 nonce=0257c8c9c1f99b59c5552000eea00b836ee31a4698facf8f8089d2d6c8ecd9b70d38c02072fc8a79f62861d8f854c2671238d71d7c99831417f429fb0c00eeb9138dfc8076ee31bdf60c25f61d458b9324 firstNonce=b5fa13dfbaa892b85b033c3793b60ea0663db3c4a06c2af6a47d7c8024a8360b lastNonce=57c8c9c1f99b59c5552000eea00b836ee31a4698facf8f8089d2d6c8ecd9b70d DEBUG[12-15|08:01:15.086|core/blockchain_reactor.go:322] Store ppos hash blockHash=27de4c…ae8bd5 blockNumber=16892818 pposHash=1ebff7fbadfbede88d93b9f4b42c43a63267398141f2cc7659e60df5ef16fa33 DEBUG[12-15|08:01:15.086|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=27de4c…ae8bd5 blockNumber=16892818 root=6cccd1…8192fc pointer=0xc1cbd9ca80 DEBUG[12-15|08:01:15.087|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892818 blockHash=27de4c…ae8bd5 DEBUG[12-15|08:01:15.087|core/blockchain.go:1578] Execute block time blockNumber=16892818 blockHash=27de4c…ae8bd5 time=2.483337ms DEBUG[12-15|08:01:15.087|core/blockchain_cache.go:300] Execute block number=16892818 hash=27de4c…ae8bd5 parentNumber=16892817 parentHash=7c4d27…767e45 duration=2.557802ms makeState=203.93µs err=nil INFO [12-15|08:01:15.087|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=cb1c17…f21c2d blockNum=16892818 INFO [12-15|08:01:15.090|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=6 qc={Epoch:67572,ViewNumber:6,Hash:27de4c…ae8bd5,Number:16892818,Index:7,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.090|core/tx_pool.go:463] call Reset() hash=27de4c…ae8bd5 number=16892818 parentHash=7c4d27…767e45 DEBUG[12-15|08:01:15.090|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=cb1c17…f21c2d DEBUG[12-15|08:01:15.090|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892817 oldHash=7c4d27…767e45 newNumber=16892818 newHash=27de4c…ae8bd5 count=0 elapsed=10.292µs DEBUG[12-15|08:01:15.090|core/tx_pool.go:473] call Reset elapse time hash=27de4c…ae8bd5 number=16892818 parentHash=7c4d27…767e45 elapseTime=416.419µs INFO [12-15|08:01:15.090|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=6 number=16892816 hash=9528ea…ebb44f DEBUG[12-15|08:01:15.090|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=6 len=0 view={Epoch:67572,ViewNumber:6} INFO [12-15|08:01:15.090|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=6 qcBlock={Epoch:67572,ViewNumber:6,Hash:27de4c…ae8bd5,Number:16892818,Index:7,ValidatorSet:BA{6:xxxxx}} INFO [12-15|08:01:15.091|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=6 number=16892819 hash=eed347…6b7834 time="2021-12-15 08:01:15.091" DEBUG[12-15|08:01:15.091|core/blockchain_cache.go:289] Start execute block hash=eed347…6b7834 number=16892819 sealHash=dce62e…fb7a42 INFO [12-15|08:01:15.091|core/blockchain_cache.go:212] Make stateDB hash=27de4c…ae8bd5 number=16892818 root=6cccd1…8192fc DEBUG[12-15|08:01:15.091|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=cb1c17…f21c2d DEBUG[12-15|08:01:15.091|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=6 hash=9528ea…ebb44f number=16892816 DEBUG[12-15|08:01:15.091|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892819 current hash=eed347…6b7834 parentHash=27de4c…ae8bd5 proof=02337bf9eb4646631d7d1e30fe5aa114801d90d31787734ada2ff03c28cbdc3695fee6d719f90a5143afdba42bd69d0db83c3cd42a94feb4f2c069c98bbcf91adae7d2b33048992b65473bb55413c02fd7 DEBUG[12-15|08:01:15.091|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892819 parentHash=27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5 DEBUG[12-15|08:01:15.091|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.091|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=8f2200…fa8552 DEBUG[12-15|08:01:15.091|miner/worker.go:684] Block is packaged by other hash=9528ea…ebb44f number=16892816 len(Receipts)=0 blockRoot=00e6b1…af906c stateIsNil=false DEBUG[12-15|08:01:15.091|miner/worker.go:693] Cbft consensus successful hash=9528ea…ebb44f number=16892816 timestamp=1639555275091 DEBUG[12-15|08:01:15.091|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.091|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892816, blockHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f, qcNumber:16892816, qcHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f]" lockState="[blockNumber:16892817, blockHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45, qcNumber:16892817, qcHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45]" qcState="[blockNumber:16892818, blockHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5, qcNumber:16892818, qcHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5]" DEBUG[12-15|08:01:15.091|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.092|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892816, blockHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f, qcNumber:16892816, qcHash:0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f]" lockState="[blockNumber:16892817, blockHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45, qcNumber:16892817, qcHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45]" qcState="[blockNumber:16892818, blockHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5, qcNumber:16892818, qcHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5]" elapsed=202.643µs INFO [12-15|08:01:15.091|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892819 current hash=eed347…6b7834 parentHash=27de4c…ae8bd5 proof=02337bf9eb4646631d7d1e30fe5aa114801d90d31787734ada2ff03c28cbdc3695fee6d719f90a5143afdba42bd69d0db83c3cd42a94feb4f2c069c98bbcf91adae7d2b33048992b65473bb55413c02fd7 input=57c8c9c1f99b59c5552000eea00b836ee31a4698facf8f8089d2d6c8ecd9b70d DEBUG[12-15|08:01:15.092|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892819 hash=eed347…6b7834 parentHash=27de4c…ae8bd5 INFO [12-15|08:01:15.092|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892819 hash=eed347…6b7834 parent=27de4c…ae8bd5 DEBUG[12-15|08:01:15.092|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892819 blockHash=eed347…6b7834 nodeId=a192ebbb32c0810d value=19 DEBUG[12-15|08:01:15.092|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=eed347…6b7834 blockNumber=16892819 root=6cccd1…8192fc pointer=0xc1cbe0c480 DEBUG[12-15|08:01:15.092|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892819 parentHash=27de4c…ae8bd5 current hash=eed347…6b7834 nonce=02337bf9eb4646631d7d1e30fe5aa114801d90d31787734ada2ff03c28cbdc3695fee6d719f90a5143afdba42bd69d0db83c3cd42a94feb4f2c069c98bbcf91adae7d2b33048992b65473bb55413c02fd7 DEBUG[12-15|08:01:15.092|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892819 parentHash=27de4c…ae8bd5 current hash=eed347…6b7834 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.092|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892819 parentHash=27de4c…ae8bd5 current hash=eed347…6b7834 valueLength=101 MaxValidators=101 nonce=02337bf9eb4646631d7d1e30fe5aa114801d90d31787734ada2ff03c28cbdc3695fee6d719f90a5143afdba42bd69d0db83c3cd42a94feb4f2c069c98bbcf91adae7d2b33048992b65473bb55413c02fd7 firstNonce=52a2a135cbde1952fa81a1ba52758f95f7cc5659a3084411ec58ef2fedd446f2 lastNonce=337bf9eb4646631d7d1e30fe5aa114801d90d31787734ada2ff03c28cbdc3695 DEBUG[12-15|08:01:15.092|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=168.269µs gcnodes=7 gcsize=2.05KiB gctime=12.865µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.092|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=12.684µs gcnodes=7 gcsize=2.05KiB gctime=12.447µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.092|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892816 blockHash=0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f root=0x00e6b127732c6652dad861233fc2806fedb0b5fbf8e55567622bde8a1caf906c DEBUG[12-15|08:01:15.093|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=9528ea…ebb44f number=16892816 DEBUG[12-15|08:01:15.092|core/blockchain_reactor.go:322] Store ppos hash blockHash=eed347…6b7834 blockNumber=16892819 pposHash=9021e2bf6826e2cda83a081d2f275daae4a2ba76dd7acc4c402248e1a8c34670 DEBUG[12-15|08:01:15.093|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=eed347…6b7834 blockNumber=16892819 root=f6a359…0cc248 pointer=0xc1cbe0c480 DEBUG[12-15|08:01:15.093|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892819 blockHash=eed347…6b7834 DEBUG[12-15|08:01:15.093|core/blockchain.go:1578] Execute block time blockNumber=16892819 blockHash=eed347…6b7834 time=2.193294ms DEBUG[12-15|08:01:15.093|core/blockchain_cache.go:300] Execute block number=16892819 hash=eed347…6b7834 parentNumber=16892818 parentHash=27de4c…ae8bd5 duration=2.212889ms makeState=39.123µs err=nil INFO [12-15|08:01:15.093|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=dce62e…fb7a42 blockNum=16892819 INFO [12-15|08:01:15.093|miner/worker.go:730] Successfully write new block hash=9528ea…ebb44f number=16892816 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966775551 root=00e6b1…af906c DEBUG[12-15|08:01:15.093|miner/worker.go:420] Clear Pending number=16892816 DEBUG[12-15|08:01:15.093|miner/worker.go:424] received a event of ChainHeadEvent hash=9528ea…ebb44f number=16892816 parentHash=dce125…33c858 INFO [12-15|08:01:15.092|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892816 blockHash=9528ea…ebb44f DEBUG[12-15|08:01:15.093|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=9528ea…ebb44f num=16892816 INFO [12-15|08:01:15.093|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892816 hash=0x9528eaede8fec08473d995c3066c05f94ccc499b0230918c21e284a04debb44f DEBUG[12-15|08:01:15.094|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892816 hash=9528ea…ebb44f base= type=snapshotdbCurrentHighestBlock INFO [12-15|08:01:15.095|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=6 qc={Epoch:67572,ViewNumber:6,Hash:eed347…6b7834,Number:16892819,Index:8,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.095|core/tx_pool.go:463] call Reset() hash=eed347…6b7834 number=16892819 parentHash=27de4c…ae8bd5 DEBUG[12-15|08:01:15.095|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=dce62e…fb7a42 DEBUG[12-15|08:01:15.095|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892818 oldHash=27de4c…ae8bd5 newNumber=16892819 newHash=eed347…6b7834 count=0 elapsed=8.428µs DEBUG[12-15|08:01:15.095|core/tx_pool.go:473] call Reset elapse time hash=eed347…6b7834 number=16892819 parentHash=27de4c…ae8bd5 elapseTime=165.292µs INFO [12-15|08:01:15.095|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=6 number=16892817 hash=7c4d27…767e45 DEBUG[12-15|08:01:15.095|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=6 len=0 view={Epoch:67572,ViewNumber:6} INFO [12-15|08:01:15.095|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=6 qcBlock={Epoch:67572,ViewNumber:6,Hash:eed347…6b7834,Number:16892819,Index:8,ValidatorSet:BA{6:xxxxx}} INFO [12-15|08:01:15.095|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=6 number=16892820 hash=158d23…492f70 time="2021-12-15 08:01:15.095" DEBUG[12-15|08:01:15.095|core/blockchain_cache.go:289] Start execute block hash=158d23…492f70 number=16892820 sealHash=2099ce…a08cb8 INFO [12-15|08:01:15.095|core/blockchain_cache.go:212] Make stateDB hash=eed347…6b7834 number=16892819 root=f6a359…0cc248 DEBUG[12-15|08:01:15.095|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=dce62e…fb7a42 DEBUG[12-15|08:01:15.095|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=6 hash=7c4d27…767e45 number=16892817 DEBUG[12-15|08:01:15.095|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892820 current hash=158d23…492f70 parentHash=eed347…6b7834 proof=03b363770d4948d6d704c14e8f44480a561b207a1a884ad4dd213eaef2b5618560b6d2f86f1571a724bea13fdb94ade3a949692a78df635a65d0fe8124562343f0fae2af8a32432c4338fce6ecef143707 DEBUG[12-15|08:01:15.096|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892820 parentHash=eed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834 DEBUG[12-15|08:01:15.096|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.096|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=d92c31…748278 DEBUG[12-15|08:01:15.096|miner/worker.go:684] Block is packaged by other hash=7c4d27…767e45 number=16892817 len(Receipts)=0 blockRoot=bcdeb5…e1bd15 stateIsNil=false DEBUG[12-15|08:01:15.096|miner/worker.go:693] Cbft consensus successful hash=7c4d27…767e45 number=16892817 timestamp=1639555275096 DEBUG[12-15|08:01:15.096|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.096|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892817, blockHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45, qcNumber:16892817, qcHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45]" lockState="[blockNumber:16892818, blockHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5, qcNumber:16892818, qcHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5]" qcState="[blockNumber:16892819, blockHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834, qcNumber:16892819, qcHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834]" DEBUG[12-15|08:01:15.096|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.096|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892817, blockHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45, qcNumber:16892817, qcHash:0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45]" lockState="[blockNumber:16892818, blockHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5, qcNumber:16892818, qcHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5]" qcState="[blockNumber:16892819, blockHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834, qcNumber:16892819, qcHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834]" elapsed=230.854µs DEBUG[12-15|08:01:15.097|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=111.435µs gcnodes=7 gcsize=2.05KiB gctime=12.447µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.097|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=14.954µs gcnodes=7 gcsize=2.05KiB gctime=14.573µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.097|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892817 blockHash=0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45 root=0xbcdeb5cc97bc7b7bfd3995e36f74b80a72cc42029fc4255b3b1ca36bfee1bd15 DEBUG[12-15|08:01:15.097|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=7c4d27…767e45 number=16892817 INFO [12-15|08:01:15.097|miner/worker.go:730] Successfully write new block hash=7c4d27…767e45 number=16892817 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966776654 root=bcdeb5…e1bd15 DEBUG[12-15|08:01:15.097|miner/worker.go:420] Clear Pending number=16892817 DEBUG[12-15|08:01:15.097|miner/worker.go:424] received a event of ChainHeadEvent hash=7c4d27…767e45 number=16892817 parentHash=9528ea…ebb44f INFO [12-15|08:01:15.096|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892820 current hash=158d23…492f70 parentHash=eed347…6b7834 proof=03b363770d4948d6d704c14e8f44480a561b207a1a884ad4dd213eaef2b5618560b6d2f86f1571a724bea13fdb94ade3a949692a78df635a65d0fe8124562343f0fae2af8a32432c4338fce6ecef143707 input=337bf9eb4646631d7d1e30fe5aa114801d90d31787734ada2ff03c28cbdc3695 DEBUG[12-15|08:01:15.098|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892820 hash=158d23…492f70 parentHash=eed347…6b7834 INFO [12-15|08:01:15.098|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892820 hash=158d23…492f70 parent=eed347…6b7834 DEBUG[12-15|08:01:15.098|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892820 blockHash=158d23…492f70 nodeId=a192ebbb32c0810d value=20 DEBUG[12-15|08:01:15.098|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=158d23…492f70 blockNumber=16892820 root=f6a359…0cc248 pointer=0xc1cbe0c900 DEBUG[12-15|08:01:15.098|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892820 parentHash=eed347…6b7834 current hash=158d23…492f70 nonce=03b363770d4948d6d704c14e8f44480a561b207a1a884ad4dd213eaef2b5618560b6d2f86f1571a724bea13fdb94ade3a949692a78df635a65d0fe8124562343f0fae2af8a32432c4338fce6ecef143707 DEBUG[12-15|08:01:15.098|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892820 parentHash=eed347…6b7834 current hash=158d23…492f70 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.098|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892820 parentHash=eed347…6b7834 current hash=158d23…492f70 valueLength=101 MaxValidators=101 nonce=03b363770d4948d6d704c14e8f44480a561b207a1a884ad4dd213eaef2b5618560b6d2f86f1571a724bea13fdb94ade3a949692a78df635a65d0fe8124562343f0fae2af8a32432c4338fce6ecef143707 firstNonce=6adb5d0710c2a1de699b4a3d01841389e9ef9c272f7706f6f19bb0138c8325c4 lastNonce=b363770d4948d6d704c14e8f44480a561b207a1a884ad4dd213eaef2b5618560 DEBUG[12-15|08:01:15.098|core/blockchain_reactor.go:322] Store ppos hash blockHash=158d23…492f70 blockNumber=16892820 pposHash=39949597367cf18e24c757f04985c456b6936a9170a4c3a5549fde5a7b8b3691 DEBUG[12-15|08:01:15.099|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=158d23…492f70 blockNumber=16892820 root=41690b…45d824 pointer=0xc1cbe0c900 DEBUG[12-15|08:01:15.099|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892820 blockHash=158d23…492f70 DEBUG[12-15|08:01:15.099|core/blockchain.go:1578] Execute block time blockNumber=16892820 blockHash=158d23…492f70 time=3.527703ms DEBUG[12-15|08:01:15.099|core/blockchain_cache.go:300] Execute block number=16892820 hash=158d23…492f70 parentNumber=16892819 parentHash=eed347…6b7834 duration=3.607356ms makeState=39.369µs err=nil INFO [12-15|08:01:15.099|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=2099ce…a08cb8 blockNum=16892820 INFO [12-15|08:01:15.095|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892817 blockHash=7c4d27…767e45 DEBUG[12-15|08:01:15.100|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=7c4d27…767e45 num=16892817 INFO [12-15|08:01:15.100|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892817 hash=0x7c4d279d701272ddcba5982e782e4f48eb8d8fff0debea7504a359dcd3767e45 DEBUG[12-15|08:01:15.100|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892817 hash=7c4d27…767e45 base= type=snapshotdbCurrentHighestBlock INFO [12-15|08:01:15.102|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=6 qc={Epoch:67572,ViewNumber:6,Hash:158d23…492f70,Number:16892820,Index:9,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.102|core/tx_pool.go:463] call Reset() hash=158d23…492f70 number=16892820 parentHash=eed347…6b7834 DEBUG[12-15|08:01:15.103|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=2099ce…a08cb8 DEBUG[12-15|08:01:15.103|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892819 oldHash=eed347…6b7834 newNumber=16892820 newHash=158d23…492f70 count=0 elapsed=9.971µs DEBUG[12-15|08:01:15.103|core/tx_pool.go:473] call Reset elapse time hash=158d23…492f70 number=16892820 parentHash=eed347…6b7834 elapseTime=418.398µs INFO [12-15|08:01:15.103|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=6 number=16892818 hash=27de4c…ae8bd5 INFO [12-15|08:01:15.103|consensus/cbft/consensus_process.go:589] Produce enough blocks, change view epoch=67572 view=6 view={Epoch:67572,ViewNumber:6} DEBUG[12-15|08:01:15.103|consensus/cbft/wal/wal.go:276] Success to update viewChange meta epoch=67572 viewNumber=7 fileID=15 seq=19804287 DEBUG[12-15|08:01:15.103|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=6 hash=27de4c…ae8bd5 number=16892818 DEBUG[12-15|08:01:15.103|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.103|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=cb1c17…f21c2d DEBUG[12-15|08:01:15.103|miner/worker.go:684] Block is packaged by other hash=27de4c…ae8bd5 number=16892818 len(Receipts)=0 blockRoot=6cccd1…8192fc stateIsNil=false DEBUG[12-15|08:01:15.103|miner/worker.go:693] Cbft consensus successful hash=27de4c…ae8bd5 number=16892818 timestamp=1639555275103 DEBUG[12-15|08:01:15.103|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.103|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892818, blockHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5, qcNumber:16892818, qcHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5]" lockState="[blockNumber:16892819, blockHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834, qcNumber:16892819, qcHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834]" qcState="[blockNumber:16892820, blockHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70, qcNumber:16892820, qcHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70]" DEBUG[12-15|08:01:15.104|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.104|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892818, blockHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5, qcNumber:16892818, qcHash:0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5]" lockState="[blockNumber:16892819, blockHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834, qcNumber:16892819, qcHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834]" qcState="[blockNumber:16892820, blockHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70, qcNumber:16892820, qcHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70]" elapsed=644.134µs DEBUG[12-15|08:01:15.104|consensus/cbft/wal_bridge.go:207] Success to confirm viewChange confirmedViewChange="[epoch:67572, viewNumber:7, blockIndex:9, blockNumber:16892820, blockHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70]" elapsed=1.426937ms DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:239] Clear cache baseBlockHash=27de4c…ae8bd5 baseBlockNumber=16892818 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:253] Clear Cache block sealHash=d9737c…fa8163 number=16892807 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:180] Clear Receipts sealHash=d9737c…fa8163 number=16892807 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:197] Clear StateDB sealHash=d9737c…fa8163 number=16892807 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:253] Clear Cache block sealHash=08b4a2…d7c2af number=16892808 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:180] Clear Receipts sealHash=08b4a2…d7c2af number=16892808 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:197] Clear StateDB sealHash=08b4a2…d7c2af number=16892808 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:253] Clear Cache block sealHash=eb3ef2…688be7 number=16892809 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:180] Clear Receipts sealHash=eb3ef2…688be7 number=16892809 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:197] Clear StateDB sealHash=eb3ef2…688be7 number=16892809 DEBUG[12-15|08:01:15.104|core/blockchain_cache.go:253] Clear Cache block sealHash=eed367…094758 number=16892810 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:180] Clear Receipts sealHash=eed367…094758 number=16892810 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:197] Clear StateDB sealHash=eed367…094758 number=16892810 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:253] Clear Cache block sealHash=568a20…0fd8e4 number=16892811 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:180] Clear Receipts sealHash=568a20…0fd8e4 number=16892811 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:197] Clear StateDB sealHash=568a20…0fd8e4 number=16892811 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:253] Clear Cache block sealHash=7fdd37…3e2335 number=16892812 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:180] Clear Receipts sealHash=7fdd37…3e2335 number=16892812 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:197] Clear StateDB sealHash=7fdd37…3e2335 number=16892812 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:253] Clear Cache block sealHash=c01b06…805d16 number=16892813 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:180] Clear Receipts sealHash=c01b06…805d16 number=16892813 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:197] Clear StateDB sealHash=c01b06…805d16 number=16892813 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:253] Clear Cache block sealHash=8eca72…671c42 number=16892814 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:180] Clear Receipts sealHash=8eca72…671c42 number=16892814 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:197] Clear StateDB sealHash=8eca72…671c42 number=16892814 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:253] Clear Cache block sealHash=1a5194…3fee56 number=16892815 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:180] Clear Receipts sealHash=1a5194…3fee56 number=16892815 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:197] Clear StateDB sealHash=1a5194…3fee56 number=16892815 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:253] Clear Cache block sealHash=8f2200…fa8552 number=16892816 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:180] Clear Receipts sealHash=8f2200…fa8552 number=16892816 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:197] Clear StateDB sealHash=8f2200…fa8552 number=16892816 WARN [12-15|08:01:15.105|core/tx_pool.go:477] Reset rollback block hash=158d23…492f70 number=16892820 rollback=0 DEBUG[12-15|08:01:15.105|consensus/cbft/network/handler.go:216] PartBroadcast message to sendQueue msgHash=2ed1cf…215faf BHash=000000…000000 msg={Epoch:67572,ViewNumber:7,BlockIndex:0} DEBUG[12-15|08:01:15.105|consensus/cbft/sync_process.go:874] Send GetPrepareBlock by part broadcast epoch=67572 view=6 msg={Epoch:67572,ViewNumber:7,BlockIndex:0} INFO [12-15|08:01:15.105|consensus/cbft/consensus_process.go:765] Success to change view, current view deadline epoch=67572 view=7 deadline=2021-12-15T08:01:35+0000 INFO [12-15|08:01:15.105|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=7 qcBlock={Epoch:67572,ViewNumber:6,Hash:158d23…492f70,Number:16892820,Index:9,ValidatorSet:BA{6:xxxxx}} INFO [12-15|08:01:15.105|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=7 number=16892821 hash=fce3ed…405596 time="2021-12-15 08:01:15.105" DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:289] Start execute block hash=fce3ed…405596 number=16892821 sealHash=bde613…576887 INFO [12-15|08:01:15.105|core/blockchain_cache.go:212] Make stateDB hash=158d23…492f70 number=16892820 root=41690b…45d824 DEBUG[12-15|08:01:15.105|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=2099ce…a08cb8 DEBUG[12-15|08:01:15.105|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892821 current hash=fce3ed…405596 parentHash=158d23…492f70 proof=03d35d49ac803740f1dd9ab1b9c0aeb3d52bae61533ef882ecc592323e0349b6dfbae153696f58d629d7ca514e5da22141df050c49aebd86304ce0e6ddea5302b6b9aea41ae8aa37f3738ffa407fcaa931 DEBUG[12-15|08:01:15.105|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892821 parentHash=158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70 DEBUG[12-15|08:01:15.106|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=197.416µs gcnodes=7 gcsize=2.05KiB gctime=14.573µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.106|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=23.301µs gcnodes=7 gcsize=2.05KiB gctime=23.019µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.106|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892818 blockHash=0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5 root=0x6cccd14991fd686d57358a5381eb9d34487ccfe91e6906363f7f58e2ff8192fc DEBUG[12-15|08:01:15.106|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=27de4c…ae8bd5 number=16892818 INFO [12-15|08:01:15.106|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892821 current hash=fce3ed…405596 parentHash=158d23…492f70 proof=03d35d49ac803740f1dd9ab1b9c0aeb3d52bae61533ef882ecc592323e0349b6dfbae153696f58d629d7ca514e5da22141df050c49aebd86304ce0e6ddea5302b6b9aea41ae8aa37f3738ffa407fcaa931 input=b363770d4948d6d704c14e8f44480a561b207a1a884ad4dd213eaef2b5618560 DEBUG[12-15|08:01:15.106|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892821 hash=fce3ed…405596 parentHash=158d23…492f70 INFO [12-15|08:01:15.106|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892821 hash=fce3ed…405596 parent=158d23…492f70 DEBUG[12-15|08:01:15.106|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892821 blockHash=fce3ed…405596 nodeId=01bdfadca94b4525 value=11 DEBUG[12-15|08:01:15.106|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=fce3ed…405596 blockNumber=16892821 root=41690b…45d824 pointer=0xc1cbe0ca80 DEBUG[12-15|08:01:15.106|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892821 parentHash=158d23…492f70 current hash=fce3ed…405596 nonce=03d35d49ac803740f1dd9ab1b9c0aeb3d52bae61533ef882ecc592323e0349b6dfbae153696f58d629d7ca514e5da22141df050c49aebd86304ce0e6ddea5302b6b9aea41ae8aa37f3738ffa407fcaa931 DEBUG[12-15|08:01:15.106|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892821 parentHash=158d23…492f70 current hash=fce3ed…405596 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.106|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892821 parentHash=158d23…492f70 current hash=fce3ed…405596 valueLength=101 MaxValidators=101 nonce=03d35d49ac803740f1dd9ab1b9c0aeb3d52bae61533ef882ecc592323e0349b6dfbae153696f58d629d7ca514e5da22141df050c49aebd86304ce0e6ddea5302b6b9aea41ae8aa37f3738ffa407fcaa931 firstNonce=67ba49df6d7c214fd02182d4e6df3782433f808fdcce628c290f9d068397b94e lastNonce=d35d49ac803740f1dd9ab1b9c0aeb3d52bae61533ef882ecc592323e0349b6df DEBUG[12-15|08:01:15.106|core/blockchain_reactor.go:322] Store ppos hash blockHash=fce3ed…405596 blockNumber=16892821 pposHash=9f2071f6d3a17e2217e31bfc75df541304691150dd418bb3eed2f8c5d60864e7 DEBUG[12-15|08:01:15.107|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=fce3ed…405596 blockNumber=16892821 root=a894d7…02aae5 pointer=0xc1cbe0ca80 DEBUG[12-15|08:01:15.107|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892821 blockHash=fce3ed…405596 DEBUG[12-15|08:01:15.107|core/blockchain.go:1578] Execute block time blockNumber=16892821 blockHash=fce3ed…405596 time=1.391793ms DEBUG[12-15|08:01:15.107|core/blockchain_cache.go:300] Execute block number=16892821 hash=fce3ed…405596 parentNumber=16892820 parentHash=158d23…492f70 duration=1.410854ms makeState=38.282µs err=nil INFO [12-15|08:01:15.107|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=bde613…576887 blockNum=16892821 INFO [12-15|08:01:15.107|miner/worker.go:730] Successfully write new block hash=27de4c…ae8bd5 number=16892818 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966777757 root=6cccd1…8192fc DEBUG[12-15|08:01:15.107|miner/worker.go:420] Clear Pending number=16892818 DEBUG[12-15|08:01:15.107|miner/worker.go:424] received a event of ChainHeadEvent hash=27de4c…ae8bd5 number=16892818 parentHash=7c4d27…767e45 INFO [12-15|08:01:15.107|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892818 blockHash=27de4c…ae8bd5 DEBUG[12-15|08:01:15.107|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=27de4c…ae8bd5 num=16892818 INFO [12-15|08:01:15.107|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892818 hash=0x27de4ca3930a915086781fa50c50dd0228b3c106fd39a427e9e17b78deae8bd5 DEBUG[12-15|08:01:15.108|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892818 hash=27de4c…ae8bd5 base= type=snapshotdbCurrentHighestBlock DEBUG[12-15|08:01:15.108|consensus/cbft/network/router.go:206] consensusNodes in kMixingRandomNodes cNodes=6 ids=01bdfadca94b4525,48f9ebd7559b7849,335c2bb831261730,c4d723c6a227346d,e35db470fc36092c,a192ebbb32c0810d DEBUG[12-15|08:01:15.108|consensus/cbft/network/router.go:234] kMixingRandomNodes select node msgHash=2ed1cf…215faf cNodesLen=6 ncNodesLen=2 peerSetLen=7 DEBUG[12-15|08:01:15.108|consensus/cbft/network/router.go:111] Gossip message msgHash=2ed1cf…215faf msgType=*protocols.GetPrepareBlock msg={Epoch:67572,ViewNumber:7,BlockIndex:0} targetPeer=e35db470fc36092c,35acf4e9301a3fa1 INFO [12-15|08:01:15.109|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=7 qc={Epoch:67572,ViewNumber:7,Hash:fce3ed…405596,Number:16892821,Index:0,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.109|core/tx_pool.go:463] call Reset() hash=fce3ed…405596 number=16892821 parentHash=158d23…492f70 DEBUG[12-15|08:01:15.109|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=bde613…576887 DEBUG[12-15|08:01:15.110|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892820 oldHash=158d23…492f70 newNumber=16892821 newHash=fce3ed…405596 count=0 elapsed=14.115µs DEBUG[12-15|08:01:15.110|core/tx_pool.go:473] call Reset elapse time hash=fce3ed…405596 number=16892821 parentHash=158d23…492f70 elapseTime=186.406µs INFO [12-15|08:01:15.110|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=7 number=16892819 hash=eed347…6b7834 DEBUG[12-15|08:01:15.110|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=7 len=0 view={Epoch:67572,ViewNumber:7} INFO [12-15|08:01:15.110|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=7 qcBlock={Epoch:67572,ViewNumber:7,Hash:fce3ed…405596,Number:16892821,Index:0,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.110|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=7 hash=eed347…6b7834 number=16892819 INFO [12-15|08:01:15.110|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=7 number=16892822 hash=de8979…e34c97 time="2021-12-15 08:01:15.11" DEBUG[12-15|08:01:15.110|core/blockchain_cache.go:289] Start execute block hash=de8979…e34c97 number=16892822 sealHash=0e298e…a704a5 INFO [12-15|08:01:15.110|core/blockchain_cache.go:212] Make stateDB hash=fce3ed…405596 number=16892821 root=a894d7…02aae5 DEBUG[12-15|08:01:15.110|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=bde613…576887 DEBUG[12-15|08:01:15.110|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.110|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=dce62e…fb7a42 DEBUG[12-15|08:01:15.110|miner/worker.go:684] Block is packaged by other hash=eed347…6b7834 number=16892819 len(Receipts)=0 blockRoot=f6a359…0cc248 stateIsNil=false DEBUG[12-15|08:01:15.110|miner/worker.go:693] Cbft consensus successful hash=eed347…6b7834 number=16892819 timestamp=1639555275110 DEBUG[12-15|08:01:15.110|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.110|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892819, blockHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834, qcNumber:16892819, qcHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834]" lockState="[blockNumber:16892820, blockHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70, qcNumber:16892820, qcHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70]" qcState="[blockNumber:16892821, blockHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596, qcNumber:16892821, qcHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596]" DEBUG[12-15|08:01:15.110|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.110|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892819, blockHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834, qcNumber:16892819, qcHash:0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834]" lockState="[blockNumber:16892820, blockHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70, qcNumber:16892820, qcHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70]" qcState="[blockNumber:16892821, blockHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596, qcNumber:16892821, qcHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596]" elapsed=101.067µs DEBUG[12-15|08:01:15.110|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892822 current hash=de8979…e34c97 parentHash=fce3ed…405596 proof=03fca01bddebfb9f079d43af50d095293d7fc795625444699e77cdef909b2f30f80ee3fc99c9ad4a46c68b389291498b4c699b95bf11b69b004638ea30eb52b7875866569f689ea978b256ade812a8fddc DEBUG[12-15|08:01:15.111|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892822 parentHash=fce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596 DEBUG[12-15|08:01:15.111|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=105.314µs gcnodes=7 gcsize=2.05KiB gctime=23.019µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.111|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=13.825µs gcnodes=7 gcsize=2.05KiB gctime=13.618µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.111|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892819 blockHash=0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834 root=0xf6a359f54e7281406fde46ab5d0f4562a4397ee074cb6fd0a44288cca40cc248 DEBUG[12-15|08:01:15.111|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=eed347…6b7834 number=16892819 INFO [12-15|08:01:15.111|miner/worker.go:730] Successfully write new block hash=eed347…6b7834 number=16892819 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966778862 root=f6a359…0cc248 DEBUG[12-15|08:01:15.111|miner/worker.go:420] Clear Pending number=16892819 DEBUG[12-15|08:01:15.111|miner/worker.go:424] received a event of ChainHeadEvent hash=eed347…6b7834 number=16892819 parentHash=27de4c…ae8bd5 INFO [12-15|08:01:15.110|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892819 blockHash=eed347…6b7834 DEBUG[12-15|08:01:15.111|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=eed347…6b7834 num=16892819 INFO [12-15|08:01:15.111|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892819 hash=0xeed347713247b3c7dd7d2837b35ff6e9050d6caf65465eeaa8de5d03306b7834 DEBUG[12-15|08:01:15.111|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892819 hash=eed347…6b7834 base= type=snapshotdbCurrentHighestBlock INFO [12-15|08:01:15.112|x/handler/vrf_handler.go:117] Vrf proves successful verification current blockNumber=16892822 current hash=de8979…e34c97 parentHash=fce3ed…405596 proof=03fca01bddebfb9f079d43af50d095293d7fc795625444699e77cdef909b2f30f80ee3fc99c9ad4a46c68b389291498b4c699b95bf11b69b004638ea30eb52b7875866569f689ea978b256ade812a8fddc input=d35d49ac803740f1dd9ab1b9c0aeb3d52bae61533ef882ecc592323e0349b6df DEBUG[12-15|08:01:15.112|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=16892822 hash=de8979…e34c97 parentHash=fce3ed…405596 INFO [12-15|08:01:15.112|core/snapshotdb/snapshotdb.go:556] NewBlock package=snapshotdb num=16892822 hash=de8979…e34c97 parent=fce3ed…405596 DEBUG[12-15|08:01:15.112|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=16892822 blockHash=de8979…e34c97 nodeId=01bdfadca94b4525 value=12 DEBUG[12-15|08:01:15.113|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=de8979…e34c97 blockNumber=16892822 root=a894d7…02aae5 pointer=0xc1cbe0cf00 DEBUG[12-15|08:01:15.113|x/handler/vrf_handler.go:124] Storage previous nonce blockNumber=16892822 parentHash=fce3ed…405596 current hash=de8979…e34c97 nonce=03fca01bddebfb9f079d43af50d095293d7fc795625444699e77cdef909b2f30f80ee3fc99c9ad4a46c68b389291498b4c699b95bf11b69b004638ea30eb52b7875866569f689ea978b256ade812a8fddc DEBUG[12-15|08:01:15.113|x/handler/vrf_handler.go:140] Storage previous nonce current blockNumber=16892822 parentHash=fce3ed…405596 current hash=de8979…e34c97 valueLength=101 MaxValidators=101 INFO [12-15|08:01:15.113|x/handler/vrf_handler.go:163] Storage previous nonce Success current blockNumber=16892822 parentHash=fce3ed…405596 current hash=de8979…e34c97 valueLength=101 MaxValidators=101 nonce=03fca01bddebfb9f079d43af50d095293d7fc795625444699e77cdef909b2f30f80ee3fc99c9ad4a46c68b389291498b4c699b95bf11b69b004638ea30eb52b7875866569f689ea978b256ade812a8fddc firstNonce=b8146ba2bd6a4abd667d11405011185432138af677ef2bc4ec4b0a7c8a0e0349 lastNonce=fca01bddebfb9f079d43af50d095293d7fc795625444699e77cdef909b2f30f8 DEBUG[12-15|08:01:15.113|core/blockchain_reactor.go:322] Store ppos hash blockHash=de8979…e34c97 blockNumber=16892822 pposHash=4dcbc8cd760d8448a10e3f6af9a4b83a9a6d5455ec6b83d6248a1a9f4656ef20 DEBUG[12-15|08:01:15.113|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=de8979…e34c97 blockNumber=16892822 root=906ab6…b7c0bd pointer=0xc1cbe0cf00 DEBUG[12-15|08:01:15.113|core/parallel_state_processor.go:88] Process end blocker cost time blockNumber=16892822 blockHash=de8979…e34c97 DEBUG[12-15|08:01:15.114|core/blockchain.go:1578] Execute block time blockNumber=16892822 blockHash=de8979…e34c97 time=3.503624ms DEBUG[12-15|08:01:15.114|core/blockchain_cache.go:300] Execute block number=16892822 hash=de8979…e34c97 parentNumber=16892821 parentHash=fce3ed…405596 duration=3.599143ms makeState=40.801µs err=nil INFO [12-15|08:01:15.114|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=0e298e…a704a5 blockNum=16892822 INFO [12-15|08:01:15.117|consensus/cbft/consensusprocess.go:246] Insert QC block epoch=67572 view=7 qc={Epoch:67572,ViewNumber:7,Hash:de8979…e34c97,Number:16892822,Index:1,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.117|core/tx_pool.go:463] call Reset() hash=de8979…e34c97 number=16892822 parentHash=fce3ed…405596 DEBUG[12-15|08:01:15.117|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=0e298e…a704a5 DEBUG[12-15|08:01:15.117|core/tx_pool.go:1408] Reinjecting stale transactions oldNumber=16892821 oldHash=fce3ed…405596 newNumber=16892822 newHash=de8979…e34c97 count=0 elapsed=9.077µs DEBUG[12-15|08:01:15.117|core/tx_pool.go:473] call Reset elapse time hash=de8979…e34c97 number=16892822 parentHash=fce3ed…405596 elapseTime=405.06µs INFO [12-15|08:01:15.117|consensus/cbft/cbft.go:1306] CommitBlock, send consensus result to worker epoch=67572 view=7 number=16892820 hash=158d23…492f70 DEBUG[12-15|08:01:15.117|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=67572 view=7 len=0 view={Epoch:67572,ViewNumber:7} INFO [12-15|08:01:15.118|consensus/cbft/consensusprocess.go:237] Insert QC block success epoch=67572 view=7 qcBlock={Epoch:67572,ViewNumber:7,Hash:de8979…e34c97,Number:16892822,Index:1,ValidatorSet:BA{6:xxxxx}} DEBUG[12-15|08:01:15.117|consensus/cbft/cbft.go:844] Seal hash epoch=67572 view=7 hash=158d23…492f70 number=16892820 DEBUG[12-15|08:01:15.118|miner/worker.go:671] Pending task exist=false DEBUG[12-15|08:01:15.118|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=2099ce…a08cb8 DEBUG[12-15|08:01:15.118|miner/worker.go:684] Block is packaged by other hash=158d23…492f70 number=16892820 len(Receipts)=0 blockRoot=41690b…45d824 stateIsNil=false DEBUG[12-15|08:01:15.118|miner/worker.go:693] Cbft consensus successful hash=158d23…492f70 number=16892820 timestamp=1639555275118 DEBUG[12-15|08:01:15.118|miner/worker.go:717] Write extra data txs=0 extra=117 DEBUG[12-15|08:01:15.118|consensus/cbft/wal_bridge.go:150] New chainState commitState="[blockNumber:16892820, blockHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70, qcNumber:16892820, qcHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70]" lockState="[blockNumber:16892821, blockHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596, qcNumber:16892821, qcHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596]" qcState="[blockNumber:16892822, blockHash:0xde8979eb73f2ab0f11b7bef2e35bf3998c1eb4a61303b45fa8598b49eae34c97, qcNumber:16892822, qcHash:0xde8979eb73f2ab0f11b7bef2e35bf3998c1eb4a61303b45fa8598b49eae34c97]" DEBUG[12-15|08:01:15.118|consensus/cbft/wal/wal.go:209] Success to update chainState INFO [12-15|08:01:15.118|consensus/cbft/wal_bridge.go:144] Success to update chainState commitState="[blockNumber:16892820, blockHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70, qcNumber:16892820, qcHash:0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70]" lockState="[blockNumber:16892821, blockHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596, qcNumber:16892821, qcHash:0xfce3ed31e491f535004cadf46103d2ccf7533a39c8fbbe345372ddc80d405596]" qcState="[blockNumber:16892822, blockHash:0xde8979eb73f2ab0f11b7bef2e35bf3998c1eb4a61303b45fa8598b49eae34c97, qcNumber:16892822, qcHash:0xde8979eb73f2ab0f11b7bef2e35bf3998c1eb4a61303b45fa8598b49eae34c97]" elapsed=88.262µs INFO [12-15|08:01:15.117|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=16892820 blockHash=158d23…492f70 DEBUG[12-15|08:01:15.118|core/snapshotdb/current.go:162] increase current highest package=snapshotdb hash=158d23…492f70 num=16892820 DEBUG[12-15|08:01:15.118|trie/database.go:921] Persisted trie from memory database nodes=0 size=0.00B time=99.203µs gcnodes=7 gcsize=2.05KiB gctime=13.618µs livenodes=31 livesize=9.52KiB DEBUG[12-15|08:01:15.118|trie/database.go:665] Dereferenced trie from memory database nodes=7 size=2.05KiB time=14.163µs gcnodes=7 gcsize=2.05KiB gctime=13.891µs livenodes=24 livesize=7.47KiB DEBUG[12-15|08:01:15.118|core/blockchain.go:1322] archive node commit stateDB trie blockNumber=16892820 blockHash=0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70 root=0x41690b36cdcf4d9f6ff640d7dec0adf74a653b4fd2c5d590fce5ba2ced45d824 DEBUG[12-15|08:01:15.118|core/blockchain.go:1417] insert into chain WriteStatus=1 hash=158d23…492f70 number=16892820 DEBUG[12-15|08:01:15.119|core/snapshotdb/current.go:183] save current to baseDB package=snapshotdb height=16892820 hash=158d23…492f70 base= type=snapshotdbCurrentHighestBlock INFO [12-15|08:01:15.119|miner/worker.go:730] Successfully write new block hash=158d23…492f70 number=16892820 coinbase=atp1zqqqqqqqqqqqqqqqqqqqqqqqqqqqqqqr5jy24r time=1633966779964 root=41690b…45d824 INFO [12-15|08:01:15.118|core/snapshotdb/snapshotdb.go:770] commit block package=snapshotdb num=16892820 hash=0x158d23a631b787e4cd73e706fd516b5421df9c59161695271daa2b30bb492f70 INFO [12-15|08:01:15.118|consensus/cbft/cbft.go:899] Insert chain epoch=67572 view=7 number=16892823 hash=7f3bc9…71a1e1 time="2021-12-15 08:01:15.118" DEBUG[12-15|08:01:15.119|miner/worker.go:420] Clear Pending number=16892820 DEBUG[12-15|08:01:15.119|miner/worker.go:424] received a event of ChainHeadEvent hash=158d23…492f70 number=16892820 parentHash=eed347…6b7834 DEBUG[12-15|08:01:15.119|core/blockchain_cache.go:289] Start execute block hash=7f3bc9…71a1e1 number=16892823 sealHash=8c2348…3a1e3a INFO [12-15|08:01:15.119|core/blockchain_cache.go:212] Make stateDB hash=de8979…e34c97 number=16892822 root=906ab6…b7c0bd DEBUG[12-15|08:01:15.119|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=0e298e…a704a5 DEBUG[12-15|08:01:15.119|x/handler/vrf_handler.go:99] Verification block vrf prove current blockNumber=16892823 current hash=7f3bc9…71a1e1 parentHash=de8979…e34c97 proof=0226affe1f3b1f03dca9258372fce8044ce0c35568c65af1c44a0363d986ff5fe2d442ca3985a37f31296491c66cce2680b4fa75f9f4469b99d8f2b917d7e8e2eada72da101845a6dcdb0c2222fa53b8ef DEBUG[12-15|08:01:15.120|x/handler/vrf_handler.go:187] Get the nonce of the previous block blockNumber=16892823 parentHash=de8979eb73f2ab0f11b7bef2e35bf3998c1eb4a61303b45fa8598b49eae34c97 fatal error: runtime: out of memory

runtime stack: runtime.throw(0x151836c, 0x16) /opt/go/src/runtime/panic.go:1117 +0x72 runtime.sysMap(0xc1cc000000, 0x4000000, 0x21b92d0) /opt/go/src/runtime/mem_linux.go:169 +0xc6 runtime.(mheap).sysAlloc(0x218ec40, 0x400000, 0x7fffffffffff, 0x4dca01cb9ec9) /opt/go/src/runtime/malloc.go:729 +0x1e5 runtime.(mheap).grow(0x218ec40, 0x1, 0x0) /opt/go/src/runtime/mheap.go:1346 +0x85 runtime.(mheap).allocSpan(0x218ec40, 0x1, 0x4a0d00, 0x0) /opt/go/src/runtime/mheap.go:1173 +0x609 runtime.(mheap).alloc.func1() /opt/go/src/runtime/mheap.go:910 +0x59 runtime.systemstack(0x0) /opt/go/src/runtime/asm_amd64.s:379 +0x66 runtime.mstart() /opt/go/src/runtime/proc.go:1246

goroutine 344 [running]: runtime.systemstack_switch() /opt/go/src/runtime/asm_amd64.s:339 fp=0xc1228d47c0 sp=0xc1228d47b8 pc=0x4ac460 runtime.(mheap).alloc(0x218ec40, 0x1, 0x46010d, 0x219f8d8) /opt/go/src/runtime/mheap.go:904 +0x85 fp=0xc1228d4810 sp=0xc1228d47c0 pc=0x463be5 runtime.(mcentral).grow(0x219f838, 0x0) /opt/go/src/runtime/mcentral.go:232 +0x79 fp=0xc1228d4858 sp=0xc1228d4810 pc=0x454c99 runtime.(mcentral).cacheSpan(0x219f838, 0x7f4712cda3a0) /opt/go/src/runtime/mcentral.go:158 +0x2ff fp=0xc1228d48b0 sp=0xc1228d4858 pc=0x454a7f runtime.(mcache).refill(0x7f477fe195b8, 0xd) /opt/go/src/runtime/mcache.go:162 +0xaa fp=0xc1228d48f8 sp=0xc1228d48b0 pc=0x453f8a runtime.(mcache).nextFree(0x7f477fe195b8, 0xc1228d490d, 0x4a8e85, 0xc121adf680, 0x200000003) /opt/go/src/runtime/malloc.go:882 +0x8d fp=0xc1228d4930 sp=0xc1228d48f8 pc=0x4491cd runtime.mallocgc(0x40, 0x13101e0, 0xc1cbf23601, 0xc1228d4a18) /opt/go/src/runtime/malloc.go:1069 +0x850 fp=0xc1228d49b8 sp=0xc1228d4930 pc=0x449bd0 runtime.makeslice(0x13101e0, 0x4, 0x8, 0x13d8440) /opt/go/src/runtime/slice.go:98 +0x6c fp=0xc1228d49e8 sp=0xc1228d49b8 pc=0x48cb4c math/big.nat.make(...) /opt/go/src/math/big/nat.go:69 math/big.nat.setBytes(0x0, 0x0, 0x0, 0xc1cbf4a8c1, 0x20, 0x40, 0x148e860, 0xc014b63c00, 0xc1228d4c18) /opt/go/src/math/big/nat.go:1518 +0x1a6 fp=0xc1228d4a28 sp=0xc1228d49e8 pc=0x5ce946 math/big.(Int).SetBytes(...) /opt/go/src/math/big/int.go:444 github.com/AlayaNetwork/Alaya-Go/crypto/secp256k1.DecompressPubkey(0xc1cbf54f60, 0x21, 0x51, 0xf, 0xc1cbe563e9) /opt/jenkins/workspace/Alaya/build_ubuntu/crypto/secp256k1/secp256.go:150 +0xee fp=0xc1228d4a98 sp=0xc1228d4a28 pc=0x7223ce github.com/AlayaNetwork/Alaya-Go/crypto/vrf.OS2ECP(...) /opt/jenkins/workspace/Alaya/build_ubuntu/crypto/vrf/vrf_secp256k1.go:155 github.com/AlayaNetwork/Alaya-Go/crypto/vrf.ECVRF_decode_proof(0xc1cbf54f60, 0x51, 0x51, 0xc1228d4c18, 0x67bffd, 0xc000042650, 0xc1cbf5d500, 0x0, 0xc1cbf7a690) /opt/jenkins/workspace/Alaya/build_ubuntu/crypto/vrf/vrf_secp256k1.go:111 +0x4f fp=0xc1228d4b10 sp=0xc1228d4a98 pc=0xc1bb0f github.com/AlayaNetwork/Alaya-Go/crypto/vrf.eCVRF_verify(0xc1cbf24e10, 0x21, 0x21, 0xc1cbf54f60, 0x51, 0x51, 0xc1cbf6da60, 0x20, 0x20, 0x16d6690, ...) /opt/jenkins/workspace/Alaya/build_ubuntu/crypto/vrf/vrf_secp256k1.go:77 +0x5d fp=0xc1228d4d18 sp=0xc1228d4b10 pc=0xc1b2fd github.com/AlayaNetwork/Alaya-Go/crypto/vrf.Verify(0xc1cbf235e0, 0xc1cbf54f60, 0x51, 0x51, 0xc1cbf6da60, 0x20, 0x20, 0x20, 0x20, 0x0) /opt/jenkins/workspace/Alaya/build_ubuntu/crypto/vrf/vrf.go:41 +0xa9 fp=0xc1228d4d88 sp=0xc1228d4d18 pc=0xc1aaa9 github.com/AlayaNetwork/Alaya-Go/x/handler.(VrfHandler).VerifyVrf(0xc0159c8750, 0xc1cbf235e0, 0xc1cbf23580, 0xfabf273eb7989de, 0x99f35be3f2beb711, 0x5fb40313a6b41e8c, 0x974ce3ea498b59a8, 0xb77dc6edddc93b7f, 0x5e86bce656345049, 0xaf62a2ec2ff19d7, ...) /opt/jenkins/workspace/Alaya/build_ubuntu/x/handler/vrf_handler.go:106 +0x417 fp=0xc1228d5280 sp=0xc1228d4d88 pc=0xc1db17 github.com/AlayaNetwork/Alaya-Go/core.(BlockChainReactor).BeginBlocker(0xc000169bc0, 0xc1cbf71680, 0x1729c38, 0xc1cbe0d380, 0x1db2d2c3afb66a90, 0x4d3a494b80b88ca3) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain_reactor.go:255 +0xe89 fp=0xc1228d56f8 sp=0xc1228d5280 pc=0xe266a9 github.com/AlayaNetwork/Alaya-Go/core.(ParallelStateProcessor).Process(0xc00002cfe0, 0xc1cadfd050, 0xc1cbe0d380, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /opt/jenkins/workspace/Alaya/build_ubuntu/core/parallel_state_processor.go:40 +0x1498 fp=0xc1228d5b90 sp=0xc1228d56f8 pc=0xe3f5d8 github.com/AlayaNetwork/Alaya-Go/core.(BlockChain).ProcessDirectly(0xc00eed6000, 0xc1cadfd050, 0xc1cbe0d380, 0xc1cadfcfc0, 0x0, 0x6, 0x6, 0x2, 0xc1cbf561a0) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain.go:1572 +0xe3 fp=0xc1228d6e18 sp=0xc1228d5b90 pc=0xe17f03 github.com/AlayaNetwork/Alaya-Go/core.(BlockChainCache).Execute(0xc000034e80, 0xc1cadfd050, 0xc1cadfcfc0, 0x0, 0x0) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain_cache.go:299 +0x517 fp=0xc1228d7208 sp=0xc1228d6e18 pc=0xe1f477 github.com/AlayaNetwork/Alaya-Go/consensus/cbft.(Cbft).InsertChain(0xc000228600, 0xc1cadfd050, 0x0, 0x0) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/cbft.go:920 +0xbe7 fp=0xc1228d76d0 sp=0xc1228d7208 pc=0xf51327 github.com/AlayaNetwork/Alaya-Go/core.(BlockChain).insertChain(0xc00eed6000, 0xc1cad99400, 0x80, 0x80, 0xbcd8102e18590f01, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain.go:1553 +0x678 fp=0xc1228d7858 sp=0xc1228d76d0 pc=0xe179d8 github.com/AlayaNetwork/Alaya-Go/core.(BlockChain).InsertChain(0xc00eed6000, 0xc1cad99400, 0x80, 0x80, 0xc1c89f2d00, 0x75, 0x75) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain.go:1480 +0xc65 fp=0xc1228d7bf0 sp=0xc1228d7858 pc=0xe171e5 github.com/AlayaNetwork/Alaya-Go/eth/downloader.(Downloader).importBlockResults(0xc00e980700, 0xc1cad99000, 0x80, 0x80, 0x80, 0x0) /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:1518 +0x50b fp=0xc1228d7f00 sp=0xc1228d7bf0 pc=0xe7260b github.com/AlayaNetwork/Alaya-Go/eth/downloader.(Downloader).processFullSyncContent(0xc00e980700, 0x2, 0x2cf092) /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:1492 +0x7d fp=0xc1228d7f58 sp=0xc1228d7f00 pc=0xe7207d github.com/AlayaNetwork/Alaya-Go/eth/downloader.(Downloader).processFullSyncContent-fm(0x12, 0x20) /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:1483 +0x33 fp=0xc1228d7f80 sp=0xc1228d7f58 pc=0xe8c6f3 github.com/AlayaNetwork/Alaya-Go/eth/downloader.(Downloader).spawnSync.func1(0xc00e980700, 0xc1220e4000, 0xc1220cc480) /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:775 +0x5b fp=0xc1228d7fc8 sp=0xc1228d7f80 pc=0xe87f9b runtime.goexit() /opt/go/src/runtime/asm_amd64.s:1371 +0x1 fp=0xc1228d7fd0 sp=0xc1228d7fc8 pc=0x4ae2a1 created by github.com/AlayaNetwork/Alaya-Go/eth/downloader.(*Downloader).spawnSync /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:775 +0xb2

goroutine 1 [chan receive]: github.com/AlayaNetwork/Alaya-Go/node.(Node).Wait(0xc000100c00) /opt/jenkins/workspace/Alaya/build_ubuntu/node/node.go:545 +0x7c main.platon(0xc0003f8160, 0xc0003f8160, 0xc00048fbd7) /opt/jenkins/workspace/Alaya/build_ubuntu/cmd/alaya/main.go:299 +0xf3 gopkg.in/urfave/cli%2ev1.HandleAction(0x1322700, 0x1571128, 0xc0003f8160, 0xc000111440, 0x0) /opt/jenkins/go/pkg/mod/gopkg.in/urfave/cli.v1@v1.20.0/app.go:490 +0x82 gopkg.in/urfave/cli%2ev1.(App).Run(0xc000164000, 0xc000032240, 0x22, 0x24, 0x0, 0x0) /opt/jenkins/go/pkg/mod/gopkg.in/urfave/cli.v1@v1.20.0/app.go:264 +0x5f5 main.main() /opt/jenkins/workspace/Alaya/build_ubuntu/cmd/alaya/main.go:284 +0x55

goroutine 24 [chan receive, 1 minutes]: github.com/rjeczalik/notify.(inotify).send(0xc000280000, 0xc00008a120) /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/watcher_inotify.go:254 +0xab created by github.com/rjeczalik/notify.(inotify).lazyinit /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/watcher_inotify.go:137 +0x19a

goroutine 20 [chan receive, 1 minutes]: github.com/rjeczalik/notify.(*nonrecursiveTree).internal(0xc0001103c0, 0xc000110360) /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/tree_nonrecursive.go:81 +0x58 created by github.com/rjeczalik/notify.newNonrecursiveTree /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/tree_nonrecursive.go:30 +0x111

goroutine 8 [chan receive]: github.com/panjf2000/ants/v2.(*Pool).periodicallyPurge(0xc0001a3340) /opt/jenkins/go/pkg/mod/github.com/panjf2000/ants/v2@v2.4.1/pool.go:69 +0xe6 created by github.com/panjf2000/ants/v2.NewPool /opt/jenkins/go/pkg/mod/github.com/panjf2000/ants/v2@v2.4.1/pool.go:136 +0x232

goroutine 19 [chan receive, 1 minutes]: github.com/rjeczalik/notify.(*nonrecursiveTree).dispatch(0xc0001103c0, 0xc000110300) /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/tree_nonrecursive.go:36 +0xb6 created by github.com/rjeczalik/notify.newNonrecursiveTree /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/tree_nonrecursive.go:29 +0xe5

goroutine 25 [chan receive, 1 minutes]: github.com/rjeczalik/notify.(inotify).send(0xc000280000, 0xc00008a120) /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/watcher_inotify.go:254 +0xab created by github.com/rjeczalik/notify.(inotify).lazyinit /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/watcher_inotify.go:137 +0x19a

goroutine 35 [chan receive, 1 minutes]: github.com/AlayaNetwork/Alaya-Go/core.(*txSenderCacher).cache(0xc00022e258) /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_cacher.go:73 +0xbb created by github.com/AlayaNetwork/Alaya-Go/core.NewTxSenderCacher /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_cacher.go:60 +0x9f

goroutine 36 [chan receive, 1 minutes]: github.com/AlayaNetwork/Alaya-Go/core.(*txSenderCacher).cache(0xc00022e258) /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_cacher.go:73 +0xbb created by github.com/AlayaNetwork/Alaya-Go/core.NewTxSenderCacher /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_cacher.go:60 +0x9f

goroutine 23 [syscall, 1 minutes]: syscall.Syscall6(0xe8, 0xd, 0xc00047cf9c, 0x1, 0xffffffffffffffff, 0x0, 0x0, 0x0, 0x0, 0x0) /opt/go/src/syscall/asm_linux_amd64.s:43 +0x5 golang.org/x/sys/unix.EpollWait(0xd, 0xc00047cf9c, 0x1, 0x1, 0xffffffffffffffff, 0x0, 0x0, 0x0) /opt/jenkins/go/pkg/mod/golang.org/x/sys@v0.0.0-20210630005230-0f9fa26af87c/unix/zsyscall_linux_amd64.go:77 +0x72 github.com/rjeczalik/notify.(inotify).loop(0xc000280000, 0xc00008a120) /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/watcher_inotify.go:194 +0x79 created by github.com/rjeczalik/notify.(inotify).lazyinit /opt/jenkins/go/pkg/mod/github.com/rjeczalik/notify@v0.9.1/watcher_inotify.go:134 +0x145

goroutine 41 [select, 1 minutes]: github.com/AlayaNetwork/Alaya-Go/accounts/keystore.(watcher).loop(0xc00013da20) /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/keystore/watch.go:94 +0x3aa created by github.com/AlayaNetwork/Alaya-Go/accounts/keystore.(watcher).start /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/keystore/watch.go:52 +0x59

goroutine 42 [select]: github.com/AlayaNetwork/Alaya-Go/accounts/keystore.(KeyStore).updater(0xc0001120f0) /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/keystore/keystore.go:205 +0x9f created by github.com/AlayaNetwork/Alaya-Go/accounts/keystore.(KeyStore).Subscribe /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/keystore/keystore.go:192 +0x12f

goroutine 43 [sleep]: time.Sleep(0x3b9aca00) /opt/go/src/runtime/time.go:193 +0xd2 github.com/AlayaNetwork/Alaya-Go/accounts/usbwallet.(Hub).updater(0xc00015b040) /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/usbwallet/hub.go:226 +0x3e created by github.com/AlayaNetwork/Alaya-Go/accounts/usbwallet.(Hub).Subscribe /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/usbwallet/hub.go:215 +0x12f

goroutine 44 [sleep]: time.Sleep(0x3b9aca00) /opt/go/src/runtime/time.go:193 +0xd2 github.com/AlayaNetwork/Alaya-Go/accounts/usbwallet.(Hub).updater(0xc00015b180) /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/usbwallet/hub.go:226 +0x3e created by github.com/AlayaNetwork/Alaya-Go/accounts/usbwallet.(Hub).Subscribe /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/usbwallet/hub.go:215 +0x12f

goroutine 45 [select, 1 minutes]: github.com/AlayaNetwork/Alaya-Go/accounts.(*Manager).update(0xc0001667e0) /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/manager.go:110 +0x185 created by github.com/AlayaNetwork/Alaya-Go/accounts.NewManager /opt/jenkins/workspace/Alaya/build_ubuntu/accounts/manager.go:78 +0x4e5

goroutine 46 [select]: github.com/syndtr/goleveldb/leveldb/util.(*BufferPool).drain(0xc000166b60) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:206 +0x105 created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:237 +0x18c

goroutine 47 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*session).refLoop(0xc0001121e0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session_util.go:189 +0x5b2 created by github.com/syndtr/goleveldb/leveldb.newSession /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session.go:93 +0x2ca

goroutine 26 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc0002defc0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:91 +0xb1 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:148 +0x429

goroutine 27 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc0002defc0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_state.go:101 +0xd7 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:149 +0x44b

goroutine 28 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc0002defc0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:836 +0x228 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:155 +0x5a5

goroutine 29 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*DB).mCompaction(0xc0002defc0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:773 +0x125 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:156 +0x5c7

goroutine 30 [select]: github.com/AlayaNetwork/Alaya-Go/ethdb/leveldb.(*Database).meter(0xc0002e7100, 0xb2d05e00) /opt/jenkins/workspace/Alaya/build_ubuntu/ethdb/leveldb/leveldb.go:441 +0x194f created by github.com/AlayaNetwork/Alaya-Go/ethdb/leveldb.New /opt/jenkins/workspace/Alaya/build_ubuntu/ethdb/leveldb/leveldb.go:136 +0xa38

goroutine 48 [select]: github.com/AlayaNetwork/Alaya-Go/core/rawdb.(*freezer).freeze(0xc00189a990, 0x1721cc8, 0xc0002e7100) /opt/jenkins/workspace/Alaya/build_ubuntu/core/rawdb/freezer.go:266 +0x1a37 created by github.com/AlayaNetwork/Alaya-Go/core/rawdb.NewDatabaseWithFreezer /opt/jenkins/workspace/Alaya/build_ubuntu/core/rawdb/database.go:179 +0x27b

goroutine 57 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc00e980c40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:91 +0xb1 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:148 +0x429

goroutine 55 [select]: github.com/syndtr/goleveldb/leveldb/util.(*BufferPool).drain(0xc000cc1a40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:206 +0x105 created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:237 +0x18c

goroutine 59 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc00e980c40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:836 +0x228 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:155 +0x5a5

goroutine 97 [select]: github.com/AlayaNetwork/Alaya-Go/consensus/cbft.(Cbft).receiveLoop(0xc000228600) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/cbft.go:524 +0x214 created by github.com/AlayaNetwork/Alaya-Go/consensus/cbft.(Cbft).Start /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/cbft.go:297 +0x7c6

goroutine 56 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*session).refLoop(0xc00e9ae5a0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session_util.go:189 +0x5b2 created by github.com/syndtr/goleveldb/leveldb.newSession /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session.go:93 +0x2ca

goroutine 58 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc00e980c40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_state.go:101 +0xd7 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:149 +0x44b

goroutine 60 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*DB).mCompaction(0xc00e980c40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:773 +0x125 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:156 +0x5c7

goroutine 61 [select]: github.com/AlayaNetwork/Alaya-Go/core.(*ChainIndexer).updateLoop(0xc00e9ae690) /opt/jenkins/workspace/Alaya/build_ubuntu/core/chain_indexer.go:310 +0xdb created by github.com/AlayaNetwork/Alaya-Go/core.NewChainIndexer /opt/jenkins/workspace/Alaya/build_ubuntu/core/chain_indexer.go:117 +0x24d

goroutine 62 [select, 1 minutes]: github.com/AlayaNetwork/Alaya-Go/core.(*Cleaner).loop(0xc0000a7000) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain_clean.go:147 +0xc8 created by github.com/AlayaNetwork/Alaya-Go/core.NewCleaner /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain_clean.go:114 +0x247

goroutine 63 [select]: github.com/AlayaNetwork/Alaya-Go/core.(*BlockChain).update(0xc00eed6000) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain.go:1745 +0xde created by github.com/AlayaNetwork/Alaya-Go/core.NewBlockChain /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain.go:335 +0x88e

goroutine 64 [select]: github.com/AlayaNetwork/Alaya-Go/core.(ChainIndexer).eventLoop(0xc00e9ae690, 0xc0001d0f00, 0xc00eb0f080, 0x1705220, 0xc000cef950) /opt/jenkins/workspace/Alaya/build_ubuntu/core/chain_indexer.go:208 +0x265 created by github.com/AlayaNetwork/Alaya-Go/core.(ChainIndexer).Start /opt/jenkins/workspace/Alaya/build_ubuntu/core/chain_indexer.go:149 +0xcf

goroutine 65 [select]: github.com/AlayaNetwork/Alaya-Go/core.(*TxPool).scheduleReorgLoop(0xc0002f5d40) /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_pool.go:1205 +0x1f9 created by github.com/AlayaNetwork/Alaya-Go/core.NewTxPool /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_pool.go:357 +0x76a

goroutine 66 [select]: github.com/AlayaNetwork/Alaya-Go/core.(*TxPool).loop(0xc0002f5d40) /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_pool.go:400 +0x1fb created by github.com/AlayaNetwork/Alaya-Go/core.NewTxPool /opt/jenkins/workspace/Alaya/build_ubuntu/core/tx_pool.go:373 +0x7b3

goroutine 67 [select]: github.com/syndtr/goleveldb/leveldb/util.(*BufferPool).drain(0xc00eeee8c0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:206 +0x105 created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:237 +0x18c

goroutine 68 [select]: github.com/syndtr/goleveldb/leveldb.(*session).refLoop(0xc00eeeb950) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session_util.go:189 +0x5b2 created by github.com/syndtr/goleveldb/leveldb.newSession /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session.go:93 +0x2ca

goroutine 69 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc00e981a40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:91 +0xb1 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:148 +0x429

goroutine 70 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc00e981a40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_state.go:101 +0xd7 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:149 +0x44b

goroutine 71 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc00e981a40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:836 +0x228 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:155 +0x5a5

goroutine 72 [select, 1 minutes]: github.com/syndtr/goleveldb/leveldb.(*DB).mCompaction(0xc00e981a40) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:773 +0x125 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:156 +0x5c7

goroutine 82 [select]: github.com/AlayaNetwork/Alaya-Go/core/snapshotdb.(snapshotDB).loopWriteWal(0xc0003ef400) /opt/jenkins/workspace/Alaya/build_ubuntu/core/snapshotdb/journal.go:50 +0xaf created by github.com/AlayaNetwork/Alaya-Go/core/snapshotdb.(snapshotDB).Start /opt/jenkins/workspace/Alaya/build_ubuntu/core/snapshotdb/snapshotdb.go:313 +0x73

goroutine 33 [select]: github.com/robfig/cron.(Cron).run(0xc00060f860) /opt/jenkins/go/pkg/mod/github.com/robfig/cron@v1.2.1-0.20190616124356-61d93e07d1be/cron.go:196 +0x28f created by github.com/robfig/cron.(Cron).Start /opt/jenkins/go/pkg/mod/github.com/robfig/cron@v1.2.1-0.20190616124356-61d93e07d1be/cron.go:149 +0x53

goroutine 77 [select]: github.com/AlayaNetwork/Alaya-Go/consensus/cbft/network.(EngineManager).sendLoop(0xc00060ff40) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/network/handler.go:131 +0x86 created by github.com/AlayaNetwork/Alaya-Go/consensus/cbft/network.(EngineManager).Start /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/network/handler.go:117 +0x3f

goroutine 78 [select]: github.com/AlayaNetwork/Alaya-Go/consensus/cbft/network.(EngineManager).synchronize(0xc00060ff40) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/network/handler.go:706 +0x1c9 created by github.com/AlayaNetwork/Alaya-Go/consensus/cbft/network.(EngineManager).Start /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/network/handler.go:118 +0x65

goroutine 83 [select, 1 minutes]: github.com/AlayaNetwork/Alaya-Go/miner.(*worker).mainLoop(0xc014643600) /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:522 +0x21c created by github.com/AlayaNetwork/Alaya-Go/miner.newWorker /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:259 +0x856

goroutine 84 [select]: github.com/AlayaNetwork/Alaya-Go/miner.(*worker).newWorkLoop(0xc014643600, 0x12309ce54000) /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:406 +0x305 created by github.com/AlayaNetwork/Alaya-Go/miner.newWorker /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:260 +0x885

goroutine 85 [select]: github.com/AlayaNetwork/Alaya-Go/miner.(*worker).resultLoop(0xc014643600) /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:639 +0xbc created by github.com/AlayaNetwork/Alaya-Go/miner.newWorker /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:261 +0x8a7

goroutine 86 [select, 1 minutes]: github.com/AlayaNetwork/Alaya-Go/miner.(*worker).taskLoop(0xc014643600) /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:586 +0xd6 created by github.com/AlayaNetwork/Alaya-Go/miner.newWorker /opt/jenkins/workspace/Alaya/build_ubuntu/miner/worker.go:262 +0x8c9

goroutine 87 [select]: github.com/AlayaNetwork/Alaya-Go/miner.(*Miner).update(0xc01586fa40) /opt/jenkins/workspace/Alaya/build_ubuntu/miner/miner.go:88 +0x1e9 created by github.com/AlayaNetwork/Alaya-Go/miner.New /opt/jenkins/workspace/Alaya/build_ubuntu/miner/miner.go:74 +0x185

goroutine 88 [chan receive]: github.com/panjf2000/ants/v2.(*PoolWithFunc).periodicallyPurge(0xc0159ba380) /opt/jenkins/go/pkg/mod/github.com/panjf2000/ants/v2@v2.4.1/pool_func.go:72 +0x2b6 created by github.com/panjf2000/ants/v2.NewPoolWithFunc /opt/jenkins/go/pkg/mod/github.com/panjf2000/ants/v2@v2.4.1/pool_func.go:151 +0x1b2

goroutine 89 [select]: github.com/AlayaNetwork/Alaya-Go/core.(BlockChainReactor).loop(0xc000169bc0) /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain_reactor.go:112 +0xae created by github.com/AlayaNetwork/Alaya-Go/core.(BlockChainReactor).Start /opt/jenkins/workspace/Alaya/build_ubuntu/core/blockchain_reactor.go:84 +0x10a

goroutine 90 [select]: github.com/AlayaNetwork/Alaya-Go/consensus/cbft/executor.(*AsyncExecutor).loop(0xc0159c8f00) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/executor/executor.go:115 +0xdb created by github.com/AlayaNetwork/Alaya-Go/consensus/cbft/executor.NewAsyncExecutor /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/executor/executor.go:80 +0xf3

goroutine 91 [chan receive]: github.com/AlayaNetwork/Alaya-Go/consensus/cbft/network.(*PeerSet).printPeers(0xc0159c93e0) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/network/peer.go:590 +0x23b created by github.com/AlayaNetwork/Alaya-Go/consensus/cbft/network.NewPeerSet /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/network/peer.go:418 +0x77

goroutine 79 [select]: github.com/AlayaNetwork/Alaya-Go/consensus/cbft/wal.(*journal).mainLoop(0xc0149c28c0, 0x12a05f200) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/wal/wal_journal.go:157 +0xc5 created by github.com/AlayaNetwork/Alaya-Go/consensus/cbft/wal.newJournal /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/wal/wal_journal.go:147 +0x135

goroutine 80 [select]: github.com/syndtr/goleveldb/leveldb/util.(*BufferPool).drain(0xc0149ca9a0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:206 +0x105 created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:237 +0x18c

goroutine 81 [select]: github.com/syndtr/goleveldb/leveldb.(*session).refLoop(0xc0149d84b0) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session_util.go:189 +0x5b2 created by github.com/syndtr/goleveldb/leveldb.newSession /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/session.go:93 +0x2ca

goroutine 98 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc0002df880) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:91 +0xb1 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:148 +0x429

goroutine 99 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc0002df880) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_state.go:101 +0xd7 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:149 +0x44b

goroutine 100 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc0002df880) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:836 +0x228 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:155 +0x5a5

goroutine 101 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).mCompaction(0xc0002df880) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:773 +0x125 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:156 +0x5c7

goroutine 114 [select]: github.com/AlayaNetwork/Alaya-Go/consensus/cbft/fetcher.(Fetcher).loop(0xc00002cd60) /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/fetcher/fetcher.go:120 +0x1fe created by github.com/AlayaNetwork/Alaya-Go/consensus/cbft/fetcher.(Fetcher).Start /opt/jenkins/workspace/Alaya/build_ubuntu/consensus/cbft/fetcher/fetcher.go:78 +0x3f

goroutine 115 [select]: github.com/AlayaNetwork/Alaya-Go/eth/downloader.(*Downloader).qosTuner(0xc00e980700) /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:1770 +0x34b created by github.com/AlayaNetwork/Alaya-Go/eth/downloader.New /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:251 +0x4da

goroutine 116 [select]: github.com/AlayaNetwork/Alaya-Go/eth/downloader.(*Downloader).stateFetcher(0xc00e980700) /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/statesync.go:78 +0xae created by github.com/AlayaNetwork/Alaya-Go/eth/downloader.New /opt/jenkins/workspace/Alaya/build_ubuntu/eth/downloader/downloader.go:252 +0x4fc

goroutine 117 [select]: github.com/AlayaNetwork/Alaya-Go/p2p/nat.Map(0x1713738, 0xc0001dd500, 0xc121b7a6c0, 0x14f88b4, 0x3, 0x4195, 0x4195, 0x1511839, 0x12) /opt/jenkins/workspace/Alaya/build_ubuntu/p2p/nat/nat.go:114 +0x42a created by github.com/AlayaNetwork/Alaya-Go/p2p.(*Server).Start /opt/jenkins/workspace/Alaya/build_ubuntu/p2p/server.go:518 +0x1045

goroutine 105 [select]: github.com/syndtr/goleveldb/leveldb/util.(*BufferPool).drain(0xc015a17500) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:206 +0x105 created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/util/buffer_pool.go:237 +0x18c

goroutine 123 [select]: github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc00e980000) /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db_compaction.go:91 +0xb1 created by github.com/syndtr/goleveldb/leveldb.openDB /opt/jenkins/go/pkg/mod/github.com/syndtr/goleveldb@v1.0.1-0.20190923125748-758128399b1d/leveldb/db.go:148 +0x429

goroutine 120 [IO wait]: internal/poll.runtime_pollWait(0x7f4754e47dd0, 0x72, 0x0) /opt/go/src/runtime/netpoll.go:222 +0x55 internal/poll.(pollDesc).wait(0xc0159bba98, 0x72, 0x0, 0x10, 0x0) /opt/go/src/internal/poll/fd_poll_runtime.go:87 +0x45 internal/poll.(pollDesc).waitRead(...) /opt/go/src/internal/poll/fd_poll_runtime.go:92

[benbaley]

请尝试增加swap分区或升级主机内存重试 受制于Go本身内存回收机制，在链上数据很多的情况下同步可能会出现内存吃紧或OOM现象，为保证节点正常运行，建议使用16G以上的主机运行