testnet n1 broken: process block 4569 from n5 cost 11s

time="2018-12-04T21:11:46+08:00" level=info msg="Change New Tail. Hash: 5e576f69e17a9f990a497c479c780c9899fd3bbd6b966e753569c2509445df94 Height: 4568" source="chain/blockchain.go:793" tag=chain
time="2018-12-04T21:11:46+08:00" level=info msg="Block 5e576f69e17a9f990a497c479c780c9899fd3bbd6b966e753569c2509445df94 connects to main chain" source="txpool/transaction_pool.go:140" tag=txpool
time="2018-12-04T21:11:46+08:00" level=debug msg="received network message. Code: 16 From: 12D3KooWQSaxCgbWakLcU69f4gmNFMszwhyHbwx4xPAhV7erDC2P" source="p2p/notifier.go:80" tag=p2p
time="2018-12-04T21:11:46+08:00" level=debug msg="received network message. Code: 16 From: 12D3KooWRHVAwymCVcA8jqyjpP3r3HBkCW2q5AZRTBvtaungzFSJ" source="p2p/notifier.go:80" tag=p2p
time="2018-12-04T21:11:46+08:00" level=debug msg="received network message. Code: 16 From: 12D3KooWNcJQzHaNpW5vZDQbTcoLXVCyGS755hTpendGzb5Hqtcu" source="p2p/notifier.go:80" tag=p2p
time="2018-12-04T21:11:46+08:00" level=debug msg="received network message. Code: 16 From: 12D3KooWSdXLNeoRQQ2a7yiS6xLpTn3LdCr8B8fqPz94Bbi7itsi" source="p2p/notifier.go:80" tag=p2p
time="2018-12-04T21:11:46+08:00" level=debug msg="received network message. Code: 16 From: 12D3KooWKPRAK7vBBrVv9szEin55kBnJEEuHG4gDTQEM72ByZDpA" source="p2p/notifier.go:80" tag=p2p
time="2018-12-04T21:11:47+08:00" level=info msg="Eternal block has changed! Hash: 5e576f69e17a9f990a497c479c780c9899fd3bbd6b966e753569c2509445df94 Height: 4568" source="dpos/bft_service.go:162" tag=dpos
time="2018-12-04T21:11:47+08:00" level=info msg="Accepted New Block. Hash: 5e576f69e17a9f990a497c479c780c9899fd3bbd6b966e753569c2509445df94 Height: 4568" source="chain/blockchain.go:288" tag=chain
time="2018-12-04T21:11:48+08:00" level=debug msg="received network message. Code: 5 From: 12D3KooWKPRAK7vBBrVv9szEin55kBnJEEuHG4gDTQEM72ByZDpA" source="p2p/notifier.go:80" tag=p2p

