Skip to content

Beacon client repeatedly processing slots during init sync #4969

@torfbolt

Description

@torfbolt

The latest beacon client (af5cc31) processes each block lots of times before moving on to the next one during init sync:

[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366652 - estimated time remaining 8m21s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m18s blocksPerSecond=8.1 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m15s blocksPerSecond=8.1 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m34s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m31s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m27s blocksPerSecond=7.9 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m24s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m21s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:36]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m18s blocksPerSecond=8.1 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m15s blocksPerSecond=8.1 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m12s blocksPerSecond=8.2 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m34s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m31s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m27s blocksPerSecond=7.9 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m24s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m21s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:37]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m18s blocksPerSecond=8.1 peers=13/17
[2020-02-29 22:10:38]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m15s blocksPerSecond=8.1 peers=13/17
[2020-02-29 22:10:38]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m12s blocksPerSecond=8.2 peers=13/17
[2020-02-29 22:10:38]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m31s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:38]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m27s blocksPerSecond=7.9 peers=13/17
[2020-02-29 22:10:38]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m24s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:38]  INFO initial-sync: Processing block 362641/366653 - estimated time remaining 8m21s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:38]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m18s blocksPerSecond=8.1 peers=13/17
[2020-02-29 22:10:39]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m40s blocksPerSecond=7.7 peers=13/17
[2020-02-29 22:10:39]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m37s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:39]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m34s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:39]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m30s blocksPerSecond=7.8 peers=13/17
[2020-02-29 22:10:40]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m27s blocksPerSecond=7.9 peers=13/17
[2020-02-29 22:10:40]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m24s blocksPerSecond=8.0 peers=13/17
[2020-02-29 22:10:40]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m51s blocksPerSecond=7.5 peers=13/17
[2020-02-29 22:10:40]  INFO initial-sync: Processing block 362642/366653 - estimated time remaining 8m47s blocksPerSecond=7.6 peers=13/17

This is unexpected and looks like a regression to me, earlier versions did not do this.

Edit: After restarting the client it continued syncing with the normal 1 log message per slot.

Metadata

Metadata

Assignees

Type

No type
No fields configured for issues without a type.

Projects

No projects

Milestone

No milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions