Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

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

Open
AndreaHan opened this issue Dec 17, 2021 · 1 comment
Open
Assignees
Labels
bug Something isn't working

Comments

@AndreaHan
Copy link

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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/consensus_process.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/[email protected]/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/[email protected]/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/[email protected]/watcher_inotify.go:254 +0xab
created by github.com/rjeczalik/notify.(*inotify).lazyinit
/opt/jenkins/go/pkg/mod/github.com/rjeczalik/[email protected]/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/[email protected]/tree_nonrecursive.go:81 +0x58
created by github.com/rjeczalik/notify.newNonrecursiveTree
/opt/jenkins/go/pkg/mod/github.com/rjeczalik/[email protected]/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/[email protected]/pool.go:69 +0xe6
created by github.com/panjf2000/ants/v2.NewPool
/opt/jenkins/go/pkg/mod/github.com/panjf2000/ants/[email protected]/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/[email protected]/tree_nonrecursive.go:36 +0xb6
created by github.com/rjeczalik/notify.newNonrecursiveTree
/opt/jenkins/go/pkg/mod/github.com/rjeczalik/[email protected]/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/[email protected]/watcher_inotify.go:254 +0xab
created by github.com/rjeczalik/notify.(*inotify).lazyinit
/opt/jenkins/go/pkg/mod/github.com/rjeczalik/[email protected]/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/[email protected]/unix/zsyscall_linux_amd64.go:77 +0x72
github.com/rjeczalik/notify.(*inotify).loop(0xc000280000, 0xc00008a120)
/opt/jenkins/go/pkg/mod/github.com/rjeczalik/[email protected]/watcher_inotify.go:194 +0x79
created by github.com/rjeczalik/notify.(*inotify).lazyinit
/opt/jenkins/go/pkg/mod/github.com/rjeczalik/[email protected]/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/[email protected]/leveldb/util/buffer_pool.go:206 +0x105
created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/session_util.go:189 +0x5b2
created by github.com/syndtr/goleveldb/leveldb.newSession
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:91 +0xb1
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:148 +0x429

goroutine 27 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc0002defc0)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_state.go:101 +0xd7
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:836 +0x228
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:773 +0x125
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:91 +0xb1
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/util/buffer_pool.go:206 +0x105
created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:836 +0x228
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/session_util.go:189 +0x5b2
created by github.com/syndtr/goleveldb/leveldb.newSession
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/session.go:93 +0x2ca

goroutine 58 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc00e980c40)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_state.go:101 +0xd7
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:773 +0x125
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/util/buffer_pool.go:206 +0x105
created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/session_util.go:189 +0x5b2
created by github.com/syndtr/goleveldb/leveldb.newSession
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/session.go:93 +0x2ca

goroutine 69 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc00e981a40)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:91 +0xb1
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:148 +0x429

goroutine 70 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc00e981a40)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_state.go:101 +0xd7
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:149 +0x44b

goroutine 71 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc00e981a40)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:836 +0x228
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:773 +0x125
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/cron.go:196 +0x28f
created by github.com/robfig/cron.(*Cron).Start
/opt/jenkins/go/pkg/mod/github.com/robfig/[email protected]/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/[email protected]/pool_func.go:72 +0x2b6
created by github.com/panjf2000/ants/v2.NewPoolWithFunc
/opt/jenkins/go/pkg/mod/github.com/panjf2000/ants/[email protected]/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/[email protected]/leveldb/util/buffer_pool.go:206 +0x105
created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/session_util.go:189 +0x5b2
created by github.com/syndtr/goleveldb/leveldb.newSession
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/session.go:93 +0x2ca

goroutine 98 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).compactionError(0xc0002df880)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:91 +0xb1
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:148 +0x429

goroutine 99 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).mpoolDrain(0xc0002df880)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_state.go:101 +0xd7
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:149 +0x44b

goroutine 100 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).tCompaction(0xc0002df880)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:836 +0x228
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db.go:155 +0x5a5

goroutine 101 [select]:
github.com/syndtr/goleveldb/leveldb.(*DB).mCompaction(0xc0002df880)
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/leveldb/db_compaction.go:773 +0x125
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/util/buffer_pool.go:206 +0x105
created by github.com/syndtr/goleveldb/leveldb/util.NewBufferPool
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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/[email protected]/leveldb/db_compaction.go:91 +0xb1
created by github.com/syndtr/goleveldb/leveldb.openDB
/opt/jenkins/go/pkg/mod/github.com/syndtr/[email protected]/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

@AndreaHan AndreaHan added the bug Something isn't working label Dec 17, 2021
@benbaley
Copy link
Collaborator

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

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

3 participants