......
......
time="2018-12-04T21:11:50+08:00" level=debug msg="received network message. Code: 4 From: 12D3KooWQSaxCgbWakLcU69f4gmNFMszwhyHbwx4xPAhV7erDC2P" source="p2p/notifier.go:80" tag=p2p
time="2018-12-04T21:11:50+08:00" level=info msg="Prepare to process block. Hash: 27ecc6e589777fa8dc1ebfccb98eaa22be67edda8baa45523acfb7655e119db6, Height: 4569" source="chain/blockchain.go:236" tag=chain
time="2018-12-04T21:11:50+08:00" level=debug msg="Try to connect block to main chain. Hash: 27ecc6e589777fa8dc1ebfccb98eaa22be67edda8baa45523acfb7655e119db6, Height: 4569" source="chain/blockchain.go:430" tag=chain
......
......
time="2018-12-04T21:11:55+08:00" level=debug msg="received network message. Code: 4 From: 12D3KooWNcJQzHaNpW5vZDQbTcoLXVCyGS755hTpendGzb5Hqtcu" source="p2p/notifier.go:80" tag=p2p
......
......
time="2018-12-04T21:12:00+08:00" level=info msg="My turn to mint a block, time: 1543929120" source="dpos/dpos.go:163" tag=dpos
time="2018-12-04T21:12:00+08:00" level=info msg="Finish packing txs. Hash: d8e32ad801228fbe7fb5dd90dd6fb584f6fd295f548c4af27c529aec93433e6f, Height: 4569, TxsNum: 33" source="dpos/dpos.go:348" tag=dpos
time="2018-12-04T21:12:00+08:00" level=debug msg="UTXO: apply block with 3 transactions" source="chain/utxoset.go:164" tag=chain
time="2018-12-04T21:12:00+08:00" level=debug msg="Create Block filter with 25 inputs and 23 outputs" source="chain/blockchain.go:1147" tag=chain
time="2018-12-04T21:12:00+08:00" level=info msg="Change New Tail. Hash: 27ecc6e589777fa8dc1ebfccb98eaa22be67edda8baa45523acfb7655e119db6 Height: 4569" source="chain/blockchain.go:793" tag=chain
time="2018-12-04T21:12:00+08:00" level=info msg="Block 27ecc6e589777fa8dc1ebfccb98eaa22be67edda8baa45523acfb7655e119db6 connects to main chain" source="txpool/transaction_pool.go:140" tag=txpool
time="2018-12-04T21:12:00+08:00" level=debug msg="Removed orphan transaction 30d98eff81a741a265259940a35663c3e8d06b1abea4a05e513445138b705653" source="txpool/transaction_pool.go:471" tag=txpool
time="2018-12-04T21:12:00+08:00" level=debug msg="Removed orphan transaction 405da325ae024942a5506ff0d3447b5af22df9d91149e097be73eefa48d9719b" source="txpool/transaction_pool.go:471" tag=txpool
time="2018-12-04T21:12:01+08:00" level=info msg="Eternal block has changed! Hash: 27ecc6e589777fa8dc1ebfccb98eaa22be67edda8baa45523acfb7655e119db6 Height: 4569" source="dpos/bft_service.go:162" tag=dpos
......
......
time="2018-12-04T21:12:01+08:00" level=debug msg="opcode: OP_CHECKSIG, pc: 132" source="script/script.go:233" tag=script
time="2018-12-04T21:12:01+08:00" level=info msg="Accepted New Block. Hash: 27ecc6e589777fa8dc1ebfccb98eaa22be67edda8baa45523acfb7655e119db6 Height: 4569" source="chain/blockchain.go:288" tag=chain
time="2018-12-04T21:12:01+08:00" level=info msg="Prepare to process block. Hash: d8e32ad801228fbe7fb5dd90dd6fb584f6fd295f548c4af27c529aec93433e6f, Height: 4569" source="chain/blockchain.go:236" tag=chain
time="2018-12-04T21:12:01+08:00" level=warning msg="The block is timeout. Now: 1543929121 BlockTimeStamp: 1543929115 Timeout: 6" source="chain/validate.go:28" tag=chain
time="2018-12-04T21:12:01+08:00" level=warning msg="Failed to processBlockMsg. Err: The block is timeout" source="chain/blockchain.go:184" tag=chain
time="2018-12-04T21:12:01+08:00" level=info msg="Block d8e32ad801228fbe7fb5dd90dd6fb584f6fd295f548c4af27c529aec93433e6f extends a side chain to height 4569 without causing reorg, main chain height 4569" source="chain/blockchain.go:352" tag=chain
time="2018-12-04T21:12:01+08:00" level=debug msg="Broadcast New Block. Hash: d8e32ad801228fbe7fb5dd90dd6fb584f6fd295f548c4af27c529aec93433e6f Height: 4569" source="chain/blockchain.go:282" tag=chain
time="2018-12-04T21:12:02+08:00" level=debug msg="received network message. Code: 5 From: 12D3KooWKPRAK7vBBrVv9szEin55kBnJEEuHG4gDTQEM72ByZDpA" source="p2p/notifier.go:80" tag=p2p
BOXFoundation / boxd

testnet n1 broken: process block 4569 from n5 cost 11s #220
