Skip to content
This repository was archived by the owner on Jan 22, 2025. It is now read-only.

Don't stop to find newer cluster-confirmed roots#14557

Merged
mergify[bot] merged 3 commits intosolana-labs:masterfrom
ryoqun:blockstore-more-cluster-roots
Jan 13, 2021
Merged

Don't stop to find newer cluster-confirmed roots#14557
mergify[bot] merged 3 commits intosolana-labs:masterfrom
ryoqun:blockstore-more-cluster-roots

Conversation

@ryoqun
Copy link
Copy Markdown
Contributor

@ryoqun ryoqun commented Jan 13, 2021

Problem

ledger processing are too prone to OOM with --no-snapshot-fetch.

Well, sadly, detection of cluster-confirmed root is done only once..... So, after that, it'll soon no exhaustively_free_unused_resource(), grow accounts db many banks and no squash because of no roots and killing validator via OOM and looping over and over. This confuses some partners very seriously:

This also means this also causes validator resumes with very unoptimized way because cleaning is stopped at latter part of blockstore_processor.

[2021-01-12T14:55:07.993768788Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257516, last root slot=60257515 slots=2 slots/s=0.2 txs/s=77.4
[2021-01-12T14:55:10.152211962Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257520, last root slot=60257519 slots=4 slots/s=2.0 txs/s=1033
[2021-01-12T14:55:12.219953425Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257527, last root slot=60257526 slots=7 slots/s=3.5 txs/s=1772
[2021-01-12T14:55:14.397713615Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257532, last root slot=60257531 slots=5 slots/s=2.5 txs/s=1121.5
[2021-01-12T14:55:16.617987351Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257539, last root slot=60257538 slots=4 slots/s=2.0 txs/s=953
[2021-01-12T14:55:27.241800066Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-12T14:55:27.243694525Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257543, last root slot=60257542 slots=4 slots/s=0.4 txs/s=250.2
[2021-01-12T14:55:29.978999830Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257548, last root slot=60257547 slots=5 slots/s=2.5 txs/s=1333.5
[2021-01-12T14:55:32.699707231Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257553, last root slot=60257552 slots=5 slots/s=2.5 txs/s=1448.5
[2021-01-12T14:55:34.777933322Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257558, last root slot=60257557 slots=5 slots/s=2.5 txs/s=1425.5
[2021-01-12T14:55:36.972528582Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257562, last root slot=60257561 slots=4 slots/s=2.0 txs/s=1151
[2021-01-12T14:55:46.327910050Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-12T14:55:46.331777880Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257563, last root slot=60257562 slots=1 slots/s=0.11111111 txs/s=51.555557
[2021-01-12T14:55:49.147052147Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257569, last root slot=60257568 slots=6 slots/s=3.0 txs/s=1801.5
[2021-01-12T14:55:51.746842686Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257573, last root slot=60257572 slots=4 slots/s=2.0 txs/s=1241
[2021-01-12T14:55:54.246564314Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257578, last root slot=60257577 slots=5 slots/s=2.5 txs/s=1120
[2021-01-12T14:56:05.493506448Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-12T14:56:05.498745039Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257585, last root slot=60257584 slots=7 slots/s=0.6363636 txs/s=360.54544
[2021-01-12T14:56:07.799532238Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257589, last root slot=60257588 slots=4 slots/s=2.0 txs/s=1152.5
[2021-01-12T14:56:10.199182731Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257594, last root slot=60257593 slots=5 slots/s=2.5 txs/s=1174.5
[2021-01-12T14:56:12.211459602Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257600, last root slot=60257599 slots=6 slots/s=3.0 txs/s=1560
[2021-01-12T14:56:14.861738827Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257605, last root slot=60257600 slots=5 slots/s=2.5 txs/s=1270.5
[2021-01-12T14:56:16.895314865Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257611, last root slot=60257600 slots=6 slots/s=3.0 txs/s=1496.5
[2021-01-12T14:56:19.048199086Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257617, last root slot=60257600 slots=6 slots/s=3.0 txs/s=1363
[2021-01-12T14:56:21.198167869Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257623, last root slot=60257600 slots=6 slots/s=3.0 txs/s=1167
[2021-01-12T14:56:23.244259183Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257629, last root slot=60257600 slots=6 slots/s=3.0 txs/s=1593.5
[2021-01-12T14:56:25.566602033Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60257601, observed in bank: 60257633
[2021-01-12T14:56:36.165297892Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-12T14:56:36.167401175Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257634, last root slot=60257601 slots=5 slots/s=0.41666666 txs/s=219.16667
[2021-01-12T14:56:37.018403634Z WARN  solana_ledger::blockstore_processor] slot 60257638 failed to verify: failed to load entries
[2021-01-12T14:56:38.592820989Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257641, last root slot=60257601 slots=5 slots/s=2.5 txs/s=850.5
[2021-01-12T14:56:40.784811195Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257647, last root slot=60257601 slots=6 slots/s=3.0 txs/s=1465.5
[2021-01-12T14:56:43.153875389Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257652, last root slot=60257601 slots=5 slots/s=2.5 txs/s=1321
[2021-01-12T14:56:45.917459794Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257657, last root slot=60257601 slots=5 slots/s=2.5 txs/s=1406.5
[2021-01-12T14:56:48.023700608Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257661, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1103.5
[2021-01-12T14:56:50.933719919Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257665, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1596
[2021-01-12T14:56:53.214754508Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257673, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1023
[2021-01-12T14:56:55.525049560Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257678, last root slot=60257601 slots=5 slots/s=2.5 txs/s=1567
[2021-01-12T14:56:57.994518535Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257683, last root slot=60257601 slots=5 slots/s=2.5 txs/s=1226.5
[2021-01-12T14:57:00.218047200Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257687, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1051.5
[2021-01-12T14:57:02.469701641Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257691, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1084.5
[2021-01-12T14:57:04.911886367Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257695, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1314
[2021-01-12T14:57:06.960446040Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257697, last root slot=60257601 slots=2 slots/s=1.0 txs/s=1033
[2021-01-12T14:57:09.168088415Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257699, last root slot=60257601 slots=2 slots/s=1.0 txs/s=913
[2021-01-12T14:57:11.199076937Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257708, last root slot=60257601 slots=6 slots/s=3.0 txs/s=1508
[2021-01-12T14:57:13.615039025Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257712, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1282.5
[2021-01-12T14:57:15.893448249Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60257716, last root slot=60257601 slots=4 slots/s=2.0 txs/s=1191

Summary of Changes

I'll add a test soon.
(ADDED d8a7c5a)

Fixes #

@ryoqun ryoqun requested a review from carllin January 13, 2021 12:24
@ryoqun
Copy link
Copy Markdown
Contributor Author

ryoqun commented Jan 13, 2021

oh test failed; nice!

@ryoqun ryoqun added this to the v1.4.24 milestone Jan 13, 2021
@ryoqun
Copy link
Copy Markdown
Contributor Author

ryoqun commented Jan 13, 2021

This pr is vouched from @carllin via dm.

I'm going to merge this as this is very nasty one and considering this is effectively one line change.

@ryoqun
Copy link
Copy Markdown
Contributor Author

ryoqun commented Jan 13, 2021

test result:

BEFORE

[2021-01-13T15:20:28.917529257Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445037, last root slot=60445036 slots=7 slots/s=3.5 txs/s=1886
[2021-01-13T15:20:30.973839373Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445043, last root slot=60445042 slots=6 slots/s=3.0 txs/s=1304
[2021-01-13T15:20:33.076065421Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445054, last root slot=60445053 slots=11 slots/s=5.5 txs/s=2678.5
[2021-01-13T15:20:35.276225642Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445065, last root slot=60445064 slots=11 slots/s=5.5 txs/s=2723.5
[2021-01-13T15:20:44.130544595Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:20:44.133185618Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445073, last root slot=60445072 slots=4 slots/s=0.5 txs/s=365.5
[2021-01-13T15:20:46.620057589Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445083, last root slot=60445082 slots=10 slots/s=5.0 txs/s=2733
[2021-01-13T15:20:49.066193746Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445089, last root slot=60445088 slots=6 slots/s=3.0 txs/s=2247.5
[2021-01-13T15:20:51.083958252Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445099, last root slot=60445098 slots=7 slots/s=3.5 txs/s=1707.5
[2021-01-13T15:20:53.186579701Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445106, last root slot=60445105 slots=7 slots/s=3.5 txs/s=2081.5
[2021-01-13T15:21:01.512197679Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:21:01.513639258Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445114, last root slot=60445113 slots=4 slots/s=0.5 txs/s=222.875
[2021-01-13T15:21:03.924169589Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445122, last root slot=60445121 slots=7 slots/s=3.5 txs/s=2258
[2021-01-13T15:21:06.030866379Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445131, last root slot=60445130 slots=9 slots/s=4.5 txs/s=2069
[2021-01-13T15:21:08.267677990Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445146, last root slot=60445145 slots=8 slots/s=4.0 txs/s=2217
[2021-01-13T15:21:10.376420538Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445151, last root slot=60445150 slots=5 slots/s=2.5 txs/s=2030
[2021-01-13T15:21:18.781282618Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:21:18.784682184Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445157, last root slot=60445156 slots=5 slots/s=0.625 txs/s=356.125
[2021-01-13T15:21:20.798167982Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445167, last root slot=60445166 slots=10 slots/s=5.0 txs/s=2358
[2021-01-13T15:21:22.947652218Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445178, last root slot=60445177 slots=11 slots/s=5.5 txs/s=2613
[2021-01-13T15:21:25.059767085Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445189, last root slot=60445188 slots=11 slots/s=5.5 txs/s=2802.5
[2021-01-13T15:21:27.202744675Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445203, last root slot=60445202 slots=14 slots/s=7.0 txs/s=2948
[2021-01-13T15:21:29.233909226Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445215, last root slot=60445207 slots=12 slots/s=6.0 txs/s=3021.5
[2021-01-13T15:21:31.269312740Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445225, last root slot=60445207 slots=10 slots/s=5.0 txs/s=2571
[2021-01-13T15:21:33.582328003Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445237, last root slot=60445207 slots=12 slots/s=6.0 txs/s=2615.5
[2021-01-13T15:21:35.613531945Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445245, last root slot=60445207 slots=8 slots/s=4.0 txs/s=1910.5
[2021-01-13T15:21:37.680381475Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445256, last root slot=60445207 slots=11 slots/s=5.5 txs/s=2048.5
[2021-01-13T15:21:38.952439055Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445208, observed in bank: 60445260
[2021-01-13T15:21:51.040096486Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:21:51.041517928Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445261, last root slot=60445208 slots=5 slots/s=0.3846154 txs/s=214.46153
[2021-01-13T15:21:53.097609882Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445272, last root slot=60445208 slots=11 slots/s=5.5 txs/s=2481.5
[2021-01-13T15:21:55.189035176Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445283, last root slot=60445208 slots=11 slots/s=5.5 txs/s=2621.5
[2021-01-13T15:21:57.313244838Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445292, last root slot=60445208 slots=9 slots/s=4.5 txs/s=2542
[2021-01-13T15:21:59.328592655Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445302, last root slot=60445208 slots=10 slots/s=5.0 txs/s=2486
[2021-01-13T15:22:01.434651144Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445310, last root slot=60445208 slots=8 slots/s=4.0 txs/s=2043.5
[2021-01-13T15:22:03.652713810Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445319, last root slot=60445208 slots=9 slots/s=4.5 txs/s=2538.5
[2021-01-13T15:22:05.734181127Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445329, last root slot=60445208 slots=8 slots/s=4.0 txs/s=2384
[2021-01-13T15:22:08.064488046Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445341, last root slot=60445208 slots=8 slots/s=4.0 txs/s=2314.5
[2021-01-13T15:22:10.099270026Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445349, last root slot=60445208 slots=8 slots/s=4.0 txs/s=2379
[2021-01-13T15:22:12.522550894Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445359, last root slot=60445208 slots=7 slots/s=3.5 txs/s=1986
[2021-01-13T15:22:14.633561231Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445364, last root slot=60445208 slots=4 slots/s=2.0 txs/s=1619.5
[2021-01-13T15:22:17.037649238Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445377, last root slot=60445208 slots=7 slots/s=3.5 txs/s=1738.5
[2021-01-13T15:22:19.351099961Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445382, last root slot=60445208 slots=5 slots/s=2.5 txs/s=1894
[2021-01-13T15:22:21.405618116Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445389, last root slot=60445208 slots=6 slots/s=3.0 txs/s=1755
[2021-01-13T15:22:23.815581150Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445401, last root slot=60445208 slots=12 slots/s=6.0 txs/s=3054
[2021-01-13T15:22:25.933573752Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445411, last root slot=60445208 slots=10 slots/s=5.0 txs/s=2259.5
[2021-01-13T15:22:28.110049474Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445418, last root slot=60445208 slots=7 slots/s=3.5 txs/s=1963
[2021-01-13T15:22:30.204816264Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445425, last root slot=60445208 slots=6 slots/s=3.0 txs/s=2105
[2021-01-13T15:22:32.243490759Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445433, last root slot=60445208 slots=8 slots/s=4.0 txs/s=2205

@ryoqun
Copy link
Copy Markdown
Contributor Author

ryoqun commented Jan 13, 2021

AFTER

[2021-01-13T15:31:01.711177562Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444524, last root slot=60444523 slots=10 slots/s=5.0 txs/s=2290.5
[2021-01-13T15:31:10.740540545Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:31:10.741520104Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444530, last root slot=60444529 slots=6 slots/s=0.6666667 txs/s=336.33334
[2021-01-13T15:31:13.074024461Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444541, last root slot=60444540 slots=11 slots/s=5.5 txs/s=3185
[2021-01-13T15:31:15.603669164Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444547, last root slot=60444546 slots=6 slots/s=3.0 txs/s=2418
[2021-01-13T15:31:17.710860744Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444556, last root slot=60444555 slots=5 slots/s=2.5 txs/s=1455.5
[2021-01-13T15:31:19.846813437Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444566, last root slot=60444565 slots=10 slots/s=5.0 txs/s=2580
[2021-01-13T15:31:28.442876942Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:31:28.444512865Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444571, last root slot=60444570 slots=5 slots/s=0.625 txs/s=300
[2021-01-13T15:31:30.802581262Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444582, last root slot=60444581 slots=11 slots/s=5.5 txs/s=3249.5
[2021-01-13T15:31:32.823657430Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444591, last root slot=60444590 slots=7 slots/s=3.5 txs/s=2023
[2021-01-13T15:31:34.869297991Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444600, last root slot=60444599 slots=9 slots/s=4.5 txs/s=2204
[2021-01-13T15:31:37.590318822Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444609, last root slot=60444608 slots=9 slots/s=4.5 txs/s=2687.5
[2021-01-13T15:31:46.075872943Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:31:46.079226708Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444613, last root slot=60444612 slots=4 slots/s=0.5 txs/s=270.125
[2021-01-13T15:31:48.243831538Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444621, last root slot=60444620 slots=8 slots/s=4.0 txs/s=2597.5
[2021-01-13T15:31:50.256818365Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444632, last root slot=60444627 slots=7 slots/s=3.5 txs/s=1828
[2021-01-13T15:31:52.496207758Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444641, last root slot=60444640 slots=5 slots/s=2.5 txs/s=1478
[2021-01-13T15:31:54.741185947Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444645, last root slot=60444644 slots=4 slots/s=2.0 txs/s=1638.5
[2021-01-13T15:32:03.620159585Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:32:03.625022109Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444653, last root slot=60444652 slots=4 slots/s=0.5 txs/s=249.875
[2021-01-13T15:32:05.809650298Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444661, last root slot=60444660 slots=5 slots/s=2.5 txs/s=2226.5
[2021-01-13T15:32:08.005083047Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444669, last root slot=60444668 slots=8 slots/s=4.0 txs/s=2440.5
[2021-01-13T15:32:10.186154019Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444681, last root slot=60444680 slots=8 slots/s=4.0 txs/s=1975
[2021-01-13T15:32:12.302804737Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444687, last root slot=60444686 slots=6 slots/s=3.0 txs/s=1679
[2021-01-13T15:32:20.999960682Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:32:21.003223491Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444692, last root slot=60444691 slots=5 slots/s=0.625 txs/s=356.125
[2021-01-13T15:32:23.130993680Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444701, last root slot=60444700 slots=9 slots/s=4.5 txs/s=2663
[2021-01-13T15:32:25.236675164Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444711, last root slot=60444710 slots=10 slots/s=5.0 txs/s=2644
[2021-01-13T15:32:27.266512967Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444719, last root slot=60444718 slots=8 slots/s=4.0 txs/s=2276.5
[2021-01-13T15:32:29.293250158Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444727, last root slot=60444726 slots=8 slots/s=4.0 txs/s=1957
[2021-01-13T15:32:38.641021516Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:32:38.644157260Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444735, last root slot=60444734 slots=8 slots/s=0.8888889 txs/s=521
[2021-01-13T15:32:40.786094593Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444746, last root slot=60444745 slots=11 slots/s=5.5 txs/s=2768.5
[2021-01-13T15:32:42.848344213Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444759, last root slot=60444758 slots=10 slots/s=5.0 txs/s=2361
[2021-01-13T15:32:45.024259420Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444769, last root slot=60444768 slots=10 slots/s=5.0 txs/s=2799.5
[2021-01-13T15:32:47.063786575Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444780, last root slot=60444779 slots=7 slots/s=3.5 txs/s=1749.5
[2021-01-13T15:32:56.223957259Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:32:56.226754513Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444787, last root slot=60444786 slots=7 slots/s=0.7777778 txs/s=421
[2021-01-13T15:32:58.343591737Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444797, last root slot=60444796 slots=10 slots/s=5.0 txs/s=2885.5
[2021-01-13T15:33:00.555821149Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444809, last root slot=60444808 slots=12 slots/s=6.0 txs/s=3097.5
[2021-01-13T15:33:02.792076707Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444821, last root slot=60444820 slots=9 slots/s=4.5 txs/s=2455
[2021-01-13T15:33:04.984348918Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444829, last root slot=60444828 slots=8 slots/s=4.0 txs/s=2364.5
[2021-01-13T15:33:13.794798347Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:33:13.795570096Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444835, last root slot=60444834 slots=6 slots/s=0.75 txs/s=345.625
[2021-01-13T15:33:15.961696269Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444845, last root slot=60444844 slots=6 slots/s=3.0 txs/s=1825
[2021-01-13T15:33:17.975097189Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444860, last root slot=60444855 slots=7 slots/s=3.5 txs/s=1499
[2021-01-13T15:33:20.113125588Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444865, last root slot=60444864 slots=5 slots/s=2.5 txs/s=1874
[2021-01-13T15:33:22.132169737Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444873, last root slot=60444872 slots=8 slots/s=4.0 txs/s=2065.5
[2021-01-13T15:33:31.566493669Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:33:31.570259215Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444881, last root slot=60444880 slots=8 slots/s=0.8888889 txs/s=465.8889
[2021-01-13T15:33:33.782108230Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444892, last root slot=60444891 slots=11 slots/s=5.5 txs/s=2703
[2021-01-13T15:33:35.785523854Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444901, last root slot=60444900 slots=9 slots/s=4.5 txs/s=2651
[2021-01-13T15:33:37.945187434Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444910, last root slot=60444909 slots=9 slots/s=4.5 txs/s=2349
[2021-01-13T15:33:39.996002824Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444917, last root slot=60444916 slots=7 slots/s=3.5 txs/s=2032
[2021-01-13T15:33:49.358516235Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:33:49.361184946Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444925, last root slot=60444924 slots=8 slots/s=0.8888889 txs/s=468.33334
[2021-01-13T15:33:51.491561632Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444935, last root slot=60444934 slots=9 slots/s=4.5 txs/s=2340
[2021-01-13T15:33:54.039085015Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444949, last root slot=60444948 slots=11 slots/s=5.5 txs/s=3310
[2021-01-13T15:33:56.367449385Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444958, last root slot=60444957 slots=8 slots/s=4.0 txs/s=2236.5
[2021-01-13T15:33:58.597380103Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444973, last root slot=60444968 slots=11 slots/s=5.5 txs/s=2672.5
[2021-01-13T15:34:07.051700694Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:34:07.052584011Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444975, last root slot=60444974 slots=2 slots/s=0.25 txs/s=158.625
[2021-01-13T15:34:09.159183708Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444983, last root slot=60444982 slots=7 slots/s=3.5 txs/s=1938
[2021-01-13T15:34:11.462198527Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60444993, last root slot=60444992 slots=10 slots/s=5.0 txs/s=3180.5
[2021-01-13T15:34:13.811336673Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445001, last root slot=60445000 slots=8 slots/s=4.0 txs/s=2281
[2021-01-13T15:34:15.811879511Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445009, last root slot=60445008 slots=8 slots/s=4.0 txs/s=2164.5
[2021-01-13T15:34:24.671228585Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:34:24.673163671Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445015, last root slot=60445014 slots=6 slots/s=0.75 txs/s=351.75
[2021-01-13T15:34:26.720728857Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445023, last root slot=60445022 slots=8 slots/s=4.0 txs/s=2276
[2021-01-13T15:34:28.879389273Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445035, last root slot=60445034 slots=8 slots/s=4.0 txs/s=2101
[2021-01-13T15:34:31.349921268Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445041, last root slot=60445040 slots=6 slots/s=3.0 txs/s=1795.5
[2021-01-13T15:34:33.624782478Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445053, last root slot=60445052 slots=12 slots/s=6.0 txs/s=2809
[2021-01-13T15:34:42.042978742Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:34:42.045675543Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445059, last root slot=60445058 slots=6 slots/s=0.75 txs/s=319.875
[2021-01-13T15:34:44.146578191Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445072, last root slot=60445067 slots=9 slots/s=4.5 txs/s=2424
[2021-01-13T15:34:46.166899274Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445081, last root slot=60445080 slots=9 slots/s=4.5 txs/s=2561.5
[2021-01-13T15:34:48.175862137Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445087, last root slot=60445086 slots=6 slots/s=3.0 txs/s=1821.5
[2021-01-13T15:34:50.306210523Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445091, last root slot=60445090 slots=4 slots/s=2.0 txs/s=1925.5
[2021-01-13T15:34:59.916292607Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:34:59.921215254Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445101, last root slot=60445100 slots=7 slots/s=0.7777778 txs/s=391.33334
[2021-01-13T15:35:02.161326393Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445113, last root slot=60445112 slots=8 slots/s=4.0 txs/s=2000
[2021-01-13T15:35:04.398285983Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445122, last root slot=60445121 slots=8 slots/s=4.0 txs/s=2422
[2021-01-13T15:35:07.062834365Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445137, last root slot=60445136 slots=11 slots/s=5.5 txs/s=2718
[2021-01-13T15:35:09.280074469Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445147, last root slot=60445146 slots=7 slots/s=3.5 txs/s=1924.5
[2021-01-13T15:35:17.569154878Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:35:17.570985544Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445149, last root slot=60445148 slots=2 slots/s=0.25 txs/s=223.125
[2021-01-13T15:35:19.603646216Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445157, last root slot=60445156 slots=7 slots/s=3.5 txs/s=2205.5
[2021-01-13T15:35:21.661828001Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445170, last root slot=60445169 slots=13 slots/s=6.5 txs/s=2966.5
[2021-01-13T15:35:23.842098262Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445181, last root slot=60445180 slots=11 slots/s=5.5 txs/s=2848.5
[2021-01-13T15:35:26.070685193Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445193, last root slot=60445192 slots=12 slots/s=6.0 txs/s=2818
[2021-01-13T15:35:35.647083563Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:35:35.649978166Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445203, last root slot=60445202 slots=10 slots/s=1.1111112 txs/s=464.1111
[2021-01-13T15:35:37.737104657Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445217, last root slot=60445207 slots=14 slots/s=7.0 txs/s=3558.5
[2021-01-13T15:35:39.859830056Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445229, last root slot=60445207 slots=12 slots/s=6.0 txs/s=3014.5
[2021-01-13T15:35:42.017091141Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445239, last root slot=60445207 slots=10 slots/s=5.0 txs/s=1845.5
[2021-01-13T15:35:44.098424426Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445246, last root slot=60445207 slots=7 slots/s=3.5 txs/s=1825
[2021-01-13T15:35:46.222467920Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445255, last root slot=60445207 slots=9 slots/s=4.5 txs/s=1774.5
[2021-01-13T15:35:47.668969871Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445208, observed in bank: 60445260
[2021-01-13T15:35:58.504088239Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:35:58.505915056Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445261, last root slot=60445208 slots=6 slots/s=0.5 txs/s=257.16666
[2021-01-13T15:35:58.733401476Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445209, observed in bank: 60445262
[2021-01-13T15:35:58.853059823Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445210, observed in bank: 60445263
[2021-01-13T15:35:59.074288065Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445212, observed in bank: 60445264
[2021-01-13T15:35:59.407205313Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445213, observed in bank: 60445266
[2021-01-13T15:35:59.515515525Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445214, observed in bank: 60445267
[2021-01-13T15:35:59.789737941Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445216, observed in bank: 60445268
[2021-01-13T15:36:00.091739139Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445217, observed in bank: 60445270
[2021-01-13T15:36:00.216054698Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445218, observed in bank: 60445271
[2021-01-13T15:36:00.418208997Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445220, observed in bank: 60445272
[2021-01-13T15:36:00.532957068Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445274, last root slot=60445220 slots=13 slots/s=6.5 txs/s=3014.5
[2021-01-13T15:36:00.657971643Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445222, observed in bank: 60445274
[2021-01-13T15:36:01.021064074Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445224, observed in bank: 60445276
[2021-01-13T15:36:01.280815905Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445226, observed in bank: 60445278
[2021-01-13T15:36:01.766747499Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445228, observed in bank: 60445280
[2021-01-13T15:36:02.145130856Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445230, observed in bank: 60445283
[2021-01-13T15:36:02.437858354Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445241, observed in bank: 60445284
[2021-01-13T15:36:02.575723331Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445286, last root slot=60445241 slots=12 slots/s=6.0 txs/s=3002.5
[2021-01-13T15:36:02.773171121Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445242, observed in bank: 60445286
[2021-01-13T15:36:02.944965694Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445243, observed in bank: 60445287
[2021-01-13T15:36:03.347369304Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445252, observed in bank: 60445288
[2021-01-13T15:36:03.607563953Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445253, observed in bank: 60445289
[2021-01-13T15:36:03.948918470Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445257, observed in bank: 60445290
[2021-01-13T15:36:04.087568951Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445258, observed in bank: 60445291
[2021-01-13T15:36:04.312625369Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445260, observed in bank: 60445292
[2021-01-13T15:36:04.627541710Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445261, observed in bank: 60445294
[2021-01-13T15:36:04.632079872Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445295, last root slot=60445261 slots=9 slots/s=4.5 txs/s=2408
[2021-01-13T15:36:04.810953342Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445262, observed in bank: 60445295
[2021-01-13T15:36:05.160470867Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445264, observed in bank: 60445296
[2021-01-13T15:36:05.551058705Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445265, observed in bank: 60445298
[2021-01-13T15:36:05.725704863Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445266, observed in bank: 60445299
[2021-01-13T15:36:06.031012667Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445268, observed in bank: 60445300
[2021-01-13T15:36:06.602976300Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445269, observed in bank: 60445303
[2021-01-13T15:36:07.071128026Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445272, observed in bank: 60445304
[2021-01-13T15:36:07.073266319Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445305, last root slot=60445272 slots=10 slots/s=5.0 txs/s=2532.5
[2021-01-13T15:36:07.870872139Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445273, observed in bank: 60445307
[2021-01-13T15:36:08.100838078Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445274, observed in bank: 60445308
[2021-01-13T15:36:08.296657208Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445275, observed in bank: 60445309
[2021-01-13T15:36:08.469945030Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445276, observed in bank: 60445310
[2021-01-13T15:36:08.679331628Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445277, observed in bank: 60445311
[2021-01-13T15:36:19.202388176Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:36:19.203357350Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445312, last root slot=60445277 slots=7 slots/s=0.5833333 txs/s=282.91666
[2021-01-13T15:36:19.541238626Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445280, observed in bank: 60445312
[2021-01-13T15:36:20.024497618Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445281, observed in bank: 60445315
[2021-01-13T15:36:20.322020000Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445284, observed in bank: 60445316
[2021-01-13T15:36:20.712121430Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445285, observed in bank: 60445318
[2021-01-13T15:36:20.880406496Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445286, observed in bank: 60445319
[2021-01-13T15:36:20.929399587Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445288, observed in bank: 60445320
[2021-01-13T15:36:21.534201750Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445325, last root slot=60445288 slots=11 slots/s=5.5 txs/s=2912.5
[2021-01-13T15:36:22.733756894Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445290, observed in bank: 60445328
[2021-01-13T15:36:23.125208032Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445291, observed in bank: 60445331
[2021-01-13T15:36:23.629652272Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445293, observed in bank: 60445336
[2021-01-13T15:36:23.631041471Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445337, last root slot=60445293 slots=8 slots/s=4.0 txs/s=2371.5
[2021-01-13T15:36:25.051018391Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445295, observed in bank: 60445340
[2021-01-13T15:36:25.686166969Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445296, observed in bank: 60445342
[2021-01-13T15:36:25.687936181Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445343, last root slot=60445296 slots=6 slots/s=3.0 txs/s=2021
[2021-01-13T15:36:25.904833864Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445297, observed in bank: 60445343
[2021-01-13T15:36:26.292544191Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445299, observed in bank: 60445344
[2021-01-13T15:36:26.651893197Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445300, observed in bank: 60445346
[2021-01-13T15:36:26.836656257Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445301, observed in bank: 60445347
[2021-01-13T15:36:27.206243641Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445303, observed in bank: 60445348
[2021-01-13T15:36:27.852999760Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445304, observed in bank: 60445351
[2021-01-13T15:36:27.856068945Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445352, last root slot=60445304 slots=9 slots/s=4.5 txs/s=2335.5
[2021-01-13T15:36:29.470910457Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445307, observed in bank: 60445356
[2021-01-13T15:36:41.333192830Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:36:41.336205266Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445357, last root slot=60445307 slots=2 slots/s=0.15384616 txs/s=158.92308
[2021-01-13T15:36:43.660030547Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445363, last root slot=60445307 slots=5 slots/s=2.5 txs/s=1833
[2021-01-13T15:36:44.695037646Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445308, observed in bank: 60445365
[2021-01-13T15:36:45.908998594Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445376, last root slot=60445308 slots=7 slots/s=3.5 txs/s=1449.5
[2021-01-13T15:36:48.268209834Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445381, last root slot=60445308 slots=5 slots/s=2.5 txs/s=2179
[2021-01-13T15:36:50.229612161Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445311, observed in bank: 60445385
[2021-01-13T15:36:50.406190669Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445387, last root slot=60445311 slots=5 slots/s=2.5 txs/s=1451
[2021-01-13T15:36:50.548143985Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445312, observed in bank: 60445387
[2021-01-13T15:36:50.925796467Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445314, observed in bank: 60445388
[2021-01-13T15:36:51.364079421Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445315, observed in bank: 60445390
[2021-01-13T15:37:03.712204128Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:37:03.714174747Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445391, last root slot=60445315 slots=4 slots/s=0.30769232 txs/s=150.84616
[2021-01-13T15:37:03.893271418Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445317, observed in bank: 60445391
[2021-01-13T15:37:04.162686599Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445324, observed in bank: 60445392
[2021-01-13T15:37:04.306295135Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445325, observed in bank: 60445393
[2021-01-13T15:37:04.453576167Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445326, observed in bank: 60445394
[2021-01-13T15:37:04.763495516Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445329, observed in bank: 60445396
[2021-01-13T15:37:05.019761044Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445336, observed in bank: 60445398
[2021-01-13T15:37:05.124556967Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445337, observed in bank: 60445399
[2021-01-13T15:37:05.491128601Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445339, observed in bank: 60445400
[2021-01-13T15:37:05.782682168Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445403, last root slot=60445339 slots=12 slots/s=6.0 txs/s=2808.5
[2021-01-13T15:37:05.903916958Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445340, observed in bank: 60445403
[2021-01-13T15:37:06.176336291Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445343, observed in bank: 60445404
[2021-01-13T15:37:06.630811872Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445344, observed in bank: 60445406
[2021-01-13T15:37:06.793862244Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445345, observed in bank: 60445407
[2021-01-13T15:37:07.006240348Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445347, observed in bank: 60445408
[2021-01-13T15:37:07.379297314Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445376, observed in bank: 60445411
[2021-01-13T15:37:07.988530430Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445414, last root slot=60445376 slots=11 slots/s=5.5 txs/s=2912.5
[2021-01-13T15:37:08.290715950Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445379, observed in bank: 60445414
[2021-01-13T15:37:08.587865187Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445380, observed in bank: 60445415
[2021-01-13T15:37:10.352513623Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445383, observed in bank: 60445420
[2021-01-13T15:37:10.365025758Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445421, last root slot=60445383 slots=6 slots/s=3.0 txs/s=1956
[2021-01-13T15:37:11.611358557Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445386, observed in bank: 60445424
[2021-01-13T15:37:12.426176689Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445428, last root slot=60445386 slots=7 slots/s=3.5 txs/s=1883.5
[2021-01-13T15:37:12.805267502Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445389, observed in bank: 60445428
[2021-01-13T15:37:12.966372169Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445390, observed in bank: 60445429
[2021-01-13T15:37:13.352320040Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445392, observed in bank: 60445431
[2021-01-13T15:37:13.689158300Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445394, observed in bank: 60445432
[2021-01-13T15:37:14.156927001Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445395, observed in bank: 60445434
[2021-01-13T15:37:26.366528501Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:37:26.367665705Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445435, last root slot=60445395 slots=7 slots/s=0.53846157 txs/s=313.07693
[2021-01-13T15:37:26.549032953Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445396, observed in bank: 60445435
[2021-01-13T15:37:26.856869276Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445398, observed in bank: 60445436
[2021-01-13T15:37:27.225469122Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445399, observed in bank: 60445438
[2021-01-13T15:37:27.378976654Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445400, observed in bank: 60445439
[2021-01-13T15:37:27.724146002Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445402, observed in bank: 60445440
 a"[2021-01-13T15:37:28.211406263Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445403, observed in bank: 60445443
^[[A[2021-01-13T15:37:28.532579546Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445406, observed in bank: 60445444
[2021-01-13T15:37:28.534219832Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445445, last root slot=60445406 slots=10 slots/s=5.0 txs/s=2865.5
[2021-01-13T15:37:28.896373723Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445407, observed in bank: 60445446
[2021-01-13T15:37:29.044861441Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445408, observed in bank: 60445447
[2021-01-13T15:37:29.303894632Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445410, observed in bank: 60445448
[2021-01-13T15:37:29.557368905Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445411, observed in bank: 60445450
[2021-01-13T15:37:29.679957873Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445412, observed in bank: 60445451
[2021-01-13T15:37:29.844722364Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445420, observed in bank: 60445452
[2021-01-13T15:37:29.979147748Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445421, observed in bank: 60445453
[2021-01-13T15:37:30.193024164Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445422, observed in bank: 60445454
[2021-01-13T15:37:30.749015053Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445424, observed in bank: 60445456
[2021-01-13T15:37:30.750600410Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445457, last root slot=60445424 slots=12 slots/s=6.0 txs/s=3159
[2021-01-13T15:37:31.257908414Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445426, observed in bank: 60445459
[2021-01-13T15:37:31.649713568Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445427, observed in bank: 60445460
[2021-01-13T15:37:31.819374818Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445428, observed in bank: 60445461
[2021-01-13T15:37:31.991299311Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445429, observed in bank: 60445462
[2021-01-13T15:37:32.765742472Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445466, last root slot=60445429 slots=8 slots/s=4.0 txs/s=2055.5
[2021-01-13T15:37:33.229700772Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445432, observed in bank: 60445466
[2021-01-13T15:37:34.346754193Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445433, observed in bank: 60445469
[2021-01-13T15:37:34.784919158Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445434, observed in bank: 60445470
[2021-01-13T15:37:34.786389036Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445471, last root slot=60445434 slots=5 slots/s=2.5 txs/s=1844.5
[2021-01-13T15:37:35.226007987Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445435, observed in bank: 60445471
[2021-01-13T15:37:35.579420152Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445437, observed in bank: 60445472
[2021-01-13T15:37:36.016329116Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445438, observed in bank: 60445474
[2021-01-13T15:37:36.204785205Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445439, observed in bank: 60445475
[2021-01-13T15:37:36.506461127Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445441, observed in bank: 60445476
[2021-01-13T15:37:47.697594287Z INFO  solana_runtime::bank] exhaustively_free_unused_resource()
[2021-01-13T15:37:47.699461135Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445477, last root slot=60445441 slots=6 slots/s=0.5 txs/s=326
[2021-01-13T15:37:48.096129879Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445442, observed in bank: 60445479
[2021-01-13T15:37:48.263095989Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445444, observed in bank: 60445480
[2021-01-13T15:37:48.616535588Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445445, observed in bank: 60445481
[2021-01-13T15:37:48.732204389Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445446, observed in bank: 60445482
[2021-01-13T15:37:49.276417964Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445449, observed in bank: 60445484
[2021-01-13T15:37:49.767154425Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445487, last root slot=60445449 slots=10 slots/s=5.0 txs/s=2431
[2021-01-13T15:37:49.956076384Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445450, observed in bank: 60445487
[2021-01-13T15:37:50.292738548Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445452, observed in bank: 60445488
[2021-01-13T15:37:50.480992398Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445453, observed in bank: 60445489
[2021-01-13T15:37:50.783680603Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445454, observed in bank: 60445491
[2021-01-13T15:37:51.155827430Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445457, observed in bank: 60445492
[2021-01-13T15:37:51.764037684Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445458, observed in bank: 60445494
[2021-01-13T15:37:52.155143857Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445459, observed in bank: 60445495
[2021-01-13T15:37:52.156414445Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445497, last root slot=60445459 slots=9 slots/s=4.5 txs/s=2760
[2021-01-13T15:37:52.641150390Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445460, observed in bank: 60445497
[2021-01-13T15:37:53.430539863Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445461, observed in bank: 60445499
[2021-01-13T15:37:54.503803195Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445506, last root slot=60445461 slots=5 slots/s=2.5 txs/s=2015.5
[2021-01-13T15:37:55.663689678Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445462, observed in bank: 60445508
[2021-01-13T15:37:56.151758828Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445466, observed in bank: 60445510
[2021-01-13T15:37:57.009361713Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445469, observed in bank: 60445512
[2021-01-13T15:37:57.012791001Z INFO  solana_ledger::blockstore_processor] processing ledger: slot=60445513, last root slot=60445469 slots=7 slots/s=3.5 txs/s=2535
[2021-01-13T15:37:57.711730937Z INFO  solana_ledger::blockstore_processor] blockstore processor found new cluster confirmed root: 60445470, observed in bank: 60445515

@ryoqun
Copy link
Copy Markdown
Contributor Author

ryoqun commented Jan 13, 2021

this is working as expected.

@ryoqun ryoqun added the automerge Merge this Pull Request automatically once CI passes label Jan 13, 2021
@codecov
Copy link
Copy Markdown

codecov Bot commented Jan 13, 2021

Codecov Report

Merging #14557 (8a3dd2a) into master (0dc17bb) will increase coverage by 0.0%.
The diff coverage is 96.6%.

@@           Coverage Diff           @@
##           master   #14557   +/-   ##
=======================================
  Coverage    80.4%    80.4%           
=======================================
  Files         402      402           
  Lines      100897   100919   +22     
=======================================
+ Hits        81149    81190   +41     
+ Misses      19748    19729   -19     

@mergify mergify Bot merged commit e95ebcf into solana-labs:master Jan 13, 2021
@sakridge
Copy link
Copy Markdown
Contributor

That's a great find. I was thinking we needed accounts background service running while doing blockstore processor.

mergify Bot pushed a commit that referenced this pull request Jan 13, 2021
* Don't stop to find newer cluster-confirmed roots

* Fix and add new tests

* nits

(cherry picked from commit e95ebcf)

# Conflicts:
#	ledger/src/blockstore_processor.rs
mergify Bot pushed a commit that referenced this pull request Jan 13, 2021
* Don't stop to find newer cluster-confirmed roots

* Fix and add new tests

* nits

(cherry picked from commit e95ebcf)
mergify Bot added a commit that referenced this pull request Jan 13, 2021
* Don't stop to find newer cluster-confirmed roots

* Fix and add new tests

* nits

(cherry picked from commit e95ebcf)

Co-authored-by: Ryo Onodera <ryoqun@gmail.com>
@carllin
Copy link
Copy Markdown
Contributor

carllin commented Jan 13, 2021

@ryoqun thanks for the fix!

mergify Bot added a commit that referenced this pull request Jan 14, 2021
* Don't stop to find newer cluster-confirmed roots (#14557)

* Don't stop to find newer cluster-confirmed roots

* Fix and add new tests

* nits

(cherry picked from commit e95ebcf)

# Conflicts:
#	ledger/src/blockstore_processor.rs

* My blind merge conflict edit!

* blind 2

Co-authored-by: Ryo Onodera <ryoqun@gmail.com>
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.

Labels

automerge Merge this Pull Request automatically once CI passes

Projects

None yet

Development

Successfully merging this pull request may close these issues.

3 participants