Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

Synced onto a fork #1744

Closed
nazar-pc opened this issue Aug 2, 2023 · 6 comments
Closed

Synced onto a fork #1744

nazar-pc opened this issue Aug 2, 2023 · 6 comments
Assignees
Labels
bug Something isn't working consensus Something that impacts consensus core Related to core protocol, may affect fundamentals node Node (service library/node app)

Comments

@nazar-pc
Copy link
Member

nazar-pc commented Aug 2, 2023

Sync without DSN ended up on a fork, might be related to new block relay protocol or might be not. Needs reliable reproduction.

Basically my node managed to sync 128 blocks, I stopped it, then started again and got this:

2023-08-02 23:04:05 [Consensus] ⚙️  Syncing, target=#1928 (4 peers), best: #128 (0xffcb…13d2), finalized #0 (0xb868…7709), ⬇ 17.0MiB/s ⬆ 4.6kiB/s    
2023-08-02 23:04:10 [Consensus] ⚙️  Syncing 12.8 bps, target=#1930 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 3.6MiB/s ⬆ 1.9kiB/s    
2023-08-02 23:04:15 [Consensus] ⚙️  Syncing  0.0 bps, target=#1933 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.8MiB/s ⬆ 1.6kiB/s    
2023-08-02 23:04:20 [Consensus] ⚙️  Syncing  0.0 bps, target=#1935 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.8MiB/s ⬆ 1.8kiB/s    
2023-08-02 23:04:25 [Consensus] ⚙️  Syncing  0.0 bps, target=#1938 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.7MiB/s ⬆ 1.4kiB/s    
2023-08-02 23:04:30 [Consensus] ⚙️  Syncing  0.0 bps, target=#1940 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.9MiB/s ⬆ 0.6kiB/s    
2023-08-02 23:04:35 [Consensus] ⚙️  Syncing  0.0 bps, target=#1943 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.7MiB/s ⬆ 0.9kiB/s    
2023-08-02 23:04:40 [Consensus] ⚙️  Syncing  0.0 bps, target=#1945 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.6MiB/s ⬆ 0.8kiB/s    
2023-08-02 23:04:45 [Consensus] ⚙️  Syncing  0.0 bps, target=#1948 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.7MiB/s ⬆ 0.5kiB/s    
2023-08-02 23:04:50 [Consensus] ⚙️  Syncing  0.0 bps, target=#1950 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.7MiB/s ⬆ 0.8kiB/s    
2023-08-02 23:04:55 [Consensus] ⚙️  Syncing  0.0 bps, target=#1953 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.7MiB/s ⬆ 0.7kiB/s    
2023-08-02 23:05:00 [Consensus] ⚙️  Syncing  0.0 bps, target=#1955 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.9MiB/s ⬆ 0.7kiB/s    
2023-08-02 23:05:05 [Consensus] ⚙️  Syncing  0.0 bps, target=#1958 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.9MiB/s ⬆ 1.0kiB/s    
2023-08-02 23:05:10 [Consensus] ⚙️  Syncing  0.0 bps, target=#1960 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.9MiB/s ⬆ 0.6kiB/s    
2023-08-02 23:05:15 [Consensus] ⚙️  Syncing  0.0 bps, target=#1963 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.8MiB/s ⬆ 0.8kiB/s    
2023-08-02 23:05:20 [Consensus] ⚙️  Syncing  0.0 bps, target=#1965 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.8MiB/s ⬆ 0.7kiB/s    
2023-08-02 23:05:25 [Consensus] ⚙️  Syncing  0.0 bps, target=#1968 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.7MiB/s ⬆ 0.7kiB/s    
2023-08-02 23:05:30 [Consensus] ⚙️  Syncing  0.0 bps, target=#1970 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.7MiB/s ⬆ 0.9kiB/s    
2023-08-02 23:05:35 [Consensus] ⚙️  Syncing  0.0 bps, target=#1973 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 3.0MiB/s ⬆ 0.6kiB/s    
2023-08-02 23:05:40 [Consensus] ⚙️  Syncing  0.0 bps, target=#1975 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 2.6MiB/s ⬆ 0.8kiB/s    
2023-08-02 23:05:45 [Consensus] ⚙️  Syncing  0.0 bps, target=#1978 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 1.3MiB/s ⬆ 1.6kiB/s    
2023-08-02 23:05:50 [Consensus] ⚙️  Syncing  0.0 bps, target=#1980 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 1.4MiB/s ⬆ 1.5kiB/s    
2023-08-02 23:05:55 [Consensus] ⚙️  Syncing  0.0 bps, target=#1983 (4 peers), best: #192 (0xa3a2…0bd7), finalized #0 (0xb868…7709), ⬇ 1.4MiB/s ⬆ 1.5kiB/s    
2023-08-02 23:06:00 [Consensus] ⚙️  Syncing 16.0 bps, target=#1985 (4 peers), best: #272 (0xff7f…9dca), finalized #0 (0xb868…7709), ⬇ 655.9kiB/s ⬆ 1.9kiB/s    
2023-08-02 23:06:05 [Consensus] ⚙️  Syncing  7.6 bps, target=#1988 (4 peers), best: #310 (0x2468…dcc1), finalized #0 (0xb868…7709), ⬇ 31.8kiB/s ⬆ 2.4kiB/s    
2023-08-02 23:06:10 [Consensus] ⚙️  Syncing  9.8 bps, target=#1990 (4 peers), best: #359 (0xdb68…3114), finalized #0 (0xb868…7709), ⬇ 29.6kiB/s ⬆ 2.2kiB/s    
2023-08-02 23:06:15 [Consensus] ⚙️  Syncing 10.0 bps, target=#1993 (4 peers), best: #409 (0x326d…a867), finalized #0 (0xb868…7709), ⬇ 18.4kiB/s ⬆ 1.3kiB/s    
2023-08-02 23:06:20 [Consensus] ⚙️  Preparing 118.4 bps, target=#1995 (4 peers), best: #1001 (0x20e0…4203), finalized #0 (0xb868…7709), ⬇ 3.5kiB/s ⬆ 0.2kiB/s    
2023-08-02 23:06:25 [Consensus] ⚙️  Preparing 154.0 bps, target=#1998 (4 peers), best: #1771 (0x7695…e01d), finalized #0 (0xb868…7709), ⬇ 1.3kiB/s ⬆ 36 B/s    
2023-08-02 23:06:26 [Consensus] 💔 Error importing block 0xbda2e0c0b4846994b047e616df7e09cb85bb4289bdaa6ca99ddcb643ed7ddc84: block has an unknown parent    
2023-08-02 23:06:26 [Consensus] 💔 Error importing block 0x29dd49093d113d948294cda1b2da2b2bff6aba7138f36b9c9f57b919719602e4: block has an unknown parent    
2023-08-02 23:06:26 [Consensus] 💔 Error importing block 0x46eca71c0668bd9da412c0417b06899ef7e8d000a34850e6c43f0573dd16e52e: block has an unknown parent    
2023-08-02 23:06:26 [Consensus] 💔 Error importing block 0x8459865e714e2dfa11e6f9bab777754e9e4976f02fb1c417551e7a48f84e9b46: block has an unknown parent    
2023-08-02 23:06:26 [Consensus] 💔 Error importing block 0xc3c76e39a4ed700c0317a21cea3af18a53e6fadbb7d9799b8c7185538ed34fcf: block has an unknown parent    
2023-08-02 23:06:26 [Consensus] 💔 Error importing block 0x93600a0b832ed71b4cf7f3bd155766946b6820c315386997ca405cbd4a2268b8: block has an unknown parent    
2023-08-02 23:06:29 [Consensus] 💔 Error importing block 0xbda2e0c0b4846994b047e616df7e09cb85bb4289bdaa6ca99ddcb643ed7ddc84: block has an unknown parent    
2023-08-02 23:06:30 [Consensus] ⚙️  Syncing 44.0 bps, target=#2000 (4 peers), best: #1991 (0x53a1…0381), finalized #0 (0xb868…7709), ⬇ 5.4kiB/s ⬆ 0.4kiB/s    
2023-08-02 23:06:32 [Consensus] 💔 Error importing block 0xbda2e0c0b4846994b047e616df7e09cb85bb4289bdaa6ca99ddcb643ed7ddc84: block has an unknown parent    
2023-08-02 23:06:35 [Consensus] ⚙️  Syncing  0.0 bps, target=#2003 (4 peers), best: #1991 (0x53a1…0381), finalized #0 (0xb868…7709), ⬇ 5.5kiB/s ⬆ 0.4kiB/s    
2023-08-02 23:06:36 [Consensus] 💔 Error importing block 0xbda2e0c0b4846994b047e616df7e09cb85bb4289bdaa6ca99ddcb643ed7ddc84: block has an unknown parent    
2023-08-02 23:06:40 [Consensus] ⚙️  Syncing  0.0 bps, target=#2005 (4 peers), best: #1991 (0x53a1…0381), finalized #0 (0xb868…7709), ⬇ 4.2kiB/s ⬆ 0.3kiB/s    
2023-08-02 23:06:41 [Consensus] 💔 Error importing block 0xbda2e0c0b4846994b047e616df7e09cb85bb4289bdaa6ca99ddcb643ed7ddc84: block has an unknown parent    
2023-08-02 23:06:45 [Consensus] ⚙️  Syncing  0.0 bps, target=#2008 (4 peers), best: #1991 (0x53a1…0381), finalized #0 (0xb868…7709), ⬇ 5.5kiB/s ⬆ 0.4kiB/s    
2023-08-02 23:06:48 [Consensus] 💔 Error importing block 0xbda2e0c0b4846994b047e616df7e09cb85bb4289bdaa6ca99ddcb643ed7ddc84: block has an unknown parent    
2023-08-02 23:06:50 [Consensus] ⚙️  Syncing  0.0 bps, target=#2010 (4 peers), best: #1991 (0x53a1…0381), finalized #0 (0xb868…7709), ⬇ 4.7kiB/s ⬆ 0.3kiB/s    

