Skip to content
This repository has been archived by the owner on Nov 15, 2023. It is now read-only.

Invalid justification provided #4678

Closed
AurevoirXavier opened this issue Jan 20, 2020 · 21 comments · Fixed by #8698
Closed

Invalid justification provided #4678

AurevoirXavier opened this issue Jan 20, 2020 · 21 comments · Fixed by #8698

Comments

@AurevoirXavier
Copy link
Contributor

Sometimes, the nodes report Invalid justification provided then it will fall behind the latest node few blocks. And I've to restart those node to (re)participate in validating.

@bkchr
Copy link
Member

bkchr commented Jan 20, 2020

CC @andresilva

@andresilva
Copy link
Contributor

Could you provide some more information? What version of substrate are you using?

Recently we added more logging for this, if you're running with a reasonably up-to-date master it would be useful to collect logs with -lafg=trace,sync=trace.

@AurevoirXavier
Copy link
Contributor Author

AurevoirXavier commented Jan 23, 2020

I've to rm -rf db. Restart doesn't work now.

substrate version: d2c4b0d

0|darwinia  | 2020-01-23 23:04:23.139 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.218 main-tokio-1 TRACE sync  BlockResponse 13 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.218 main-tokio-1 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.218 main-tokio-1 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.218 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.218 main-tokio-1 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.218 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.219 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.219 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.298 main-tokio-0 TRACE sync  BlockResponse 14 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.298 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.298 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.298 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.298 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.298 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.299 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.299 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.378 main-tokio-1 TRACE sync  BlockResponse 15 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.378 main-tokio-1 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.378 main-tokio-1 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.378 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.378 main-tokio-1 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.378 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.379 main-tokio-1 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.379 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.458 main-tokio-0 TRACE sync  BlockResponse 16 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.458 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.458 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.458 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.458 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.458 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.459 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.459 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.538 main-tokio-1 TRACE sync  BlockResponse 17 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.538 main-tokio-1 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.538 main-tokio-1 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.538 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.538 main-tokio-1 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.538 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.539 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.539 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.618 main-tokio-0 TRACE sync  BlockResponse 18 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.618 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.618 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.618 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.618 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.618 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.619 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.619 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.619 main-tokio-1 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.619 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.698 main-tokio-0 TRACE sync  BlockResponse 19 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.698 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.698 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.698 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.698 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.698 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.698 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.699 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.778 main-tokio-1 TRACE sync  BlockResponse 20 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.778 main-tokio-1 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.778 main-tokio-1 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.778 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.778 main-tokio-1 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.778 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.779 main-tokio-1 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.779 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.866 main-tokio-0 TRACE sync  BlockResponse 21 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.866 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.866 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.866 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.866 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.866 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.866 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.867 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.867 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.867 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:23.946 main-tokio-0 TRACE sync  BlockResponse 22 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.946 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:23.946 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:23.946 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:23.946 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:23.946 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:23.947 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:23.947 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.026 main-tokio-1 TRACE sync  BlockResponse 23 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.026 main-tokio-1 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:24.026 main-tokio-1 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:24.026 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:24.026 main-tokio-1 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:24.026 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.027 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:24.027 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:24.027 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.027 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:24.027 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:24.027 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:24.027 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.106 main-tokio-1 TRACE sync  BlockResponse 24 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.106 main-tokio-1 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:24.106 main-tokio-1 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:24.106 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:24.106 main-tokio-1 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:24.106 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:24.107 main-tokio-1 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:24.107 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.186 main-tokio-0 TRACE sync  BlockResponse 25 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.186 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:24.186 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:24.186 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:24.186 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:24.186 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:24.187 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:24.187 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.266 main-tokio-0 TRACE sync  BlockResponse 26 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.266 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:24.266 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:24.266 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567863, common:567859) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567860), to: None, direction: Ascending, max: Some(4) }
0|darwinia  | 2020-01-23 23:04:24.266 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:24.266 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:24.267 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:24.267 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.271 main-tokio-1 TRACE sync  Connecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:24.272 main-tokio-1 TRACE sync  New peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567863, best_hash: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:24.272 main-tokio-1 DEBUG sync  Connected QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:24.272 main-tokio-1 DEBUG sync  New peer with known best hash 0x1851…f041 (567863).
0|darwinia  | 2020-01-23 23:04:24.272 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.320 main-tokio-0 DEBUG sync  Received block announcement 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693 with number 567864 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:24.321 main-tokio-0 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:24.321 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.334 main-tokio-0 TRACE sync  BlockResponse 27 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.334 main-tokio-0 TRACE sync  Drained 4 blocks
0|darwinia  | 2020-01-23 23:04:24.334 main-tokio-0 TRACE sync  Accepted 4 blocks (0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041) with origin NetworkInitialSync
0|darwinia  | 2020-01-23 23:04:24.334 main-tokio-0 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:24.334 main-tokio-0 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567864, common:567863) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567864), to: None, direction: Ascending, max: Some(1) }
0|darwinia  | 2020-01-23 23:04:24.334 main-tokio-0 TRACE sync  Scheduling 4 blocks for import
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Starting import of 4 blocks  (567860..567863)
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Header 0xa4f4…8294 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block already in chain 567860: 0xa4f49a5c62f6ac377478b2411ad51f08709144dced4be0819b8275da41ec8294
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block imported successfully Some(567860) (0xa4f4…8294)
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Header 0x701c…638e has 2 logs
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block already in chain 567861: 0x701c78cd0ed66e998ba678d2ece938ef3740da2f60d678e9b0426cd7e73c638e
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block imported successfully Some(567861) (0x701c…638e)
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Header 0xbc43…a8d9 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block already in chain 567862: 0xbc4313f06440a969c0ae1ca914079377f2a1f8bc67ae0d4b5d5097139d7fa8d9
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block imported successfully Some(567862) (0xbc43…a8d9)
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Header 0x1851…f041 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block already in chain 567863: 0x185119e0909309c63762d7f2bd4fa7ae1231fc5e68db0681c492b264d5eaf041
0|darwinia  | 2020-01-23 23:04:24.334 import-queue-worker-0 TRACE sync  Block imported successfully Some(567863) (0x1851…f041)
0|darwinia  | 2020-01-23 23:04:24.335 main-tokio-0 TRACE sync  Imported 4 of 4
0|darwinia  | 2020-01-23 23:04:24.335 main-tokio-0 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:24.335 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.415 main-tokio-0 TRACE afg  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:24.416 main-tokio-0 TRACE sync  BlockResponse 0 from QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ with 1 blocks
0|darwinia  | 2020-01-23 23:04:24.416 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.416 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:24.416 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ")
0|darwinia  | 2020-01-23 23:04:24.417 main-tokio-0 INFO substrate_network::service  Invalid justification provided by QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:24.418 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.418 main-tokio-0 TRACE sync  Disconnecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:24.419 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.455 main-tokio-0 TRACE sync  BlockResponse 28 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks  (567864)
0|darwinia  | 2020-01-23 23:04:24.455 main-tokio-0 TRACE sync  Drained 1 blocks
0|darwinia  | 2020-01-23 23:04:24.455 main-tokio-0 TRACE sync  Accepted 1 blocks (0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693) with origin NetworkBroadcast
0|darwinia  | 2020-01-23 23:04:24.455 main-tokio-0 TRACE sync  Updating peer QmVrNxamzTSURX7ThHWmikZtGj74utPML6LdHfXdvDdVi3 info, ours=567864, common=485503->485503, their best=485503
0|darwinia  | 2020-01-23 23:04:24.455 main-tokio-0 TRACE sync  Updating peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU info, ours=567864, common=567863->567864, their best=567864
0|darwinia  | 2020-01-23 23:04:24.455 main-tokio-0 TRACE sync  Scheduling 1 blocks for import
0|darwinia  | 2020-01-23 23:04:24.455 import-queue-worker-0 TRACE sync  Starting import of 1 blocks  (567864)
0|darwinia  | 2020-01-23 23:04:24.455 import-queue-worker-0 TRACE sync  Header 0xac67…4693 has 2 logs
0|darwinia  | 2020-01-23 23:04:24.459 import-queue-worker-0 TRACE sync  Block imported successfully Some(567864) (0xac67…4693)
0|darwinia  | 2020-01-23 23:04:24.459 main-tokio-1 TRACE afg  Polling round 1, state = State { prevote_ghost: None, finalized: None, estimate: None, completable: false }, step = Some(Prevoted)
0|darwinia  | 2020-01-23 23:04:24.460 main-tokio-1 INFO substrate  Imported #567864 (0xac67…4693)
0|darwinia  | 2020-01-23 23:04:24.459 main-tokio-0 TRACE sync  Announcing block 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693 to QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:24.460 main-tokio-0 TRACE sync  Announcing block 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693 to QmVrNxamzTSURX7ThHWmikZtGj74utPML6LdHfXdvDdVi3
0|darwinia  | 2020-01-23 23:04:24.460 main-tokio-0 TRACE sync  Imported 1 of 1
0|darwinia  | 2020-01-23 23:04:24.460 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:24.575 main-tokio-1 TRACE sync  BlockResponse 29 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks
0|darwinia  | 2020-01-23 23:04:24.575 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:24.575 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU")
0|darwinia  | 2020-01-23 23:04:24.575 main-tokio-1 INFO substrate_network::service  Invalid justification provided by QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:24.575 main-tokio-0 TRACE sync  Disconnecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:25.018 main-tokio-1 TRACE sync  Connecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:25.018 main-tokio-1 TRACE sync  New peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567864, best_hash: 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:25.018 main-tokio-1 DEBUG sync  Connected QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:25.019 main-tokio-1 DEBUG sync  New peer with known best hash 0xac67…4693 (567864).
0|darwinia  | 2020-01-23 23:04:25.093 main-tokio-0 TRACE sync  Connecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:25.094 main-tokio-0 TRACE sync  New peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567864, best_hash: 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:25.094 main-tokio-0 DEBUG sync  Connected QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:25.094 main-tokio-0 DEBUG sync  New peer with known best hash 0xac67…4693 (567864).
0|darwinia  | 2020-01-23 23:04:25.094 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:25.134 main-tokio-0 TRACE afg  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:25.134 main-tokio-0 TRACE sync  BlockResponse 0 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks
0|darwinia  | 2020-01-23 23:04:25.135 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:25.135 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU")
0|darwinia  | 2020-01-23 23:04:25.135 main-tokio-0 INFO substrate_network::service  Invalid justification provided by QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:25.136 main-tokio-0 TRACE sync  Disconnecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:25.136 main-tokio-0 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:25.206 main-tokio-0 TRACE afg  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:25.207 main-tokio-0 TRACE sync  BlockResponse 0 from QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ with 1 blocks
0|darwinia  | 2020-01-23 23:04:25.207 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:25.207 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ")
0|darwinia  | 2020-01-23 23:04:25.207 main-tokio-0 INFO substrate_network::service  Invalid justification provided by QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:25.207 main-tokio-0 TRACE sync  Disconnecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:25.838 main-tokio-0 DEBUG sync  Propagating extrinsics
0|darwinia  | 2020-01-23 23:04:25.933 main-tokio-1 TRACE sync  Connecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:25.933 main-tokio-1 TRACE sync  New peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567864, best_hash: 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:25.933 main-tokio-1 DEBUG sync  Connected QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:25.933 main-tokio-1 DEBUG sync  New peer with known best hash 0xac67…4693 (567864).
0|darwinia  | 2020-01-23 23:04:26.049 main-tokio-1 TRACE afg  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:26.049 main-tokio-1 TRACE sync  BlockResponse 0 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks
0|darwinia  | 2020-01-23 23:04:26.049 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:26.049 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU")
0|darwinia  | 2020-01-23 23:04:26.049 main-tokio-1 INFO substrate_network::service  Invalid justification provided by QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:26.050 main-tokio-1 TRACE sync  Disconnecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:26.065 main-tokio-1 TRACE sync  Connecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:26.065 main-tokio-1 TRACE sync  New peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567864, best_hash: 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:26.065 main-tokio-1 DEBUG sync  Connected QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:26.065 main-tokio-1 DEBUG sync  New peer with known best hash 0xac67…4693 (567864).
0|darwinia  | 2020-01-23 23:04:26.086 main-tokio-0 TRACE afg  Polling round 1, state = State { prevote_ghost: None, finalized: None, estimate: None, completable: false }, step = Some(Prevoted)
0|darwinia  | 2020-01-23 23:04:26.182 main-tokio-0 INFO substrate  Idle (2 peers), best: #567864 (0xac67…4693), finalized #332569 (0x05db…16de), ⬇ 17.4kiB/s ⬆ 12.4kiB/s
0|darwinia  | 2020-01-23 23:04:26.182 main-tokio-1 TRACE afg  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:26.183 main-tokio-1 TRACE sync  BlockResponse 0 from QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ with 1 blocks
0|darwinia  | 2020-01-23 23:04:26.183 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:26.184 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ")
0|darwinia  | 2020-01-23 23:04:26.184 main-tokio-1 INFO substrate_network::service  Invalid justification provided by QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:26.184 main-tokio-1 TRACE sync  Disconnecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:26.613 main-tokio-0 TRACE sync  Connecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:26.613 main-tokio-0 TRACE sync  New peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567864, best_hash: 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:26.613 main-tokio-0 DEBUG sync  Connected QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:26.613 main-tokio-0 DEBUG sync  New peer with known best hash 0xac67…4693 (567864).
0|darwinia  | 2020-01-23 23:04:26.700 main-tokio-0 TRACE afg  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:26.700 main-tokio-0 TRACE sync  BlockResponse 0 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks
0|darwinia  | 2020-01-23 23:04:26.701 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:26.701 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU")
0|darwinia  | 2020-01-23 23:04:26.701 main-tokio-0 INFO substrate_network::service  Invalid justification provided by QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:26.701 main-tokio-0 TRACE sync  Disconnecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:27.102 main-tokio-1 TRACE sync  Connecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:27.103 main-tokio-1 TRACE sync  New peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567865, best_hash: 0x8c58d85aeb4ee8d2367ebd5446ed2e27351428a936af010fa68c7d3be08fb031, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:27.103 main-tokio-1 DEBUG sync  Connected QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:27.104 main-tokio-1 DEBUG sync  New peer with unknown best hash 0x8c58…b031 (567865), searching for common ancestor.
0|darwinia  | 2020-01-23 23:04:27.104 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:27.137 main-tokio-1 TRACE sync  Connecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:27.137 main-tokio-1 TRACE sync  New peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567864, best_hash: 0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:27.137 main-tokio-1 DEBUG sync  Connected QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:27.137 main-tokio-1 DEBUG sync  New peer with known best hash 0xac67…4693 (567864).
0|darwinia  | 2020-01-23 23:04:27.137 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:27.217 main-tokio-1 TRACE afg  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:27.217 main-tokio-1 TRACE sync  BlockResponse 0 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks  (567864)
0|darwinia  | 2020-01-23 23:04:27.217 main-tokio-1 TRACE sync  Got ancestry block #567864 (0xac67…4693) from peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:27.217 main-tokio-1 TRACE sync  Ancestry search complete. Ours=0xac67…4693 (567864), Theirs=0x8c58…b031 (567865), Common=Some(0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693) (567864)
0|darwinia  | 2020-01-23 23:04:27.217 main-tokio-1 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:27.217 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567865, common:567864) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567865), to: None, direction: Ascending, max: Some(1) }
0|darwinia  | 2020-01-23 23:04:27.218 main-tokio-1 TRACE sync  Disconnecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:27.218 main-tokio-1 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:27.244 main-tokio-1 TRACE afg  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:27.244 main-tokio-1 TRACE sync  BlockResponse 0 from QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ with 1 blocks
0|darwinia  | 2020-01-23 23:04:27.244 import-queue-worker-0 DEBUG afg  Bad signature on message from Public(443995be13a38fe2876f44be5a8883d068e9a77d19ce4c22dafaf461b90a7cf4 (5DcADh3n...))
0|darwinia  | 2020-01-23 23:04:27.244 import-queue-worker-0 DEBUG sync  Justification import failed with ClientImport("bad justification for header: invalid signature for precommit in grandpa justification") for hash: 0x0684b03ac3b864a18e30eb9770b892b752c126c13d6af22ee325aee94b7b2adb number: 564786 coming from node: PeerId("QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ")
0|darwinia  | 2020-01-23 23:04:27.244 main-tokio-1 INFO substrate_network::service  Invalid justification provided by QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ for #0x0684…2adb
0|darwinia  | 2020-01-23 23:04:27.245 main-tokio-0 TRACE sync  Disconnecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.036 main-tokio-1 TRACE sync  Connecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.037 main-tokio-1 TRACE sync  New peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567865, best_hash: 0x8c58d85aeb4ee8d2367ebd5446ed2e27351428a936af010fa68c7d3be08fb031, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:28.037 main-tokio-1 DEBUG sync  Connected QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.037 main-tokio-1 DEBUG sync  New peer with unknown best hash 0x8c58…b031 (567865), searching for common ancestor.
0|darwinia  | 2020-01-23 23:04:28.037 main-tokio-1 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:28.152 main-tokio-1 TRACE afg  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:28.153 main-tokio-1 TRACE sync  BlockResponse 0 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks  (567864)
0|darwinia  | 2020-01-23 23:04:28.153 main-tokio-1 TRACE sync  Got ancestry block #567864 (0xac67…4693) from peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.153 main-tokio-1 TRACE sync  Ancestry search complete. Ours=0xac67…4693 (567864), Theirs=0x8c58…b031 (567865), Common=Some(0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693) (567864)
0|darwinia  | 2020-01-23 23:04:28.153 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567865, common:567864) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567865), to: None, direction: Ascending, max: Some(1) }
0|darwinia  | 2020-01-23 23:04:28.154 main-tokio-1 TRACE sync  Disconnecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.171 main-tokio-1 TRACE sync  Connecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.171 main-tokio-1 TRACE sync  New peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567865, best_hash: 0x8c58d85aeb4ee8d2367ebd5446ed2e27351428a936af010fa68c7d3be08fb031, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:28.171 main-tokio-1 DEBUG sync  Connected QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.171 main-tokio-1 DEBUG sync  New peer with unknown best hash 0x8c58…b031 (567865), searching for common ancestor.
0|darwinia  | 2020-01-23 23:04:28.171 main-tokio-1 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:28.194 main-tokio-1 TRACE sync  Connecting QmW6BapsGSjSCgfr8wMMJPF45EPwH94xfSfW7ZNjzmzqEs

