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

If node in the snapshot prune phase, the synchronization process will be affected, causing the block to lag behind #587

Open
atlasW opened this issue Jan 2, 2025 · 59 comments

Comments

@atlasW
Copy link

atlasW commented Jan 2, 2025

version:bsc-erigon_v1.3.0-alpha7 ec2 instance: i4i.4xlarge Startup parameter:/root/bsc-erigon/erigon \ --datadir="/root/node" \ --chain=bsc \ --port=30303 \ --http.port=8545 \ --authrpc.port=8551 \ --torrent.port=42069 \ --private.api.addr=127.0.0.1:9090 \ --http --ws \ --http.addr=0.0.0.0 \ --rpc.batch.limit=300 \ --http.vhosts=* \ --prune.mode=archive \ --prune.distance.blocks=900000 \ --prune.distance=900000 \ --http.api=eth,debug,net,trace,web3,erigon,bsc \ --nat=none

issue: We have three Erigon nodes, but these nodes enter the snapshot prune phase simultaneously. During this phase, block synchronization is affected, causing the nodes to fall behind in block height. This situation lasts for approximately 17 minutes each time. Is there any way to mitigate this impact?
image
image

@atlasW
Copy link
Author

atlasW commented Jan 2, 2025

image

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 3, 2025

Will do more investigate.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 8, 2025

You node not on chain tip?

@atlasW
Copy link
Author

atlasW commented Jan 8, 2025

You node not on chain tip?
In most cases, it is possible to catch up to the latest block, but it falls behind when the log shows 'snapshot prune'.

@atlasW
Copy link
Author

atlasW commented Jan 9, 2025

"[snapshots] pruning state "

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 10, 2025

You can grep logs about PruneSmallBatches finished, it should quickly when in live sync.

@atlasW
Copy link
Author

atlasW commented Jan 10, 2025

You can grep logs about PruneSmallBatches finished, it should quickly when in live sync.

image more than 10min 。During these10 minutes, block synchronization is lagging behind.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 10, 2025

Could you please provide more logs regarding PruneSmallBatches finish. I want to know what happen before this.

@atlasW
Copy link
Author

atlasW commented Jan 10, 2025

Could you please provide more logs regarding PruneSmallBatches finish. I want to know what happen before this.
grep -B 50 PruneSmallBatches

