节点运行过程中出现BAD BLOCK

[WeiLoy]

环境：私链
现象：
节点正常同步区块、执行区块中，执行完5650799块高了，之后出现了很多PposStorageMsg消息，然后又接着从5650754开始执行区块了，执行到5650794区块，直接就执行5650800区块，结果导致BAD BLOCK

部分日志：

INFO [05-19|03:26:35.858|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650789 hash=6bb78a…941654
DEBUG[05-19|03:26:35.858|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.858|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:cc7bf9…807d48,Number:5650791,Index:0,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
INFO [05-19|03:26:35.858|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650792 hash=e674b2…922fc6 time="2021-05-19 03:26:35.858"
DEBUG[05-19|03:26:35.858|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=6bb78a…941654 number=5650789
WARN [05-19|03:26:35.858|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=6bb78a…941654 number=5650789
DEBUG[05-19|03:26:35.858|core/blockchain_cache.go:289] Start execute block hash=e674b2…922fc6 number=5650792 sealHash=905aaf…2c776a
INFO [05-19|03:26:35.858|core/blockchain_cache.go:212] Make stateDB hash=cc7bf9…807d48 number=5650791 root=c8cdae…a26556
DEBUG[05-19|03:26:35.858|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=be5a9d…bde7a7
INFO [05-19|03:26:35.858|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650789 blockHash=6bb78a…941654
DEBUG[05-19|03:26:35.859|core/snapshotdb/current.go:161] increase current highest package=snapshotdb hash=6bb78a…941654 num=5650789
INFO [05-19|03:26:35.859|core/snapshotdb/snapshotdb.go:769] commit block package=snapshotdb num=5650789 hash=0x6bb78ad9e2c8852a529a32f2118a2e5d03baffcb38792ccedd454fd7b8941654
DEBUG[05-19|03:26:35.859|core/snapshotdb/current.go:182] save current to baseDB package=snapshotdb height=5650789 hash=6bb78a…941654 base= type=snapshotdbCurrentHighestBlock
DEBUG[05-19|03:26:35.859|x/handler/vrf_handler.go:98] Verification block vrf prove current blockNumber=5650792 current hash=e674b2…922fc6 parentHash=cc7bf9…807d48 proof=039a38ae22324a458d7db4535ee22b5fd29d92b933aeca61648018455b47431814bd668ddf13a03ebc93757e8d03f246a9b9507501494a44d07a5b308bdc3a460d60f75e03e194eb007cf2d22f2d5b0bce
DEBUG[05-19|03:26:35.859|x/handler/vrf_handler.go:186] Get the nonce of the previous block blockNumber=5650792 parentHash=cc7bf917efa717079e7fdca4ace2eb589d9c9f47b5b887acb7dbd97b75807d48
INFO [05-19|03:26:35.859|x/handler/vrf_handler.go:116] Vrf proves successful verification current blockNumber=5650792 current hash=e674b2…922fc6 parentHash=cc7bf9…807d48 proof=039a38ae22324a458d7db4535ee22b5fd29d92b933aeca61648018455b47431814bd668ddf13a03ebc93757e8d03f246a9b9507501494a44d07a5b308bdc3a460d60f75e03e194eb007cf2d22f2d5b0bce input=87241a7eb9254b7d419c0cc24fb34354a1b12b024f0532f9e62bf637eade21bc
DEBUG[05-19|03:26:35.860|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=5650792 hash=e674b2…922fc6 parentHash=cc7bf9…807d48
INFO [05-19|03:26:35.860|core/snapshotdb/snapshotdb.go:555] NewBlock package=snapshotdb num=5650792 hash=e674b2…922fc6 parent=cc7bf9…807d48
DEBUG[05-19|03:26:35.860|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=5650792 blockHash=e674b2…922fc6 nodeId=081250e25b846948 value=2
DEBUG[05-19|03:26:35.860|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=e674b2…922fc6 blockNumber=5650792 root=c8cdae…a26556 pointer=0xc00514c140
DEBUG[05-19|03:26:35.860|x/handler/vrf_handler.go:123] Storage previous nonce blockNumber=5650792 parentHash=cc7bf9…807d48 current hash=e674b2…922fc6 nonce=039a38ae22324a458d7db4535ee22b5fd29d92b933aeca61648018455b47431814bd668ddf13a03ebc93757e8d03f246a9b9507501494a44d07a5b308bdc3a460d60f75e03e194eb007cf2d22f2d5b0bce
DEBUG[05-19|03:26:35.860|x/handler/vrf_handler.go:139] Storage previous nonce current blockNumber=5650792 parentHash=cc7bf9…807d48 current hash=e674b2…922fc6 valueLength=201 MaxValidators=201
INFO [05-19|03:26:35.860|x/handler/vrf_handler.go:162] Storage previous nonce Success current blockNumber=5650792 parentHash=cc7bf9…807d48 current hash=e674b2…922fc6 valueLength=201 MaxValidators=201 nonce=039a38ae22324a458d7db4535ee22b5fd29d92b933aeca61648018455b47431814bd668ddf13a03ebc93757e8d03f246a9b9507501494a44d07a5b308bdc3a460d60f75e03e194eb007cf2d22f2d5b0bce firstNonce=97540ea64a4869e9b7714d61b42f9e244f971e81c339d874e9e69db315feacb6 lastNonce=9a38ae22324a458d7db4535ee22b5fd29d92b933aeca61648018455b47431814
DEBUG[05-19|03:26:35.860|x/plugin/reward_plugin.go:469] allocate package reward, delegate reward blockNumber=5650792 blockHash=e674b2…922fc6 delegateReward=2646833600466268821 epochDelegateReward=852280419350138560362
DEBUG[05-19|03:26:35.860|x/plugin/reward_plugin.go:480] allocate package reward,block reward blockNumber=5650792 blockHash=e674b2…922fc6 coinBase=lat1x3xagmwac3mpry5jnewktm44jxxqkqw0d5qgju reward=1764555733644179215
DEBUG[05-19|03:26:35.860|core/blockchain_reactor.go:322] Store ppos hash blockHash=e674b2…922fc6 blockNumber=5650792 pposHash=397bbe7ca01fe9b680178b44495973b2d9fa655686c39178934144b083b3acac
DEBUG[05-19|03:26:35.860|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=e674b2…922fc6 blockNumber=5650792 root=f2961d…91f555 pointer=0xc00514c140
DEBUG[05-19|03:26:35.860|core/parallel_state_processor.go:86] Process end blocker cost time blockNumber=5650792 blockHash=e674b2…922fc6
DEBUG[05-19|03:26:35.860|core/blockchain.go:1233] Execute block time blockNumber=5650792 blockHash=e674b2…922fc6 time=1.731382ms
DEBUG[05-19|03:26:35.860|core/blockchain_cache.go:300] Execute block number=5650792 hash=e674b2…922fc6 parentNumber=5650791 parentHash=cc7bf9…807d48 duration=1.756081ms makeState=42.737µs err=nil
INFO [05-19|03:26:35.860|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=905aaf…2c776a blockNum=5650792
INFO [05-19|03:26:35.862|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:e674b2…922fc6,Number:5650792,Index:1,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.862|core/tx_pool.go:462] call Reset() hash=e674b2…922fc6 number=5650792 parentHash=cc7bf9…807d48
DEBUG[05-19|03:26:35.862|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=905aaf…2c776a
DEBUG[05-19|03:26:35.862|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650791 oldHash=cc7bf9…807d48 newNumber=5650792 newHash=e674b2…922fc6 count=0 elapsed=8.923µs
DEBUG[05-19|03:26:35.862|core/tx_pool.go:472] call Reset elapse time hash=e674b2…922fc6 number=5650792 parentHash=cc7bf9…807d48 elapseTime=177.392µs
INFO [05-19|03:26:35.862|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650790 hash=26b3f6…05fb99
DEBUG[05-19|03:26:35.862|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.862|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:e674b2…922fc6,Number:5650792,Index:1,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
INFO [05-19|03:26:35.862|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650793 hash=964d18…bf920c time="2021-05-19 03:26:35.862"
DEBUG[05-19|03:26:35.862|core/blockchain_cache.go:289] Start execute block hash=964d18…bf920c number=5650793 sealHash=6c39f1…e7c0ff
INFO [05-19|03:26:35.862|core/blockchain_cache.go:212] Make stateDB hash=e674b2…922fc6 number=5650792 root=f2961d…91f555
DEBUG[05-19|03:26:35.862|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=905aaf…2c776a
DEBUG[05-19|03:26:35.862|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=26b3f6…05fb99 number=5650790
WARN [05-19|03:26:35.862|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=26b3f6…05fb99 number=5650790
INFO [05-19|03:26:35.862|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650790 blockHash=26b3f6…05fb99
DEBUG[05-19|03:26:35.862|core/snapshotdb/current.go:161] increase current highest package=snapshotdb hash=26b3f6…05fb99 num=5650790
INFO [05-19|03:26:35.862|core/snapshotdb/snapshotdb.go:769] commit block package=snapshotdb num=5650790 hash=0x26b3f6789cf88864bf1930b47bef518086b2f94cf3141304f891be980705fb99
DEBUG[05-19|03:26:35.863|core/snapshotdb/current.go:182] save current to baseDB package=snapshotdb height=5650790 hash=26b3f6…05fb99 base= type=snapshotdbCurrentHighestBlock
DEBUG[05-19|03:26:35.863|x/handler/vrf_handler.go:98] Verification block vrf prove current blockNumber=5650793 current hash=964d18…bf920c parentHash=e674b2…922fc6 proof=022add33628fd117e79eb07fd189e944cc399abd2cca43fe88633d68f7e1b8de11f08ba28d25a7ee7527b25935500aee1f4805596ea945e71e0ad30811570b40f8f3417a94161104b735862b7895f635bc
DEBUG[05-19|03:26:35.863|x/handler/vrf_handler.go:186] Get the nonce of the previous block blockNumber=5650793 parentHash=e674b2daa947abc9e36a536423fd2a6ad487bb10644a0f062ef308fb08922fc6
INFO [05-19|03:26:35.863|x/handler/vrf_handler.go:116] Vrf proves successful verification current blockNumber=5650793 current hash=964d18…bf920c parentHash=e674b2…922fc6 proof=022add33628fd117e79eb07fd189e944cc399abd2cca43fe88633d68f7e1b8de11f08ba28d25a7ee7527b25935500aee1f4805596ea945e71e0ad30811570b40f8f3417a94161104b735862b7895f635bc input=9a38ae22324a458d7db4535ee22b5fd29d92b933aeca61648018455b47431814
DEBUG[05-19|03:26:35.863|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=5650793 hash=964d18…bf920c parentHash=e674b2…922fc6
INFO [05-19|03:26:35.863|core/snapshotdb/snapshotdb.go:555] NewBlock package=snapshotdb num=5650793 hash=964d18…bf920c parent=e674b2…922fc6
DEBUG[05-19|03:26:35.863|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=5650793 blockHash=964d18…bf920c nodeId=081250e25b846948 value=3
DEBUG[05-19|03:26:35.863|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=964d18…bf920c blockNumber=5650793 root=f2961d…91f555 pointer=0xc0051a6500
DEBUG[05-19|03:26:35.863|x/handler/vrf_handler.go:123] Storage previous nonce blockNumber=5650793 parentHash=e674b2…922fc6 current hash=964d18…bf920c nonce=022add33628fd117e79eb07fd189e944cc399abd2cca43fe88633d68f7e1b8de11f08ba28d25a7ee7527b25935500aee1f4805596ea945e71e0ad30811570b40f8f3417a94161104b735862b7895f635bc
DEBUG[05-19|03:26:35.864|x/handler/vrf_handler.go:139] Storage previous nonce current blockNumber=5650793 parentHash=e674b2…922fc6 current hash=964d18…bf920c valueLength=201 MaxValidators=201
INFO [05-19|03:26:35.864|x/handler/vrf_handler.go:162] Storage previous nonce Success current blockNumber=5650793 parentHash=e674b2…922fc6 current hash=964d18…bf920c valueLength=201 MaxValidators=201 nonce=022add33628fd117e79eb07fd189e944cc399abd2cca43fe88633d68f7e1b8de11f08ba28d25a7ee7527b25935500aee1f4805596ea945e71e0ad30811570b40f8f3417a94161104b735862b7895f635bc firstNonce=fd686295fbbbd073ad3a54322d272afca789ce8d0b4768665789fde87f89b8e2 lastNonce=2add33628fd117e79eb07fd189e944cc399abd2cca43fe88633d68f7e1b8de11
DEBUG[05-19|03:26:35.864|x/plugin/reward_plugin.go:469] allocate package reward, delegate reward blockNumber=5650793 blockHash=964d18…bf920c delegateReward=2646833600466268821 epochDelegateReward=854927252950604829183
DEBUG[05-19|03:26:35.864|x/plugin/reward_plugin.go:480] allocate package reward,block reward blockNumber=5650793 blockHash=964d18…bf920c coinBase=lat1x3xagmwac3mpry5jnewktm44jxxqkqw0d5qgju reward=1764555733644179215
DEBUG[05-19|03:26:35.864|core/blockchain_reactor.go:322] Store ppos hash blockHash=964d18…bf920c blockNumber=5650793 pposHash=52a2a273f61e5e144460d411c996cacd8ac97770bbcb1da79a27813994990f10
DEBUG[05-19|03:26:35.864|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=964d18…bf920c blockNumber=5650793 root=8d3bc9…b3be36 pointer=0xc0051a6500
DEBUG[05-19|03:26:35.864|core/parallel_state_processor.go:86] Process end blocker cost time blockNumber=5650793 blockHash=964d18…bf920c
DEBUG[05-19|03:26:35.864|core/blockchain.go:1233] Execute block time blockNumber=5650793 blockHash=964d18…bf920c time=1.726668ms
DEBUG[05-19|03:26:35.864|core/blockchain_cache.go:300] Execute block number=5650793 hash=964d18…bf920c parentNumber=5650792 parentHash=e674b2…922fc6 duration=1.751261ms makeState=39.729µs err=nil
INFO [05-19|03:26:35.864|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=6c39f1…e7c0ff blockNum=5650793
INFO [05-19|03:26:35.866|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:964d18…bf920c,Number:5650793,Index:2,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.866|core/tx_pool.go:462] call Reset() hash=964d18…bf920c number=5650793 parentHash=e674b2…922fc6
DEBUG[05-19|03:26:35.866|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=6c39f1…e7c0ff
DEBUG[05-19|03:26:35.866|core/tx_pool.go:472] call Reset elapse time hash=964d18…bf920c number=5650793 parentHash=e674b2…922fc6 elapseTime=88.111µs
INFO [05-19|03:26:35.866|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650791 hash=cc7bf9…807d48
DEBUG[05-19|03:26:35.866|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.866|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:964d18…bf920c,Number:5650793,Index:2,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.866|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650792 oldHash=e674b2…922fc6 newNumber=5650793 newHash=964d18…bf920c count=0 elapsed=9.342µs
DEBUG[05-19|03:26:35.866|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=cc7bf9…807d48 number=5650791
WARN [05-19|03:26:35.866|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=cc7bf9…807d48 number=5650791
INFO [05-19|03:26:35.866|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650794 hash=c394f6…de745c time="2021-05-19 03:26:35.866"
INFO [05-19|03:26:35.866|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650791 blockHash=cc7bf9…807d48
DEBUG[05-19|03:26:35.866|core/snapshotdb/current.go:161] increase current highest package=snapshotdb hash=cc7bf9…807d48 num=5650791
INFO [05-19|03:26:35.866|core/snapshotdb/snapshotdb.go:769] commit block package=snapshotdb num=5650791 hash=0xcc7bf917efa717079e7fdca4ace2eb589d9c9f47b5b887acb7dbd97b75807d48
DEBUG[05-19|03:26:35.866|core/blockchain_cache.go:289] Start execute block hash=c394f6…de745c number=5650794 sealHash=4562ca…159a73
DEBUG[05-19|03:26:35.866|core/snapshotdb/current.go:182] save current to baseDB package=snapshotdb height=5650791 hash=cc7bf9…807d48 base= type=snapshotdbCurrentHighestBlock
INFO [05-19|03:26:35.866|core/blockchain_cache.go:212] Make stateDB hash=964d18…bf920c number=5650793 root=8d3bc9…b3be36
DEBUG[05-19|03:26:35.866|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=6c39f1…e7c0ff
DEBUG[05-19|03:26:35.866|x/handler/vrf_handler.go:98] Verification block vrf prove current blockNumber=5650794 current hash=c394f6…de745c parentHash=964d18…bf920c proof=02451f37f0da72f047ebdb739fee69ba766649e1d086eed4c5afa15e0ada2c3b4d368f31b161d05a9e380e0b0f3ee4f6a9c049588bfaf5504a9e2fee15e3a4ed5e6ac14494f354215ad3fd4f672970f931
DEBUG[05-19|03:26:35.866|x/handler/vrf_handler.go:186] Get the nonce of the previous block blockNumber=5650794 parentHash=964d18bdf6c55b49fff0b08c4714f93edfa0d247f4f5fcae3e13003368bf920c
INFO [05-19|03:26:35.867|x/handler/vrf_handler.go:116] Vrf proves successful verification current blockNumber=5650794 current hash=c394f6…de745c parentHash=964d18…bf920c proof=02451f37f0da72f047ebdb739fee69ba766649e1d086eed4c5afa15e0ada2c3b4d368f31b161d05a9e380e0b0f3ee4f6a9c049588bfaf5504a9e2fee15e3a4ed5e6ac14494f354215ad3fd4f672970f931 input=2add33628fd117e79eb07fd189e944cc399abd2cca43fe88633d68f7e1b8de11
DEBUG[05-19|03:26:35.867|core/blockchain_reactor.go:260] Call snapshotDB newBlock on blockchain_reactor blockNumber=5650794 hash=c394f6…de745c parentHash=964d18…bf920c
INFO [05-19|03:26:35.867|core/snapshotdb/snapshotdb.go:555] NewBlock package=snapshotdb num=5650794 hash=c394f6…de745c parent=964d18…bf920c
DEBUG[05-19|03:26:35.867|x/plugin/slashing_plugin.go:464] Call setPackAmount finished blockNumber=5650794 blockHash=c394f6…de745c nodeId=081250e25b846948 value=4
DEBUG[05-19|03:26:35.867|core/blockchain_reactor.go:279] BeginBlock StateDB root, end blockHash=c394f6…de745c blockNumber=5650794 root=8d3bc9…b3be36 pointer=0xc0051eaa00
DEBUG[05-19|03:26:35.867|x/handler/vrf_handler.go:123] Storage previous nonce blockNumber=5650794 parentHash=964d18…bf920c current hash=c394f6…de745c nonce=02451f37f0da72f047ebdb739fee69ba766649e1d086eed4c5afa15e0ada2c3b4d368f31b161d05a9e380e0b0f3ee4f6a9c049588bfaf5504a9e2fee15e3a4ed5e6ac14494f354215ad3fd4f672970f931
DEBUG[05-19|03:26:35.867|x/handler/vrf_handler.go:139] Storage previous nonce current blockNumber=5650794 parentHash=964d18…bf920c current hash=c394f6…de745c valueLength=201 MaxValidators=201
INFO [05-19|03:26:35.867|x/handler/vrf_handler.go:162] Storage previous nonce Success current blockNumber=5650794 parentHash=964d18…bf920c current hash=c394f6…de745c valueLength=201 MaxValidators=201 nonce=02451f37f0da72f047ebdb739fee69ba766649e1d086eed4c5afa15e0ada2c3b4d368f31b161d05a9e380e0b0f3ee4f6a9c049588bfaf5504a9e2fee15e3a4ed5e6ac14494f354215ad3fd4f672970f931 firstNonce=902d4ccc8d4c45dd4ff5a131f23beb3f3d47c047a768158c03818e31f1b8df08 lastNonce=451f37f0da72f047ebdb739fee69ba766649e1d086eed4c5afa15e0ada2c3b4d
DEBUG[05-19|03:26:35.867|x/plugin/reward_plugin.go:469] allocate package reward, delegate reward blockNumber=5650794 blockHash=c394f6…de745c delegateReward=2646833600466268821 epochDelegateReward=857574086551071098004
DEBUG[05-19|03:26:35.868|x/plugin/reward_plugin.go:480] allocate package reward,block reward blockNumber=5650794 blockHash=c394f6…de745c coinBase=lat1x3xagmwac3mpry5jnewktm44jxxqkqw0d5qgju reward=1764555733644179215
DEBUG[05-19|03:26:35.868|core/blockchain_reactor.go:322] Store ppos hash blockHash=c394f6…de745c blockNumber=5650794 pposHash=c610159fafba6e4c5750495f64b34119e2043c528deb7eafa6891ac4b6158ee2
DEBUG[05-19|03:26:35.868|core/blockchain_reactor.go:328] EndBlock StateDB root, end blockHash=c394f6…de745c blockNumber=5650794 root=4570bd…2e6292 pointer=0xc0051eaa00
DEBUG[05-19|03:26:35.868|core/parallel_state_processor.go:86] Process end blocker cost time blockNumber=5650794 blockHash=c394f6…de745c
DEBUG[05-19|03:26:35.868|core/blockchain.go:1233] Execute block time blockNumber=5650794 blockHash=c394f6…de745c time=1.398777ms
DEBUG[05-19|03:26:35.868|core/blockchain_cache.go:300] Execute block number=5650794 hash=c394f6…de745c parentNumber=5650793 parentHash=964d18…bf920c duration=1.414198ms makeState=46.534µs err=nil
INFO [05-19|03:26:35.868|core/blockchain_cache.go:166] Write a StateDB instance to the cache sealHash=4562ca…159a73 blockNum=5650794
INFO [05-19|03:26:35.869|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:c394f6…de745c,Number:5650794,Index:3,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.869|core/tx_pool.go:462] call Reset() hash=c394f6…de745c number=5650794 parentHash=964d18…bf920c
DEBUG[05-19|03:26:35.869|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=4562ca…159a73
DEBUG[05-19|03:26:35.870|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650793 oldHash=964d18…bf920c newNumber=5650794 newHash=c394f6…de745c count=0 elapsed=8.236µs
DEBUG[05-19|03:26:35.870|core/tx_pool.go:472] call Reset elapse time hash=c394f6…de745c number=5650794 parentHash=964d18…bf920c elapseTime=161.059µs
INFO [05-19|03:26:35.870|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650792 hash=e674b2…922fc6
DEBUG[05-19|03:26:35.870|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.870|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:c394f6…de745c,Number:5650794,Index:3,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
INFO [05-19|03:26:35.870|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650795 hash=a35fee…a13590 time="2021-05-19 03:26:35.87"
DEBUG[05-19|03:26:35.870|core/blockchain_cache.go:272] Block has executed number=5650795 hash=a35fee…a13590 parentNumber=5650794 parentHash=c394f6…de745c
DEBUG[05-19|03:26:35.870|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=e674b2…922fc6 number=5650792
WARN [05-19|03:26:35.870|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=e674b2…922fc6 number=5650792
INFO [05-19|03:26:35.870|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650792 blockHash=e674b2…922fc6
DEBUG[05-19|03:26:35.870|core/snapshotdb/current.go:161] increase current highest package=snapshotdb hash=e674b2…922fc6 num=5650792
INFO [05-19|03:26:35.870|core/snapshotdb/snapshotdb.go:769] commit block package=snapshotdb num=5650792 hash=0xe674b2daa947abc9e36a536423fd2a6ad487bb10644a0f062ef308fb08922fc6
DEBUG[05-19|03:26:35.870|core/snapshotdb/current.go:182] save current to baseDB package=snapshotdb height=5650792 hash=e674b2…922fc6 base= type=snapshotdbCurrentHighestBlock
INFO [05-19|03:26:35.871|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:a35fee…a13590,Number:5650795,Index:4,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.871|core/tx_pool.go:462] call Reset() hash=a35fee…a13590 number=5650795 parentHash=c394f6…de745c
DEBUG[05-19|03:26:35.872|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=e3ad3d…87d787
DEBUG[05-19|03:26:35.872|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650794 oldHash=c394f6…de745c newNumber=5650795 newHash=a35fee…a13590 count=0 elapsed=8.188µs
DEBUG[05-19|03:26:35.872|core/tx_pool.go:472] call Reset elapse time hash=a35fee…a13590 number=5650795 parentHash=c394f6…de745c elapseTime=159.581µs
INFO [05-19|03:26:35.872|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650793 hash=964d18…bf920c
DEBUG[05-19|03:26:35.872|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.872|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:a35fee…a13590,Number:5650795,Index:4,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
INFO [05-19|03:26:35.872|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650796 hash=d69eb5…eb7bb9 time="2021-05-19 03:26:35.872"
DEBUG[05-19|03:26:35.872|core/blockchain_cache.go:272] Block has executed number=5650796 hash=d69eb5…eb7bb9 parentNumber=5650795 parentHash=a35fee…a13590
DEBUG[05-19|03:26:35.872|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=964d18…bf920c number=5650793
WARN [05-19|03:26:35.872|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=964d18…bf920c number=5650793
INFO [05-19|03:26:35.872|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650793 blockHash=964d18…bf920c
DEBUG[05-19|03:26:35.872|core/snapshotdb/current.go:161] increase current highest package=snapshotdb hash=964d18…bf920c num=5650793
INFO [05-19|03:26:35.872|core/snapshotdb/snapshotdb.go:769] commit block package=snapshotdb num=5650793 hash=0x964d18bdf6c55b49fff0b08c4714f93edfa0d247f4f5fcae3e13003368bf920c
DEBUG[05-19|03:26:35.872|core/snapshotdb/current.go:182] save current to baseDB package=snapshotdb height=5650793 hash=964d18…bf920c base= type=snapshotdbCurrentHighestBlock
INFO [05-19|03:26:35.874|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:d69eb5…eb7bb9,Number:5650796,Index:5,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.874|core/tx_pool.go:462] call Reset() hash=d69eb5…eb7bb9 number=5650796 parentHash=a35fee…a13590
DEBUG[05-19|03:26:35.874|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=bb564c…26b025
DEBUG[05-19|03:26:35.874|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650795 oldHash=a35fee…a13590 newNumber=5650796 newHash=d69eb5…eb7bb9 count=0 elapsed=8.705µs
DEBUG[05-19|03:26:35.874|core/tx_pool.go:472] call Reset elapse time hash=d69eb5…eb7bb9 number=5650796 parentHash=a35fee…a13590 elapseTime=162.255µs
INFO [05-19|03:26:35.874|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650794 hash=c394f6…de745c
DEBUG[05-19|03:26:35.874|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.874|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:d69eb5…eb7bb9,Number:5650796,Index:5,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.874|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=c394f6…de745c number=5650794
WARN [05-19|03:26:35.874|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=c394f6…de745c number=5650794
INFO [05-19|03:26:35.874|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650797 hash=236e6b…1ffec9 time="2021-05-19 03:26:35.874"
DEBUG[05-19|03:26:35.874|core/blockchain_cache.go:272] Block has executed number=5650797 hash=236e6b…1ffec9 parentNumber=5650796 parentHash=d69eb5…eb7bb9
INFO [05-19|03:26:35.874|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650794 blockHash=c394f6…de745c
DEBUG[05-19|03:26:35.874|core/snapshotdb/current.go:161] increase current highest package=snapshotdb hash=c394f6…de745c num=5650794
INFO [05-19|03:26:35.874|core/snapshotdb/snapshotdb.go:769] commit block package=snapshotdb num=5650794 hash=0xc394f629c39a5b74ef9a780398c171ceb6fda9a42285040e419dc25507de745c
DEBUG[05-19|03:26:35.874|core/snapshotdb/current.go:182] save current to baseDB package=snapshotdb height=5650794 hash=c394f6…de745c base= type=snapshotdbCurrentHighestBlock
INFO [05-19|03:26:35.876|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:236e6b…1ffec9,Number:5650797,Index:6,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.876|core/tx_pool.go:462] call Reset() hash=236e6b…1ffec9 number=5650797 parentHash=d69eb5…eb7bb9
DEBUG[05-19|03:26:35.876|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=bcd46d…c31e68
DEBUG[05-19|03:26:35.876|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650796 oldHash=d69eb5…eb7bb9 newNumber=5650797 newHash=236e6b…1ffec9 count=0 elapsed=8.514µs
DEBUG[05-19|03:26:35.876|core/tx_pool.go:472] call Reset elapse time hash=236e6b…1ffec9 number=5650797 parentHash=d69eb5…eb7bb9 elapseTime=163.522µs
INFO [05-19|03:26:35.876|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650795 hash=a35fee…a13590
DEBUG[05-19|03:26:35.876|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.876|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:236e6b…1ffec9,Number:5650797,Index:6,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.876|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=a35fee…a13590 number=5650795
INFO [05-19|03:26:35.876|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650798 hash=06f8fa…ea7d6c time="2021-05-19 03:26:35.876"
WARN [05-19|03:26:35.876|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=a35fee…a13590 number=5650795
INFO [05-19|03:26:35.876|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650795 blockHash=a35fee…a13590
ERROR[05-19|03:26:35.876|core/blockchain_reactor.go:152] Failed to call snapshotdb commit on blockchain_reactor blockNumber=5650795 blockHash=a35fee…a13590 err="[snapshotdb]commit fail, not found block from recognized :0xa35fee7fbaea9d8960af6eafbf8eb11443e6defa22faedf38b58251908a13590"
DEBUG[05-19|03:26:35.876|core/blockchain_cache.go:272] Block has executed number=5650798 hash=06f8fa…ea7d6c parentNumber=5650797 parentHash=236e6b…1ffec9
INFO [05-19|03:26:35.878|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:06f8fa…ea7d6c,Number:5650798,Index:7,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.878|core/tx_pool.go:462] call Reset() hash=06f8fa…ea7d6c number=5650798 parentHash=236e6b…1ffec9
DEBUG[05-19|03:26:35.878|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=71fd18…1ad088
DEBUG[05-19|03:26:35.878|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650797 oldHash=236e6b…1ffec9 newNumber=5650798 newHash=06f8fa…ea7d6c count=0 elapsed=8.49µs
DEBUG[05-19|03:26:35.878|core/tx_pool.go:472] call Reset elapse time hash=06f8fa…ea7d6c number=5650798 parentHash=236e6b…1ffec9 elapseTime=168.909µs
INFO [05-19|03:26:35.878|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650796 hash=d69eb5…eb7bb9
DEBUG[05-19|03:26:35.878|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.878|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:06f8fa…ea7d6c,Number:5650798,Index:7,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.878|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=d69eb5…eb7bb9 number=5650796
INFO [05-19|03:26:35.878|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650799 hash=2f9c19…43c1b2 time="2021-05-19 03:26:35.878"
WARN [05-19|03:26:35.878|miner/worker.go:667] Duplicated cbft result caused by resubmitting or P2P sync. hash=d69eb5…eb7bb9 number=5650796
INFO [05-19|03:26:35.878|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650796 blockHash=d69eb5…eb7bb9
ERROR[05-19|03:26:35.878|core/blockchain_reactor.go:152] Failed to call snapshotdb commit on blockchain_reactor blockNumber=5650796 blockHash=d69eb5…eb7bb9 err="[snapshotdb]commit fail, not found block from recognized :0xd69eb598c4d298bb783a2e2e4ff489c5742a8062964c31ff0697ee1cd1eb7bb9"
DEBUG[05-19|03:26:35.878|core/blockchain_cache.go:272] Block has executed number=5650799 hash=2f9c19…43c1b2 parentNumber=5650798 parentHash=06f8fa…ea7d6c
INFO [05-19|03:26:35.880|consensus/cbft/consensus_process.go:246] Insert QC block epoch=13142 view=17 qc={Epoch:13142,ViewNumber:17,Hash:2f9c19…43c1b2,Number:5650799,Index:8,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
DEBUG[05-19|03:26:35.880|core/tx_pool.go:462] call Reset() hash=2f9c19…43c1b2 number=5650799 parentHash=06f8fa…ea7d6c
DEBUG[05-19|03:26:35.880|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=1fd968…455db8
DEBUG[05-19|03:26:35.880|core/tx_pool.go:1395] Reinjecting stale transactions oldNumber=5650798 oldHash=06f8fa…ea7d6c newNumber=5650799 newHash=2f9c19…43c1b2 count=0 elapsed=8.54µs
DEBUG[05-19|03:26:35.880|core/tx_pool.go:472] call Reset elapse time hash=2f9c19…43c1b2 number=5650799 parentHash=06f8fa…ea7d6c elapseTime=151.79µs
INFO [05-19|03:26:35.880|consensus/cbft/cbft.go:1301] CommitBlock, send consensus result to worker epoch=13142 view=17 number=5650797 hash=236e6b…1ffec9
DEBUG[05-19|03:26:35.880|consensus/cbft/consensus_process.go:599] Try change view failed, had receive viewchange epoch=13142 view=17 len=0 view={Epoch:13142,ViewNumber:17}
INFO [05-19|03:26:35.880|consensus/cbft/consensus_process.go:237] Insert QC block success epoch=13142 view=17 qcBlock={Epoch:13142,ViewNumber:17,Hash:2f9c19…43c1b2,Number:5650799,Index:8,ValidatorSet:BA{20:_xxxx___xxxxx_xxxx_x}}
INFO [05-19|03:26:35.880|consensus/cbft/cbft.go:894] Insert chain epoch=13142 view=17 number=5650800 hash=af5c3b…b761ea time="2021-05-19 03:26:35.88"
INFO [05-19|03:26:35.880|core/blockchain_reactor.go:150] Call snapshotdb commit on blockchain_reactor blockNumber=5650797 blockHash=236e6b…1ffec9
DEBUG[05-19|03:26:35.880|core/blockchain_cache.go:289] Start execute block hash=af5c3b…b761ea number=5650800 sealHash=fde0cd…317889
INFO [05-19|03:26:35.880|core/blockchain_cache.go:212] Make stateDB hash=2f9c19…43c1b2 number=5650799 root=1bde29…2f04fd
DEBUG[05-19|03:26:35.880|core/blockchain_cache.go:146] Read the StateDB instance from the cache map sealHash=1fd968…455db8
ERROR[05-19|03:26:35.880|core/blockchain_reactor.go:152] Failed to call snapshotdb commit on blockchain_reactor blockNumber=5650797 blockHash=236e6b…1ffec9 err="[snapshotdb]commit fail, not found block from recognized :0x236e6bac9806335e7e70bfb3ff10117c8f0c3f88ce71ac5f8d264dd8531ffec9"
DEBUG[05-19|03:26:35.880|consensus/cbft/cbft.go:839] Seal hash epoch=13142 view=17 hash=236e6b…1ffec9 number=5650797
DEBUG[05-19|03:26:35.881|miner/worker.go:674] Pending task exist=false
DEBUG[05-19|03:26:35.881|core/blockchain_cache.go:136] Read the StateDB instance from the cache map sealHash=bcd46d…c31e68
DEBUG[05-19|03:26:35.881|miner/worker.go:687] Block is packaged by other hash=236e6b…1ffec9 number=5650797 len(Receipts)=0 blockRoot=e329ba…7aef2c stateIsNil=false
DEBUG[05-19|03:26:35.881|miner/worker.go:696] Cbft consensus successful hash=236e6b…1ffec9 number=5650797 timestamp=1621394795881
DEBUG[05-19|03:26:35.881|miner/worker.go:720] Write extra data txs=0 extra=118
WARN [05-19|03:26:35.881|consensus/cbft/wal_bridge.go:130] The chainState of wal and updating are discontinuous,ignore this updating walCommit="[blockNumber:5650731, blockHash:0x96fffdf78d9a66bdb2748098d118f14150e71768050185dc9879e20776b11aa9, qcNumber:5650731, qcHash:0x96fffdf78d9a66bdb2748098d118f14150e71768050185dc9879e20776b11aa9]" updateCommit="[blockNumber:5650797, blockHash:0x236e6bac9806335e7e70bfb3ff10117c8f0c3f88ce71ac5f8d264dd8531ffec9, qcNumber:5650797, qcHash:0x236e6bac9806335e7e70bfb3ff10117c8f0c3f88ce71ac5f8d264dd8531ffec9]"
DEBUG[05-19|03:26:35.881|x/handler/vrf_handler.go:98] Verification block vrf prove current blockNumber=5650800 current hash=af5c3b…b761ea parentHash=2f9c19…43c1b2 proof=02bcd0d7be023f30eb2d8d6a20ac8156530d88aac4d7dc2b934d5ded1becb66e04414ead5e2ca4ec245b754704da8cdfafef4e20fd0d551ec2f54195b3b09c27c43676ccf518fb9af3dc7229a0df6bd916
DEBUG[05-19|03:26:35.881|x/handler/vrf_handler.go:186] Get the nonce of the previous block blockNumber=5650800 parentHash=2f9c194649063490057fd193c57b1d08a83e530be18961e56844f28fde43c1b2
ERROR[05-19|03:26:35.882|x/handler/vrf_handler.go:111] Vrf proves verification failure current blockNumber=5650800 current hash=af5c3b717b52bdeeb3be3bce08821a2ee5af1e6b6e0000294c6e6222b2b761ea parentHash=2f9c194649063490057fd193c57b1d08a83e530be18961e56844f28fde43c1b2 proof=02bcd0d7be023f30eb2d8d6a20ac8156530d88aac4d7dc2b934d5ded1becb66e04414ead5e2ca4ec245b754704da8cdfafef4e20fd0d551ec2f54195b3b09c27c43676ccf518fb9af3dc7229a0df6bd916 input=451f37f0da72f047ebdb739fee69ba766649e1d086eed4c5afa15e0ada2c3b4d
ERROR[05-19|03:26:35.882|core/parallel_state_processor.go:40] Failed to call BeginBlocker on StateProcessor blockNumber=5650800 blockHash=af5c3b…b761ea err="invalid vrf prove"
ERROR[05-19|03:26:35.882|core/blockchain.go:1229] Failed to ProcessDirectly blockNumber=5650800 blockHash=af5c3b…b761ea err="invalid vrf prove"
ERROR[05-19|03:26:35.882|core/blockchain.go:1482]
########## BAD BLOCK #########
Chain config: {ChainID: 210309 EIP155: 3 Engine: &{20000 10 [{{47.241.99.12 16789 16789 24243dd75eab3fa7821fbeb527debcb0961ff879d2b8131ae96b1fefa94467c36a6abb85ab318a5b09666aa6ee0edb985497d77970b14c28b4df572cebe956a6 [42 12 68 176 7 70 34 89 91 145 10 221 220 13 244 253 90 110 179 214 50 213 157 238 202 40 205 57 218 128 74 138] {0 0 }} {{{[12942555049379552481 666007496503642887 592176693918110440 1259708079547869575 1458673191315007303 888417539849799038 7069210151848966611 2485528992410680715 14658348564587891202 14416132067297619337 13291544058492040985 752296948554547590 14442672775090346761 17462253310877726084 17852807929853106457 174014310862738769 14104873823856840289 818788232063057586 13641000107832164429 15054445332005067965 11558218596807526262 1998038287474165552 3796590237008952266 615477080172363669 8505329371266088957 17002214543764226050 6865905132761471162 8632934651105793861 6631298214892334189 1582556514881692819 0 0 0 0 0 0]}}}} {{47.241.98.219 16789 16789 35981cdc5fed4f5e275f6f69673e7910613194c902e423d788950c9dcbb4c0eea089e6f79d8caf53ab038ec3931dbfca083cb3bda2064d674e81482e05d8261d [224 196 17 47 193 80 198 82 29 5 77 33 14 24 176 245 238 244 180 251 6 198 180 191 242 97 67 217 182 32 149 41] {0 0 }} {{{[14919191643542586085 10530236984578105602 4587480556028543903 3661136873146798410 14613236559267916280 813528953442445698 1184385551834888375 13554151637581814779 10756772801667352341 16483494575649064106 11774751508375610814 1166519708875642044 3880139481601451171 10691884682421544833 3579865521984076554 10803178724070645111 9593187778532905644 368341044990970573 2012338059044734104 10757296062810816503 6634991520868685063 13604441945681687436 12515924386740181469 1452781383584846173 8505329371266088957 17002214543764226050 6865905132761471162 8632934651105793861 6631298214892334189 1582556514881692819 0 0 0 0 0 0]}}}} {{47.241.93.189 16789 16789 c72a4d2cb8228ca6f9072daa66566bcafa17bec6a9e53765c85c389434488c393357c5c7c5d18cf9b26ceda46aca4da20755cd01bcc1478fff891a201042ba84 [212 237 156 1 70 251 162 198 194 235 78 212 59 9 155 165 45 75 224 215 197 232 28 247 16 141 39 6 15 47 160 188] {0 0 }} {{{[6355104550062896618 18312404849321644507 6294402757356579978 1592375122028768328 16294735985357657756 1198460090836116619 884976625344700963 2301376661067999056 10584361948211727337 9108960845780306966 12738197323428078286 31421418594883786 10421708294908327234 11058644354413396665 5346470674570919535 1811174825804369278 11580436160012671422 189158667857212024 3097767192103862667 1797933901162056904 6513452895982102437 6666177578739399153 14321257335313464803 220331570741857223 8505329371266088957 17002214543764226050 6865905132761471162 8632934651105793861 6631298214892334189 1582556514881692819 0 0 0 0 0 0]}}}} {{47.241.99.44 16789 16789 ec16a382123a7cecddfda005b8e463a5e65dbe4844a082391419fc15f1899b6c9ea246d04b0a6b9f6350e7f7445066ff8b69271cd5be021783bccb2b9c804aff [247 96 170 243 210 83 14 164 77 62 217 219 134 43 224 75 205 117 52 20 250 170 195 203 215 134 64 232 207 230 196 31] {0 0 }} {{{[11696293226039224483 12147886878747042437 13081092550740717181 11352378670098924923 6580676322866811647 1719291218667136039 4881811360140623 10261642687356740187 15961347159092371061 17752732803898379614 9423709784846534482 1464557901857516729 10372993128814467636 15579543084206678685 17932394193682873801 17519215303509640175 13722343416910807094 1580133414165980932 16029114068510082285 1075390133548982360 14954834855722375901 18284065366957553401 6036453711973340110 650876776294223910 8505329371266088957 17002214543764226050 6865905132761471162 8632934651105793861 6631298214892334189 1582556514881692819 0 0 0 0 0 0]}}}}] ppos}}

Number: 5650800
Hash: 0xaf5c3b717b52bdeeb3be3bce08821a2ee5af1e6b6e0000294c6e6222b2b761ea

Error: invalid vrf prove
##############################

[cheng762]

这是程序上的bug，如果初次fast同步失败，会导致后续同步出现BAD BLOCK。
bug产生的原因，快照DB的状态在fast同步失败后被错误的更新，由此导致后续同步数据异常。

[niuxiaojie81]

该节点是初始节点（基金会节点、种子节点），节点启动后一直通过共识流程出块和跟块。5月19日节点出现区块落后后，根据启动命令--syncmode fast，首次启动同步逻辑，synchronise函数中存在bug，没有强制修改为full模式同步。

[niuxiaojie81]

blockchain中存在区块，应该强制执行full同步，否则会导致cbft、snapshotdb、BlockChain.blockCache等很多地方状态不连续

[benbaley]

fixed in AlayaNetwork/Alaya-Go#33