0|darwinia  | 2020-01-23 23:04:28.286 main-tokio-1 TRACE afg  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:28.286 main-tokio-1 TRACE sync  BlockResponse 0 from QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ with 1 blocks  (567864)
0|darwinia  | 2020-01-23 23:04:28.286 main-tokio-1 TRACE sync  Got ancestry block #567864 (0xac67…4693) from peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.286 main-tokio-1 TRACE sync  Ancestry search complete. Ours=0xac67…4693 (567864), Theirs=0x8c58…b031 (567865), Common=Some(0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693) (567864)
0|darwinia  | 2020-01-23 23:04:28.286 main-tokio-1 TRACE sync  New block request for QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ, (best:567865, common:567864) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567865), to: None, direction: Ascending, max: Some(1) }
0|darwinia  | 2020-01-23 23:04:28.287 main-tokio-1 TRACE sync  Disconnecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.335 main-tokio-1 TRACE sync  Disconnecting QmW6BapsGSjSCgfr8wMMJPF45EPwH94xfSfW7ZNjzmzqEs


0|darwinia  | 2020-01-23 23:04:28.725 main-tokio-0 TRACE sync  Connecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.726 main-tokio-0 TRACE sync  New peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567865, best_hash: 0x8c58d85aeb4ee8d2367ebd5446ed2e27351428a936af010fa68c7d3be08fb031, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:28.726 main-tokio-0 DEBUG sync  Connected QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.726 main-tokio-0 DEBUG sync  New peer with unknown best hash 0x8c58…b031 (567865), searching for common ancestor.
0|darwinia  | 2020-01-23 23:04:28.726 main-tokio-0 TRACE sync  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU is busy
0|darwinia  | 2020-01-23 23:04:28.738 main-tokio-1 DEBUG sync  Propagating extrinsics
0|darwinia  | 2020-01-23 23:04:28.841 main-tokio-1 TRACE afg  Peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:28.841 main-tokio-1 TRACE sync  BlockResponse 0 from QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU with 1 blocks  (567864)
0|darwinia  | 2020-01-23 23:04:28.841 main-tokio-1 TRACE sync  Got ancestry block #567864 (0xac67…4693) from peer QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.841 main-tokio-1 TRACE sync  Ancestry search complete. Ours=0xac67…4693 (567864), Theirs=0x8c58…b031 (567865), Common=Some(0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693) (567864)
0|darwinia  | 2020-01-23 23:04:28.842 main-tokio-1 TRACE sync  New block request for QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU, (best:567865, common:567864) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567865), to: None, direction: Ascending, max: Some(1) }
0|darwinia  | 2020-01-23 23:04:28.842 main-tokio-1 TRACE sync  Disconnecting QmQErbTRWF3dnGZtGp58ZEyjCek6PAwftSY5Q9fRdEYWYU
0|darwinia  | 2020-01-23 23:04:28.845 main-tokio-1 TRACE sync  Connecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.845 main-tokio-1 TRACE sync  New peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ Status { version: 5, min_supported_version: 3, roles: AUTHORITY, best_number: 567865, best_hash: 0x8c58d85aeb4ee8d2367ebd5446ed2e27351428a936af010fa68c7d3be08fb031, genesis_hash: 0xa7de3b38d9a0a71cdc15acf5cf7e6d352350e221c5e3222dbba92b6f10497c54, chain_status: [] }
0|darwinia  | 2020-01-23 23:04:28.845 main-tokio-1 DEBUG sync  Connected QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.845 main-tokio-1 DEBUG sync  New peer with unknown best hash 0x8c58…b031 (567865), searching for common ancestor.
0|darwinia  | 2020-01-23 23:04:28.845 main-tokio-1 TRACE sync  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ is busy
0|darwinia  | 2020-01-23 23:04:28.964 main-tokio-1 TRACE afg  Peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ updated view. Now at Round(15), SetId(1506)
0|darwinia  | 2020-01-23 23:04:28.964 main-tokio-1 TRACE sync  BlockResponse 0 from QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ with 1 blocks  (567864)
0|darwinia  | 2020-01-23 23:04:28.964 main-tokio-1 TRACE sync  Got ancestry block #567864 (0xac67…4693) from peer QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
0|darwinia  | 2020-01-23 23:04:28.964 main-tokio-1 TRACE sync  Ancestry search complete. Ours=0xac67…4693 (567864), Theirs=0x8c58…b031 (567865), Common=Some(0xac67605db19d57422e1b076908cc4d45e83f40bff3e6507161933cfc3ea04693) (567864)
0|darwinia  | 2020-01-23 23:04:28.964 main-tokio-1 TRACE sync  New block request for QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ, (best:567865, common:567864) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(567865), to: None, direction: Ascending, max: Some(1) }
0|darwinia  | 2020-01-23 23:04:28.965 main-tokio-1 TRACE sync  Disconnecting QmRbSAYVEUdLD17CaQh6sk3KYeSxJPw2HL4Hr25yMCCfoJ
^C