As you can see it was going successfully, but then it got block 1991 from a fork, failed to import 1992 and got stuck there 😕
Tried to reproduce, didn't work, so probably not very common issue, but still concerning.

None of the blocks are finalized, so I don't understand why it didn't converge to canonical chain.

@nazar-pc nazar-pc added the open-problem Non-trivial issue whose answer is not yet known/decided label Aug 2, 2023
@nazar-pc nazar-pc added bug Something isn't working node Node (service library/node app) core Related to core protocol, may affect fundamentals consensus Something that impacts consensus and removed open-problem Non-trivial issue whose answer is not yet known/decided labels Aug 2, 2023
@nazar-pc
Copy link
Member Author

nazar-pc commented Aug 2, 2023

@rahulksnv I believe we have some latency issue with block relay.

Here is my node:

2023-08-03 00:18:45 [Consensus] ✨ Imported #2368 (0x7626…600a)    
2023-08-03 00:18:46 [Consensus] 🗳️ Claimed vote at slot 845505563    
2023-08-03 00:18:48 [Consensus] 🗳️ Claimed vote at slot 845505564    
2023-08-03 00:18:48 [Consensus] 💤 Idle (6 peers), best: #2368 (0x7626…600a), finalized #0 (0xb868…7709), ⬇ 9.5kiB/s ⬆ 102.9kiB/s    
2023-08-03 00:18:50 [Consensus] 🗳️ Claimed vote at slot 845505565    
2023-08-03 00:18:52 [Consensus] 🚜 Claimed block at slot 845505566    
2023-08-03 00:18:52 [Consensus] 🙌 Starting consensus session on top of parent 0x76266ba050d95f848f81427ae35b88ec7dd84d6ad004878d23b14a99cce8600a    
2023-08-03 00:18:52 [Consensus] 🎁 Prepared block for proposing at 2369 (13 ms) [hash: 0x0ab2f6570cba39469e58651ecfd464964a44f4243f5ab6ab83d6582ff9eccab5; parent_hash: 0x7626…600a; extrinsics (6): [0x6972…6108, 0x9eb4…88d8, 0xfabf…835d, 0xccb8…0696, 0x1e1d…595c, 0xbf3b…f986]]    
2023-08-03 00:18:52 [Consensus] 🔖 Pre-sealed block for proposal at 2369. Hash now 0x8c2abb6b2d45551656b260d6cb7271d4577b8f370bac6260b92cea3cb5a99978, previously 0x0ab2f6570cba39469e58651ecfd464964a44f4243f5ab6ab83d6582ff9eccab5.    
2023-08-03 00:18:52 [Consensus] ✨ Imported #2369 (0x8c2a…9978)    
2023-08-03 00:18:53 [Consensus] 💤 Idle (6 peers), best: #2369 (0x8c2a…9978), finalized #0 (0xb868…7709), ⬇ 15.5kiB/s ⬆ 176.7kiB/s    
2023-08-03 00:18:54 [Consensus] 🗳️ Claimed vote at slot 845505567    
2023-08-03 00:18:56 [Consensus] 🗳️ Claimed vote at slot 845505568    
2023-08-03 00:18:58 [Consensus] 🗳️ Claimed vote at slot 845505569    
2023-08-03 00:18:58 [Consensus] 💤 Idle (6 peers), best: #2369 (0x8c2a…9978), finalized #0 (0xb868…7709), ⬇ 15.0kiB/s ⬆ 172.4kiB/s    
2023-08-03 00:19:00 [Consensus] 🚜 Claimed block at slot 845505570    
2023-08-03 00:19:00 [Consensus] 🙌 Starting consensus session on top of parent 0x8c2abb6b2d45551656b260d6cb7271d4577b8f370bac6260b92cea3cb5a99978    
2023-08-03 00:19:00 [Consensus] 🎁 Prepared block for proposing at 2370 (8 ms) [hash: 0x0d80399a5136e71371372362b4a0f9802c36add4f61c3ae308bb39a7b31f7ca8; parent_hash: 0x8c2a…9978; extrinsics (4): [0x59eb…dace, 0xb6d3…2e38, 0x010f…9e9c, 0xab4f…9c78]]    
2023-08-03 00:19:00 [Consensus] 🔖 Pre-sealed block for proposal at 2370. Hash now 0x2689e4a8e46a5a2bfecd841e5cd8614f9988b3d2c1e154ec0bfdb738572531da, previously 0x0d80399a5136e71371372362b4a0f9802c36add4f61c3ae308bb39a7b31f7ca8.    
2023-08-03 00:19:00 [Consensus] ✨ Imported #2370 (0x2689…31da)    
2023-08-03 00:19:01 [Consensus] ✨ Imported #2369 (0xc7dd…0cba)    
2023-08-03 00:19:02 [Consensus] 🗳️ Claimed vote at slot 845505571    
2023-08-03 00:19:03 [Consensus] 💤 Idle (6 peers), best: #2370 (0x2689…31da), finalized #0 (0xb868…7709), ⬇ 17.2kiB/s ⬆ 174.1kiB/s    
2023-08-03 00:19:04 [Consensus] 🗳️ Claimed vote at slot 845505572    
2023-08-03 00:19:06 [Consensus] 🗳️ Claimed vote at slot 845505573    
2023-08-03 00:19:08 [Consensus] 🚜 Claimed block at slot 845505574    
2023-08-03 00:19:08 [Consensus] 🙌 Starting consensus session on top of parent 0x2689e4a8e46a5a2bfecd841e5cd8614f9988b3d2c1e154ec0bfdb738572531da    
2023-08-03 00:19:08 [Consensus] 🎁 Prepared block for proposing at 2371 (7 ms) [hash: 0x57b228fd6a25d06ca1f143fda2ac0a8e69612dd3792ed0a6415db8f4f1053624; parent_hash: 0x2689…31da; extrinsics (4): [0x1de6…adb8, 0x7aae…2fb6, 0x32be…4e38, 0xd147…b993]]    
2023-08-03 00:19:08 [Consensus] 🔖 Pre-sealed block for proposal at 2371. Hash now 0xda9fc9e72e1b22d33a015a798d2d659e3c1f7f2ae73dfeb27137f4c8347de47a, previously 0x57b228fd6a25d06ca1f143fda2ac0a8e69612dd3792ed0a6415db8f4f1053624.    
2023-08-03 00:19:08 [Consensus] ✨ Imported #2371 (0xda9f…e47a)    
2023-08-03 00:19:08 [Consensus] 💤 Idle (3 peers), best: #2371 (0xda9f…e47a), finalized #0 (0xb868…7709), ⬇ 15.0kiB/s ⬆ 174.6kiB/s    
2023-08-03 00:19:09 [Consensus] ✨ Imported #2370 (0x7909…3fe9)    

