log: "Replaying blocks" / "Rolling forward" logging is rate-limited

fanquake commented at 11:49 am on November 3, 2025: member

This should probably be excluded from rate-limiting:

 02025-11-03T11:42:20Z Rolling forward 000000004a2ed49fca14f2a937747a307110a8da0a5052dd9065548c36aaaf17 (9627)
 12025-11-03T11:42:20Z Rolling forward 0000000019bee828395d1d6a283362222991a5bcfa37604b381ca0db25b1c191 (9628)
 22025-11-03T11:42:20Z Rolling forward 000000008b57d05f433a6fcf0942b7fef226678fc0a6a623088fd846f48274e0 (9629)
 32025-11-03T11:42:20Z Rolling forward 00000000de934300732c23fa07ebc4778d9b64a14d998e6eb3cffbefe01ad950 (9630)
 4[*] 2025-11-03T11:42:20Z [warning] Excessive logging detected from ./validation.cpp:4947 (bool Chainstate::ReplayBlocks()): >1048576 bytes logged during the last time window of 3600s. Suppressing logging to disk from this source location until time window resets. Console logging unaffected. Last log entry.
 5[*] 2025-11-03T11:42:20Z Rolling forward 0000000059958a2cc66e0d460faa7d11f4bff6a332ccac1c1435d25fd812a4d4 (9631)
 6[*] 2025-11-03T11:42:20Z Rolling forward 00000000062252579e0d74e9fbee693645f52b085a5e29928b1c213b6a8f93aa (9632)
 7[*] 2025-11-03T11:42:20Z Rolling forward 000000002a50b1ae5b32b06970f7a7a082c05328dac9969ccb55f7a05e5f25a0 (9633)
 8[*] 2025-11-03T11:42:20Z Rolling forward 00000000260d5e479301d6f898e16ff58cf2e94da0ceb2f0c039eed791170d44 (9634)
 9[*] 2025-11-03T11:42:20Z Rolling forward 00000000a0fac1ecca01d1eb33ce4981634ee2de5b6ebd944988a5010b147359 (9635)
10<snip>
11[*] 2025-11-03T11:42:35Z Rolling forward 000000000000089e9cca1b62ff9c0dcffce69e514f86c6ab78c5d95d0bc327f2 (137258)
12[*] 2025-11-03T11:42:35Z Rolling forward 000000000000047457383ea46e0313250f1c327f04ab3001267ce9ee8e6031d1 (137259)
13[*] 2025-11-03T11:42:35Z Rolling forward 00000000000007800f9de4fa37e8de049649a1efe8236508678a4d2f45052d72 (137260)
14[*] 2025-11-03T11:42:35Z Rolling forward 00000000000002eb049c8f116e6209e400d9568770c8bca3a21fd00771e8c0f2 (137261)
15[*] 2025-11-03T11:42:35Z Rolling forward 00000000000002cdcb65d9f67986589d12dbab16e049ac598fda46da13e95f17 (137262)
16[*] 2025-11-03T11:42:35Z Rolling forward 00000000000008754a10cbd100b3b7e5da82eaa6183470fb53bba9a98421cc9b (137263)
17[*] 2025-11-03T11:42:35Z Rolling forward 00000000000003c3e43b723c40e7b3753459c13c5ba035578a4a0a25773fa9c5 (137264)
18[*] 2025-11-03T11:42:36Z Loaded best chain: hashBestChain=00000000000003c3e43b723c40e7b3753459c13c5ba035578a4a0a25773fa9c5 height=137264 date=2011-07-21T00:58:46Z progress=0.000862
19[*] 2025-11-03T11:42:36Z init message: Verifying blocks…
20[*] 2025-11-03T11:42:36Z Verifying last 6 blocks at level 3
21[*] 2025-11-03T11:42:36Z Verification progress: 0%
22[*] 2025-11-03T11:42:36Z Shutdown requested. Exiting.
23[*] 2025-11-03T11:42:36Z Shutdown in progress...
24[*] 2025-11-03T11:42:36Z scheduler thread exit
25[*] 2025-11-03T11:42:36Z Flushed fee estimates to fee_estimates.dat.
26[*] 2025-11-03T11:42:36Z Shutdown done