@vedhavyas
Copy link

@andresilva I have seen this on my Centrifuge flint node that was running rc3 while the other nodes running alpha-2. I have restarted with -lafg=trace,sync=trace. I Will post logs here if I see this again.

@thadouk
Copy link

thadouk commented Mar 29, 2021

So what was the resolution to this? Is there a fix in substrate master?

@andresilva
Copy link
Contributor

Yes, there were multiple fixes targeting this issue. If you encounter it again with a recent master feel free to drop some logs here.

@thadouk
Copy link

thadouk commented Mar 29, 2021

Yes, there were multiple fixes targeting this issue. If you encounter it again with a recent master feel free to drop some logs here.

That is great! At the moment we are at a 2.x branch so I can't test it. What is the issue number so we can track the commits and try to backport them to 2.0.0. Are there any plans to provide this fix to a 2.x branch?

@andresilva
Copy link
Contributor

#7307 #7321 #7322 IIRC

@AurevoirXavier AurevoirXavier reopened this Apr 7, 2021
@AurevoirXavier
Copy link
Contributor Author

AurevoirXavier commented Apr 7, 2021

So what was the resolution to this? Is there a fix in substrate master?

I think this happens again.


I'm on rococo-v1.

Recently this error happens again.

Can be found on our mainnet and testnet.