Here is Jeremy's node:

2023-08-02 15:18:44 [Consensus] 💤 Idle (4 peers), best: #2367 (0xce01…7ff4), finalized #0 (0xb868…7709), ⬇ 1.1kiB/s ⬆ 1.9kiB/s
2023-08-02 15:18:44 [Consensus] 🚜 Claimed block at slot 845505562
2023-08-02 15:18:44 [Consensus] 🙌 Starting consensus session on top of parent 0xce0135e49459749f4e6e070f4aa090e346208d07ad218881896db3416cb87ff4
2023-08-02 15:18:44 [Consensus] 🎁 Prepared block for proposing at 2368 (64 ms) [hash: 0x7e5c3d62889f32feea92f40046a0b9b5ac6a96eafa3bccad591b9cc45588b396; parent_hash: 0xce01…7ff4; extrinsics (16): [0x3d7a…e68c, 0xd8f6…ab4c, 0x99c8…5003, 0xfcdd…03a3, 0x49d7…2b03, 0xa0e0…f01e, 0x9d51…d056, 0x56c7…fc7f, 0x7f21…a0b9, 0x62ec…1551, 0x8369…0783, 0x81a4…6bb9, 0x1bb8…bd63, 0x9f5b…b0ad, 0xafa7…8521, 0x16aa…ba68]]
2023-08-02 15:18:44 [Consensus] 🔖 Pre-sealed block for proposal at 2368. Hash now 0x76266ba050d95f848f81427ae35b88ec7dd84d6ad004878d23b14a99cce8600a, previously 0x7e5c3d62889f32feea92f40046a0b9b5ac6a96eafa3bccad591b9cc45588b396.
2023-08-02 15:18:44 [Consensus] ✨ Imported #2368 (0x7626…600a)
2023-08-02 15:18:46 [Consensus] 🗳️ Claimed vote at slot 845505563
2023-08-02 15:18:49 [Consensus] 💤 Idle (4 peers), best: #2368 (0x7626…600a), finalized #0 (0xb868…7709), ⬇ 0.6kiB/s ⬆ 4.1kiB/s
2023-08-02 15:18:50 [Consensus] 🗳️ Claimed vote at slot 845505565
2023-08-02 15:18:52 [Consensus] 🚜 Claimed block at slot 845505566
2023-08-02 15:18:52 [Consensus] 🙌 Starting consensus session on top of parent 0x76266ba050d95f848f81427ae35b88ec7dd84d6ad004878d23b14a99cce8600a
2023-08-02 15:18:52 [Consensus] 🎁 Prepared block for proposing at 2369 (21 ms) [hash: 0x65bd96d70f7b4e82096f59e932aa3f53a15ef2be616c7ea4b08913c9860756e1; parent_hash: 0x7626…600a; extrinsics (6): [0x6972…6108, 0x9eb4…88d8, 0xfabf…835d, 0xccb8…0696, 0x1e1d…595c, 0xbf3b…f986]]
2023-08-02 15:18:52 [Consensus] 🔖 Pre-sealed block for proposal at 2369. Hash now 0xc7dda88f891dfb6ee271f68923401b8f1dd0b9922190fbd464e0e6ed2aeb0cba, previously 0x65bd96d70f7b4e82096f59e932aa3f53a15ef2be616c7ea4b08913c9860756e1.
2023-08-02 15:18:52 [Consensus] ✨ Imported #2369 (0xc7dd…0cba)
2023-08-02 15:18:54 [Consensus] 💤 Idle (4 peers), best: #2369 (0xc7dd…0cba), finalized #0 (0xb868…7709), ⬇ 0.6kiB/s ⬆ 1.6kiB/s
2023-08-02 15:18:54 [Consensus] 🗳️ Claimed vote at slot 845505567
2023-08-02 15:18:56 [Consensus] 🗳️ Claimed vote at slot 845505568
2023-08-02 15:18:58 [Consensus] 🗳️ Claimed vote at slot 845505569
2023-08-02 15:18:59 [Consensus] 💤 Idle (4 peers), best: #2369 (0xc7dd…0cba), finalized #0 (0xb868…7709), ⬇ 1.3kiB/s ⬆ 1.7kiB/s
2023-08-02 15:19:00 [Consensus] 🗳️ Claimed vote at slot 845505570
2023-08-02 15:19:02 [Consensus] 🗳️ Claimed vote at slot 845505571
2023-08-02 15:19:04 [Consensus] 💤 Idle (4 peers), best: #2369 (0xc7dd…0cba), finalized #0 (0xb868…7709), ⬇ 0.5kiB/s ⬆ 1.7kiB/s
2023-08-02 15:19:04 [Consensus] 🚜 Claimed block at slot 845505572
2023-08-02 15:19:04 [Consensus] 🙌 Starting consensus session on top of parent 0xc7dda88f891dfb6ee271f68923401b8f1dd0b9922190fbd464e0e6ed2aeb0cba
2023-08-02 15:19:04 [Consensus] 🎁 Prepared block for proposing at 2370 (21 ms) [hash: 0xa75515390dfc5658360616eda2bb2a298ef96d51b9cef8b2de09c812a89a0395; parent_hash: 0xc7dd…0cba; extrinsics (6): [0x63bd…7d57, 0x0698…d5a4, 0x718d…6617, 0x2690…d685, 0x5fc8…cbc2, 0xe7ea…cb94]]
2023-08-02 15:19:04 [Consensus] 🔖 Pre-sealed block for proposal at 2370. Hash now 0x7909c8dd57d33de7908c3f6ba3943572aeb0b7130e16758b5b90e26b1c833fe9, previously 0xa75515390dfc5658360616eda2bb2a298ef96d51b9cef8b2de09c812a89a0395.
2023-08-02 15:19:04 [Consensus] ✨ Imported #2370 (0x7909…3fe9)

