Skip to content

Conversation

@AnnaShaleva
Copy link
Member

If node is stale and far behind its peers, mining is aborted by the signal of Downloader. If mining is aborted, then sealing task won't be submitted to the consensus engine, and thus, dBFT awaiting for sealing task may hang forever in case of multiple block batch processing by Downloader. It may happen because dBFT's block notificaiton channel has buffer size of 2 and block notification processing is managed by the same eventLoop as sealing tasks awaiting. So dBFT blocks notifications processing by sealing task awaiting, and thus, blocks the whole chain from the further sync process:

2024-04-18T10:26:44.856+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 6470, "view": 0, "cache": {}}
2024-04-18T10:26:45.264+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 6470, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T10:26:45.265+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 6470, "view": 0, "cache": {}}
INFO [04-18|10:26:50.148] Imported new chain segment               number=192 hash=775270..7b8b06 blocks=192 txs=0 mgas=0.000 elapsed=562.005ms   mgasps=0.000 age=19h32m52s triedirty=0.00B
INFO [04-18|10:26:50.149] New block in the chain                   "dbft index"=1 "chain index"=192 hash=0x77527025ea64a0ceb26235efaf6ddc5430d0e2de15757869da2e90a1c57b8b06 "parent hash"=0xa9cde87fe65f34bf1f40982015e1a7e73b117a80fadf707b0deda67a34855092 primary=3 coinbase=0x1212000000000000000000000000000000000003 "mix digest"=0x23c10fa9c1fae49f6139db9c3ecff715de101c145e56e4d390a16e67d5c32e1b
INFO [04-18|10:26:50.149] Fetching latest sealing proposal         "desired number"=193
INFO [04-18|10:26:50.152] Indexed transactions                     blocks=193 txs=0 tail=0 elapsed=3.786ms
INFO [04-18|10:26:50.770] Imported new chain segment               number=384 hash=a5dac5..02375b blocks=192 txs=0 mgas=0.000 elapsed=472.546ms   mgasps=0.000 age=18h57m12s triedirty=0.00B
INFO [04-18|10:26:51.344] Imported new chain segment               number=576 hash=1d8a8e..3e8def blocks=192 txs=0 mgas=0.000 elapsed=570.798ms   mgasps=0.000 age=18h21m29s triedirty=0.00B
INFO [04-18|10:26:52.221] Imported new chain segment               number=960 hash=6fde0c..d75d78 blocks=384 txs=0 mgas=0.000 elapsed=868.154ms   mgasps=0.000 age=17h10m6s  triedirty=0.00B
INFO [04-18|10:27:07.683] Looking for peers                        peercount=1 tried=1 static=0

To fix this problem we have to omit block notifications processing until the node sync process end, i.e. util the moment miner resumes its work.

@AnnaShaleva AnnaShaleva added bug Something isn't working dBFT Everything related to dBFT module integration labels Apr 18, 2024
@AnnaShaleva AnnaShaleva added this to the v0.1.1 milestone Apr 18, 2024
@AnnaShaleva AnnaShaleva force-pushed the fix-sync branch 2 times, most recently from feb21ed to 72418a6 Compare April 18, 2024 10:02
@AnnaShaleva
Copy link
Member Author

AnnaShaleva commented Apr 18, 2024

Tested with stale watch-only T3 CN, works as expected:

anna@kiwi:~/Documents/GitProjects/bane-labs/go-ethereum$ cat ./nodes/node1/node.log 
INFO [04-18|13:05:45.762] Enabling metrics collection
INFO [04-18|13:05:45.762] Enabling stand-alone metrics HTTP endpoint address=0.0.0.0:6060
INFO [04-18|13:05:45.762] Starting metrics server                  addr=http://0.0.0.0:6060/debug/metrics
INFO [04-18|13:05:45.763] Maximum peer count                       ETH=30 total=30
INFO [04-18|13:05:45.764] Smartcard socket not found, disabling    err="stat /run/pcscd/pcscd.comm: no such file or directory"
INFO [04-18|13:05:45.766] Enabling recording of key preimages since archive mode is used
WARN [04-18|13:05:45.766] Disabled transaction unindexing for archive node
INFO [04-18|13:05:45.767] Set global gas cap                       cap=50,000,000
INFO [04-18|13:05:45.767] Initializing the KZG library             backend=gokzg
INFO [04-18|13:05:45.797] Allocated trie memory caches             clean=307.00MiB dirty=0.00B
INFO [04-18|13:05:45.797] Using pebble as the backing database
INFO [04-18|13:05:45.797] Allocated cache and file handles         database=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/nodes/node1/geth/chaindata cache=512.00MiB handles=524,288
INFO [04-18|13:05:45.816] Opened ancient database                  database=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/nodes/node1/geth/chaindata/ancient/chain readonly=false
INFO [04-18|13:05:45.817] State scheme set to already existing     scheme=hash
INFO [04-18|13:05:45.817] Initialising Ethereum protocol           network=12,227,331 dbversion=<nil>
INFO [04-18|13:05:45.819] 
INFO [04-18|13:05:45.819] ---------------------------------------------------------------------------------------------------------------------------------------------------------
INFO [04-18|13:05:45.819] Chain ID:  12227331 (unknown)
INFO [04-18|13:05:45.819] Consensus: dBFT (proof-of-authority)
INFO [04-18|13:05:45.819] 
INFO [04-18|13:05:45.819] Pre-Merge hard forks (block based):
INFO [04-18|13:05:45.819]  - Homestead:                   #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/homestead.md)
INFO [04-18|13:05:45.819]  - Tangerine Whistle (EIP 150): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/tangerine-whistle.md)
INFO [04-18|13:05:45.819]  - Spurious Dragon/1 (EIP 155): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/spurious-dragon.md)
INFO [04-18|13:05:45.819]  - Spurious Dragon/2 (EIP 158): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/spurious-dragon.md)
INFO [04-18|13:05:45.819]  - Byzantium:                   #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/byzantium.md)
INFO [04-18|13:05:45.819]  - Constantinople:              #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/constantinople.md)
INFO [04-18|13:05:45.819]  - Petersburg:                  #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/petersburg.md)
INFO [04-18|13:05:45.819]  - Istanbul:                    #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/istanbul.md)
INFO [04-18|13:05:45.819]  - Muir Glacier:                #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/muir-glacier.md)
INFO [04-18|13:05:45.819]  - Berlin:                      #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/berlin.md)
INFO [04-18|13:05:45.819]  - London:                      #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/london.md)
INFO [04-18|13:05:45.819]  - Arrow Glacier:               #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/arrow-glacier.md)
INFO [04-18|13:05:45.819]  - Gray Glacier:                #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/gray-glacier.md)
INFO [04-18|13:05:45.819] 
INFO [04-18|13:05:45.819] The Merge is not yet available for this network!
INFO [04-18|13:05:45.819]  - Hard-fork specification: https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/paris.md
INFO [04-18|13:05:45.819] 
INFO [04-18|13:05:45.819] Post-Merge hard forks (timestamp based):
INFO [04-18|13:05:45.819]  - Shanghai:                    @0          (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/shanghai.md)
INFO [04-18|13:05:45.819] 
INFO [04-18|13:05:45.819] ---------------------------------------------------------------------------------------------------------------------------------------------------------
INFO [04-18|13:05:45.819] 
INFO [04-18|13:05:45.819] Loaded most recent local block           number=0 hash=b30d58..397530 td=1 age=55y1mo1d
WARN [04-18|13:05:45.819] Failed to load snapshot                  err="missing or corrupted snapshot"
INFO [04-18|13:05:45.822] Rebuilding state snapshot
INFO [04-18|13:05:45.823] Initialized transaction indexer          range="entire chain"
INFO [04-18|13:05:45.823] Resuming state snapshot generation       root=1f8500..822e52 accounts=0 slots=0 storage=0.00B dangling=0 elapsed=1.097ms
INFO [04-18|13:05:45.823] Regenerated local transaction journal    transactions=0 accounts=0
INFO [04-18|13:05:45.825] Generated state snapshot                 accounts=12 slots=46 storage=4.60KiB dangling=0 elapsed=3.868ms
INFO [04-18|13:05:45.838] Gasprice oracle is ignoring threshold set threshold=2
WARN [04-18|13:05:45.839] Engine API enabled                       protocol=eth
WARN [04-18|13:05:45.839] Engine API started but chain not configured for merge yet
INFO [04-18|13:05:45.839] Starting peer-to-peer node               instance=Geth/nodes/node1/v0.1.1-unstable-72418a62-20240418/linux-amd64/go1.22.0
INFO [04-18|13:05:45.844] New local node record                    seq=1,713,434,745,844 id=78f20fc0f85e3d82 ip=127.0.0.1 udp=30301 tcp=30301
INFO [04-18|13:05:45.845] Started P2P networking                   self=enode://f098a34ceef9637915a2f76b67c6c91fce36f427bcbfe5d60505101442a9d63695ff5535351140151b02f27b5dc615101b1f328e86c0fd70f070df6fc759b2c9@127.0.0.1:30301
INFO [04-18|13:05:45.846] IPC endpoint opened                      url=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/nodes/node1/geth.ipc
INFO [04-18|13:05:45.847] Generated JWT secret                     path=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/nodes/node1/geth/jwtsecret
INFO [04-18|13:05:45.847] WebSocket enabled                        url=ws://127.0.0.1:8562
INFO [04-18|13:05:45.847] HTTP server started                      endpoint=127.0.0.1:8562 auth=true prefix= cors=localhost vhosts=localhost
INFO [04-18|13:05:46.599] Unlocked account                         address=0xf0783241343402A2FEe8A2E8914efE42eb4801aF
INFO [04-18|13:05:46.599] Legacy pool tip threshold updated        tip=0
INFO [04-18|13:05:46.599] Legacy pool tip threshold updated        tip=1,000,000,000
INFO [04-18|13:05:46.599] Initializing BFT consensus               account=0xf0783241343402A2FEe8A2E8914efE42eb4801aF
INFO [04-18|13:05:46.599] Fetching latest sealing proposal         "desired number"=1
INFO [04-18|13:05:46.599] Commit new sealing work                  number=1 sealhash=d665a5..000bb2 "parent hash"=b30d58..397530 etherbase=0x1212000000000000000000000000000000000003 txs=0 gas=0 fees=0 elapsed="227.489µs"
INFO [04-18|13:05:47.599] Sealing proposal updated                 number=1 sealhash=d665a5..000bb2 "parent hash"=b30d58..397530 txs=0
INFO [04-18|13:05:47.599] Starting dBFT engine                     "last height"=0 "last timestamp"=0
2024-04-18T13:05:47.600+0300	INFO	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:107	initializing dbft	{"height": 1, "view": 0, "index": -1, "role": "WatchOnly"}
INFO [04-18|13:05:47.600] dBFT event loop started
INFO [04-18|13:05:48.293] NAT mapped port                          proto=TCP extport=30301 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|13:05:48.914] NAT mapped alternative port              proto=UDP extport=58480 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|13:05:49.535] NAT mapped port                          proto=UDP extport=58480 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|13:05:49.948] NAT mapped port                          proto=TCP extport=30301 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|13:05:50.355] New local node record                    seq=1,713,434,745,845 id=78f20fc0f85e3d82 ip=172.30.218.222 udp=58480 tcp=30301
2024-04-18T13:05:51.161+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 4, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.161+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": null}
2024-04-18T13:05:51.161+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.161+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.161+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.162+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 4, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 4, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.362+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.363+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.364+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.364+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.364+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.364+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.364+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.364+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.364+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.365+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.367+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.367+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.491+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.491+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.629+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.629+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.629+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.629+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.630+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.630+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.630+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.630+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.631+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.631+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.631+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.631+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.632+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.632+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.696+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.696+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
2024-04-18T13:05:51.696+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7326, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:05:51.696+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7326, "view": 0, "cache": {}}
INFO [04-18|13:05:55.849] Block synchronisation started
INFO [04-18|13:05:55.849] Mining aborted due to sync
INFO [04-18|13:05:59.763] Imported new chain segment               number=192 hash=775270..7b8b06 blocks=192 txs=0 mgas=0.000 elapsed=407.853ms   mgasps=0.000 age=22h12m1s triedirty=0.00B
INFO [04-18|13:05:59.763] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=192 "chain index"=192 hash=0x77527025ea64a0ceb26235efaf6ddc5430d0e2de15757869da2e90a1c57b8b06
INFO [04-18|13:05:59.765] Indexed transactions                     blocks=193 txs=0 tail=0 elapsed=1.256ms
INFO [04-18|13:06:00.569] Imported new chain segment               number=576 hash=1d8a8e..3e8def blocks=384 txs=0 mgas=0.000 elapsed=803.580ms   mgasps=0.000 age=21h38s   triedirty=0.00B
INFO [04-18|13:06:00.569] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=576 "chain index"=576 hash=0x1d8a8e6bda337eb6a1f4213fa40c3ca85b62da9e630c16e5d6e49508d73e8def
2024-04-18T13:06:01.574+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 5, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.574+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": null}
2024-04-18T13:06:01.576+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.576+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.577+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.578+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.685+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 5, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.685+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.685+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 5, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.685+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.685+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.686+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.686+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.686+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.687+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.687+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.687+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.688+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.688+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.688+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.688+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.688+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.689+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.689+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.689+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.689+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.689+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.689+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.691+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.691+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.692+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.692+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.693+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.694+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.694+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.694+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.695+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.695+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.695+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.695+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.814+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.815+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:01.816+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:01.816+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
INFO [04-18|13:06:01.853] Imported new chain segment               number=994 hash=5f2929..d7648a blocks=418 txs=0 mgas=0.000 elapsed=1.130s      mgasps=0.000 age=19h42m54s triedirty=0.00B
INFO [04-18|13:06:01.853] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=994 "chain index"=994 hash=0x5f2929c939919aaa6b4f2e2c31cced0710e7273a287f6d62d23af8cb54d7648a
2024-04-18T13:06:02.158+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:02.158+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:02.159+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:02.159+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:02.164+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:02.164+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:02.265+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:02.265+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:02.265+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:02.265+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
2024-04-18T13:06:02.267+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7327, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:02.267+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7327, "view": 0, "cache": {}}
INFO [04-18|13:06:06.885] Imported new chain segment               number=3042 hash=df09b1..39ac8a blocks=2048 txs=8 mgas=0.899 elapsed=5.004s      mgasps=0.180 age=13h21m3s  snapdiffs=7.73KiB triedirty=0.00B
INFO [04-18|13:06:06.886] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=3042 "chain index"=3042 hash=0xdf09b191de85eed0845d17f771649c1387b91610c53d8b1af1dfaf3b2639ac8a
INFO [04-18|13:06:11.840] Imported new chain segment               number=5090 hash=269322..bbc7e7 blocks=2048 txs=3 mgas=0.380 elapsed=4.928s      mgasps=0.077 age=6h56m9s   snapdiffs=10.65KiB triedirty=0.00B
INFO [04-18|13:06:11.840] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=5090 "chain index"=5090 hash=0x269322530e0bb335fd336d2072948f04bf7c4fac83b0e2edc716dd3ee3bbc7e7
2024-04-18T13:06:13.381+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 6, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.381+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": null}
2024-04-18T13:06:13.381+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 6, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.381+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.382+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.383+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.383+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.383+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.648+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 6, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.648+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.648+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.648+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 0, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.649+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.650+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.717+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.718+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.719+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7328, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:13.722+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7328, "view": 0, "cache": {}}
INFO [04-18|13:06:16.772] Imported new chain segment               number=7138 hash=a28f0e..62b230 blocks=2048 txs=22 mgas=5.487 elapsed=4.905s      mgasps=1.118 age=35m33s    snapdiffs=32.94KiB triedirty=0.00B
INFO [04-18|13:06:16.772] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=7138 "chain index"=7138 hash=0xa28f0e7075601a2226648609a4ed081239e2cdcdbeeadb3842b79c9ef462b230
INFO [04-18|13:06:17.460] Imported new chain segment               number=7326 hash=ac6c0e..439d62 blocks=188  txs=15 mgas=6.298 elapsed=684.680ms   mgasps=9.199 snapdiffs=46.41KiB triedirty=0.00B
INFO [04-18|13:06:17.461] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=7326 "chain index"=7326 hash=0xac6c0e8edf150dd37c67bafc36b06019131953e1c11160145459657097439d62
INFO [04-18|13:06:19.135] Imported new chain segment               number=7327 hash=288ba2..1fed22 blocks=1    txs=0  mgas=0.000 elapsed=17.026ms    mgasps=0.000 snapdiffs=46.41KiB triedirty=0.00B
INFO [04-18|13:06:19.136] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=7327 "chain index"=7327 hash=0x288ba2a48a89b0dea4ab2c41408f8b3034265f841695a0ce5a655984231fed22
INFO [04-18|13:06:19.335] Imported new chain segment               number=7328 hash=4576b8..d9bdcc blocks=1    txs=1  mgas=0.166 elapsed=14.440ms    mgasps=11.528 snapdiffs=47.08KiB triedirty=0.00B
INFO [04-18|13:06:19.336] Skip chain block processing since miner is interrupted "dbft index"=1 "block index"=7328 "chain index"=7328 hash=0x4576b8bd59a3a56d39ea99cac9452a328c2cdbedb28e8a004fd1467646d9bdcc
INFO [04-18|13:06:19.338] Commit new sealing work                  number=7329 sealhash=e8d1a5..acf2de "parent hash"=4576b8..d9bdcc etherbase=0x1212000000000000000000000000000000000003 txs=0  gas=0 fees=0 elapsed=2.501ms
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 0, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": null}
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 0, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.032+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.033+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.299+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 0, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.299+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.299+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 3, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.299+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.300+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 6, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.300+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.300+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 2, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.300+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.301+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.301+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.301+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.301+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.302+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.302+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.302+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.302+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.303+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.303+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.303+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.303+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.366+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.367+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.367+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.367+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 0, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.367+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.367+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.368+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.368+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 3, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.368+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.368+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.368+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.368+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 6, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.368+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 1, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 4, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.369+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.370+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.370+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.370+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 1, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.370+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.370+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.371+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.371+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.371+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.371+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 2, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.371+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.371+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.371+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.372+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.372+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
2024-04-18T13:06:24.372+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 5, "height": 7329, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T13:06:24.372+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 7329, "view": 0, "cache": {}}
INFO [04-18|13:06:25.067] Imported new chain segment               number=7329 hash=5c7748..f5d869 blocks=1    txs=0  mgas=0.000 elapsed=9.798ms     mgasps=0.000  snapdiffs=47.61KiB triedirty=0.00B
INFO [04-18|13:06:25.067] New block in the chain                   "dbft index"=1 "chain index"=7329 hash=0x5c7748c9b762ce92ee672f48fdb6e5bc58b0cf800b9d8add118a36b282f5d869 "parent hash"=0x4576b8bd59a3a56d39ea99cac9452a328c2cdbedb28e8a004fd1467646d9bdcc primary=0 coinbase=0x1212000000000000000000000000000000000003 "mix digest"=0x23c10fa9c1fae49f6139db9c3ecff715de101c145e56e4d390a16e67d5c32e1b
INFO [04-18|13:06:25.068] Fetching latest sealing proposal         "desired number"=7330
INFO [04-18|13:06:25.068] Commit new sealing work                  number=7330 sealhash=fad20c..9730e6 "parent hash"=5c7748..f5d869 etherbase=0x1212000000000000000000000000000000000003 txs=0  gas=0 fees=0 elapsed="897.438µs"
INFO [04-18|13:06:25.076] Imported new chain segment               number=7329 hash=962827..5a7fa2 blocks=1    txs=0  mgas=0.000 elapsed=8.089ms     mgasps=0.000  snapdiffs=47.61KiB triedirty=0.00B
INFO [04-18|13:06:25.084] Imported new chain segment               number=7329 hash=bda719..74944c blocks=1    txs=0  mgas=0.000 elapsed=8.072ms     mgasps=0.000  snapdiffs=47.61KiB triedirty=0.00B
INFO [04-18|13:06:26.069] Sealing proposal updated                 number=7330 sealhash=fad20c..9730e6 "parent hash"=5c7748..f5d869 txs=0
2024-04-18T13:06:26.070+0300	INFO	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:107	initializing dbft	{"height": 7330, "view": 0, "index": -1, "role": "WatchOnly"}
2024-04-18T13:06:35.667+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 1, "height": 7330, "view": 0, "my_height": 7330, "my_view": 0}
INFO [04-18|13:06:35.670] New parent stored                        number=7329 "old hash"=0x5c7748c9b762ce92ee672f48fdb6e5bc58b0cf800b9d8add118a36b282f5d869 "new hash"=0x9628275d4952bd9f0a89f63bf963eb137fc84bd28e8f8b9352cf20a1345a7fa2 sealhash=0x8370d030b858ccd87751159f5a5e12686072ecc55d92a34a12269f4d08d63c99 "old extra"=0001b517b301bb143476da35bb4a1399500d9255144ea2a4697d40247c8be1f2b9ffa03a0e92dcbacc7976ad987d572377d39fb4bab86c80e08b6f8327a51fe05b0183d01607bf48c1718d1168a1c11171cbbeca26e89011e32ba25610520b20741b809007d10f47396dc6c76ad53546158751582d3e2683efd711da2d8c71a801fc351163337656f1321343a009ae820127dfb884ca3cec5d3e59c53d0229289f6bde6fa7f0b64069d7e5bb13086000278257edb054425c4b1f66876f06238fd05ca1b69f96636bcb0bb85d2c01570e65653a44e378399f668b89a1a021dd23bc27cdd8f25afbf28fa2f726e9b7704adbfd0c09fd8439b2dc6135eb9bfe5a4047b93241e74b2c84c485858ccb160002aa1c282da5d2c85f8e15d7a47ac73b7fb259fa22fd9ebb6e5fb07a0a3d758529edda010e74f5ad4459a7b0eaae48c55916d641bfc84e0a1e6b7ac04956bc1c0108b6cbb571a223ee2ac798657f40a68991d987d38c9d298500577247bba5377831f91a1f0ffe6a28308fd068ec9ae69536cef6d9bd21650dcbfe4e18a113ec500060b3038db540baa5e23728d055a5d054ee7e1394bf3b880d96f3e95bf735fbd9273606e2a54d25047a29d6fb3ef78d2c459c2c6cb4b84a937b8738c760c442ef01 "new extra"=0001b517b301bb143476da35bb4a1399500d9255144ea2a4697d40247c8be1f2b9ffa03a0e92dcbacc7976ad987d572377d39fb4bab86c80e08b6f8327a51fe05b0183d01607bf48c1718d1168a1c11171cbbeca26e89011e32ba25610520b20741b809007d10f47396dc6c76ad53546158751582d3e2683efd711da2d8c71a801fc351163337656f1321343a009ae820127dfb884ca3cec5d3e59c53d0229289f6bde6fa7f0b64069d7e5bb13086000278257edb054425c4b1f66876f06238fd05ca1b69f96636bcb0bb85d2c01570e65653a44e378399f668b89a1a021dd23bc27cdd8f25afbf28fa2f726e9b7704adbfd0c09fd8439b2dc6135eb9bfe5a4047b93241e74b2c84c485858ccb160008b6cbb571a223ee2ac798657f40a68991d987d38c9d298500577247bba5377831f91a1f0ffe6a28308fd068ec9ae69536cef6d9bd21650dcbfe4e18a113ec5000bd3144f1dfb10444d0f6e6043e307bdd434527f5cabb657ad82a6d01ce6bf81a69126b18ccec66fdcf118dc43d08afe220b7e623d8555e5883608d85b8603e630160b3038db540baa5e23728d055a5d054ee7e1394bf3b880d96f3e95bf735fbd9273606e2a54d25047a29d6fb3ef78d2c459c2c6cb4b84a937b8738c760c442ef01
2024-04-18T13:06:35.670+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:325	received empty PrepareRequest
2024-04-18T13:06:35.670+0300	INFO	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:333	received PrepareRequest	{"validator": 1, "tx": 0}
2024-04-18T13:06:35.672+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareResponse", "from": 5, "height": 7330, "view": 0, "my_height": 7330, "my_view": 0}

@AnnaShaleva
Copy link
Member Author

@roman-khimov, ready for review and merge.

@AnnaShaleva
Copy link
Member Author

@chenquanyu, @txhsl, @nicolegys welcome to review and test, the problem is described in the commit message, but in short there's a possibility that stale CN node will hang during the sync process without this patch.

Copy link
Contributor

@roman-khimov roman-khimov left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Does this affect initial sync only or can also happen on a synchronised node that was disconnected for some time?

c.requestTxs = f
}

// WithMinerInterrupted sets callback to indicate whether miner is interrupted sue
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

due

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Fixed.

// sync. In this case dBFT can't react properly on the newcoming blocks since no
// sealing task is expected from miner.
if c.minerInterrupted() {
log.Info("Skip chain block processing since miner is interrupted",
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

This would look scary in logs. And it's a perfectly fine condition, not requiring "info".

Copy link
Member Author

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Fixed.

@AnnaShaleva
Copy link
Member Author

AnnaShaleva commented Apr 18, 2024

Does this affect initial sync only or can also happen on a synchronised node that was disconnected for some time?

It affects any sync initiated by Downloader, since miner is tightly bound to the Downloader events and miner aways interrupts his work if Downloader is started:

go-ethereum/miner/miner.go

Lines 128 to 137 in 9faaba0

case downloader.StartEvent:
wasMining := miner.Mining()
miner.worker.stop()
canStart = false
if wasMining {
// Resume mining after sync was finished
shouldStart = true
log.Info("Mining aborted due to sync")
}
miner.worker.syncing.Store(true)

Right now I'm checking the latter case, will post the result here.

@AnnaShaleva
Copy link
Member Author

Right now I'm checking the latter case, will post the result here.

OK, the node needs to be too stale for Downloader to be enabled (at least at the previous snap-sync block, AFAIU), so can't check it right now:

2024-04-18T17:20:32.189+0300	INFO	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:437	received PrepareResponse	{"validator": 4}

<--- here the node is disconnected from the network

INFO [04-18|17:21:02.188] Looking for peers                        peercount=2 tried=0 static=0
INFO [04-18|17:22:02.066] NAT mapped alternative port              proto=UDP extport=0 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|17:22:02.067] NAT mapped alternative port              proto=TCP extport=0 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|17:22:02.581] New local node record                    seq=1,713,434,745,848 id=78f20fc0f85e3d82 ip=172.30.218.222 udp=0     tcp=0
INFO [04-18|17:24:02.066] NAT mapped alternative port              proto=TCP extport=0 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|17:24:02.067] NAT mapped alternative port              proto=UDP extport=0 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|17:26:02.487] NAT mapped port                          proto=TCP extport=30301 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|17:26:02.738] New local node record                    seq=1,713,434,745,849 id=78f20fc0f85e3d82 ip=172.30.218.222 udp=0     tcp=30301
INFO [04-18|17:26:02.901] NAT mapped port                          proto=UDP extport=30301 intport=30301 interface="UPNP IGDv1-IP1"
INFO [04-18|17:26:03.740] New local node record                    seq=1,713,434,745,850 id=78f20fc0f85e3d82 ip=172.30.218.222 udp=30301 tcp=30301