Once this happen, the peers drop down to 0. Very dangerous.

Restart can fix this. But this should not be happen. Any idea? @andresilva

2021-04-07 05:38:44 💔 Invalid justification provided by 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b for #0x100d…82b7
2021-04-07 05:38:44 Reserved peer 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b disconnected
2021-04-07 05:38:44 💤 Idle (0 peers), best: #9692 (0x7e13…a884), finalized #9527 (0x99f3…77d9), ⬇ 1.2kiB/s ⬆ 0.6kiB/s

@AurevoirXavier
Copy link
Contributor Author

Interesting. Even the peers count is 0.

After a while, it can sync some blocks.

2021-04-07 06:15:55 💤 Idle (0 peers), best: #9947 (0x6f00…e55e), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.3kiB/s
2021-04-07 06:15:55 💔 Invalid justification provided by 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b for #0x100d…82b7
2021-04-07 06:15:55 Reserved peer 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b disconnected
2021-04-07 06:15:55 [#9988] 🗳  Starting signed phase round 57.
2021-04-07 06:15:55 [#9995] 🗳  Starting unsigned phase(true).
2021-04-07 06:15:55 [#10002] 🗳  Finalized election round with compute ElectionCompute::OnChain.
2021-04-07 06:15:55 [10002] 💸 new validator set of size 7 has been processed for era 56
2021-04-07 06:15:55 era_duration: 1074000, living_time: 59069988, total_left: 9691199242872418663, payout_fraction: Perbill(500000000)
2021-04-07 06:16:00 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 12.3kiB/s ⬆ 2.6kiB/s
2021-04-07 06:16:05 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:16:10 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.3kiB/s
2021-04-07 06:16:15 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.6kiB/s ⬆ 0.4kiB/s
2021-04-07 06:16:20 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:16:25 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.8kiB/s ⬆ 1.1kiB/s
2021-04-07 06:16:30 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.3kiB/s
2021-04-07 06:16:35 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:16:40 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:16:45 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 1.5kiB/s ⬆ 1.2kiB/s
2021-04-07 06:16:50 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:16:55 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.2kiB/s
2021-04-07 06:17:00 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:17:05 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:17:10 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:17:15 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.2kiB/s
2021-04-07 06:17:20 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 73 B/s
2021-04-07 06:17:25 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:17:30 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:17:35 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 1.0kiB/s ⬆ 0.7kiB/s
2021-04-07 06:17:40 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:17:45 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:17:50 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:17:55 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:18:00 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 73 B/s
2021-04-07 06:18:05 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.4kiB/s
2021-04-07 06:18:10 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.2kiB/s
2021-04-07 06:18:15 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:18:20 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:18:25 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 1.3kiB/s ⬆ 0.9kiB/s
2021-04-07 06:18:30 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.2kiB/s
2021-04-07 06:18:35 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:18:40 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:18:45 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:18:50 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:18:55 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.2kiB/s
2021-04-07 06:19:00 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:19:05 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.4kiB/s
2021-04-07 06:19:10 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.5kiB/s ⬆ 0.4kiB/s
2021-04-07 06:19:15 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:19:20 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:19:25 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 1.3kiB/s ⬆ 1.0kiB/s
2021-04-07 06:19:30 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 96 B/s ⬆ 44 B/s
2021-04-07 06:19:35 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.1kiB/s
2021-04-07 06:19:40 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 54 B/s ⬆ 61 B/s
2021-04-07 06:19:45 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.6kiB/s ⬆ 0.3kiB/s
2021-04-07 06:19:50 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 98 B/s ⬆ 48 B/s
2021-04-07 06:19:55 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.2kiB/s
2021-04-07 06:20:00 💤 Idle (0 peers), best: #10047 (0x1dec…bdea), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:20:02 💔 Invalid justification provided by 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b for #0x100d…82b7
2021-04-07 06:20:02 Reserved peer 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b disconnected
2021-04-07 06:20:05 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 5.0kiB/s ⬆ 1.0kiB/s
2021-04-07 06:20:10 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:20:15 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 1.0kiB/s ⬆ 0.8kiB/s
2021-04-07 06:20:20 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:20:25 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:20:30 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:20:35 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.2kiB/s
2021-04-07 06:20:40 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:20:45 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 1.0kiB/s ⬆ 0.7kiB/s
2021-04-07 06:20:50 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:20:55 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:21:00 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:21:05 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 1.3kiB/s ⬆ 0.9kiB/s
2021-04-07 06:21:10 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:21:15 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:21:20 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:21:25 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:21:30 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:21:32 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:21:32 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:21:32 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:21:32 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:21:33 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:21:35 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.3kiB/s
2021-04-07 06:21:36 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:21:40 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:21:45 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:21:50 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.2kiB/s
2021-04-07 06:21:55 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:22:00 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:22:05 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 1.2kiB/s ⬆ 0.8kiB/s
2021-04-07 06:22:10 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.2kiB/s
2021-04-07 06:22:15 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.1kiB/s
2021-04-07 06:22:20 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 52 B/s ⬆ 55 B/s
2021-04-07 06:22:25 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.6kiB/s ⬆ 0.2kiB/s
2021-04-07 06:22:30 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 96 B/s ⬆ 42 B/s
2021-04-07 06:22:34 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:34 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:35 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.2kiB/s
2021-04-07 06:22:35 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:35 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:37 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:37 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:40 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 86 B/s
2021-04-07 06:22:41 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:41 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:22:45 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.6kiB/s ⬆ 0.3kiB/s
2021-04-07 06:22:50 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 94 B/s
2021-04-07 06:22:55 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.9kiB/s ⬆ 0.7kiB/s
2021-04-07 06:23:00 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:23:05 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:23:10 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.3kiB/s
2021-04-07 06:23:15 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.1kiB/s
2021-04-07 06:23:20 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0 ⬆ 0
2021-04-07 06:23:25 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:23:30 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 96 B/s ⬆ 44 B/s
2021-04-07 06:23:35 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:23:40 💤 Idle (0 peers), best: #10088 (0xd32e…d36c), finalized #9527 (0x99f3…77d9), ⬇ 52 B/s ⬆ 55 B/s
2021-04-07 06:23:43 💔 Invalid justification provided by 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b for #0x100d…82b7
2021-04-07 06:23:43 Reserved peer 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b disconnected
2021-04-07 06:23:45 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 5.5kiB/s ⬆ 1.6kiB/s
2021-04-07 06:23:50 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:23:55 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:24:00 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:24:05 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.4kiB/s
2021-04-07 06:24:10 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:24:15 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.5kiB/s ⬆ 0.4kiB/s
2021-04-07 06:24:20 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:24:25 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:24:30 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.2kiB/s
2021-04-07 06:24:35 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:24:40 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:24:45 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 1.3kiB/s ⬆ 0.9kiB/s
2021-04-07 06:24:50 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:24:55 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.3kiB/s ⬆ 0.2kiB/s
2021-04-07 06:25:00 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.1kiB/s ⬆ 0.1kiB/s
2021-04-07 06:25:05 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.7kiB/s ⬆ 0.3kiB/s
2021-04-07 06:25:10 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.2kiB/s
2021-04-07 06:25:15 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.3kiB/s
2021-04-07 06:25:20 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:25:20 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:20 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:21 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:21 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:21 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:23 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:23 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:25 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.8kiB/s ⬆ 0.5kiB/s
2021-04-07 06:25:27 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:27 Parsing mdns packet failed: LabelIsNotAscii
2021-04-07 06:25:30 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.1kiB/s
2021-04-07 06:25:35 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 1.1kiB/s ⬆ 0.9kiB/s
2021-04-07 06:25:40 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 56 B/s ⬆ 60 B/s
2021-04-07 06:25:45 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.8kiB/s ⬆ 0.5kiB/s
2021-04-07 06:25:50 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.2kiB/s ⬆ 0.2kiB/s
2021-04-07 06:25:55 💤 Idle (0 peers), best: #10125 (0x0ab1…0120), finalized #9527 (0x99f3…77d9), ⬇ 0.4kiB/s ⬆ 0.4kiB/s
2021-04-07 06:25:55 💔 Invalid justification provided by 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b for #0x100d…82b7
2021-04-07 06:25:55 Reserved peer 12D3KooWENAaAX2i8jhUY8F1YStQDDpfhnicLiExbKkQoGL8f11b disconnected
2021-04-07 06:26:00 💤 Idle (0 peers), best: #10147 (0x2297…8205), finalized #9527 (0x99f3…77d9), ⬇ 6.0kiB/s ⬆ 2.4kiB/s

@andresilva
Copy link
Contributor

andresilva commented Apr 7, 2021

#7640 introduced a migration that had a bug which borked the existing justifications in the database. It was later fixed by #8489. But any node that ran the code from #7640 will have broken its justifications and will be serving invalid justifications.

It could be another issue but this one is a prime suspect.

@AurevoirXavier
Copy link
Contributor Author

#7640 introduced a migration that had a bug which borked the existing justifications in the database. It was later fixed by #8489. But any node that ran the code from #7640 will have broken its justifications and will be serving invalid justifications.

It could be another issue but this one is a prime suspect.

Okay let me update my testnet to #8489.

@andresilva
Copy link
Contributor

Just to be clear #8489 won't fix any justifications that were already borked by #7640. Ideally you'd have some node with a db that didn't run #7640, running #8489 on such a db should make the migration work correctly. You could then replace this db on other nodes that ran #7640 earlier.

@andresilva
Copy link
Contributor

I think if the case was the borked migration then it wouldn't be fixed with restarting the node though (unless this means you connect to some different peer). Is this easily reproducible? I will try to have a look into this in the next days.

@AurevoirXavier
Copy link
Contributor Author

I think if the case was the borked migration then it wouldn't be fixed with restarting the node though (unless this means you connect to some different peer). Is this easily reproducible? I will try to have a look into this in the next days.

PM you at element. I can provide some information

@martinfridrich
Copy link

Hi, we have a justification error. Our bootnodes seem to work ok(sync best and finalized blocks) but our rpc nodes are stuck and won't sync. We try to clear db and resync only from bootnodes but we get a justification error at block 104960.

Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.584  DEBUG tokio-runtime-worker afg: Finalizing blocks up to (104960, 0x7590…69ab)
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.801  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.801  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.801  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.802  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.803  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.804  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: Block imported successfully Some(104960) (0x7590…69ab)
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: Starting import of 128 blocks  (104961..105088)
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: Header 0x0b42…812f has 2 logs
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: Imported 128 of 128
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.805  TRACE tokio-runtime-worker sync: New block request for 12D3KooWH9rsDFq3wo13eKR5PWCvEDieK8uUKd1C1dLQNNxeU5AU, (best:147188, common:107008) BlockRequest { id: 0, fields: HEADER | BODY | JUSTIFICATION, from: Number(107136), to: None, direction: Descending, max: Some(128) }
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.821  TRACE tokio-runtime-worker sync: BlockResponse 0 from 12D3KooWH9rsDFq3wo13eKR5PWCvEDieK8uUKd1C1dLQNNxeU5AU with 128 blocks  (107136..107009)
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.821  TRACE tokio-runtime-worker sync: Reversing incoming block list
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Drained 128 blocks
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Accepted 128 blocks (0x42104a1fd0785aeae69e4faeb8f1b8ae40dc9b5d436f4373c1b4ed2d0155f44a) with origin NetworkInitialSync
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Updating peer 12D3KooWH9rsDFq3wo13eKR5PWCvEDieK8uUKd1C1dLQNNxeU5AU info, ours=107136, common=107008->107136, their best=147188
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Scheduling 128 blocks for import
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.822  TRACE tokio-runtime-worker sync: Too many blocks in the queue.
Apr 26 17:46:51 snakenet-gen2-rpc-02 hydra-dx[33450]: 2021-04-26 17:46:51.824  TRACE tokio-runtime-worker sync: Block imported successfully Some(104961) (0x0b42…812f)

Any idea how to fix this? We have lot of nodes in network.

@andresilva
Copy link
Contributor

The logs you posted don't show anything related to finality. Could you have a look at my earlier comments about the borked db migration to see if they apply to your node? Can you replicate the issue if you don't sync just from your bootnodes but instead sync from other nodes in the network (if it works this would be an indication that your bootnodes' db is borked)?

There is a pending issue with justifications that can lead to the Invalid justification provided error that was reported originally in this issue, but this only happens on validator nodes which is not your case.

@martinfridrich
Copy link

thanks. We don't have a problem with finality, the network continues to produce and finalize blocks, but some nodes, including our rpc and archive nodes are stuck at different finalized blocks than the rest of the network. When we try to re-sync our rpc nodes we get Invalid justification provided. We tried to sync from all nodes in the network and the result was the same. Sometimes it stuck at different finalized block, but it stuck.

Our bootnodes were first validators before opening staking. Now they are not selected to validator set but they are still running as validator

@martinfridrich
Copy link

I've looked at logs once again and I sent you the wrong part, sorry. Full log is too big for github so if there is something specific what should I search for, please let me know. Thanks for help

rpc.log

@andresilva
Copy link
Contributor

andresilva commented Apr 27, 2021

@martinfridrich I synced your chain to look into the problem. The issue is related to forced changes so it's not related to the issue that was previously found here. From my analysis block #105469 created a forced changed which stated that the best finalized block was #103166 when in fact the best finalized block was at least #104960, this caused different nodes to have inconsistent views of what the actual set id is which leads to justifications not being able to be verified. Any node syncing the chain will now not be able to validate finality moving forward. I can't figure out everything about the current state as the nodes that are actually finalizing have a different view of what happened that I can't see now. Of the top of my head I don't know of any easy way to fix this, there's nothing for me to fix here as you used a critical API (forcing authority set changes) with wrong parameters. Here's some logs that show the issue:

2021-04-27 15:22:09.757   INFO tokio-runtime-worker substrate: ⚙️  Syncing 1023.6 bps, target=#159153 (18 peers), best: #103454 (0x5c9c…ce8b), finalized #103424 (0x889a…13cb), ⬇ 207.5kiB/s ⬆ 1.3kiB/s
2021-04-27 15:22:09.876  TRACE tokio-runtime-worker afg: Peer 12D3KooWJikH9QcBX7Rhy3FMxb5srdJFq46ddq5KRVzs1oZPXK1Q updated view. Now at Round(562), SetId(31)
2021-04-27 15:22:10.205  DEBUG tokio-runtime-worker afg: Finalizing blocks up to (103936, 0xf431…fd08)
2021-04-27 15:22:10.688  DEBUG tokio-runtime-worker afg: Finalizing blocks up to (104448, 0x7c15…e1ca)
2021-04-27 15:22:11.209  DEBUG tokio-runtime-worker afg: Finalizing blocks up to (104960, 0x7590…69ab)
2021-04-27 15:22:11.673  DEBUG tokio-runtime-worker afg: Inserting potential forced set change at block (105459, 0xff38446ecd341b6311fde4d7e4104d19520aae877674dd09c95fa4a533388db1) (delayed by 0 blocks).
2021-04-27 15:22:11.673  DEBUG tokio-runtime-worker afg: Best finalized block: 103166

You won't see the last line in your own logs as I added it myself, but this was the parameter that you passed to the note_stalled runtime API. There are other forced changes after this one which also repeated the same mistake.

@yashbhavsar007
Copy link

Hey guys,
I was on westend chain with the latest docker image and happened the incident with me here are some logs peers count dropped to 0.

Idle (0 peers), best: #5432095 (0x8e44…99df), finalized #5337990 (0x8d33…9dba), ⬇ 7.7kiB/s ⬆ 3.7kiB/s    
2021-04-30 10:40:21  ✨ Imported #5432096 (0x51c5…035b)    
2021-04-30 10:40:21  💔 Invalid justification provided by 12D3KooWLmEf7AfaWne7oDg2eMm5RPPe2HnRv8MLc2s1KZAV4WFp for #0xa251…024e    
2021-04-30 10:40:21  💔 Invalid justification provided by 12D3KooWBSMcGrNrdZmQtU9AKZowEeC2uQCwcyzR691z7JeuHGJc for #0xa251…024e  

This issue was closed.
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
None yet
Projects
None yet
Development

Successfully merging a pull request may close this issue.

7 participants