If you open them side by side you can see that everything was fine until block 2368, Jeremy's node produced it and my node got it roughly a second later.

What happens next is interesting because my node produced block 2369 was produced on both nodes at xx:18:52, fair enough, neither knows what the future chain will look like and sticks to their block for now.
I'm not entirely sure why my node even downloaded Jeremy's blocks if it was always ahead, but even then block 2369 was received by my node only at xx:19:01, which is 9 seconds later, Jeremy's node didn't seem to ever receive block produced by my node.

Block 2370 was produced by my node at xx:19:00, but Jeremy's node has not seen it and produced own block at xx:19:04.
My node again received that block 5 seconds later at xx:19:09.

From there chains diverged further and further until they stopped being able to import each other's blocks at all saying "block has an unknown parent".

This looks suspiciously potentially related to block relay we have enabled in this devnet.

UPD: I was running two nodes locally, both got stuck exactly the same way and continue running in sync with each other. Do you think it is possible for new block relay to introduce multiple seconds of latency under some circumstances?

@rahulksnv
Copy link
Contributor

Can we enable more logs on the block relay side? (couple of logs are trace right now, we can bump it up)

@nazar-pc
Copy link
Member Author

nazar-pc commented Aug 2, 2023

Potentially yes. Looks like all nodes in the US remained with Jeremy, my nodes were further away and forked.

Will notify everyone on Slack.

@rahulksnv
Copy link
Contributor

#1748 waiting to go in.

The issue may potentially be what we discussed before: sending 1 block at at time, which may slow down the sync. I will try to make a change for this issue meanwhile

@nazar-pc
Copy link
Member Author

nazar-pc commented Aug 2, 2023

There must be something else. We were sending just one block at a time because both nodes were synced, also I have not seen anything special about those blocks like segment headers produced around that time or similar, just a few votes and that is is, not a big block at all.

@nazar-pc
Copy link
Member Author

There is paritytech/polkadot-sdk#493 upstream, for compact blocks side of things I believe we have fixed it and DSN sync was resolved in #1803. Closing for now, will reopen if it keeps happening with compact blocks enabled by default.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working consensus Something that impacts consensus core Related to core protocol, may affect fundamentals node Node (service library/node app)
Projects
Development

No branches or pull requests

2 participants