<--- here the node is connected back

INFO [04-18|17:35:16.103] Looking for peers                        peercount=0 tried=0 static=0
INFO [04-18|17:35:20.462] Imported new chain segment               number=8769 hash=9df9d5..06b863 blocks=78   txs=0  mgas=0.000 elapsed=180.665ms   mgasps=0.000 snapdiffs=67.11KiB triedirty=0.00B
INFO [04-18|17:35:20.463] New block in the chain                   "dbft index"=8692 "chain index"=8769 hash=0x9df9d583f4ea41af61cf7e63c89b93235d019e6d371e2a39f01d338bb306b863 "parent hash"=0xface93c73d3a9ef1c0b6e1d398ee560c54d666dbe90124a92740d867eb67e731 primary=5 coinbase=0x1212000000000000000000000000000000000003 "mix digest"=0x23c10fa9c1fae49f6139db9c3ecff715de101c145e56e4d390a16e67d5c32e1b
INFO [04-18|17:35:20.463] Fetching latest sealing proposal         "desired number"=8770
INFO [04-18|17:35:20.464] Commit new sealing work                  number=8770 sealhash=4a3138..f83916 "parent hash"=9df9d5..06b863 etherbase=0x1212000000000000000000000000000000000003 txs=0  gas=0 fees=0 elapsed=1.148ms
INFO [04-18|17:35:20.628] Imported new chain segment               number=8771 hash=532c60..021199 blocks=2    txs=0  mgas=0.000 elapsed=13.995ms    mgasps=0.000 snapdiffs=67.11KiB triedirty=0.00B
INFO [04-18|17:35:20.628] Commit new sealing work                  number=8772 sealhash=01e3a0..2fd111 "parent hash"=532c60..021199 etherbase=0x1212000000000000000000000000000000000003 txs=0  gas=0 fees=0 elapsed="295.447µs"
INFO [04-18|17:35:21.464] New chain segment detected               "dBFT latest block index"=8769 "sealing proposal index"=8772
INFO [04-18|17:35:21.464] Sealing proposal updated                 number=8772 sealhash=01e3a0..2fd111 "parent hash"=532c60..021199 txs=0
2024-04-18T17:35:21.464+0300	INFO	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:107	initializing dbft	{"height": 8772, "view": 0, "index": -1, "role": "WatchOnly"}
2024-04-18T17:35:26.129+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "PrepareRequest", "from": 1, "height": 8772, "view": 0, "my_height": 8772, "my_view": 0}
INFO [04-18|17:35:26.143] Imported new chain segment               number=8771 hash=08eb8b..faeb14 blocks=1    txs=0  mgas=0.000 elapsed=12.258ms    mgasps=0.000 snapdiffs=67.11KiB triedirty=0.00B
INFO [04-18|17:35:26.143] Successfully inserted new block          number=8771 hash=08eb8b..faeb14