Resulting in a debug.log like:

 02025-11-03T11:42:20Z Rolling forward 0000000002026d84baaf6c16bbc54f39bb89f10aa1b135d05265fad0266976a7 (9621)
 12025-11-03T11:42:20Z Rolling forward 000000000276b83d2c228c3d7de5fdc026ea345e4a8d77c098d654ff9464f89c (9622)
 22025-11-03T11:42:20Z Rolling forward 00000000dfac8a589bbb066ab83b93d49d463ab2dbf77ba0d70ad51c11177a3e (9623)
 32025-11-03T11:42:20Z Rolling forward 00000000fb2a066ba024f8e7fe54bb61599f7a2810748598aad77f369e9fcc9b (9624)
 42025-11-03T11:42:20Z Rolling forward 00000000b91b9b688617963d22b2b4d638cda7588a87c830f2111fc3eeb1b526 (9625)
 52025-11-03T11:42:20Z Rolling forward 00000000a6b6f1b2e7526a8c26043a01ce7098cbc5d95896b4dc24836b908411 (9626)
 62025-11-03T11:42:20Z Rolling forward 000000004a2ed49fca14f2a937747a307110a8da0a5052dd9065548c36aaaf17 (9627)
 72025-11-03T11:42:20Z Rolling forward 0000000019bee828395d1d6a283362222991a5bcfa37604b381ca0db25b1c191 (9628)
 82025-11-03T11:42:20Z Rolling forward 000000008b57d05f433a6fcf0942b7fef226678fc0a6a623088fd846f48274e0 (9629)
 92025-11-03T11:42:20Z Rolling forward 00000000de934300732c23fa07ebc4778d9b64a14d998e6eb3cffbefe01ad950 (9630)
10[*] 2025-11-03T11:42:20Z [warning] Excessive logging detected from ./validation.cpp:4947 (bool Chainstate::ReplayBlocks()): >1048576 bytes logged during the last time window of 3600s. Suppressing logging to disk from this source location until time window resets. Console logging unaffected. Last log entry.
11[*] 2025-11-03T11:42:20Z Rolling forward 0000000059958a2cc66e0d460faa7d11f4bff6a332ccac1c1435d25fd812a4d4 (9631)
12[*] 2025-11-03T11:42:36Z Loaded best chain: hashBestChain=00000000000003c3e43b723c40e7b3753459c13c5ba035578a4a0a25773fa9c5 height=137264 date=2011-07-21T00:58:46Z progress=0.000862
13[*] 2025-11-03T11:42:36Z init message: Verifying blocks…
14[*] 2025-11-03T11:42:36Z Verifying last 6 blocks at level 3
15[*] 2025-11-03T11:42:36Z Verification progress: 0%
16[*] 2025-11-03T11:42:36Z Shutdown requested. Exiting.
17[*] 2025-11-03T11:42:36Z Shutdown in progress...
18[*] 2025-11-03T11:42:36Z scheduler thread exit
19[*] 2025-11-03T11:42:36Z Flushed fee estimates to fee_estimates.dat.
20[*] 2025-11-03T11:42:36Z Shutdown done

fanquake added the label Utils/log/libs on Nov 3, 2025

stickies-v commented at 12:56 pm on November 3, 2025: contributor

Is there a good reason to unconditionally log this statement? It seems to me like a single unconditional “Replaying blocks from hash (height)” before starting the loop, and a conditional (debug) per-iteration statement would be sensible?

l0rinc commented at 1:33 pm on November 3, 2025: contributor

I have pushed something similar in https://github.com/bitcoin/bitcoin/pull/33443

fanquake commented at 4:45 pm on November 10, 2025: member

Closing given #33443.

fanquake closed this on Nov 10, 2025

log: “Replaying blocks” / “Rolling forward” logging is rate-limited #33769