INFO[01-09|15:48:56.309] [2/9 Headers] Waiting for headers...     from=45618801 hash=0x0c24c61e2b66df86bcf48352a89e861de5f434e7139271daae7236058511c536
INFO[01-09|15:48:56.407] [2/9 Headers] Processed                  highest=45618827 age=0 headers=26 in=0.099 blk/sec=262
INFO[01-09|15:48:56.426] [5/9 Bodies] Processing bodies...        from=45618801 to=45618827
INFO[01-09|15:48:56.453] [mem] memory stats                       Rss=28.1GB Size=0B Pss=28.1GB SharedClean=160.0KB SharedDirty=0B PrivateClean=8.0GB PrivateDirty=20.1GB Referenced=28.0GB Anonymous=20.1GB Swap=0B alloc=14.7GB sys=33.7GB
INFO[01-09|15:48:56.562] [5/9 Bodies] Processed                   highest=45618827 blocks=26 in=0.136 blk/sec=191
INFO[01-09|15:48:56.562] [6/9 Senders] Started                    from=45618801 to=45618827
INFO[01-09|15:48:56.625] [7/9 Execution] starting                 from=45618801 to=45618827 fromTxNum=6717211038 offsetFromBlockBeginning=0 initialCycle=false useExternalTx=true inMem=false
INFO[01-09|15:48:56.634] [7/9 Execution] starting                 from=45618802 to=45618827 fromTxNum=6717211039 offsetFromBlockBeginning=0 initialCycle=false useExternalTx=true
INFO[01-09|15:49:18.158] [7/9 Execution] Commit every block       blk=45618815 blks=15 blk/s=0.7 txs=2603 tx/s=120 gas/s=12.20M buf=76.6MB/512.0MB stepsInDB=1.02 step=4299.0 inMem=false alloc=16.2GB sys=33.7GB
INFO[01-09|15:50:07.241] [7/9 Execution] Done Commit every block  blk=45618827 blks=27 blk/s=0.4 txs=5082 tx/s=71 gas/s=7.20M buf=126.2MB/512.0MB stepsInDB=0.00 step=4299.0 inMem=false alloc=12.8GB sys=33.7GB
INFO[01-09|15:50:07.247] [7/9 Execution] DONE                     in=1m10.624490298s block=45618801
INFO[01-09|15:50:10.861] Timings (slower than 50ms)               Headers=98ms Bodies=135ms Senders=60ms Execution=1m10.624s TxLookup=3.574s mgas/s=0.294 alloc=12.8GB sys=33.7GB
INFO[01-09|15:50:11.447] [2/9 Headers] Waiting for headers...     from=45618827 hash=0x3958f91dca19593d819e060616464865eef621c1d6bad2f1a4ead7f7aeb1949c
INFO[01-09|15:50:11.532] [2/9 Headers] Processed                  highest=45618852 age=0 headers=25 in=0.086 blk/sec=290
INFO[01-09|15:50:11.547] [5/9 Bodies] Processing bodies...        from=45618827 to=45618852
INFO[01-09|15:50:11.807] [5/9 Bodies] Processed                   highest=45618852 blocks=25 in=0.260 blk/sec=96
INFO[01-09|15:50:11.807] [6/9 Senders] Started                    from=45618827 to=45618852
INFO[01-09|15:50:11.894] [7/9 Execution] starting                 from=45618827 to=45618852 fromTxNum=6717216120 offsetFromBlockBeginning=0 initialCycle=false useExternalTx=true inMem=false
INFO[01-09|15:50:11.904] [7/9 Execution] starting                 from=45618828 to=45618852 fromTxNum=6717216121 offsetFromBlockBeginning=0 initialCycle=false useExternalTx=true
INFO[01-09|15:50:18.241] [snapshots] aggregated                   step=4298 took=8m27.653694967s
INFO[01-09|15:50:33.562] [7/9 Execution] Commit every block       blk=45618842 blks=16 blk/s=0.7 txs=2896 tx/s=133 gas/s=14.44M buf=81.8MB/512.0MB stepsInDB=1.02 step=4299.0 inMem=false alloc=14.0GB sys=33.7GB
INFO[01-09|15:51:20.898] [7/9 Execution] Done Commit every block  blk=45618852 blks=26 blk/s=0.4 txs=5313 tx/s=77 gas/s=9.36M buf=142.4MB/512.0MB stepsInDB=0.00 step=4299.0 inMem=false alloc=15.0GB sys=33.7GB
INFO[01-09|15:51:20.901] [7/9 Execution] DONE                     in=1m9.011577431s block=45618827
INFO[01-09|15:51:24.413] Timings (slower than 50ms)               Headers=86ms Bodies=259ms Senders=82ms Execution=1m9.011s TxLookup=3.445s mgas/s=0.264 alloc=15.0GB sys=33.7GB
INFO[01-09|15:51:28.544] [2/9 Headers] Waiting for headers...     from=45618852 hash=0xd6dd8881ea9abec3532e91db41a476f26ab1b17e5ea910e51c5e28830849055d
INFO[01-09|15:51:28.650] [2/9 Headers] Processed                  highest=45618877 age=2s headers=25 in=0.106 blk/sec=235
INFO[01-09|15:51:28.664] [5/9 Bodies] Processing bodies...        from=45618852 to=45618877
INFO[01-09|15:51:28.754] [5/9 Bodies] Processed                   highest=45618877 blocks=25 in=0.090 blk/sec=276
INFO[01-09|15:51:28.754] [6/9 Senders] Started                    from=45618852 to=45618877
INFO[01-09|15:51:28.821] [7/9 Execution] starting                 from=45618852 to=45618877 fromTxNum=6717221433 offsetFromBlockBeginning=0 initialCycle=false useExternalTx=true inMem=false
INFO[01-09|15:51:28.829] [7/9 Execution] starting                 from=45618853 to=45618877 fromTxNum=6717221434 offsetFromBlockBeginning=0 initialCycle=false useExternalTx=true
INFO[01-09|15:51:49.404] [7/9 Execution] Commit every block       blk=45618864 blks=13 blk/s=0.6 txs=2773 tx/s=134 gas/s=13.74M buf=81.8MB/512.0MB stepsInDB=1.02 step=4299.0 inMem=false alloc=15.8GB sys=33.7GB
INFO[01-09|15:51:55.209] [p2p] GoodPeers                          eth68=10
INFO[01-09|15:51:55.234] [txpool] stat                            pending=10000 baseFee=0 queued=28550 alloc=15.9GB sys=33.7GB
INFO[01-09|15:51:56.224] [mem] memory stats                       Rss=28.5GB Size=0B Pss=28.5GB SharedClean=160.0KB SharedDirty=0B PrivateClean=6.0GB PrivateDirty=22.5GB Referenced=28.4GB Anonymous=22.4GB Swap=0B alloc=15.9GB sys=33.7GB
INFO[01-09|15:54:55.211] [p2p] GoodPeers                          eth68=10
INFO[01-09|15:54:55.239] [txpool] stat                            pending=10000 baseFee=0 queued=30000 alloc=12.7GB sys=33.7GB
INFO[01-09|15:54:56.393] [mem] memory stats                       Rss=28.5GB Size=0B Pss=28.5GB SharedClean=160.0KB SharedDirty=0B PrivateClean=6.4GB PrivateDirty=22.0GB Referenced=28.4GB Anonymous=22.0GB Swap=0B alloc=12.8GB sys=33.7GB
INFO[01-09|15:56:33.560] [snapshots] pruning state                until commit=9h55m30.573768476s pruneLimit=1000000 aggregatedStep=4298 stepsRangeInDB="accounts:0.4, storage:0.0, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 663.30k from steps 4298-4298, values: 3.78M, txns: 1000000 from 6.71B-6.71B; code| kv: 4.95k from steps 4298-4298, values: 2.85k, txns: 1266 from 6.71B-6.71B; commitment| kv: 1.00M from steps 4298-4298; receipt| values: 1.64M, txns: 1000000 from 6.71B-6.71B; storage| kv: 1.00M from steps 4298-4298, values: 5.88M, txns: 992886 from 6.71B-6.71B; logaddrs| values: 2.04M, txns: 1000000 from 6.71B-6.71B; logtopics| values: 5.83M, txns: 1000000 from 6.71B-6.71B; tracesfrom| values: 2.02M, txns: 1000000 from 6.71B-6.71B; tracesto| values: 2.75M, txns: 1000000 from 6.71B-6.71B"
INFO[01-09|15:57:55.211] [p2p] GoodPeers                          eth68=10
INFO[01-09|15:57:55.237] [txpool] stat                            pending=10000 baseFee=0 queued=30000 alloc=14.8GB sys=33.7GB
INFO[01-09|15:57:56.365] [mem] memory stats                       Rss=28.3GB Size=0B Pss=28.3GB SharedClean=160.0KB SharedDirty=0B PrivateClean=7.1GB PrivateDirty=21.2GB Referenced=27.8GB Anonymous=21.2GB Swap=0B alloc=14.9GB sys=33.7GB
INFO[01-09|15:58:47.759] [snapshots] pruning state                until commit=9h53m16.373952647s pruneLimit=1000000 aggregatedStep=4298 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 663.30k from steps 4298-4298, values: 5.92M, txns: 1546882 from 6.71B-6.71B; code| kv: 4.95k from steps 4298-4298, values: 2.85k, txns: 1266 from 6.71B-6.71B; commitment| kv: 2.00M from steps 4298-4298; receipt| values: 2.56M, txns: 1554191 from 6.71B-6.71B; storage| kv: 1.68M from steps 4298-4298, values: 5.88M, txns: 992886 from 6.71B-6.71B; logaddrs| values: 2.06M, txns: 1009845 from 6.71B-6.71B; logtopics| values: 5.88M, txns: 1009845 from 6.71B-6.71B; tracesfrom| values: 3.20M, txns: 1546882 from 6.71B-6.71B; tracesto| values: 4.40M, txns: 1546882 from 6.71B-6.71B"
INFO[01-09|15:59:37.624] [snapshots] pruning state                until commit=9h52m26.509695865s pruneLimit=1000000 aggregatedStep=4298 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 663.30k from steps 4298-4298, values: 5.92M, txns: 1546882 from 6.71B-6.71B; code| kv: 4.95k from steps 4298-4298, values: 2.85k, txns: 1266 from 6.71B-6.71B; commitment| kv: 3.00M from steps 4298-4298; receipt| values: 2.56M, txns: 1554191 from 6.71B-6.71B; storage| kv: 1.68M from steps 4298-4298, values: 5.88M, txns: 992886 from 6.71B-6.71B; logaddrs| values: 2.06M, txns: 1009845 from 6.71B-6.71B; logtopics| values: 5.88M, txns: 1009845 from 6.71B-6.71B; tracesfrom| values: 3.20M, txns: 1546882 from 6.71B-6.71B; tracesto| values: 4.40M, txns: 1546882 from 6.71B-6.71B"
INFO[01-09|16:00:25.507] [snapshots] pruning state                until commit=9h51m38.625305127s pruneLimit=1000000 aggregatedStep=4298 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 663.30k from steps 4298-4298, values: 5.92M, txns: 1546882 from 6.71B-6.71B; code| kv: 4.95k from steps 4298-4298, values: 2.85k, txns: 1266 from 6.71B-6.71B; commitment| kv: 4.00M from steps 4298-4298; receipt| values: 2.56M, txns: 1554191 from 6.71B-6.71B; storage| kv: 1.68M from steps 4298-4298, values: 5.88M, txns: 992886 from 6.71B-6.71B; logaddrs| values: 2.06M, txns: 1009845 from 6.71B-6.71B; logtopics| values: 5.88M, txns: 1009845 from 6.71B-6.71B; tracesfrom| values: 3.20M, txns: 1546882 from 6.71B-6.71B; tracesto| values: 4.40M, txns: 1546882 from 6.71B-6.71B"
INFO[01-09|16:00:55.211] [p2p] GoodPeers                          eth68=10
INFO[01-09|16:00:55.234] [txpool] stat                            pending=10000 baseFee=0 queued=30000 alloc=13.0GB sys=33.7GB
INFO[01-09|16:00:56.348] [mem] memory stats                       Rss=28.4GB Size=0B Pss=28.4GB SharedClean=160.0KB SharedDirty=0B PrivateClean=9.3GB PrivateDirty=19.1GB Referenced=28.1GB Anonymous=19.1GB Swap=0B alloc=13.0GB sys=33.7GB
INFO[01-09|16:01:19.433] [snapshots] pruning state                until commit=9h50m44.700757907s pruneLimit=1000000 aggregatedStep=4298 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 663.30k from steps 4298-4298, values: 5.92M, txns: 1546882 from 6.71B-6.71B; code| kv: 4.95k from steps 4298-4298, values: 2.85k, txns: 1266 from 6.71B-6.71B; commitment| kv: 4.89M from steps 4298-4298; receipt| values: 2.56M, txns: 1554191 from 6.71B-6.71B; storage| kv: 1.68M from steps 4298-4298, values: 5.88M, txns: 992886 from 6.71B-6.71B; logaddrs| values: 2.06M, txns: 1009845 from 6.71B-6.71B; logtopics| values: 5.88M, txns: 1009845 from 6.71B-6.71B; tracesfrom| values: 3.20M, txns: 1546882 from 6.71B-6.71B; tracesto| values: 4.40M, txns: 1546882 from 6.71B-6.71B"
INFO[01-09|16:01:19.438] [snapshots] PruneSmallBatches finished   took=9m15.308138936s stat="accounts| kv: 663.30k from steps 4298-4298, values: 5.92M, txns: 1546882 from 6.71B-6.71B; code| kv: 4.95k from steps 4298-4298, values: 2.85k, txns: 1266 from 6.71B-6.71B; commitment| kv: 4.89M from steps 4298-4298; receipt| values: 2.56M, txns: 1554191 from 6.71B-6.71B; storage| kv: 1.68M from steps 4298-4298, values: 5.88M, txns: 992886 from 6.71B-6.71B; logaddrs| values: 2.06M, txns: 1009845 from 6.71B-6.71B; logtopics| values: 5.88M, txns: 1009845 from 6.71B-6.71B; tracesfrom| values: 3.20M, txns: 1546882 from 6.71B-6.71B; tracesto| values: 4.40M, txns: 1546882 from 6.71B-6.71B"```

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 13, 2025

Try with i7ie instance, 14.44Mgas/s is too slow. Faster cpu frequencies will benefit more when in live sync.
BTW, we use r7a.4xlarge, it have 40Mgas/s.

@atlasW
Copy link
Author

atlasW commented Jan 13, 2025

@blxdyx r7a.4xlarge + io2 ? how much io2 ebs's iops ?

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 14, 2025

8000 IOPS

@atlasW
Copy link
Author

atlasW commented Jan 14, 2025

8000 IOPS

Are you using io2 or gp3 volumes for storage? If it's an 8000 IOPS gp3 volume, it might not be sufficient, right? Let me run a test.

@atlasW
Copy link
Author

atlasW commented Jan 14, 2025

Hi @blxdyx

INFO[01-14|12:56:32.827] [snapshots] pruning state                until commit=9h55m59.562894607s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 597.15k from steps 4312-4312, values: 5.92M, txns: 1543848 from 6.73B-6.73B; code| kv: 6.54k from steps 4312-4312, values: 6.54k, txns: 2069 from 6.73B-6.73B; commitment| kv: 5.06M from steps 4312-4312; receipt| kv: 3 from steps 4312-4312, values: 2.64M, txns: 1553174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.18M, txns: 1073598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1093391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1093391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1543848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1543848 from 6.73B-6.73B"
INFO[01-14|12:56:32.828] [snapshots] PruneSmallBatches finished   took=4m0.437288989s stat="accounts| kv: 597.15k from steps 4312-4312, values: 5.92M, txns: 1543848 from 6.73B-6.73B; code| kv: 6.54k from steps 4312-4312, values: 6.54k, txns: 2069 from 6.73B-6.73B; commitment| kv: 5.06M from steps 4312-4312; receipt| kv: 3 from steps 4312-4312, values: 2.64M, txns: 1553174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.18M, txns: 1073598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1093391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1093391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1543848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1543848 from 6.73B-6.73B"
INFO[01-14|12:56:33.038] Committed                                time=4m47.709757541s block=45758249 txNum=6739099770 step=4313.0 flush+commitment=47.062049834s tx.commit=208.86453ms prune=4m0.437339338s
INFO[01-14|12:56:33.239] [7/9 Execution]                          blk=45758250 blks=2 blk/s=0.0 txs=248 tx/s=0 gas/s=78.10k buf=242.1KB/512.0MB stepsInDB=0.02 step=4313.0 alloc=13.3GB sys=21.7GB
INFO[01-14|12:56:45.221] [7/9 Execution]                          blk=45758313 blks=64 blk/s=5.3 txs=13172 tx/s=1.09k gas/s=105.37M buf=8.1MB/512.0MB stepsInDB=0.02 step=4313.0 alloc=14.8GB sys=21.7GB
INFO[01-14|12:57:05.283] [7/9 Execution]                          blk=45758441 blks=129 blk/s=6.4 txs=23177 tx/s=1.15k gas/s=119.65M buf=19.9MB/512.0MB stepsInDB=0.02 step=4313.0 alloc=17.7GB sys=21.7GB
INFO[01-14|12:57:25.446] [7/9 Execution]                          blk=45758588 blks=148 blk/s=7.3 txs=26178 tx/s=1.29k gas/s=125.54M buf=31.0MB/512.0MB stepsInDB=0.02 step=4313.1 alloc=11.1GB sys=21.7GB
image

Our i4i.4xlarge also achieves a speed of 125.54M gas/s. However, the node blocks still fall behind in synchronization during the snapshot prune state.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

Not live sync. It can reach 300Mgas/s during history sync in our node
.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

Not live sync. It can reach 300Mgas/s during history sync.

image On the left is r7a.4xlarge with an 16000 IOPS gp3 disk, and on the right is i4i.4xlarge with NVMe instance storage. Judging from the logs, it seems that the performance of r7a is lower than that of i4i.4xlarge. Can it handle the snapshot prune phase in this case? I’ll need to monitor it further to see.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

Sorry, it's an 16000 IOPS gp3 volume, you could try it.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

Sorry, it's an 16000 IOPS gp3 volume

image On the left is a 16k IOPS gp3 hard drive. However, the performance is still very low. Could it be that the high traffic to our node is affecting the synchronization speed? We have several chain-scanning tasks. Currently, the speed of the nodes is not lagging behind; they only fall behind when the nodes display snapshot prune logs.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

It will affect the performance, but if your node has a stable live sync speed of 40M gas/s, it shouldn't lag.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

It will affect the performance, but if your node has a stable live sync speed of 40M gas/s, it shouldn't lag.

r7a.4xlarge + 16k iops gp3 volume, It was tested. Node synchronization still lags behind at the snapshot prune stage. This phase has high performance requirements on hard disks.

image image

Even though I used the i4i.4xlarge instance nvme store, the hard drive was very heavy at this stage.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

image This snapshort prune phase affects the synchronized thread.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

What's about stop the chain-scanning tasks? May you need bigger ram and higher cpu frequencies.
BTW, we offer a experiment sync mode, it won't calu merkle root, so it won't have commitment file. It can work with DISCARD_COMMITMENT=true.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

It will affect the performance, but if your node has a stable live sync speed of 40M gas/s, it shouldn't lag.

r7a.4xlarge + 16k iops gp3 volume

What's about stop the chain-scanning tasks? May you need bigger ram and higher cpu frequencies. BTW, we offer a experiment sync mode, it won't calu merkle root, so it won't have commitment file. It can work with DISCARD_COMMITMENT=true.

We are monitoring it with all chain-scanning tasks stopped. As you can see from the grafana chart, the synchronization thread suffered a lot during the snapshort prune process.
image

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

It's in my r7a.4xlarge device. it won't take too much time when your node Commit every block.
image

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

It's in my r7a.4xlarge device. it won't take too much time when your node Commit every block. image

image It's my i3en.3xlarge node's logs . “[snapshots] PruneSmallBatches finished took=18m2.670764319s” at this log ,The nodes will fall behind in sync.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

The latest log shows it's on chain tip, when after steps 4308. Before this the node still do history sync.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

The latest log shows it's on chain tip, when after steps 4308. Before this the node still do history sync.

image The node fell behind for a period just now, and the logs during that time looked like this. Each time it's in the prune state, it affects the synchronization process, causing the blocks to fall behind.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

This block lagging issue occurs approximately once a day, always during the prune state.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

But the logs seem shows that your node restart at [01-15|03:07]. Could you upload all the log after restart the node.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

After the restart you will do history sync. That's why the step 4134 need so much time to pruneSmallBatches.
But when on the chain-tip, PruneSmallBatches won't blocking blocks processing. So it's expected.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

After the restart you will do history sync. That's why the step 4134 need so much time to pruneSmallBatches. But when on the chain-tip, PruneSmallBatches won't blocking blocks processing. So it's expected.

INFO[01-13|04:31:31.813] [snapshots:blocks:retire] Stat           blocks=45.71M indices=45.71M alloc=12.4GB sys=30.4GB
INFO[01-13|04:59:18.394] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.0GB sys=30.4GB
INFO[01-13|05:04:09.071] [snapshots:merge] Stat                   blocks=45.72M indices=45.72M alloc=12.9GB sys=30.4GB
INFO[01-13|05:49:17.668] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.9GB sys=30.4GB
INFO[01-13|06:39:18.281] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.2GB sys=30.4GB
INFO[01-13|07:29:31.367] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.1GB sys=30.4GB
INFO[01-13|08:19:54.358] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=13.8GB sys=30.4GB
INFO[01-13|09:10:16.413] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=13.5GB sys=30.4GB
INFO[01-13|10:00:07.589] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.3GB sys=30.4GB
INFO[01-13|10:51:32.173] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.8GB sys=30.4GB
INFO[01-13|11:40:15.152] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.3GB sys=30.4GB
INFO[01-13|12:11:21.900] [snapshots] aggregated                   step=4309 took=12m15.50811451s
INFO[01-13|12:11:21.911] [snapshots] merge state accounts(val=4308-4310, hist=4308-4310, idx=4308-4310), storage(val=4308-4310, hist=4308-4310, idx=4308-4310), code(val=4308-4310, hist=4308-4310, idx=4308-4310), commitment(val=4308-4310), receipt(val=4308-4310, hist=4308-4310, idx=4308-4310), logAddr=4308-4310, logTopic=4308-4310, traceFrom=4308-4310, traceTo=4308-4310
INFO[01-13|12:18:03.783] [snapshots] state merge done accounts(val=4308-4310, hist=4308-4310, idx=4308-4310), storage(val=4308-4310, hist=4308-4310, idx=4308-4310), code(val=4308-4310, hist=4308-4310, idx=4308-4310), commitment(val=4308-4310), receipt(val=4308-4310, hist=4308-4310, idx=4308-4310), logAddr=4308-4310, logTopic=4308-4310, traceFrom=4308-4310, traceTo=4308-4310
INFO[01-13|12:23:42.182] [snapshots] pruning state                until commit=9h53m22.753361804s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.4, storage:0.2, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.2, logtopics: 0.2, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 3.91M, txns: 1000000 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 1.00M from steps 4309-4309; receipt| values: 1.74M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4309-4309, values: 6.01M, txns: 1000000 from 6.73B-6.73B; logaddrs| values: 2.01M, txns: 1000000 from 6.73B-6.73B; logtopics| values: 5.84M, txns: 1000000 from 6.73B-6.73B; tracesfrom| values: 2.17M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 3.02M, txns: 1000000 from 6.73B-6.73B"
INFO[01-13|12:26:37.316] [snapshots] pruning state                until commit=9h50m27.608800368s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 2.00M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:27:04.924] [snapshots] prune domain                 name=commitment pruned keys=485747 steps=0.00-4310.00
INFO[01-13|12:27:34.525] [snapshots] pruning state                until commit=9h49m30.404273583s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 3.00M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:28:30.050] [snapshots] pruning state                until commit=9h48m34.87421004s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 4.00M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:29:32.289] [snapshots] pruning state                until commit=9h47m32.639462717s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 4.95M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:29:32.293] [snapshots] PruneSmallBatches finished   took=12m27.370645086s stat="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 4.95M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:39:10.442] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.1GB sys=30.4GB
INFO[01-13|13:19:49.825] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.9GB sys=30.4GB
INFO[01-13|13:27:11.085] [snapshots:merge] Stat                   blocks=45.73M indices=45.73M alloc=13.5GB sys=30.4GB
INFO[01-13|14:10:35.008] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.1GB sys=30.4GB
INFO[01-13|15:08:00.669] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=13.7GB sys=30.4GB
INFO[01-13|15:59:21.903] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=12.6GB sys=30.4GB
INFO[01-13|16:41:10.472] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=13.9GB sys=30.4GB
INFO[01-13|17:30:05.450] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.2GB sys=30.4GB
INFO[01-13|18:19:42.297] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=12.9GB sys=30.4GB
INFO[01-13|18:34:09.146] [snapshots] aggregated                   step=4310 took=11m45.670193628s
INFO[01-13|18:44:00.832] [snapshots] pruning state                until commit=9h54m38.490582869s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 3.92M, txns: 1000000 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 1.00M from steps 4310-4310; receipt| values: 1.70M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4310-4310, values: 6.18M, txns: 1000000 from 6.73B-6.73B; logaddrs| values: 2.09M, txns: 1000000 from 6.73B-6.73B; logtopics| values: 5.78M, txns: 1000000 from 6.73B-6.73B; tracesfrom| values: 2.22M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 3.11M, txns: 1000000 from 6.73B-6.73B"
INFO[01-13|18:46:44.120] [snapshots] pruning state                until commit=9h51m55.199944018s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 2.00M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:47:56.352] [snapshots] pruning state                until commit=9h50m42.964802301s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 3.00M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:48:39.313] [snapshots] prune domain                 name=commitment pruned keys=700724 steps=0.00-4311.00
INFO[01-13|18:49:02.107] [snapshots] pruning state                until commit=9h49m37.211346516s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 4.00M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:50:07.806] [snapshots] pruning state                until commit=9h48m31.510788626s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 4.79M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:50:07.816] [snapshots] PruneSmallBatches finished   took=11m28.504117132s stat="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 4.79M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|19:09:18.757] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.1GB sys=30.4GB
INFO[01-13|19:59:19.271] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=13.1GB sys=30.4GB
INFO[01-13|20:49:19.873] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=15.0GB sys=30.4GB
INFO[01-13|21:39:18.611] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=14.0GB sys=30.4GB
INFO[01-13|21:46:42.908] [snapshots:merge] Stat                   blocks=45.74M indices=45.74M alloc=13.6GB sys=30.4GB
INFO[01-13|22:29:20.770] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.8GB sys=30.4GB
INFO[01-13|23:19:34.330] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.3GB sys=30.4GB
INFO[01-14|00:09:36.295] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.9GB sys=30.4GB
INFO[01-14|00:59:53.320] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.4GB sys=30.4GB
INFO[01-14|01:49:46.079] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.5GB sys=30.4GB
INFO[01-14|02:39:31.364] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.7GB sys=30.4GB
INFO[01-14|03:29:42.195] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.9GB sys=30.4GB
INFO[01-14|04:16:25.311] [snapshots] aggregated                   step=4311 took=12m21.14948786s
INFO[01-14|04:16:25.315] [snapshots] merge state accounts(val=4304-4312, hist=4304-4312, idx=4304-4312), storage(val=4304-4312, hist=4304-4312, idx=4304-4312), code(val=4304-4312, hist=4304-4312, idx=4304-4312), commitment(val=4304-4312), receipt(val=4304-4312, hist=4304-4312, idx=4304-4312), logAddr=4304-4312, logTopic=4304-4312, traceFrom=4304-4312, traceTo=4304-4312
INFO[01-14|04:29:26.305] [snapshots] pruning state                until commit=9h51m58.554001485s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.4, storage:0.0, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 3.89M, txns: 1000000 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 1.00M from steps 4311-4311; receipt| values: 1.58M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 2.16M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 2.97M, txns: 1000000 from 6.73B-6.73B"
INFO[01-14|04:32:27.260] [snapshots] prune index                  name=receipt pruned tx=0 pruned values=1 steps=0.00-4311.65
INFO[01-14|04:32:50.144] [snapshots] pruning state                until commit=9h48m34.705576053s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 2.00M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:35:28.518] [snapshots] pruning state                until commit=9h45m56.332378434s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 3.00M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:38:49.892] [snapshots] pruning state                until commit=9h42m34.960771816s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 4.00M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:41:42.231] [snapshots] pruning state                until commit=9h39m42.621767067s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 4.77M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:41:42.242] [snapshots] PruneSmallBatches finished   took=20m17.399707769s stat="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 4.77M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:44:35.926] [snapshots] state merge done accounts(val=4304-4312, hist=4304-4312, idx=4304-4312), storage(val=4304-4312, hist=4304-4312, idx=4304-4312), code(val=4304-4312, hist=4304-4312, idx=4304-4312), commitment(val=4304-4312), receipt(val=4304-4312, hist=4304-4312, idx=4304-4312), logAddr=4304-4312, logTopic=4304-4312, traceFrom=4304-4312, traceTo=4304-4312
INFO[01-14|04:52:06.488] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.7GB sys=30.4GB
INFO[01-14|05:09:30.060] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.7GB sys=30.4GB
INFO[01-14|05:59:27.552] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=12.6GB sys=30.4GB
INFO[01-14|06:05:00.001] [snapshots:merge] Stat                   blocks=45.75M indices=45.75M alloc=15.0GB sys=30.4GB
INFO[01-14|06:49:56.726] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.9GB sys=30.4GB
INFO[01-14|07:40:22.766] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.4GB sys=30.4GB
INFO[01-14|08:30:53.430] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.1GB sys=30.4GB
INFO[01-14|09:20:00.282] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.9GB sys=30.4GB
INFO[01-14|10:13:27.944] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=12.8GB sys=30.4GB
INFO[01-14|11:00:53.606] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=13.8GB sys=30.4GB
INFO[01-14|11:50:52.381] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=12.9GB sys=30.4GB
INFO[01-14|12:03:36.891] [snapshots] aggregated                   step=4312 took=12m12.139310639s
INFO[01-14|12:14:50.611] [snapshots] pruning state                until commit=9h53m24.587496752s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 3.81M, txns: 1000000 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 1.00M from steps 4312-4312; receipt| values: 1.70M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4312-4312, values: 5.77M, txns: 1000000 from 6.73B-6.73B; logaddrs| values: 1.97M, txns: 1000000 from 6.73B-6.73B; logtopics| values: 5.81M, txns: 1000000 from 6.73B-6.73B; tracesfrom| values: 2.06M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 2.85M, txns: 1000000 from 6.73B-6.73B"
INFO[01-14|12:17:56.732] [snapshots] pruning state                until commit=9h50m18.460999905s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 2.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:18:15.185] [snapshots] prune domain                 name=commitment pruned keys=184714 steps=0.00-4313.00
INFO[01-14|12:19:08.164] [snapshots] pruning state                until commit=9h49m7.028553579s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 3.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:20:19.466] [snapshots] pruning state                until commit=9h47m55.723755105s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 4.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:21:43.844] [snapshots] pruning state                until commit=9h46m31.347186301s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 5.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:22:11.421] [snapshots] pruning state                until commit=9h46m3.763393154s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 5.06M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:22:11.422] [snapshots] PruneSmallBatches finished   took=13m56.236785569s stat="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 5.06M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:39:34.449] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=13.3GB sys=30.4GB
INFO[01-14|13:29:40.357] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=15.4GB sys=30.4GB
INFO[01-14|14:21:00.755] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.0GB sys=30.4GB
INFO[01-14|14:28:14.449] [snapshots:merge] Stat                   blocks=45.76M indices=45.76M alloc=15.1GB sys=30.4GB
INFO[01-14|15:14:51.957] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.4GB sys=30.4GB
INFO[01-14|16:00:19.514] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=12.8GB sys=30.4GB
INFO[01-14|16:50:39.107] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=14.4GB sys=30.4GB
INFO[01-14|17:39:49.248] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=12.8GB sys=30.4GB
INFO[01-14|18:29:33.113] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.8GB sys=30.4GB
INFO[01-14|19:19:47.884] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.2GB sys=30.4GB
INFO[01-14|19:46:46.584] [snapshots] aggregated                   step=4313 took=12m4.064842027s
INFO[01-14|19:46:46.596] [snapshots] merge state accounts(val=4312-4314, hist=4312-4314, idx=4312-4314), storage(val=4312-4314, hist=4312-4314, idx=4312-4314), code(val=4312-4314, hist=4312-4314, idx=4312-4314), commitment(val=4312-4314), receipt(val=4312-4314, hist=4312-4314, idx=4312-4314), logAddr=4312-4314, logTopic=4312-4314, traceFrom=4312-4314, traceTo=4312-4314
INFO[01-14|19:52:48.683] [snapshots] state merge done accounts(val=4312-4314, hist=4312-4314, idx=4312-4314), storage(val=4312-4314, hist=4312-4314, idx=4312-4314), code(val=4312-4314, hist=4312-4314, idx=4312-4314), commitment(val=4312-4314), receipt(val=4312-4314, hist=4312-4314, idx=4312-4314), logAddr=4312-4314, logTopic=4312-4314, traceFrom=4312-4314, traceTo=4312-4314
INFO[01-14|19:58:08.824] [snapshots] pruning state                until commit=9h51m57.464514204s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 3.88M, txns: 1000000 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 1.00M from steps 4313-4313; receipt| values: 1.70M, txns: 1000000 from 6.73B-6.74B; storage| kv: 1.00M from steps 4313-4313, values: 5.91M, txns: 1000000 from 6.73B-6.74B; logaddrs| values: 2.04M, txns: 1000000 from 6.73B-6.74B; logtopics| values: 5.86M, txns: 1000000 from 6.73B-6.74B; tracesfrom| values: 2.15M, txns: 1000000 from 6.73B-6.74B; tracesto| values: 3.01M, txns: 1000000 from 6.73B-6.74B"
INFO[01-14|20:01:11.172] [snapshots] prune index                  name=receipt pruned tx=0 pruned values=1 steps=0.00-4313.65
INFO[01-14|20:01:45.419] [snapshots] pruning state                until commit=9h48m20.858578678s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 2.00M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:02:59.690] [snapshots] pruning state                until commit=9h47m6.58944038s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 3.00M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:04:10.660] [snapshots] pruning state                until commit=9h45m55.618024072s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 4.00M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:05:35.917] [snapshots] pruning state                until commit=9h44m30.361006813s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 4.94M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:05:35.927] [snapshots] PruneSmallBatches finished   took=15m29.654402978s stat="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 4.94M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:11:18.099] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=14.7GB sys=30.4GB
INFO[01-14|20:59:29.168] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.6GB sys=30.4GB
INFO[01-14|21:49:28.550] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=12.7GB sys=30.4GB
INFO[01-14|22:39:35.797] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=14.5GB sys=30.4GB
INFO[01-14|22:45:51.402] [snapshots:merge] Stat                   blocks=45.77M indices=45.77M alloc=12.3GB sys=30.4GB
INFO[01-14|23:29:27.672] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.2GB sys=30.4GB
INFO[01-15|00:19:28.164] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=12.8GB sys=30.4GB
INFO[01-15|01:09:32.944] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=14.2GB sys=30.4GB
INFO[01-15|01:59:32.489] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.7GB sys=30.4GB
INFO[01-15|02:49:29.956] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=12.6GB sys=30.4GB
INFO[01-15|03:39:50.376] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=14.1GB sys=30.4GB
INFO[01-15|04:29:40.510] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.4GB sys=30.4GB
INFO[01-15|05:19:35.473] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=12.5GB sys=30.4GB
INFO[01-15|06:10:41.478] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.0GB sys=30.4GB
INFO[01-15|06:44:48.343] [snapshots] aggregated                   step=4314 took=12m45.168608705s
INFO[01-15|06:55:15.117] [snapshots] pruning state                until commit=9h53m0.644669736s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 3.98M, txns: 1000000 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 1.00M from steps 4314-4314; receipt| values: 1.67M, txns: 1000000 from 6.74B-6.74B; storage| kv: 1.00M from steps 4314-4314, values: 6.32M, txns: 1000000 from 6.74B-6.74B; logaddrs| values: 2.15M, txns: 1000000 from 6.74B-6.74B; logtopics| values: 6.07M, txns: 1000000 from 6.74B-6.74B; tracesfrom| values: 2.30M, txns: 1000000 from 6.74B-6.74B; tracesto| values: 3.23M, txns: 1000000 from 6.74B-6.74B"
INFO[01-15|06:58:17.837] [snapshots] prune index                  name=tracesfrom pruned tx=0 pruned values=1 steps=0.00-4314.65
INFO[01-15|06:58:33.593] [snapshots] pruning state                until commit=9h49m42.1625275s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 2.00M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|06:59:52.282] [snapshots] pruning state                until commit=9h48m23.475352354s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 3.00M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:01:08.318] [snapshots] pruning state                until commit=9h47m7.440447744s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 4.00M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:02:31.066] [snapshots] pruning state                until commit=9h45m44.693671562s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 4.93M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:02:31.075] [snapshots] PruneSmallBatches finished   took=14m15.326049953s stat="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 4.93M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:12:47.004] [snapshots:blocks:retire] Stat           blocks=45.78M indices=45.78M alloc=13.9GB sys=30.4GB
INFO[01-15|07:17:56.251] [snapshots:merge] Stat                   blocks=45.78M indices=45.78M alloc=14.4GB sys=30.4GB
INFO[01-15|07:49:37.095] [snapshots:blocks:retire] Stat           blocks=45.78M indices=45.78M alloc=12.4GB sys=30.4GB
INFO[01-15|08:41:25.675] [snapshots:blocks:retire] Stat           blocks=45.78M indices=45.78M alloc=13.7GB sys=30.4GB

This is the log from one of our nodes that has been running for three weeks.The nodes haven’t been restarted for three weeks。

It shows it never on the chain tip. You can cat the mgas in this node.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

After the restart you will do history sync. That's why the step 4134 need so much time to pruneSmallBatches. But when on the chain-tip, PruneSmallBatches won't blocking blocks processing. So it's expected.

INFO[01-13|04:31:31.813] [snapshots:blocks:retire] Stat           blocks=45.71M indices=45.71M alloc=12.4GB sys=30.4GB
INFO[01-13|04:59:18.394] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.0GB sys=30.4GB
INFO[01-13|05:04:09.071] [snapshots:merge] Stat                   blocks=45.72M indices=45.72M alloc=12.9GB sys=30.4GB
INFO[01-13|05:49:17.668] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.9GB sys=30.4GB
INFO[01-13|06:39:18.281] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.2GB sys=30.4GB
INFO[01-13|07:29:31.367] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.1GB sys=30.4GB
INFO[01-13|08:19:54.358] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=13.8GB sys=30.4GB
INFO[01-13|09:10:16.413] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=13.5GB sys=30.4GB
INFO[01-13|10:00:07.589] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.3GB sys=30.4GB
INFO[01-13|10:51:32.173] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=12.8GB sys=30.4GB
INFO[01-13|11:40:15.152] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.3GB sys=30.4GB
INFO[01-13|12:11:21.900] [snapshots] aggregated                   step=4309 took=12m15.50811451s
INFO[01-13|12:11:21.911] [snapshots] merge state accounts(val=4308-4310, hist=4308-4310, idx=4308-4310), storage(val=4308-4310, hist=4308-4310, idx=4308-4310), code(val=4308-4310, hist=4308-4310, idx=4308-4310), commitment(val=4308-4310), receipt(val=4308-4310, hist=4308-4310, idx=4308-4310), logAddr=4308-4310, logTopic=4308-4310, traceFrom=4308-4310, traceTo=4308-4310
INFO[01-13|12:18:03.783] [snapshots] state merge done accounts(val=4308-4310, hist=4308-4310, idx=4308-4310), storage(val=4308-4310, hist=4308-4310, idx=4308-4310), code(val=4308-4310, hist=4308-4310, idx=4308-4310), commitment(val=4308-4310), receipt(val=4308-4310, hist=4308-4310, idx=4308-4310), logAddr=4308-4310, logTopic=4308-4310, traceFrom=4308-4310, traceTo=4308-4310
INFO[01-13|12:23:42.182] [snapshots] pruning state                until commit=9h53m22.753361804s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.4, storage:0.2, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.2, logtopics: 0.2, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 3.91M, txns: 1000000 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 1.00M from steps 4309-4309; receipt| values: 1.74M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4309-4309, values: 6.01M, txns: 1000000 from 6.73B-6.73B; logaddrs| values: 2.01M, txns: 1000000 from 6.73B-6.73B; logtopics| values: 5.84M, txns: 1000000 from 6.73B-6.73B; tracesfrom| values: 2.17M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 3.02M, txns: 1000000 from 6.73B-6.73B"
INFO[01-13|12:26:37.316] [snapshots] pruning state                until commit=9h50m27.608800368s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 2.00M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:27:04.924] [snapshots] prune domain                 name=commitment pruned keys=485747 steps=0.00-4310.00
INFO[01-13|12:27:34.525] [snapshots] pruning state                until commit=9h49m30.404273583s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 3.00M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:28:30.050] [snapshots] pruning state                until commit=9h48m34.87421004s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 4.00M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:29:32.289] [snapshots] pruning state                until commit=9h47m32.639462717s pruneLimit=1000000 aggregatedStep=4309 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 4.95M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:29:32.293] [snapshots] PruneSmallBatches finished   took=12m27.370645086s stat="accounts| kv: 523.53k from steps 4309-4309, values: 6.04M, txns: 1542230 from 6.73B-6.73B; code| kv: 6.23k from steps 4309-4309, values: 4.48k, txns: 1521 from 6.73B-6.73B; commitment| kv: 4.95M from steps 4309-4309; receipt| values: 2.71M, txns: 1551865 from 6.73B-6.73B; storage| kv: 1.89M from steps 4309-4309, values: 6.84M, txns: 1136526 from 6.73B-6.73B; logaddrs| values: 2.33M, txns: 1156840 from 6.73B-6.73B; logtopics| values: 6.71M, txns: 1156840 from 6.73B-6.73B; tracesfrom| values: 3.37M, txns: 1542230 from 6.73B-6.73B; tracesto| values: 4.69M, txns: 1542230 from 6.73B-6.73B"
INFO[01-13|12:39:10.442] [snapshots:blocks:retire] Stat           blocks=45.72M indices=45.72M alloc=14.1GB sys=30.4GB
INFO[01-13|13:19:49.825] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.9GB sys=30.4GB
INFO[01-13|13:27:11.085] [snapshots:merge] Stat                   blocks=45.73M indices=45.73M alloc=13.5GB sys=30.4GB
INFO[01-13|14:10:35.008] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.1GB sys=30.4GB
INFO[01-13|15:08:00.669] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=13.7GB sys=30.4GB
INFO[01-13|15:59:21.903] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=12.6GB sys=30.4GB
INFO[01-13|16:41:10.472] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=13.9GB sys=30.4GB
INFO[01-13|17:30:05.450] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.2GB sys=30.4GB
INFO[01-13|18:19:42.297] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=12.9GB sys=30.4GB
INFO[01-13|18:34:09.146] [snapshots] aggregated                   step=4310 took=11m45.670193628s
INFO[01-13|18:44:00.832] [snapshots] pruning state                until commit=9h54m38.490582869s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 3.92M, txns: 1000000 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 1.00M from steps 4310-4310; receipt| values: 1.70M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4310-4310, values: 6.18M, txns: 1000000 from 6.73B-6.73B; logaddrs| values: 2.09M, txns: 1000000 from 6.73B-6.73B; logtopics| values: 5.78M, txns: 1000000 from 6.73B-6.73B; tracesfrom| values: 2.22M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 3.11M, txns: 1000000 from 6.73B-6.73B"
INFO[01-13|18:46:44.120] [snapshots] pruning state                until commit=9h51m55.199944018s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 2.00M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:47:56.352] [snapshots] pruning state                until commit=9h50m42.964802301s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 3.00M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:48:39.313] [snapshots] prune domain                 name=commitment pruned keys=700724 steps=0.00-4311.00
INFO[01-13|18:49:02.107] [snapshots] pruning state                until commit=9h49m37.211346516s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 4.00M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:50:07.806] [snapshots] pruning state                until commit=9h48m31.510788626s pruneLimit=1000000 aggregatedStep=4310 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 4.79M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|18:50:07.816] [snapshots] PruneSmallBatches finished   took=11m28.504117132s stat="accounts| kv: 587.92k from steps 4310-4310, values: 6.01M, txns: 1544154 from 6.73B-6.73B; code| kv: 4.17k from steps 4310-4310; commitment| kv: 4.79M from steps 4310-4310; receipt| values: 2.59M, txns: 1551827 from 6.73B-6.73B; storage| kv: 1.67M from steps 4310-4310, values: 6.32M, txns: 1020787 from 6.73B-6.73B; logaddrs| values: 2.18M, txns: 1040312 from 6.73B-6.73B; logtopics| values: 6.02M, txns: 1040312 from 6.73B-6.73B; tracesfrom| values: 3.35M, txns: 1544154 from 6.73B-6.73B; tracesto| values: 4.67M, txns: 1544154 from 6.73B-6.73B"
INFO[01-13|19:09:18.757] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=14.1GB sys=30.4GB
INFO[01-13|19:59:19.271] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=13.1GB sys=30.4GB
INFO[01-13|20:49:19.873] [snapshots:blocks:retire] Stat           blocks=45.73M indices=45.73M alloc=15.0GB sys=30.4GB
INFO[01-13|21:39:18.611] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=14.0GB sys=30.4GB
INFO[01-13|21:46:42.908] [snapshots:merge] Stat                   blocks=45.74M indices=45.74M alloc=13.6GB sys=30.4GB
INFO[01-13|22:29:20.770] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.8GB sys=30.4GB
INFO[01-13|23:19:34.330] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.3GB sys=30.4GB
INFO[01-14|00:09:36.295] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.9GB sys=30.4GB
INFO[01-14|00:59:53.320] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.4GB sys=30.4GB
INFO[01-14|01:49:46.079] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.5GB sys=30.4GB
INFO[01-14|02:39:31.364] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.7GB sys=30.4GB
INFO[01-14|03:29:42.195] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=12.9GB sys=30.4GB
INFO[01-14|04:16:25.311] [snapshots] aggregated                   step=4311 took=12m21.14948786s
INFO[01-14|04:16:25.315] [snapshots] merge state accounts(val=4304-4312, hist=4304-4312, idx=4304-4312), storage(val=4304-4312, hist=4304-4312, idx=4304-4312), code(val=4304-4312, hist=4304-4312, idx=4304-4312), commitment(val=4304-4312), receipt(val=4304-4312, hist=4304-4312, idx=4304-4312), logAddr=4304-4312, logTopic=4304-4312, traceFrom=4304-4312, traceTo=4304-4312
INFO[01-14|04:29:26.305] [snapshots] pruning state                until commit=9h51m58.554001485s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.4, storage:0.0, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 3.89M, txns: 1000000 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 1.00M from steps 4311-4311; receipt| values: 1.58M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 2.16M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 2.97M, txns: 1000000 from 6.73B-6.73B"
INFO[01-14|04:32:27.260] [snapshots] prune index                  name=receipt pruned tx=0 pruned values=1 steps=0.00-4311.65
INFO[01-14|04:32:50.144] [snapshots] pruning state                until commit=9h48m34.705576053s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 2.00M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:35:28.518] [snapshots] pruning state                until commit=9h45m56.332378434s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 3.00M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:38:49.892] [snapshots] pruning state                until commit=9h42m34.960771816s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 4.00M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:41:42.231] [snapshots] pruning state                until commit=9h39m42.621767067s pruneLimit=1000000 aggregatedStep=4311 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 4.77M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:41:42.242] [snapshots] PruneSmallBatches finished   took=20m17.399707769s stat="accounts| kv: 664.68k from steps 4311-4311, values: 5.96M, txns: 1538216 from 6.73B-6.73B; code| kv: 8.40k from steps 4311-4311, values: 4.28k, txns: 1187 from 6.73B-6.73B; commitment| kv: 4.77M from steps 4311-4311; receipt| values: 2.50M, txns: 1549857 from 6.73B-6.73B; storage| kv: 1.49M from steps 4311-4311, values: 5.82M, txns: 930474 from 6.73B-6.73B; logaddrs| values: 2.07M, txns: 950745 from 6.73B-6.73B; logtopics| values: 5.82M, txns: 950745 from 6.73B-6.73B; tracesfrom| values: 3.28M, txns: 1538216 from 6.73B-6.73B; tracesto| values: 4.52M, txns: 1538216 from 6.73B-6.73B"
INFO[01-14|04:44:35.926] [snapshots] state merge done accounts(val=4304-4312, hist=4304-4312, idx=4304-4312), storage(val=4304-4312, hist=4304-4312, idx=4304-4312), code(val=4304-4312, hist=4304-4312, idx=4304-4312), commitment(val=4304-4312), receipt(val=4304-4312, hist=4304-4312, idx=4304-4312), logAddr=4304-4312, logTopic=4304-4312, traceFrom=4304-4312, traceTo=4304-4312
INFO[01-14|04:52:06.488] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.7GB sys=30.4GB
INFO[01-14|05:09:30.060] [snapshots:blocks:retire] Stat           blocks=45.74M indices=45.74M alloc=13.7GB sys=30.4GB
INFO[01-14|05:59:27.552] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=12.6GB sys=30.4GB
INFO[01-14|06:05:00.001] [snapshots:merge] Stat                   blocks=45.75M indices=45.75M alloc=15.0GB sys=30.4GB
INFO[01-14|06:49:56.726] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.9GB sys=30.4GB
INFO[01-14|07:40:22.766] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.4GB sys=30.4GB
INFO[01-14|08:30:53.430] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.1GB sys=30.4GB
INFO[01-14|09:20:00.282] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=14.9GB sys=30.4GB
INFO[01-14|10:13:27.944] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=12.8GB sys=30.4GB
INFO[01-14|11:00:53.606] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=13.8GB sys=30.4GB
INFO[01-14|11:50:52.381] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=12.9GB sys=30.4GB
INFO[01-14|12:03:36.891] [snapshots] aggregated                   step=4312 took=12m12.139310639s
INFO[01-14|12:14:50.611] [snapshots] pruning state                until commit=9h53m24.587496752s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 3.81M, txns: 1000000 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 1.00M from steps 4312-4312; receipt| values: 1.70M, txns: 1000000 from 6.73B-6.73B; storage| kv: 1.00M from steps 4312-4312, values: 5.77M, txns: 1000000 from 6.73B-6.73B; logaddrs| values: 1.97M, txns: 1000000 from 6.73B-6.73B; logtopics| values: 5.81M, txns: 1000000 from 6.73B-6.73B; tracesfrom| values: 2.06M, txns: 1000000 from 6.73B-6.73B; tracesto| values: 2.85M, txns: 1000000 from 6.73B-6.73B"
INFO[01-14|12:17:56.732] [snapshots] pruning state                until commit=9h50m18.460999905s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 2.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:18:15.185] [snapshots] prune domain                 name=commitment pruned keys=184714 steps=0.00-4313.00
INFO[01-14|12:19:08.164] [snapshots] pruning state                until commit=9h49m7.028553579s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 3.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:20:19.466] [snapshots] pruning state                until commit=9h47m55.723755105s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 4.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:21:43.844] [snapshots] pruning state                until commit=9h46m31.347186301s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 5.00M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:22:11.421] [snapshots] pruning state                until commit=9h46m3.763393154s pruneLimit=1000000 aggregatedStep=4312 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 5.06M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:22:11.422] [snapshots] PruneSmallBatches finished   took=13m56.236785569s stat="accounts| kv: 596.15k from steps 4312-4312, values: 5.92M, txns: 1542848 from 6.73B-6.73B; code| kv: 5.54k from steps 4312-4312, values: 3.09k, txns: 1069 from 6.73B-6.73B; commitment| kv: 5.06M from steps 4312-4312; receipt| values: 2.64M, txns: 1552174 from 6.73B-6.73B; storage| kv: 1.83M from steps 4312-4312, values: 6.17M, txns: 1072598 from 6.73B-6.73B; logaddrs| values: 2.15M, txns: 1092391 from 6.73B-6.73B; logtopics| values: 6.34M, txns: 1092391 from 6.73B-6.73B; tracesfrom| values: 3.22M, txns: 1542848 from 6.73B-6.73B; tracesto| values: 4.47M, txns: 1542848 from 6.73B-6.73B"
INFO[01-14|12:39:34.449] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=13.3GB sys=30.4GB
INFO[01-14|13:29:40.357] [snapshots:blocks:retire] Stat           blocks=45.75M indices=45.75M alloc=15.4GB sys=30.4GB
INFO[01-14|14:21:00.755] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.0GB sys=30.4GB
INFO[01-14|14:28:14.449] [snapshots:merge] Stat                   blocks=45.76M indices=45.76M alloc=15.1GB sys=30.4GB
INFO[01-14|15:14:51.957] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.4GB sys=30.4GB
INFO[01-14|16:00:19.514] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=12.8GB sys=30.4GB
INFO[01-14|16:50:39.107] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=14.4GB sys=30.4GB
INFO[01-14|17:39:49.248] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=12.8GB sys=30.4GB
INFO[01-14|18:29:33.113] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.8GB sys=30.4GB
INFO[01-14|19:19:47.884] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.2GB sys=30.4GB
INFO[01-14|19:46:46.584] [snapshots] aggregated                   step=4313 took=12m4.064842027s
INFO[01-14|19:46:46.596] [snapshots] merge state accounts(val=4312-4314, hist=4312-4314, idx=4312-4314), storage(val=4312-4314, hist=4312-4314, idx=4312-4314), code(val=4312-4314, hist=4312-4314, idx=4312-4314), commitment(val=4312-4314), receipt(val=4312-4314, hist=4312-4314, idx=4312-4314), logAddr=4312-4314, logTopic=4312-4314, traceFrom=4312-4314, traceTo=4312-4314
INFO[01-14|19:52:48.683] [snapshots] state merge done accounts(val=4312-4314, hist=4312-4314, idx=4312-4314), storage(val=4312-4314, hist=4312-4314, idx=4312-4314), code(val=4312-4314, hist=4312-4314, idx=4312-4314), commitment(val=4312-4314), receipt(val=4312-4314, hist=4312-4314, idx=4312-4314), logAddr=4312-4314, logTopic=4312-4314, traceFrom=4312-4314, traceTo=4312-4314
INFO[01-14|19:58:08.824] [snapshots] pruning state                until commit=9h51m57.464514204s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 3.88M, txns: 1000000 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 1.00M from steps 4313-4313; receipt| values: 1.70M, txns: 1000000 from 6.73B-6.74B; storage| kv: 1.00M from steps 4313-4313, values: 5.91M, txns: 1000000 from 6.73B-6.74B; logaddrs| values: 2.04M, txns: 1000000 from 6.73B-6.74B; logtopics| values: 5.86M, txns: 1000000 from 6.73B-6.74B; tracesfrom| values: 2.15M, txns: 1000000 from 6.73B-6.74B; tracesto| values: 3.01M, txns: 1000000 from 6.73B-6.74B"
INFO[01-14|20:01:11.172] [snapshots] prune index                  name=receipt pruned tx=0 pruned values=1 steps=0.00-4313.65
INFO[01-14|20:01:45.419] [snapshots] pruning state                until commit=9h48m20.858578678s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 2.00M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:02:59.690] [snapshots] pruning state                until commit=9h47m6.58944038s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 3.00M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:04:10.660] [snapshots] pruning state                until commit=9h45m55.618024072s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 4.00M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:05:35.917] [snapshots] pruning state                until commit=9h44m30.361006813s pruneLimit=1000000 aggregatedStep=4313 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 4.94M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:05:35.927] [snapshots] PruneSmallBatches finished   took=15m29.654402978s stat="accounts| kv: 548.55k from steps 4313-4313, values: 6.00M, txns: 1539920 from 6.73B-6.74B; code| kv: 2.15k from steps 4313-4313; commitment| kv: 4.94M from steps 4313-4313; receipt| values: 2.63M, txns: 1549210 from 6.73B-6.74B; storage| kv: 1.80M from steps 4313-4313, values: 6.29M, txns: 1065495 from 6.73B-6.74B; logaddrs| values: 2.22M, txns: 1084323 from 6.73B-6.74B; logtopics| values: 6.38M, txns: 1084323 from 6.73B-6.74B; tracesfrom| values: 3.34M, txns: 1539920 from 6.73B-6.74B; tracesto| values: 4.69M, txns: 1539920 from 6.73B-6.74B"
INFO[01-14|20:11:18.099] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=14.7GB sys=30.4GB
INFO[01-14|20:59:29.168] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=13.6GB sys=30.4GB
INFO[01-14|21:49:28.550] [snapshots:blocks:retire] Stat           blocks=45.76M indices=45.76M alloc=12.7GB sys=30.4GB
INFO[01-14|22:39:35.797] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=14.5GB sys=30.4GB
INFO[01-14|22:45:51.402] [snapshots:merge] Stat                   blocks=45.77M indices=45.77M alloc=12.3GB sys=30.4GB
INFO[01-14|23:29:27.672] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.2GB sys=30.4GB
INFO[01-15|00:19:28.164] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=12.8GB sys=30.4GB
INFO[01-15|01:09:32.944] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=14.2GB sys=30.4GB
INFO[01-15|01:59:32.489] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.7GB sys=30.4GB
INFO[01-15|02:49:29.956] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=12.6GB sys=30.4GB
INFO[01-15|03:39:50.376] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=14.1GB sys=30.4GB
INFO[01-15|04:29:40.510] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.4GB sys=30.4GB
INFO[01-15|05:19:35.473] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=12.5GB sys=30.4GB
INFO[01-15|06:10:41.478] [snapshots:blocks:retire] Stat           blocks=45.77M indices=45.77M alloc=13.0GB sys=30.4GB
INFO[01-15|06:44:48.343] [snapshots] aggregated                   step=4314 took=12m45.168608705s
INFO[01-15|06:55:15.117] [snapshots] pruning state                until commit=9h53m0.644669736s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.4, storage:0.1, code:0.0, commitment:0.0, receipt:0.4, logaddrs: 0.1, logtopics: 0.1, tracesfrom: 0.4, tracesto: 0.4" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 3.98M, txns: 1000000 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 1.00M from steps 4314-4314; receipt| values: 1.67M, txns: 1000000 from 6.74B-6.74B; storage| kv: 1.00M from steps 4314-4314, values: 6.32M, txns: 1000000 from 6.74B-6.74B; logaddrs| values: 2.15M, txns: 1000000 from 6.74B-6.74B; logtopics| values: 6.07M, txns: 1000000 from 6.74B-6.74B; tracesfrom| values: 2.30M, txns: 1000000 from 6.74B-6.74B; tracesto| values: 3.23M, txns: 1000000 from 6.74B-6.74B"
INFO[01-15|06:58:17.837] [snapshots] prune index                  name=tracesfrom pruned tx=0 pruned values=1 steps=0.00-4314.65
INFO[01-15|06:58:33.593] [snapshots] pruning state                until commit=9h49m42.1625275s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 2.00M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|06:59:52.282] [snapshots] pruning state                until commit=9h48m23.475352354s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 3.00M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:01:08.318] [snapshots] pruning state                until commit=9h47m7.440447744s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 4.00M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:02:31.066] [snapshots] pruning state                until commit=9h45m44.693671562s pruneLimit=1000000 aggregatedStep=4314 stepsRangeInDB="accounts:0.0, storage:0.0, code:0.0, commitment:0.0, receipt:0.0, logaddrs: 0.0, logtopics: 0.0, tracesfrom: 0.0, tracesto: 0.0" pruned="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 4.93M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:02:31.075] [snapshots] PruneSmallBatches finished   took=14m15.326049953s stat="accounts| kv: 559.59k from steps 4314-4314, values: 6.04M, txns: 1535242 from 6.74B-6.74B; code| kv: 8.37k from steps 4314-4314, values: 4.88k, txns: 1563 from 6.74B-6.74B; commitment| kv: 4.93M from steps 4314-4314; receipt| values: 2.60M, txns: 1548371 from 6.74B-6.74B; storage| kv: 1.73M from steps 4314-4314, values: 6.49M, txns: 1030048 from 6.74B-6.74B; logaddrs| values: 2.25M, txns: 1051148 from 6.74B-6.74B; logtopics| values: 6.36M, txns: 1051148 from 6.74B-6.74B; tracesfrom| values: 3.44M, txns: 1535242 from 6.74B-6.74B; tracesto| values: 4.80M, txns: 1535242 from 6.74B-6.74B"
INFO[01-15|07:12:47.004] [snapshots:blocks:retire] Stat           blocks=45.78M indices=45.78M alloc=13.9GB sys=30.4GB
INFO[01-15|07:17:56.251] [snapshots:merge] Stat                   blocks=45.78M indices=45.78M alloc=14.4GB sys=30.4GB
INFO[01-15|07:49:37.095] [snapshots:blocks:retire] Stat           blocks=45.78M indices=45.78M alloc=12.4GB sys=30.4GB
INFO[01-15|08:41:25.675] [snapshots:blocks:retire] Stat           blocks=45.78M indices=45.78M alloc=13.7GB sys=30.4GB

This is the log from one of our nodes that has been running for three weeks.The nodes haven’t been restarted for three weeks。

It shows it never on the chain tip. You can cat the mgas in this node.

This node has consistently stayed at the chain tip for about 99% of the time, maintaining the latest block height. Only 1% of the time, when the logs show "snapshot state prune," does the block height fall behind. This is because we’ve been using it for chain scanning. We have multiple Erigon nodes, and we also compare the block height with the official nodes and our other nodes. If this node falls behind, an alert is triggered immediately, and we are notified right away.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

image

logs like thie , 99%+ of the time .

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

That's on-chain tip. But you logs shows before pruning state is near 9Mgas/s. I believe your other tasks may be affecting that. I suggest using a better device with more RAM and a more powerful CPU.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

That's on-chain tip. But you logs shows before pruning state is near 9Mgas/s. I believe your other tasks may be affecting that. I suggest using a better device with more RAM and a more powerful CPU.

The memory is sufficient. I've tested it on i3en.3xlarge,i4i.4xlarge,r7a.4xlarge. It have the same logs 。That is, every day there will be 10 minutes to 20 minutes of block not on-chain tip.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

So could you show the log before the 10 minutes to 20 minutes lag. BTW, you can upload the log file.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

So could you show the log before the 10 minutes to 20 minutes lag. BTW, you can upload the log file.

[
8.log
](url)

image

This node only falls behind during the pruning period, and at all other times, it is at the on-chain tip, maintaining the latest block.
The monitoring time zone should be 8 hours ahead of the log time zone.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

Other nodes are similar. But mgas/s is going to be higher than this node.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

It will affect the performance, but if your node has a stable live sync speed of 40M gas/s, it shouldn't lag.

r7a.4xlarge + 16k iops gp3 volume

What's about stop the chain-scanning tasks? May you need bigger ram and higher cpu frequencies. BTW, we offer a experiment sync mode, it won't calu merkle root, so it won't have commitment file. It can work with DISCARD_COMMITMENT=true.

We are monitoring it with all chain-scanning tasks stopped. As you can see from the grafana chart, the synchronization thread suffered a lot during the snapshort prune process. image

Disk data of this node during this period

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

The reason is your device need improve. You can see during the log your speed is nearly blk/s=0.5. You should make sure have avg 40Mgas/s in your node.
Why every day only have 10 minutes to 20 minutes?
Because it will pruning state every step. One day nearly 2 step(nearly 3 million tx). It means it will do two times pruning state. During the pruning state, it will blocking the block process.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 15, 2025

The PruneSmallBatches logic is when you can finish prune in 250ms, it will don't blocking the block process. But when you can't finish during this time. It will do PruneSmallBatches every time finish the step which will blocking the block process.
It's obvious your node can't finish that.

@atlasW
Copy link
Author

atlasW commented Jan 15, 2025

The PruneSmallBatches logic is when you can finish prune in 250ms, it will don't blocking the block process. But when you can't finish during this time. It will do PruneSmallBatches every time finish the step which will blocking the block process. It's obvious your node can't finish that.

thanks a lot .let me hava a try

@atlasW
Copy link
Author

atlasW commented Jan 16, 2025

The reason is your device need improve. You can see during the log your speed is nearly blk/s=0.5. You should make sure have avg 40Mgas/s in your node. Why every day only have 10 minutes to 20 minutes? Because it will pruning state every step. One day nearly 2 step(nearly 3 million tx). It means it will do two times pruning state. During the pruning state, it will blocking the block process.

Are you sure you're using a gp3 storage with 16k IOPS? Why is it that with the same machine and storage I agreed on, it can't even reach 40M gas/s without any API requests?

@atlasW
Copy link
Author

atlasW commented Jan 16, 2025

--prune.mode=archive \ --prune.distance.blocks=900000 \ --prune.distance=900000

Does these affect the speed?

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 16, 2025

--prune.mode=archive \ --prune.distance.blocks=900000 \ --prune.distance=900000

Does these affect the speed?

No, i make sure it reach 40Mgas/s from our grafana.

@atlasW
Copy link
Author

atlasW commented Jan 16, 2025

Sorry, it's an 16000 IOPS gp3 volume

image On the left is a 16k IOPS gp3 hard drive. However, the performance is still very low. Could it be that the high traffic to our node is affecting the synchronization speed? We have several chain-scanning tasks. Currently, the speed of the nodes is not lagging behind; they only fall behind when the nodes display snapshot prune logs.

Can you see the parameters of your hard drive?

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 16, 2025

Sorry, it's an 16000 IOPS gp3 volume

image On the left is a 16k IOPS gp3 hard drive. However, the performance is still very low. Could it be that the high traffic to our node is affecting the synchronization speed? We have several chain-scanning tasks. Currently, the speed of the nodes is not lagging behind; they only fall behind when the nodes display snapshot prune logs.

Can you see the parameters of your hard drive?

Check with our dev, it's 250 MiB/s

@atlasW
Copy link
Author

atlasW commented Jan 16, 2025

image

The speed is now achievable because the program was previously running inside a Docker container. Now it's been moved to run outside the container. For some reason, the speed was significantly slower when it was running inside the container.
The log on the left is from a node running directly on an EC2 Linux 2023 system, while the log on the right is from a node running inside a container. The speed of the node running in the container is significantly slower compared to running directly on the system.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 17, 2025

It may affect the Mmap or other system calls performance, maybe try bigger memory limit.

@atlasW
Copy link
Author

atlasW commented Jan 17, 2025

It may affect the Mmap or other system calls performance, maybe try bigger memory limit.

Image

A large enough memory limit is set. But, it's not as fast as outside the container.

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 20, 2025

It may affect the Mmap or other system calls performance, maybe try bigger memory limit.

Image A large enough memory limit is set. But, it's not as fast as outside the container.

You set 35GB mem limit? What about the limit when out side the container?

@atlasW
Copy link
Author

atlasW commented Jan 20, 2025

It may affect the Mmap or other system calls performance, maybe try bigger memory limit.

Image A large enough memory limit is set. But, it's not as fast as outside the container.

You set 35GB mem limit? What about the limit when out side the container?

Increasing the value of memory limit really improves the speed. What is this about?

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 20, 2025

@blxdyx
Copy link
Collaborator

blxdyx commented Jan 22, 2025

Reopen if still have problem.

@blxdyx blxdyx closed this as completed Jan 22, 2025
@snf
Copy link

snf commented Feb 18, 2025

I'm getting this from time to time but it doesn't seem like I have a memory limit set in the container? @atlasW which arguments did you end up using for your conf?

CONTAINER ID   NAME                      CPU %     MEM USAGE / LIMIT     MEM %     NET I/O           BLOCK I/O         PIDS
6ff0651fe78b   bsc-erigon_erigon-bsc_1   48.57%    33.49GiB / 251.4GiB   13.32%    225GB / 1.36TB    7.04TB / 26.4TB   219

@blxdyx
Copy link
Collaborator

blxdyx commented Feb 19, 2025

cpu, ram and disk all will affect. Could you show more detail.

@atlasW
Copy link
Author

atlasW commented Feb 19, 2025

I'm getting this from time to time but it doesn't seem like I have a memory limit set in the container? @atlasW which arguments did you end up using for your conf?

CONTAINER ID   NAME                      CPU %     MEM USAGE / LIMIT     MEM %     NET I/O           BLOCK I/O         PIDS
6ff0651fe78b   bsc-erigon_erigon-bsc_1   48.57%    33.49GiB / 251.4GiB   13.32%    225GB / 1.36TB    7.04TB / 26.4TB   219

No matter how I set up and configure it inside the container, the problem couldn’t be resolved. I tried various Docker configurations and node settings, but none of them could prevent this issue. In the end, I abandoned the container and ran it directly on the system, which finally made it stable.

@snf
Copy link

snf commented Feb 19, 2025

Welp, I'm not sure what it could be, it only started happening with Erigon3, it never happened with the previous versions. I don't think docker should affect the i/o performance

  • Disk used exclusively for the node: Samsung Electronics Co Ltd NVMe SSD Controller PM9A1/PM9A3/980PRO - 8TB
  • CPU: AMD EPYC 9454P 48-Core Processor
Filesystem            Size  Used Avail Use% Mounted on
/dev/nvme2n1p1        7.0T  4.7T  2.4T  68% /media/big0
 free -m
               total        used        free      shared  buff/cache   available
Mem:          257408      161978       14546          35       83170       95429
Swap:              0           0           0

@blxdyx blxdyx reopened this Feb 19, 2025
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

3 participants