Will create an issue to check it, but the fix should work in this case as far.

If node is stale and far behind its peers, mining is aborted by the
signal of Downloader. If mining is aborted, then sealing task won't be
submitted to the consensus engine, and thus, dBFT awaiting for sealing
task may hang forever in case of multiple block batch processing by
Downloader. It may happen because dBFT's block notificaiton channel has
buffer size of 2 and block notification processing is managed by the
same eventLoop as sealing tasks awaiting. So dBFT blocks notifications
processing by sealing task awaiting, and thus, blocks the whole chain
from the further sync process:
```
2024-04-18T10:26:44.856+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 6470, "view": 0, "cache": {}}
2024-04-18T10:26:45.264+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:214	received message	{"type": "Commit", "from": 4, "height": 6470, "view": 0, "my_height": 1, "my_view": 0}
2024-04-18T10:26:45.265+0300	DEBUG	dbft@v0.0.0-20240130131324-6d59675ad370/dbft.go:229	caching message from future	{"height": 6470, "view": 0, "cache": {}}
INFO [04-18|10:26:50.148] Imported new chain segment               number=192 hash=775270..7b8b06 blocks=192 txs=0 mgas=0.000 elapsed=562.005ms   mgasps=0.000 age=19h32m52s triedirty=0.00B
INFO [04-18|10:26:50.149] New block in the chain                   "dbft index"=1 "chain index"=192 hash=0x77527025ea64a0ceb26235efaf6ddc5430d0e2de15757869da2e90a1c57b8b06 "parent hash"=0xa9cde87fe65f34bf1f40982015e1a7e73b117a80fadf707b0deda67a34855092 primary=3 coinbase=0x1212000000000000000000000000000000000003 "mix digest"=0x23c10fa9c1fae49f6139db9c3ecff715de101c145e56e4d390a16e67d5c32e1b
INFO [04-18|10:26:50.149] Fetching latest sealing proposal         "desired number"=193
INFO [04-18|10:26:50.152] Indexed transactions                     blocks=193 txs=0 tail=0 elapsed=3.786ms
INFO [04-18|10:26:50.770] Imported new chain segment               number=384 hash=a5dac5..02375b blocks=192 txs=0 mgas=0.000 elapsed=472.546ms   mgasps=0.000 age=18h57m12s triedirty=0.00B
INFO [04-18|10:26:51.344] Imported new chain segment               number=576 hash=1d8a8e..3e8def blocks=192 txs=0 mgas=0.000 elapsed=570.798ms   mgasps=0.000 age=18h21m29s triedirty=0.00B
INFO [04-18|10:26:52.221] Imported new chain segment               number=960 hash=6fde0c..d75d78 blocks=384 txs=0 mgas=0.000 elapsed=868.154ms   mgasps=0.000 age=17h10m6s  triedirty=0.00B
INFO [04-18|10:27:07.683] Looking for peers                        peercount=1 tried=1 static=0
```

To fix this problem we have to omit block notifications processing until
the node sync process end, i.e. util the moment miner resumes its work.

Signed-off-by: Anna Shaleva <shaleva.ann@nspcc.ru>
@AnnaShaleva AnnaShaleva merged commit 9ffc8c9 into bane-main Apr 18, 2024
@AnnaShaleva AnnaShaleva deleted the fix-sync branch April 18, 2024 15:33
@txhsl txhsl mentioned this pull request Jul 15, 2025
5 tasks
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Labels

bug Something isn't working dBFT Everything related to dBFT module integration

Projects

None yet

Development

Successfully merging this pull request may close these issues.

3 participants