[INFO] [07-19|09:46:16.280] Starting pprof server cpu="go tool pprof -lines -http=: http://0.0.0.0:6060/debug/pprof/profile?seconds=20" heap="go tool pprof -lines -http=: http://0.0.0.0:6060/debug/pprof/heap" [INFO] [07-19|09:46:16.281] Build info git_branch=devel git_tag=v2021.10.03-1482-gab2808958-dirty git_commit=ab28089583570217cea4e6cc389b429d69f1d558 [INFO] [07-19|09:46:16.281] Starting Erigon on Ropsten testnet... [INFO] [07-19|09:46:16.282] Maximum peer count ETH=100 total=100 [INFO] [07-19|09:46:16.282] torrent verbosity level=2 [INFO] [07-19|09:46:18.385] Set global gas cap cap=50000000 [INFO] [07-19|09:46:18.387] Opening Database label=chaindata path=/data/erigon-ropsten/chaindata [INFO] [07-19|09:46:18.399] Initialised chain configuration config="{ChainID: 3, Homestead: 0, DAO: , DAO Support: true, Tangerine Whistle: 0, Spurious Dragon: 10, Byzantium: 1700000, Constantinople: 4230000, Petersburg: 4939394, Istanbul: 6485846, Muir Glacier: 7117117, Berlin: 9812189, London: 10499401, Arrow Glacier: , Gray Glacier: , Terminal Total Difficulty: 50000000000000000, Engine: ethash}" genesis=0x41941023680923e0fe4d74a34bdac8141f2540e3ae90623718e47d66d1ca4a2d [INFO] [07-19|09:46:18.399] Initialising Ethereum protocol network=3 [INFO] [07-19|09:46:18.399] Using snapshots on=true [INFO] [07-19|09:46:18.399] Effective prune_flags="--prune.h.older=90000 --prune.t.older=90000 --prune.c.older=90000 --prune.r.before=12269948" snapshot_flags="--snapshots=true" [INFO] [07-19|09:46:26.661] Disk storage enabled for ethash DAGs dir=/data/erigon-ropsten/ethash-dags count=2 [INFO] [07-19|09:46:27.449] Starting private RPC server on=0.0.0.0:9090 [INFO] [07-19|09:46:27.449] rpc filters: subscribing to Erigon events [INFO] [07-19|09:46:27.449] new subscription to logs established [INFO] [07-19|09:46:27.450] new subscription to newHeaders established [INFO] [07-19|09:46:27.450] New txs subscriber joined [INFO] [07-19|09:46:27.451] Reading JWT secret path=/secrets/jwtsecret [INFO] [07-19|09:46:27.451] HTTP endpoint opened for Engine API url=0.0.0.0:8551 ws=false [INFO] [07-19|09:46:27.451] HTTP endpoint opened url=0.0.0.0:8545 ws=false ws.compression=true grpc=false [INFO] [07-19|09:46:27.462] [Snapshots] Fetching torrent files metadata [INFO] [07-19|09:46:27.464] Started P2P networking version=66 self=enode://4a9408ec0de8ef7597a655c341cec18ffa63d67e8104a68deb02317769b58faa75ec1a478fb70c45072df46fbf40fafa8abb160df98c154dea4466780d6220a4@127.0.0.1:30303 name=erigon/v2022.99.99-dev-ab280895/linux-amd64/go1.18.4 [INFO] [07-19|09:46:28.137] [txpool] Started [INFO] [07-19|09:46:32.254] [Snapshots] Stat blocks=12525999 segments=12525999 indices=12525999 alloc=2.3GB sys=2.6GB [INFO] [07-19|09:46:32.254] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.388] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:41.388] [1/16 Headers] Downloading PoS headers... height=12623497 hash=0x0d696e75e2fc549b4af0ee9cc1262d5bceae9a126af71c0dc77105dfb52d54c4 requestId=1 [INFO] [07-19|09:46:41.392] Timings (slower than 50ms) Headers=13.928s [INFO] [07-19|09:46:41.394] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.455385ms log sending=482ns [INFO] [07-19|09:46:41.394] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.459] [1/16 Headers] Fork choice missing header with hash 42365501759174666f0588d0560fb8b18194739d5572b0018f94fff796a8aa13 [INFO] [07-19|09:46:41.460] Timings (slower than 50ms) Headers=64ms [INFO] [07-19|09:46:41.460] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:46:41.463] Commit cycle in=3.155135ms [INFO] [07-19|09:46:41.465] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.391528ms log sending=383ns [INFO] [07-19|09:46:41.465] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.471] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:41.473] Commit cycle in=1.648668ms [INFO] [07-19|09:46:41.474] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.408879ms log sending=382ns [INFO] [07-19|09:46:41.475] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.523] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:41.526] Commit cycle in=1.899121ms [INFO] [07-19|09:46:41.527] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.336215ms log sending=385ns [INFO] [07-19|09:46:41.527] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.537] [1/16 Headers] Fork choice missing header with hash e223b06b89caeca92f1a36570038527783244b277a2089ca7c7d1957854ba3ed [INFO] [07-19|09:46:41.552] Commit cycle in=13.933465ms [INFO] [07-19|09:46:41.553] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.161504ms log sending=351ns [INFO] [07-19|09:46:41.553] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.560] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:41.592] Commit cycle in=31.386401ms [INFO] [07-19|09:46:41.593] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.253556ms log sending=376ns [INFO] [07-19|09:46:41.593] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.599] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:41.606] Commit cycle in=5.956762ms [INFO] [07-19|09:46:41.607] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.211589ms log sending=370ns [INFO] [07-19|09:46:41.607] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.642] [1/16 Headers] Fork choice missing header with hash af67d670d62931982864e0168be48534fa933b91a104bece98cd44e6345b1a15 [INFO] [07-19|09:46:41.646] Commit cycle in=2.570724ms [INFO] [07-19|09:46:41.647] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.36408ms log sending=376ns [INFO] [07-19|09:46:41.647] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.653] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:41.667] Commit cycle in=12.668582ms [INFO] [07-19|09:46:41.668] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.210187ms log sending=378ns [INFO] [07-19|09:46:41.668] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.691] [1/16 Headers] Fork choice missing header with hash 57715b1d4cd5ce3bdf6aeeee7bb0811a9c25a844a66bcd6dd9dffa6d1df9b81e [INFO] [07-19|09:46:41.717] Commit cycle in=25.452162ms [INFO] [07-19|09:46:41.718] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.148687ms log sending=358ns [INFO] [07-19|09:46:41.718] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:41.722] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:41.733] Commit cycle in=10.473742ms [INFO] [07-19|09:46:41.735] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.32285ms log sending=292ns [INFO] [07-19|09:46:41.736] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:42.271] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:42.272] Timings (slower than 50ms) Headers=534ms [INFO] [07-19|09:46:42.272] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:46:42.273] Commit cycle in=1.826762ms [INFO] [07-19|09:46:42.275] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.242524ms log sending=381ns [INFO] [07-19|09:46:42.275] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:42.336] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:42.337] Timings (slower than 50ms) Headers=60ms [INFO] [07-19|09:46:42.337] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:46:42.338] Commit cycle in=1.790734ms [INFO] [07-19|09:46:42.341] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.231614ms log sending=409ns [INFO] [07-19|09:46:42.345] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:43.273] [1/16 Headers] Fork choice missing header with hash 327c812d50da46847191859c1bb9dcd94acadbddbfda0ea07b8e721f8e853f97 [INFO] [07-19|09:46:43.274] Timings (slower than 50ms) Headers=927ms [INFO] [07-19|09:46:43.274] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:46:43.277] Commit cycle in=3.150979ms [INFO] [07-19|09:46:43.279] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.315465ms log sending=334ns [INFO] [07-19|09:46:43.280] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:43.285] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:43.288] Commit cycle in=1.777041ms [INFO] [07-19|09:46:43.290] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.120402ms log sending=387ns [INFO] [07-19|09:46:43.290] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:43.397] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:43.398] Timings (slower than 50ms) Headers=106ms [INFO] [07-19|09:46:43.398] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:46:43.400] Commit cycle in=2.043044ms [INFO] [07-19|09:46:43.402] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.325738ms log sending=371ns [INFO] [07-19|09:46:43.402] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:43.649] [1/16 Headers] Fork choice missing header with hash 9e9652d363ef7839c278bc2be0c615a054a0f2d889824ea1b05701f68bd0a4c6 [INFO] [07-19|09:46:43.650] Timings (slower than 50ms) Headers=246ms [INFO] [07-19|09:46:43.650] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:46:43.651] Commit cycle in=1.530566ms [INFO] [07-19|09:46:43.654] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.687103ms log sending=413ns [INFO] [07-19|09:46:43.654] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:48.272] [1/16 Headers] New payload missing parent [INFO] [07-19|09:46:48.273] Timings (slower than 50ms) Headers=4.617s [INFO] [07-19|09:46:48.273] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:46:48.299] Commit cycle in=26.814141ms [INFO] [07-19|09:46:48.302] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.226227ms log sending=368ns [INFO] [07-19|09:46:48.302] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:48.570] [1/16 Headers] Fork choice missing header with hash 5cb2fd8ee9071fb9e55422907f3089a739066c74774d02253e7fd5ab4f0cd40e [INFO] [07-19|09:46:48.570] Timings (slower than 50ms) Headers=267ms [INFO] [07-19|09:46:48.570] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:46:48.591] Commit cycle in=20.53002ms [INFO] [07-19|09:46:48.594] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.482399ms log sending=537ns [INFO] [07-19|09:46:48.594] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:46:57.929] posAnchor blockHeight=12623498 [INFO] [07-19|09:47:00.204] [1/16 Headers] New payload missing parent [INFO] [07-19|09:47:00.205] Timings (slower than 50ms) Headers=11.61s [INFO] [07-19|09:47:00.206] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:00.207] Commit cycle in=1.496258ms [INFO] [07-19|09:47:00.209] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.256299ms log sending=377ns [INFO] [07-19|09:47:00.210] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:00.236] [1/16 Headers] Fork choice missing header with hash bb6151e4a1338da1d3eb50ee4775f0a3be7964be930aed4ffa2cf60a80df21e9 [INFO] [07-19|09:47:00.246] Commit cycle in=8.568322ms [INFO] [07-19|09:47:00.248] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.245443ms log sending=455ns [INFO] [07-19|09:47:00.248] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:12.264] [1/16 Headers] New payload missing parent [INFO] [07-19|09:47:12.266] Timings (slower than 50ms) Headers=12.015s [INFO] [07-19|09:47:12.266] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:12.269] Commit cycle in=3.158071ms [INFO] [07-19|09:47:12.271] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.356757ms log sending=360ns [INFO] [07-19|09:47:12.271] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:13.778] [1/16 Headers] Fork choice missing header with hash 8750b89b0229005269bce055d55605c6160eea20da9493e3ff429146a2fa61fd [INFO] [07-19|09:47:13.779] Timings (slower than 50ms) Headers=1.506s [INFO] [07-19|09:47:13.780] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:13.782] Commit cycle in=1.964447ms [INFO] [07-19|09:47:13.784] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.077019ms log sending=485ns [INFO] [07-19|09:47:13.784] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:24.359] [1/16 Headers] New payload missing parent [INFO] [07-19|09:47:24.360] Timings (slower than 50ms) Headers=10.574s [INFO] [07-19|09:47:24.360] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:24.377] Commit cycle in=17.256681ms [INFO] [07-19|09:47:24.380] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.504265ms log sending=358ns [INFO] [07-19|09:47:24.380] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:24.435] [1/16 Headers] Fork choice missing header with hash ec096794c31655629c9e32bb246a0ad5684944a4953e433045434cf98bf2a952 [INFO] [07-19|09:47:24.436] Timings (slower than 50ms) Headers=55ms [INFO] [07-19|09:47:24.436] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:24.438] Commit cycle in=1.755334ms [INFO] [07-19|09:47:24.440] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.127999ms log sending=383ns [INFO] [07-19|09:47:24.440] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:27.451] Downloaded PoS Headers now=12623497 blk/sec=0.000 [INFO] [07-19|09:47:27.451] [txpool] stat block=12616632 pending=9326 baseFee=77 queued=7816 alloc=2.5GB sys=2.7GB [INFO] [07-19|09:47:36.373] [1/16 Headers] New payload missing parent [INFO] [07-19|09:47:36.375] Timings (slower than 50ms) Headers=11.933s [INFO] [07-19|09:47:36.375] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:36.377] Commit cycle in=1.930149ms [INFO] [07-19|09:47:36.379] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.224943ms log sending=389ns [INFO] [07-19|09:47:36.379] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:36.439] [1/16 Headers] Fork choice missing header with hash 4076e4047162fc305c7262b4cc5dd687ad8c70231507bbdf1b2596a994f38d3a [INFO] [07-19|09:47:36.440] Timings (slower than 50ms) Headers=60ms [INFO] [07-19|09:47:36.440] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:36.442] Commit cycle in=1.963899ms [INFO] [07-19|09:47:36.445] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.487638ms log sending=383ns [INFO] [07-19|09:47:36.445] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:50.064] [1/16 Headers] New payload missing parent [INFO] [07-19|09:47:50.065] Timings (slower than 50ms) Headers=13.618s [INFO] [07-19|09:47:50.065] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:50.067] Commit cycle in=1.930675ms [INFO] [07-19|09:47:50.069] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.085238ms log sending=372ns [INFO] [07-19|09:47:50.069] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:50.132] [1/16 Headers] Fork choice missing header with hash bbf44e3b95d19eea8ceaa0d4f4c67e20eb19059951f7f0289f5149cab667af18 [INFO] [07-19|09:47:50.133] Timings (slower than 50ms) Headers=63ms [INFO] [07-19|09:47:50.133] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:47:50.135] Commit cycle in=2.106644ms [INFO] [07-19|09:47:50.138] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.706971ms log sending=567ns [INFO] [07-19|09:47:50.138] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:47:57.450] Downloaded PoS Headers now=12623497 blk/sec=0.000 [INFO] [07-19|09:48:00.197] [1/16 Headers] New payload missing parent [INFO] [07-19|09:48:00.198] Timings (slower than 50ms) Headers=10.059s [INFO] [07-19|09:48:00.198] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:48:00.200] Commit cycle in=1.425428ms [INFO] [07-19|09:48:00.203] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=3.057755ms log sending=413ns [INFO] [07-19|09:48:00.203] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:00.244] [1/16 Headers] Fork choice missing header with hash 191e4cfcae93f811b6ad74e659032ae26a3a501d879681043716391aaf2f853e [INFO] [07-19|09:48:00.248] Commit cycle in=2.686551ms [INFO] [07-19|09:48:00.252] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=3.179722ms log sending=379ns [INFO] [07-19|09:48:00.252] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:06.501] posAnchor blockHeight=12623498 [INFO] [07-19|09:48:07.603] posAnchor blockHeight=12623498 [INFO] [07-19|09:48:08.028] posAnchor blockHeight=12623498 [INFO] [07-19|09:48:12.437] [1/16 Headers] New payload missing parent [INFO] [07-19|09:48:12.439] Timings (slower than 50ms) Headers=12.185s [INFO] [07-19|09:48:12.439] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:48:12.465] Commit cycle in=26.24742ms [INFO] [07-19|09:48:12.468] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.603031ms log sending=440ns [INFO] [07-19|09:48:12.468] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:12.494] [1/16 Headers] Fork choice missing header with hash 7890d3692c9c3f6cf6428a5d258bebdf92b8da56da8439a1b1d429cd16d6cc52 [INFO] [07-19|09:48:12.501] Commit cycle in=5.354872ms [INFO] [07-19|09:48:12.503] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.346369ms log sending=394ns [INFO] [07-19|09:48:12.503] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:13.481] posAnchor blockHeight=12623498 [INFO] [07-19|09:48:18.399] [p2p] GoodPeers eth66=4 [INFO] [07-19|09:48:25.828] [1/16 Headers] New payload missing parent [INFO] [07-19|09:48:25.829] Timings (slower than 50ms) Headers=13.325s [INFO] [07-19|09:48:25.829] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:48:25.836] Commit cycle in=6.127954ms [INFO] [07-19|09:48:25.838] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.679528ms log sending=407ns [INFO] [07-19|09:48:25.839] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:25.852] [1/16 Headers] Fork choice missing header with hash 1116864a6b2d4bf5bf035acc22cb15a38f6cab93c21a1ab7d20d1962f5ca36fb [INFO] [07-19|09:48:25.858] Commit cycle in=4.438556ms [INFO] [07-19|09:48:25.860] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.425175ms log sending=369ns [INFO] [07-19|09:48:25.860] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:27.456] [txpool] stat block=12616632 pending=9330 baseFee=77 queued=7892 alloc=2.0GB sys=2.9GB [INFO] [07-19|09:48:28.290] Downloaded PoS Headers now=12622537 blk/sec=32.000 [INFO] [07-19|09:48:30.896] posAnchor blockHeight=12622346 [INFO] [07-19|09:48:35.702] posAnchor blockHeight=12621962 [INFO] [07-19|09:48:37.889] [1/16 Headers] New payload missing parent [INFO] [07-19|09:48:37.890] Timings (slower than 50ms) Headers=12.029s [INFO] [07-19|09:48:37.891] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:48:37.898] Commit cycle in=7.424919ms [INFO] [07-19|09:48:37.900] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.660877ms log sending=353ns [INFO] [07-19|09:48:37.900] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:37.910] [1/16 Headers] Fork choice missing header with hash 6a7ed958d3618f0d199da8c993477085e06b0a4737fe3dd600611f739d521147 [INFO] [07-19|09:48:37.919] Commit cycle in=7.863938ms [INFO] [07-19|09:48:37.921] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.704707ms log sending=351ns [INFO] [07-19|09:48:37.921] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:43.119] posAnchor blockHeight=12621386 [INFO] [07-19|09:48:43.119] posAnchor blockHeight=12621386 [INFO] [07-19|09:48:48.325] [1/16 Headers] New payload missing parent [INFO] [07-19|09:48:48.326] Timings (slower than 50ms) Headers=10.403s [INFO] [07-19|09:48:48.326] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:48:48.331] Commit cycle in=4.75625ms [INFO] [07-19|09:48:48.332] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.725259ms log sending=464ns [INFO] [07-19|09:48:48.333] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:48.369] [1/16 Headers] Fork choice missing header with hash a3e6eb871f2d2a39d5643315f5e84cf44eed08b842864a1d5a18f51cfe622b9f [INFO] [07-19|09:48:48.373] Commit cycle in=3.14098ms [INFO] [07-19|09:48:48.375] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.551404ms log sending=389ns [INFO] [07-19|09:48:48.375] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:48:52.388] posAnchor blockHeight=12621194 [INFO] [07-19|09:48:57.656] Downloaded PoS Headers now=12621001 blk/sec=51.200 [INFO] [07-19|09:49:00.222] [1/16 Headers] New payload missing parent [INFO] [07-19|09:49:00.223] Timings (slower than 50ms) Headers=11.846s [INFO] [07-19|09:49:00.223] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=692.0KB ReclaimableSpace=692.0MB [INFO] [07-19|09:49:00.234] Commit cycle in=10.693181ms [INFO] [07-19|09:49:00.236] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.131066ms log sending=567ns [INFO] [07-19|09:49:00.236] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:00.334] [1/16 Headers] Fork choice missing header with hash a25b1829ac9f72443ba4cd8ef926e7bd226f89a3a95431d6ec10609d5973dac5 [INFO] [07-19|09:49:00.335] Timings (slower than 50ms) Headers=97ms [INFO] [07-19|09:49:00.335] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:00.341] Commit cycle in=5.580489ms [INFO] [07-19|09:49:00.343] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.797179ms log sending=407ns [INFO] [07-19|09:49:00.343] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:12.372] [1/16 Headers] New payload missing parent [INFO] [07-19|09:49:12.373] Timings (slower than 50ms) Headers=12.029s [INFO] [07-19|09:49:12.373] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:12.377] Commit cycle in=4.102846ms [INFO] [07-19|09:49:12.380] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.851613ms log sending=383ns [INFO] [07-19|09:49:12.380] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:12.440] [1/16 Headers] Fork choice missing header with hash d910ee190cdad3a00f04fa192f00ce1fae93c233954430c0345c6e94c65091f5 [INFO] [07-19|09:49:12.441] Timings (slower than 50ms) Headers=60ms [INFO] [07-19|09:49:12.441] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:12.443] Commit cycle in=2.249141ms [INFO] [07-19|09:49:12.445] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.916591ms log sending=371ns [INFO] [07-19|09:49:12.446] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:20.499] posAnchor blockHeight=12620234 [INFO] [07-19|09:49:24.712] [1/16 Headers] New payload missing parent [INFO] [07-19|09:49:24.713] Timings (slower than 50ms) Headers=12.266s [INFO] [07-19|09:49:24.713] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:24.717] Commit cycle in=4.081902ms [INFO] [07-19|09:49:24.719] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=2.215414ms log sending=406ns [INFO] [07-19|09:49:24.720] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:24.731] [1/16 Headers] Fork choice missing header with hash 35b171d7c017c34752eba8b44879eda6fa59b552a2332b43518828bf24a795e7 [INFO] [07-19|09:49:24.735] Commit cycle in=3.206811ms [INFO] [07-19|09:49:24.737] RPC Daemon notified of new headers from=12616632 to=12618884 header sending=1.790713ms log sending=392ns [INFO] [07-19|09:49:24.737] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:27.451] Downloaded PoS Headers now=12619849 blk/sec=38.400 [INFO] [07-19|09:49:27.451] [txpool] stat block=12616632 pending=9354 baseFee=77 queued=8025 alloc=2.5GB sys=2.9GB [INFO] [07-19|09:49:41.707] posAnchor blockHeight=12619082 [INFO] [07-19|09:49:45.463] PoS headers verified and saved requestId=1 [INFO] [07-19|09:49:45.464] Timings (slower than 50ms) Headers=20.725s [INFO] [07-19|09:49:45.464] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:46.105] Commit cycle in=641.132505ms [INFO] [07-19|09:49:46.110] RPC Daemon notified of new headers from=12616632 to=12623497 header sending=4.831065ms log sending=452ns [INFO] [07-19|09:49:46.111] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.155] Commit cycle in=9.036235ms [INFO] [07-19|09:49:46.160] RPC Daemon notified of new headers from=12616632 to=12623498 header sending=4.533675ms log sending=360ns [INFO] [07-19|09:49:46.160] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.195] Commit cycle in=9.339831ms [INFO] [07-19|09:49:46.200] RPC Daemon notified of new headers from=12616632 to=12623499 header sending=4.510889ms log sending=367ns [INFO] [07-19|09:49:46.200] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.254] Commit cycle in=21.275337ms [INFO] [07-19|09:49:46.264] RPC Daemon notified of new headers from=12616632 to=12623499 header sending=10.164074ms log sending=377ns [INFO] [07-19|09:49:46.264] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.318] Commit cycle in=6.466278ms [INFO] [07-19|09:49:46.328] RPC Daemon notified of new headers from=12616632 to=12623500 header sending=10.02641ms log sending=380ns [INFO] [07-19|09:49:46.328] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.398] Commit cycle in=23.991275ms [INFO] [07-19|09:49:46.408] RPC Daemon notified of new headers from=12616632 to=12623500 header sending=10.019353ms log sending=436ns [INFO] [07-19|09:49:46.408] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.460] Commit cycle in=7.986257ms [INFO] [07-19|09:49:46.470] RPC Daemon notified of new headers from=12616632 to=12623501 header sending=9.833586ms log sending=379ns [INFO] [07-19|09:49:46.470] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.538] Timings (slower than 50ms) Headers=67ms [INFO] [07-19|09:49:46.538] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:46.565] Commit cycle in=26.818008ms [INFO] [07-19|09:49:46.577] RPC Daemon notified of new headers from=12616632 to=12623501 header sending=11.796659ms log sending=445ns [INFO] [07-19|09:49:46.577] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.642] Commit cycle in=18.29595ms [INFO] [07-19|09:49:46.653] RPC Daemon notified of new headers from=12616632 to=12623502 header sending=10.236808ms log sending=376ns [INFO] [07-19|09:49:46.653] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.717] Timings (slower than 50ms) Headers=63ms [INFO] [07-19|09:49:46.717] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:46.749] Commit cycle in=31.497933ms [INFO] [07-19|09:49:46.759] RPC Daemon notified of new headers from=12616632 to=12623502 header sending=10.129765ms log sending=390ns [INFO] [07-19|09:49:46.759] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.805] Commit cycle in=9.068717ms [INFO] [07-19|09:49:46.812] RPC Daemon notified of new headers from=12616632 to=12623503 header sending=6.495246ms log sending=438ns [INFO] [07-19|09:49:46.812] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.849] Commit cycle in=2.199839ms [INFO] [07-19|09:49:46.856] RPC Daemon notified of new headers from=12616632 to=12623503 header sending=6.509549ms log sending=368ns [INFO] [07-19|09:49:46.856] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.910] Timings (slower than 50ms) Headers=53ms [INFO] [07-19|09:49:46.910] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:46.912] Commit cycle in=2.272454ms [INFO] [07-19|09:49:46.919] RPC Daemon notified of new headers from=12616632 to=12623504 header sending=6.302567ms log sending=662ns [INFO] [07-19|09:49:46.919] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:46.965] Commit cycle in=11.570758ms [INFO] [07-19|09:49:46.971] RPC Daemon notified of new headers from=12616632 to=12623505 header sending=6.371616ms log sending=395ns [INFO] [07-19|09:49:46.971] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.025] Commit cycle in=3.799866ms [INFO] [07-19|09:49:47.033] RPC Daemon notified of new headers from=12616632 to=12623506 header sending=7.201763ms log sending=373ns [INFO] [07-19|09:49:47.033] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.072] Commit cycle in=2.665683ms [INFO] [07-19|09:49:47.078] RPC Daemon notified of new headers from=12616632 to=12623507 header sending=6.440889ms log sending=448ns [INFO] [07-19|09:49:47.078] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.133] Timings (slower than 50ms) Headers=53ms [INFO] [07-19|09:49:47.133] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:47.136] Commit cycle in=3.390105ms [INFO] [07-19|09:49:47.143] RPC Daemon notified of new headers from=12616632 to=12623508 header sending=6.590389ms log sending=626ns [INFO] [07-19|09:49:47.143] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.206] Timings (slower than 50ms) Headers=62ms [INFO] [07-19|09:49:47.206] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:47.219] Commit cycle in=12.817358ms [INFO] [07-19|09:49:47.226] RPC Daemon notified of new headers from=12616632 to=12623509 header sending=6.522602ms log sending=383ns [INFO] [07-19|09:49:47.226] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.271] Commit cycle in=10.805879ms [INFO] [07-19|09:49:47.277] RPC Daemon notified of new headers from=12616632 to=12623510 header sending=6.248964ms log sending=377ns [INFO] [07-19|09:49:47.278] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.340] Commit cycle in=15.081278ms [INFO] [07-19|09:49:47.347] RPC Daemon notified of new headers from=12616632 to=12623511 header sending=6.549161ms log sending=386ns [INFO] [07-19|09:49:47.347] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.430] Commit cycle in=47.538529ms [INFO] [07-19|09:49:47.436] RPC Daemon notified of new headers from=12616632 to=12623512 header sending=6.503934ms log sending=372ns [INFO] [07-19|09:49:47.436] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.513] Commit cycle in=41.353315ms [INFO] [07-19|09:49:47.520] RPC Daemon notified of new headers from=12616632 to=12623513 header sending=6.35748ms log sending=381ns [INFO] [07-19|09:49:47.520] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.558] Commit cycle in=3.810588ms [INFO] [07-19|09:49:47.565] RPC Daemon notified of new headers from=12616632 to=12623514 header sending=6.416169ms log sending=377ns [INFO] [07-19|09:49:47.565] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.620] Timings (slower than 50ms) Headers=54ms [INFO] [07-19|09:49:47.620] Tables PlainState=28.8GB AccountChangeSet=94.7MB StorageChangeSet=201.2MB BlockTransaction=737.0MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:49:47.623] Commit cycle in=2.865002ms [INFO] [07-19|09:49:47.631] RPC Daemon notified of new headers from=12616632 to=12623515 header sending=8.296804ms log sending=375ns [INFO] [07-19|09:49:47.631] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.674] Commit cycle in=3.357176ms [INFO] [07-19|09:49:47.705] RPC Daemon notified of new headers from=12616632 to=12623516 header sending=30.543507ms log sending=400ns [INFO] [07-19|09:49:47.705] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.767] Commit cycle in=25.748385ms [INFO] [07-19|09:49:47.773] RPC Daemon notified of new headers from=12616632 to=12623517 header sending=6.721677ms log sending=371ns [INFO] [07-19|09:49:47.774] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:49:47.810] [1/16 Headers] Fork choice re-org headerNumber=12623517 forkingPoint=12616589 [INFO] [07-19|09:49:47.810] UnwindTo block=12616589 bad_block_hash=0x0000000000000000000000000000000000000000000000000000000000000000 [INFO] [07-19|09:49:48.238] [10/16 CallTraces] Unwind from=12616632 to=12616589 [INFO] [07-19|09:49:48.433] [8/16 HashState] Unwinding started from=12616632 to=12616589 storage=false codes=true [INFO] [07-19|09:49:48.450] [8/16 HashState] Unwinding started from=12616632 to=12616589 storage=false codes=false [INFO] [07-19|09:49:48.554] [8/16 HashState] Unwinding started from=12616632 to=12616589 storage=true codes=false [INFO] [07-19|09:49:48.720] [9/16 IntermediateHashes] Unwinding of trie hashes from=12616632 to=12616589 csbucket=AccountChangeSet [INFO] [07-19|09:49:48.805] [9/16 IntermediateHashes] Unwinding of trie hashes from=12616632 to=12616589 csbucket=StorageChangeSet [INFO] [07-19|09:49:49.450] [9/16 IntermediateHashes] Trie root hash=0x8369ebd423858226a69a9154e4f67d67383f57542d799c4bbb555b85038e6e4d [INFO] [07-19|09:49:49.610] [6/16 Execution] Unwind Execution from=12616632 to=12616589 [INFO] [07-19|09:49:49.912] [1/16 Headers] Unsettled forkchoice after unwind height=12623517 forkchoice="&{HeadBlockHash:0x35b171d7c017c34752eba8b44879eda6fa59b552a2332b43518828bf24a795e7 SafeBlockHash:0x115245f46073214d8a9245d0cb052b67e5b050c0003f616bcdced5931a98b756 FinalizedBlockHash:0xcce58ea6470095c41018cd984e247360379af5b0ea479b023ffdfc21e7a7e0c3}" [INFO] [07-19|09:49:50.029] [4/16 Bodies] Processing bodies... from=12616589 to=12623517 [INFO] [07-19|09:49:53.415] [4/16 Bodies] Processed highest=12623517 [INFO] [07-19|09:49:53.415] [5/16 Senders] Started from=12616589 to=12623517 [INFO] [07-19|09:49:59.077] [6/16 Execution] Blocks execution from=12616589 to=12623517 [INFO] [07-19|09:50:18.400] [p2p] GoodPeers eth66=10 [INFO] [07-19|09:50:19.106] [6/16 Execution] Executed blocks number=12618511 blk/s=96.0 tx/s=1907.8 Mgas/s=299.4 gasState=0.01 batch=16.9MB alloc=3.5GB sys=4.1GB [INFO] [07-19|09:50:27.454] [txpool] stat block=12616632 pending=9360 baseFee=78 queued=8096 alloc=2.2GB sys=4.1GB [INFO] [07-19|09:50:39.079] [6/16 Execution] Executed blocks number=12621357 blk/s=142.5 tx/s=1673.5 Mgas/s=246.1 gasState=0.02 batch=29.4MB alloc=2.9GB sys=4.1GB [INFO] [07-19|09:51:05.297] [6/16 Execution] Completed on block=12623517 [INFO] [07-19|09:51:05.297] [6/16 Execution] DONE in=1m6.220279173s [INFO] [07-19|09:51:05.297] [8/16 HashState] Promoting plain state from=12616589 to=12623517 [INFO] [07-19|09:51:05.297] [8/16 HashState] Incremental promotion started from=12616589 to=12623517 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:51:12.100] [8/16 HashState] Incremental promotion started from=12616589 to=12623517 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:51:21.731] [8/16 HashState] Incremental promotion started from=12616589 to=12623517 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:51:27.589] [txpool] stat block=12616632 pending=9409 baseFee=78 queued=8165 alloc=2.6GB sys=4.5GB [INFO] [07-19|09:51:31.853] [9/16 IntermediateHashes] Generating intermediate hashes from=12616589 to=12623517 [INFO] [07-19|09:52:03.314] [9/16 IntermediateHashes] Calculating Merkle root current key=b2ecc593... [INFO] [07-19|09:52:18.400] [p2p] GoodPeers eth66=11 [INFO] [07-19|09:52:20.107] [10/16 CallTraces] Pruned call trace intermediate table from=12526632 to=12533516 [INFO] [07-19|09:52:27.459] [txpool] stat block=12616632 pending=9418 baseFee=78 queued=8244 alloc=3.6GB sys=4.8GB [INFO] [07-19|09:52:45.816] [13/16 LogIndex] Running from blocks 12616590 to 12623517 endBlock=12623517 [INFO] [07-19|09:53:08.459] Timings (slower than 50ms) Unwind TxLookup=96ms Unwind StorageHistoryIndex=163ms Unwind AccountHistoryIndex=118ms Unwind CallTraces=195ms Unwind HashState=287ms Unwind IntermediateHashes=890ms Unwind Execution=209ms Unwind Bodies=92ms BlockHashes=76ms Bodies=3.386s Senders=5.661s Execution=1m6.22s HashState=26.556s IntermediateHashes=46.64s CallTraces=13.575s AccountHistoryIndex=4.169s StorageHistoryIndex=9.576s LogIndex=2.869s TxLookup=6.142s Prune TxLookup=8.239s Prune StorageHistoryIndex=3.384s Prune AccountHistoryIndex=1.852s Prune Execution=153ms [INFO] [07-19|09:53:08.459] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=799.7MB TransactionLog=1.2GB FreeList=688.0KB ReclaimableSpace=688.0MB [INFO] [07-19|09:53:08.459] [snapshots] Retire Blocks range=12526k-12527k [INFO] [07-19|09:53:13.616] Commit cycle in=5.156778662s [INFO] [07-19|09:53:13.635] RPC Daemon notified of new headers from=12616589 to=12623517 header sending=18.8633ms log sending=347ns [INFO] [07-19|09:53:13.637] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:53:17.045] [snapshots] Compression ratio=1.92 file=v1-012526-012527-transactions [INFO] [07-19|09:53:24.413] [snapshots] Build indices from=12525999 [INFO] [07-19|09:53:24.443] [1/16 Headers] New payload missing parent [INFO] [07-19|09:53:24.443] [1/16 Headers] Downloading PoS headers... height=12623534 hash=0xcb94ccc63771ec465dec66ec6dcfbc8a8551bf76cb9f1898b7928b569572bed2 requestId=46 [INFO] [07-19|09:53:24.444] Timings (slower than 50ms) Headers=10.805s [INFO] [07-19|09:53:24.444] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=799.7MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:53:24.446] Commit cycle in=1.435247ms [INFO] [07-19|09:53:24.446] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=7.944µs log sending=3.385µs [INFO] [07-19|09:53:24.447] [1/16 Headers] Waiting for Beacon Chain... panic: runtime error: index out of range [-1] goroutine 3225 [running]: github.com/ledgerwatch/erigon-lib/txpool.(*bestSlice).Swap(...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:2025 github.com/ledgerwatch/erigon-lib/txpool.(*bestSlice).UnsafeRemove(...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:2030 github.com/ledgerwatch/erigon-lib/txpool.(*PendingPool).Remove(0xc091472f40, 0xc005452be0) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:2071 +0x165 github.com/ledgerwatch/erigon-lib/txpool.onSenderStateChange.func1(0xc005452be0) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1163 +0x42f github.com/ledgerwatch/erigon-lib/txpool.(*BySenderAndNonce).ascend.func1(0xc065bf3488?) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1931 +0x3a github.com/google/btree.(*node[...]).iterate(0xc000375100, 0x1, {0xc09225b090, 0xd8?}, {0x0?, 0x0?}, 0x1?, 0x0, 0xc065bf3610) github.com/google/btree@v1.1.2/btree_generic.go:522 +0x349 github.com/google/btree.(*node[...]).iterate(0xc000383dc0, 0x1, {0xc09225b090, 0xd8?}, {0x0?, 0x60?}, 0x1?, 0x0, 0xc065bf3610) github.com/google/btree@v1.1.2/btree_generic.go:510 +0x1c9 github.com/google/btree.(*node[...]).iterate(0xc000383140, 0x1, {0xc09225b090, 0x2c?}, {0x0?, 0xf?}, 0x1?, 0x0, 0xc065bf3610) github.com/google/btree@v1.1.2/btree_generic.go:510 +0x1c9 github.com/google/btree.(*BTreeG[...]).AscendGreaterOrEqual(0x18b0e40?, 0xc062d46b00?, 0xc065bf3658?) github.com/google/btree@v1.1.2/btree_generic.go:770 +0x45 github.com/ledgerwatch/erigon-lib/txpool.(*BySenderAndNonce).ascend(0x16b2f00?, 0xc097809ec0?, 0xc0939243c0?) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1927 +0x65 github.com/ledgerwatch/erigon-lib/txpool.onSenderStateChange(0x0?, 0x1f4af70?, {0x37102cb3428669a, 0x0, 0x0, 0x0}, 0xc062d2f0f8?, 0xc08aea3400?, 0xc094ce3480?, 0x0?, ...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1152 +0x13e github.com/ledgerwatch/erigon-lib/txpool.addTxs(0xc083b8, {0x1f4af70, 0xc062d2f0f8}, 0x0?, {{0xc0fe2dc240, 0x4, 0x4}, {0xc08be32a20, 0x50, 0x60}, ...}, ...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:931 +0x6ac github.com/ledgerwatch/erigon-lib/txpool.(*TxPool).processRemoteTxs(0xc090087980, {0x1f541e8, 0xc0013fecc0?}) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:508 +0x48d github.com/ledgerwatch/erigon-lib/txpool.MainLoop({0x1f541e8?, 0xc0013fecc0}, {0x1f5be70, 0xc08b0fb860}, {0x1f463e0?, 0xc090236040?}, 0xc090087980, 0xc092244ae0, 0xc091473000, 0xc091477ba8, ...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1341 +0x54e created by github.com/ledgerwatch/erigon/eth.New github.com/ledgerwatch/erigon/eth/backend.go:465 +0x2d51 [INFO] [07-19|09:53:27.841] Starting pprof server cpu="go tool pprof -lines -http=: http://0.0.0.0:6060/debug/pprof/profile?seconds=20" heap="go tool pprof -lines -http=: http://0.0.0.0:6060/debug/pprof/heap" [INFO] [07-19|09:53:27.841] Build info git_branch=devel git_tag=v2021.10.03-1482-gab2808958-dirty git_commit=ab28089583570217cea4e6cc389b429d69f1d558 [INFO] [07-19|09:53:27.841] Starting Erigon on Ropsten testnet... [INFO] [07-19|09:53:27.843] Maximum peer count ETH=100 total=100 [INFO] [07-19|09:53:27.843] torrent verbosity level=2 [INFO] [07-19|09:53:29.944] Set global gas cap cap=50000000 [INFO] [07-19|09:53:29.949] Opening Database label=chaindata path=/data/erigon-ropsten/chaindata [INFO] [07-19|09:53:30.004] Initialised chain configuration config="{ChainID: 3, Homestead: 0, DAO: , DAO Support: true, Tangerine Whistle: 0, Spurious Dragon: 10, Byzantium: 1700000, Constantinople: 4230000, Petersburg: 4939394, Istanbul: 6485846, Muir Glacier: 7117117, Berlin: 9812189, London: 10499401, Arrow Glacier: , Gray Glacier: , Terminal Total Difficulty: 50000000000000000, Engine: ethash}" genesis=0x41941023680923e0fe4d74a34bdac8141f2540e3ae90623718e47d66d1ca4a2d [INFO] [07-19|09:53:30.004] Initialising Ethereum protocol network=3 [INFO] [07-19|09:53:30.004] Using snapshots on=true [INFO] [07-19|09:53:30.004] Effective prune_flags="--prune.h.older=90000 --prune.t.older=90000 --prune.c.older=90000 --prune.r.before=12269948" snapshot_flags="--snapshots=true" [INFO] [07-19|09:53:37.851] Disk storage enabled for ethash DAGs dir=/data/erigon-ropsten/ethash-dags count=2 [INFO] [07-19|09:53:39.027] Starting private RPC server on=0.0.0.0:9090 [INFO] [07-19|09:53:39.028] rpc filters: subscribing to Erigon events [INFO] [07-19|09:53:39.028] new subscription to logs established [INFO] [07-19|09:53:39.029] new subscription to newHeaders established [INFO] [07-19|09:53:39.029] New txs subscriber joined [INFO] [07-19|09:53:39.031] Reading JWT secret path=/secrets/jwtsecret [INFO] [07-19|09:53:39.031] HTTP endpoint opened for Engine API url=0.0.0.0:8551 ws=false [INFO] [07-19|09:53:39.031] HTTP endpoint opened url=0.0.0.0:8545 ws=false ws.compression=true grpc=false [INFO] [07-19|09:53:39.041] [Snapshots] Fetching torrent files metadata [INFO] [07-19|09:53:39.048] Started P2P networking version=66 self=enode://4a9408ec0de8ef7597a655c341cec18ffa63d67e8104a68deb02317769b58faa75ec1a478fb70c45072df46fbf40fafa8abb160df98c154dea4466780d6220a4@127.0.0.1:30303 name=erigon/v2022.99.99-dev-ab280895/linux-amd64/go1.18.4 [INFO] [07-19|09:53:40.147] [txpool] Started [INFO] [07-19|09:53:46.639] [Snapshots] Stat blocks=12525999 segments=12526999 indices=12525999 alloc=2.1GB sys=4.6GB [INFO] [07-19|09:53:46.640] [snapshots] Build indices from=12525999 [INFO] [07-19|09:53:52.223] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:00.331] [1/16 Headers] New payload missing parent [INFO] [07-19|09:54:00.331] [1/16 Headers] Downloading PoS headers... height=12623536 hash=0x3013ffd4e7285907fa82a2e7ac4b30a33fde66a57758f70bc4142188b7556c37 requestId=1 [INFO] [07-19|09:54:00.465] [snapshots] Retire Blocks range=12527k-12528k [INFO] [07-19|09:54:00.475] Timings (slower than 50ms) Headers=21.293s CumulativeIndex=128ms [INFO] [07-19|09:54:00.476] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.108µs log sending=430ns [INFO] [07-19|09:54:00.476] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:00.488] [1/16 Headers] Fork choice missing header with hash de2b4cc01a0b55b83c8e41b7f3b1ff9f5451cd8b859ec24458bb7d28dde2cddc [INFO] [07-19|09:54:00.507] Commit cycle in=14.038396ms [INFO] [07-19|09:54:00.507] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=88.608µs log sending=419ns [INFO] [07-19|09:54:00.507] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:11.435] [snapshots] Compression ratio=1.81 file=v1-012527-012528-transactions [INFO] [07-19|09:54:15.550] [snapshots] Build indices from=12526999 [INFO] [07-19|09:54:15.552] [1/16 Headers] New payload missing parent [INFO] [07-19|09:54:15.557] Timings (slower than 50ms) Headers=15.045s [INFO] [07-19|09:54:15.557] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=798.3MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:54:15.568] Commit cycle in=11.06555ms [INFO] [07-19|09:54:15.568] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=21.214µs log sending=401ns [INFO] [07-19|09:54:15.569] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:15.604] [1/16 Headers] Fork choice missing header with hash 62bc79f17311da42716bcf93518b8c4c25d3a076c64bb4d20c0780e48a0ed8da [INFO] [07-19|09:54:15.611] Commit cycle in=1.69978ms [INFO] [07-19|09:54:15.611] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.065µs log sending=380ns [INFO] [07-19|09:54:15.611] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:24.474] [1/16 Headers] New payload missing parent [INFO] [07-19|09:54:24.478] Timings (slower than 50ms) Headers=8.863s [INFO] [07-19|09:54:24.478] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=797.3MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:54:24.478] [snapshots] Retire Blocks range=12528k-12529k [INFO] [07-19|09:54:24.481] Commit cycle in=2.276899ms [INFO] [07-19|09:54:24.481] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=12.425µs log sending=351ns [INFO] [07-19|09:54:24.481] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:24.609] [1/16 Headers] Fork choice missing header with hash ca1f66c2c6f7511ef2bd1f3c02c39666634c834789cf8017c1f4a89fd2948ed4 [INFO] [07-19|09:54:24.616] Timings (slower than 50ms) Headers=128ms [INFO] [07-19|09:54:24.616] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=796.9MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:54:24.622] Commit cycle in=6.765624ms [INFO] [07-19|09:54:24.623] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=20.447µs log sending=388ns [INFO] [07-19|09:54:24.624] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:36.254] [1/16 Headers] New payload missing parent [INFO] [07-19|09:54:36.260] Timings (slower than 50ms) Headers=11.631s [INFO] [07-19|09:54:36.260] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=796.4MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:54:36.263] Commit cycle in=3.02904ms [INFO] [07-19|09:54:36.263] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=169.752µs log sending=422ns [INFO] [07-19|09:54:36.264] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:36.308] [1/16 Headers] Fork choice missing header with hash fafc06b25e6767ad6e3e56e87566a9f98e7a9d50e9d16e7e2d934edc9074e9da [INFO] [07-19|09:54:36.321] Commit cycle in=9.139011ms [INFO] [07-19|09:54:36.321] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=45.574µs log sending=372ns [INFO] [07-19|09:54:36.321] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:38.331] [snapshots] Compression ratio=1.92 file=v1-012528-012529-transactions [INFO] [07-19|09:54:39.030] Downloaded PoS Headers now=12623536 blk/sec=0.000 [INFO] [07-19|09:54:39.031] [txpool] stat block=12623517 pending=82 baseFee=0 queued=5559 alloc=3.0GB sys=5.0GB [INFO] [07-19|09:54:42.757] [snapshots] Build indices from=12527999 [INFO] [07-19|09:54:46.724] posAnchor blockHeight=12623537 [INFO] [07-19|09:54:49.203] [1/16 Headers] New payload missing parent [INFO] [07-19|09:54:49.208] Timings (slower than 50ms) Headers=12.883s [INFO] [07-19|09:54:49.208] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=795.7MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:54:49.208] [snapshots] Retire Blocks range=12529k-12530k [INFO] [07-19|09:54:49.215] Commit cycle in=7.628992ms [INFO] [07-19|09:54:49.216] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=18.976µs log sending=425ns [INFO] [07-19|09:54:49.216] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:54:49.273] [1/16 Headers] Fork choice missing header with hash b4f16824c639d4283c0092cc834ab4bae74579f4aa34b927fda00fb96a4c936a [INFO] [07-19|09:54:49.279] Timings (slower than 50ms) Headers=57ms [INFO] [07-19|09:54:49.279] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=795.3MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:54:49.340] Commit cycle in=61.733727ms [INFO] [07-19|09:54:49.341] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=24.369µs log sending=429ns [INFO] [07-19|09:54:49.341] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:00.174] [1/16 Headers] New payload missing parent [INFO] [07-19|09:55:00.207] Timings (slower than 50ms) Headers=10.832s [INFO] [07-19|09:55:00.207] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=794.8MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:55:00.212] Commit cycle in=4.144099ms [INFO] [07-19|09:55:00.212] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=22.827µs log sending=441ns [INFO] [07-19|09:55:00.213] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:00.225] [1/16 Headers] Fork choice missing header with hash 7535e54825d0e5598000450de1982b3eae573c64be0a43fa4f1f8d3c8ef7ec4e [INFO] [07-19|09:55:00.235] Commit cycle in=2.048246ms [INFO] [07-19|09:55:00.235] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=28.898µs log sending=287ns [INFO] [07-19|09:55:00.236] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:09.029] Downloaded PoS Headers now=12623536 blk/sec=0.000 [INFO] [07-19|09:55:11.422] [snapshots] Compression ratio=2.00 file=v1-012529-012530-transactions [INFO] [07-19|09:55:16.244] [snapshots] Build indices from=12528999 [INFO] [07-19|09:55:16.246] [1/16 Headers] New payload missing parent [INFO] [07-19|09:55:16.252] Timings (slower than 50ms) Headers=16.01s [INFO] [07-19|09:55:16.252] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=793.5MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:55:16.255] Commit cycle in=2.405633ms [INFO] [07-19|09:55:16.255] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=21.185µs log sending=365ns [INFO] [07-19|09:55:16.256] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:16.274] [1/16 Headers] Fork choice missing header with hash 76f5d1d200de47d232e76b99442e4321ad09065072ea8caea8273e214b9880e7 [INFO] [07-19|09:55:16.283] Commit cycle in=2.623588ms [INFO] [07-19|09:55:16.283] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=20.462µs log sending=391ns [INFO] [07-19|09:55:16.283] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:21.822] [snapshots] Merge segments ranges=[12520k-12530k] [INFO] [07-19|09:55:25.708] [1/16 Headers] New payload missing parent [INFO] [07-19|09:55:25.714] Timings (slower than 50ms) Headers=9.425s [INFO] [07-19|09:55:25.714] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=792.4MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:55:25.719] Commit cycle in=5.709007ms [INFO] [07-19|09:55:25.720] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=27.852µs log sending=375ns [INFO] [07-19|09:55:25.721] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:25.809] [1/16 Headers] Fork choice missing header with hash a49f282d3332a17ff6198b2bc3e35ccc82e94d46c01683ee628ca2c2c5df6a08 [INFO] [07-19|09:55:25.815] Timings (slower than 50ms) Headers=87ms [INFO] [07-19|09:55:25.815] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=791.9MB TransactionLog=1.2GB FreeList=1.4MB ReclaimableSpace=1.4GB [INFO] [07-19|09:55:25.828] Commit cycle in=13.484024ms [INFO] [07-19|09:55:25.829] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=25.152µs log sending=652ns [INFO] [07-19|09:55:25.829] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:30.005] [p2p] GoodPeers eth66=0 [INFO] [07-19|09:55:36.569] posAnchor blockHeight=12623537 [INFO] [07-19|09:55:37.044] [1/16 Headers] New payload missing parent [INFO] [07-19|09:55:37.050] Timings (slower than 50ms) Headers=11.215s [INFO] [07-19|09:55:37.050] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=791.2MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:55:37.056] Commit cycle in=6.728867ms [INFO] [07-19|09:55:37.057] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=29.636µs log sending=500ns [INFO] [07-19|09:55:37.058] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:37.205] [1/16 Headers] Fork choice missing header with hash 383f55fc85a60db35808dec972a04d6c63566e582f85c0ae9aadb2c0e70a7195 [INFO] [07-19|09:55:37.212] Timings (slower than 50ms) Headers=147ms [INFO] [07-19|09:55:37.212] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=790.5MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:55:37.214] Commit cycle in=2.109836ms [INFO] [07-19|09:55:37.215] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=18.273µs log sending=379ns [INFO] [07-19|09:55:37.215] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:39.030] Downloaded PoS Headers now=12623536 blk/sec=0.000 [INFO] [07-19|09:55:39.031] [txpool] stat block=12623517 pending=82 baseFee=0 queued=5560 alloc=2.3GB sys=5.1GB [INFO] [07-19|09:55:47.674] posAnchor blockHeight=12623537 [INFO] [07-19|09:55:48.321] [1/16 Headers] New payload missing parent [INFO] [07-19|09:55:48.333] Timings (slower than 50ms) Headers=11.106s [INFO] [07-19|09:55:48.334] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=789.7MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:55:48.340] Commit cycle in=5.781836ms [INFO] [07-19|09:55:48.341] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=18.574µs log sending=674ns [INFO] [07-19|09:55:48.341] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:48.378] [1/16 Headers] Fork choice missing header with hash 6c71638489a2489cd5186c7c2286e604fc8193036ccfb7a771fb58a1f9f90fb3 [INFO] [07-19|09:55:48.414] Commit cycle in=5.963751ms [INFO] [07-19|09:55:48.414] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.861µs log sending=438ns [INFO] [07-19|09:55:48.414] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:55:57.056] [snapshots] Merge progress=57.29% to=v1-012520-012530-transactions.seg [INFO] [07-19|09:56:00.432] [1/16 Headers] New payload missing parent [INFO] [07-19|09:56:00.438] Timings (slower than 50ms) Headers=12.018s [INFO] [07-19|09:56:00.438] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=788.4MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:56:00.447] Commit cycle in=9.292266ms [INFO] [07-19|09:56:00.448] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=56.31µs log sending=533ns [INFO] [07-19|09:56:00.449] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:56:00.490] [1/16 Headers] Fork choice missing header with hash cca4474fa033b0ed5a1ec2b99e769c95df569961c4e5662fa336c62e7c634d36 [INFO] [07-19|09:56:00.515] Commit cycle in=6.966141ms [INFO] [07-19|09:56:00.515] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=23.124µs log sending=368ns [INFO] [07-19|09:56:00.516] [1/16 Headers] Waiting for Beacon Chain... [WARN] [07-19|09:56:01.232] Timeout requestId=1 [INFO] [07-19|09:56:12.171] [1/16 Headers] New payload missing parent [INFO] [07-19|09:56:12.171] [1/16 Headers] Downloading PoS headers... height=12623547 hash=0xcca4474fa033b0ed5a1ec2b99e769c95df569961c4e5662fa336c62e7c634d36 requestId=23 [INFO] [07-19|09:56:12.177] Timings (slower than 50ms) Headers=11.655s [INFO] [07-19|09:56:12.177] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=787.1MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:56:12.210] Commit cycle in=32.763407ms [INFO] [07-19|09:56:12.210] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=7.31µs log sending=373ns [INFO] [07-19|09:56:12.210] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:56:12.225] [1/16 Headers] Fork choice missing header with hash 36b09571c9e63b46074c80f62b7e0211af9b0877a84aa017d7677f3280e700ee [INFO] [07-19|09:56:12.245] Commit cycle in=14.237088ms [INFO] [07-19|09:56:12.245] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=15.262µs log sending=350ns [INFO] [07-19|09:56:12.245] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:56:12.977] posAnchor blockHeight=12623548 [INFO] [07-19|09:56:24.471] [1/16 Headers] New payload missing parent [INFO] [07-19|09:56:24.480] Timings (slower than 50ms) Headers=12.226s [INFO] [07-19|09:56:24.480] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=785.8MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:56:24.559] Commit cycle in=79.279014ms [INFO] [07-19|09:56:24.560] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=25.134µs log sending=458ns [INFO] [07-19|09:56:24.561] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:56:24.586] [1/16 Headers] Fork choice missing header with hash 6df34ee6aa790c48ec922b8e9ace65f4f7a6f5332ad55feb766250119701957e [INFO] [07-19|09:56:24.621] Commit cycle in=10.855354ms [INFO] [07-19|09:56:24.622] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=25.939µs log sending=445ns [INFO] [07-19|09:56:24.622] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:56:28.253] [snapshots] Merge progress=94.10% to=v1-012520-012530-transactions.seg [INFO] [07-19|09:56:32.862] posAnchor blockHeight=12623548 [INFO] [07-19|09:56:32.927] posAnchor blockHeight=12623548 [INFO] [07-19|09:56:39.030] [txpool] stat block=12623517 pending=83 baseFee=0 queued=5561 alloc=3.5GB sys=5.2GB [INFO] [07-19|09:56:48.418] [1/16 Headers] New payload missing parent [INFO] [07-19|09:56:48.424] Timings (slower than 50ms) Headers=23.795s [INFO] [07-19|09:56:48.425] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=784.6MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:56:48.431] Commit cycle in=6.080747ms [INFO] [07-19|09:56:48.431] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=50.466µs log sending=372ns [INFO] [07-19|09:56:48.432] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:56:48.571] [1/16 Headers] Fork choice missing header with hash 682592757018c557919e37ed7e90159d5e0d370be72174ea3a7409781c14e557 [INFO] [07-19|09:56:48.581] Timings (slower than 50ms) Headers=139ms [INFO] [07-19|09:56:48.581] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=783.7MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:56:48.616] Commit cycle in=34.998431ms [INFO] [07-19|09:56:48.616] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.623µs log sending=402ns [INFO] [07-19|09:56:48.616] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:00.205] [1/16 Headers] New payload missing parent [INFO] [07-19|09:57:00.211] Timings (slower than 50ms) Headers=11.588s [INFO] [07-19|09:57:00.211] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=782.6MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:57:00.218] Commit cycle in=6.965326ms [INFO] [07-19|09:57:00.219] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=25.793µs log sending=410ns [INFO] [07-19|09:57:00.219] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:00.303] [1/16 Headers] Fork choice missing header with hash 74450a9fc034476e32cd259710039d02875c753abb0679ec35aee7032349d7f5 [INFO] [07-19|09:57:00.309] Timings (slower than 50ms) Headers=84ms [INFO] [07-19|09:57:00.310] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=781.6MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:57:00.317] Commit cycle in=7.456974ms [INFO] [07-19|09:57:00.318] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.506µs log sending=369ns [INFO] [07-19|09:57:00.318] [1/16 Headers] Waiting for Beacon Chain... [WARN] [07-19|09:57:02.202] Timeout requestId=23 [INFO] [07-19|09:57:12.159] [1/16 Headers] New payload missing parent [INFO] [07-19|09:57:12.159] [1/16 Headers] Downloading PoS headers... height=12623551 hash=0x74450a9fc034476e32cd259710039d02875c753abb0679ec35aee7032349d7f5 requestId=31 [INFO] [07-19|09:57:12.165] Timings (slower than 50ms) Headers=11.84s [INFO] [07-19|09:57:12.165] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=780.6MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:57:12.202] Commit cycle in=37.07044ms [INFO] [07-19|09:57:12.202] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.731µs log sending=394ns [INFO] [07-19|09:57:12.203] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:12.220] [1/16 Headers] Fork choice missing header with hash 144de43f6e81e1ce7a809cd74fd1e41a3c8abf7455fd023a2f0f36e1cd5a3018 [INFO] [07-19|09:57:12.230] Commit cycle in=2.492293ms [INFO] [07-19|09:57:12.231] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=15.997µs log sending=370ns [INFO] [07-19|09:57:12.231] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:14.958] posAnchor blockHeight=12623552 [INFO] [07-19|09:57:21.810] [merge] Replacement preprocessing processed=16.98% alloc=2.3GB sys=5.2GB [INFO] [07-19|09:57:24.374] [1/16 Headers] New payload missing parent [INFO] [07-19|09:57:24.413] Timings (slower than 50ms) Headers=12.143s [INFO] [07-19|09:57:24.413] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=778.5MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:57:24.424] Commit cycle in=11.177339ms [INFO] [07-19|09:57:24.425] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=31.534µs log sending=466ns [INFO] [07-19|09:57:24.425] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:24.440] [1/16 Headers] Fork choice missing header with hash 20c77e28bff174a91706f4fc5075974cac0aef5ac6ef829ba3ded75a5342991b [INFO] [07-19|09:57:24.462] Commit cycle in=2.461451ms [INFO] [07-19|09:57:24.463] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.578µs log sending=386ns [INFO] [07-19|09:57:24.463] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:30.004] [p2p] GoodPeers eth66=2 [INFO] [07-19|09:57:36.206] [1/16 Headers] New payload missing parent [INFO] [07-19|09:57:36.217] Timings (slower than 50ms) Headers=11.743s [INFO] [07-19|09:57:36.218] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=776.3MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:57:36.248] Commit cycle in=29.938321ms [INFO] [07-19|09:57:36.249] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=17.572µs log sending=409ns [INFO] [07-19|09:57:36.250] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:36.265] [1/16 Headers] Fork choice missing header with hash 760f05b1155b500373c8340b296d043aa675c74773287bb5aba5608b6fa8a5dc [INFO] [07-19|09:57:36.303] Commit cycle in=31.652464ms [INFO] [07-19|09:57:36.303] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=14.801µs log sending=352ns [INFO] [07-19|09:57:36.304] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:39.031] [txpool] stat block=12623517 pending=83 baseFee=0 queued=5561 alloc=2.4GB sys=5.2GB [INFO] [07-19|09:57:41.809] [merge] Replacement preprocessing processed=31.94% alloc=2.4GB sys=5.2GB [INFO] [07-19|09:57:48.705] [1/16 Headers] New payload missing parent [INFO] [07-19|09:57:48.712] Timings (slower than 50ms) Headers=12.4s [INFO] [07-19|09:57:48.712] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=773.8MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:57:48.714] Commit cycle in=2.514824ms [INFO] [07-19|09:57:48.715] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=17.409µs log sending=443ns [INFO] [07-19|09:57:48.715] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:57:48.818] [1/16 Headers] Fork choice missing header with hash dd1c2654dd2828aeb89a98778fc66eb04d2829e35e5c7fdfd84ef880aebe0ace [INFO] [07-19|09:57:48.824] Timings (slower than 50ms) Headers=103ms [INFO] [07-19|09:57:48.825] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=772.7MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:57:48.829] Commit cycle in=4.918754ms [INFO] [07-19|09:57:48.830] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=21.992µs log sending=414ns [INFO] [07-19|09:57:48.831] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:00.505] [1/16 Headers] New payload missing parent [INFO] [07-19|09:58:00.514] Timings (slower than 50ms) Headers=11.675s [INFO] [07-19|09:58:00.515] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=771.0MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:58:00.534] Commit cycle in=18.936865ms [INFO] [07-19|09:58:00.534] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=23.971µs log sending=397ns [INFO] [07-19|09:58:00.534] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:00.571] [1/16 Headers] Fork choice missing header with hash a729dcf2e32ec9002d86f0b5a6b59234d4846a4e89aa9213ca2e3da4e471e7b4 [INFO] [07-19|09:58:00.580] Commit cycle in=2.189057ms [INFO] [07-19|09:58:00.580] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=7.975µs log sending=398ns [INFO] [07-19|09:58:00.580] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:01.809] [merge] Replacement preprocessing processed=52.02% alloc=2.5GB sys=5.2GB [WARN] [07-19|09:58:02.175] Timeout requestId=31 [INFO] [07-19|09:58:12.287] [1/16 Headers] New payload missing parent [INFO] [07-19|09:58:12.287] [1/16 Headers] Downloading PoS headers... height=12623556 hash=0xa729dcf2e32ec9002d86f0b5a6b59234d4846a4e89aa9213ca2e3da4e471e7b4 requestId=41 [INFO] [07-19|09:58:12.303] Timings (slower than 50ms) Headers=11.707s [INFO] [07-19|09:58:12.303] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=769.9MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:58:12.305] Commit cycle in=1.521772ms [INFO] [07-19|09:58:12.305] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=47.515µs log sending=375ns [INFO] [07-19|09:58:12.306] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:12.351] [1/16 Headers] Fork choice missing header with hash 09c1d5591e69ab22f95c803e8b13089de1378c3f0ef1eba4dec50813f83a8324 [INFO] [07-19|09:58:12.357] Commit cycle in=4.65226ms [INFO] [07-19|09:58:12.357] RPC Daemon notified of new headers from=12623517 to=12623518 header sending=17.624µs log sending=343ns [INFO] [07-19|09:58:12.357] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:15.325] posAnchor blockHeight=12623557 [INFO] [07-19|09:58:21.812] [merge] Replacement preprocessing processed=75.29% alloc=2.6GB sys=5.2GB [INFO] [07-19|09:58:22.665] [5/7 Execution] Completed on block=12623518 [INFO] [07-19|09:58:22.772] [5/7 Execution] Completed on block=12623519 [INFO] [07-19|09:58:22.830] [5/7 Execution] Completed on block=12623520 [INFO] [07-19|09:58:22.910] [5/7 Execution] Completed on block=12623521 [INFO] [07-19|09:58:23.038] [5/7 Execution] Completed on block=12623522 [INFO] [07-19|09:58:23.201] [5/7 Execution] Completed on block=12623523 [INFO] [07-19|09:58:23.258] Timings (slower than 50ms) Execution=57ms [INFO] [07-19|09:58:23.258] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:23.461] [5/7 Execution] Completed on block=12623524 [INFO] [07-19|09:58:23.507] Timings (slower than 50ms) Execution=88ms [INFO] [07-19|09:58:23.507] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:23.576] [5/7 Execution] Completed on block=12623525 [INFO] [07-19|09:58:23.760] [5/7 Execution] Completed on block=12623526 [INFO] [07-19|09:58:23.928] [5/7 Execution] Completed on block=12623527 [INFO] [07-19|09:58:23.958] Timings (slower than 50ms) Execution=64ms [INFO] [07-19|09:58:23.958] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:24.148] [5/7 Execution] Completed on block=12623528 [INFO] [07-19|09:58:24.206] Timings (slower than 50ms) Execution=107ms IntermediateHashes=51ms [INFO] [07-19|09:58:24.206] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:24.307] [5/7 Execution] Completed on block=12623529 [INFO] [07-19|09:58:24.338] Timings (slower than 50ms) Execution=56ms [INFO] [07-19|09:58:24.338] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:24.463] [5/7 Execution] Completed on block=12623530 [INFO] [07-19|09:58:24.523] Timings (slower than 50ms) Execution=63ms IntermediateHashes=55ms [INFO] [07-19|09:58:24.523] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:24.641] [5/7 Execution] Completed on block=12623531 [INFO] [07-19|09:58:24.693] Timings (slower than 50ms) Execution=61ms [INFO] [07-19|09:58:24.693] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:24.946] [5/7 Execution] Completed on block=12623532 [INFO] [07-19|09:58:25.019] Timings (slower than 50ms) Execution=82ms IntermediateHashes=56ms [INFO] [07-19|09:58:25.019] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:25.154] [5/7 Execution] Completed on block=12623533 [INFO] [07-19|09:58:25.223] Timings (slower than 50ms) Execution=78ms IntermediateHashes=63ms [INFO] [07-19|09:58:25.223] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:25.287] [4/7 Senders] Started from=12623517 to=12623534 [INFO] [07-19|09:58:25.316] [5/7 Execution] Blocks execution from=12623517 to=12623534 [INFO] [07-19|09:58:25.424] [5/7 Execution] Completed on block=12623534 [INFO] [07-19|09:58:25.424] [6/7 HashState] Promoting plain state from=12623517 to=12623534 [INFO] [07-19|09:58:25.424] [6/7 HashState] Incremental promotion started from=12623517 to=12623534 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:25.426] [6/7 HashState] Incremental promotion started from=12623517 to=12623534 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:25.427] [6/7 HashState] Incremental promotion started from=12623517 to=12623534 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:25.430] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623534 [INFO] [07-19|09:58:25.506] Timings (slower than 50ms) Execution=108ms IntermediateHashes=75ms [INFO] [07-19|09:58:25.506] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:25.545] [4/7 Senders] Started from=12623517 to=12623535 [INFO] [07-19|09:58:25.564] [5/7 Execution] Blocks execution from=12623517 to=12623535 [INFO] [07-19|09:58:25.660] [5/7 Execution] Completed on block=12623535 [INFO] [07-19|09:58:25.660] [6/7 HashState] Promoting plain state from=12623517 to=12623535 [INFO] [07-19|09:58:25.660] [6/7 HashState] Incremental promotion started from=12623517 to=12623535 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:25.663] [6/7 HashState] Incremental promotion started from=12623517 to=12623535 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:25.664] [6/7 HashState] Incremental promotion started from=12623517 to=12623535 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:25.668] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623535 [INFO] [07-19|09:58:25.733] Timings (slower than 50ms) Execution=96ms IntermediateHashes=65ms [INFO] [07-19|09:58:25.733] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:25.866] [4/7 Senders] Started from=12623517 to=12623536 [INFO] [07-19|09:58:25.896] [5/7 Execution] Blocks execution from=12623517 to=12623536 [INFO] [07-19|09:58:26.049] [5/7 Execution] Completed on block=12623536 [INFO] [07-19|09:58:26.049] [6/7 HashState] Promoting plain state from=12623517 to=12623536 [INFO] [07-19|09:58:26.049] [6/7 HashState] Incremental promotion started from=12623517 to=12623536 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:26.050] [6/7 HashState] Incremental promotion started from=12623517 to=12623536 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:26.052] [6/7 HashState] Incremental promotion started from=12623517 to=12623536 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:26.056] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623536 [INFO] [07-19|09:58:26.120] Timings (slower than 50ms) Execution=152ms IntermediateHashes=64ms [INFO] [07-19|09:58:26.120] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:26.165] [4/7 Senders] Started from=12623517 to=12623537 [INFO] [07-19|09:58:26.197] [5/7 Execution] Blocks execution from=12623517 to=12623537 [INFO] [07-19|09:58:26.336] [5/7 Execution] Completed on block=12623537 [INFO] [07-19|09:58:26.336] [6/7 HashState] Promoting plain state from=12623517 to=12623537 [INFO] [07-19|09:58:26.336] [6/7 HashState] Incremental promotion started from=12623517 to=12623537 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:26.344] [6/7 HashState] Incremental promotion started from=12623517 to=12623537 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:26.346] [6/7 HashState] Incremental promotion started from=12623517 to=12623537 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:26.352] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623537 [INFO] [07-19|09:58:26.461] Timings (slower than 50ms) Execution=139ms IntermediateHashes=109ms [INFO] [07-19|09:58:26.461] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:26.539] [4/7 Senders] Started from=12623517 to=12623538 [INFO] [07-19|09:58:26.561] [5/7 Execution] Blocks execution from=12623517 to=12623538 [INFO] [07-19|09:58:26.703] [5/7 Execution] Completed on block=12623538 [INFO] [07-19|09:58:26.703] [6/7 HashState] Promoting plain state from=12623517 to=12623538 [INFO] [07-19|09:58:26.703] [6/7 HashState] Incremental promotion started from=12623517 to=12623538 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:26.707] [6/7 HashState] Incremental promotion started from=12623517 to=12623538 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:26.708] [6/7 HashState] Incremental promotion started from=12623517 to=12623538 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:26.713] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623538 [INFO] [07-19|09:58:26.769] Timings (slower than 50ms) Execution=141ms IntermediateHashes=56ms [INFO] [07-19|09:58:26.769] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:26.842] [4/7 Senders] Started from=12623517 to=12623539 [INFO] [07-19|09:58:26.903] [5/7 Execution] Blocks execution from=12623517 to=12623539 [INFO] [07-19|09:58:27.045] [5/7 Execution] Completed on block=12623539 [INFO] [07-19|09:58:27.045] [6/7 HashState] Promoting plain state from=12623517 to=12623539 [INFO] [07-19|09:58:27.045] [6/7 HashState] Incremental promotion started from=12623517 to=12623539 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:27.049] [6/7 HashState] Incremental promotion started from=12623517 to=12623539 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:27.051] [6/7 HashState] Incremental promotion started from=12623517 to=12623539 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:27.056] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623539 [INFO] [07-19|09:58:27.148] Timings (slower than 50ms) Senders=61ms Execution=141ms IntermediateHashes=92ms [INFO] [07-19|09:58:27.148] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:27.190] [4/7 Senders] Started from=12623517 to=12623540 [INFO] [07-19|09:58:27.214] [5/7 Execution] Blocks execution from=12623517 to=12623540 [INFO] [07-19|09:58:27.369] [5/7 Execution] Completed on block=12623540 [INFO] [07-19|09:58:27.369] [6/7 HashState] Promoting plain state from=12623517 to=12623540 [INFO] [07-19|09:58:27.369] [6/7 HashState] Incremental promotion started from=12623517 to=12623540 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:27.371] [6/7 HashState] Incremental promotion started from=12623517 to=12623540 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:27.373] [6/7 HashState] Incremental promotion started from=12623517 to=12623540 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:27.378] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623540 [INFO] [07-19|09:58:27.450] Timings (slower than 50ms) Execution=155ms IntermediateHashes=72ms [INFO] [07-19|09:58:27.451] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:27.566] [4/7 Senders] Started from=12623517 to=12623541 [INFO] [07-19|09:58:27.617] [5/7 Execution] Blocks execution from=12623517 to=12623541 [INFO] [07-19|09:58:27.761] [5/7 Execution] Completed on block=12623541 [INFO] [07-19|09:58:27.761] [6/7 HashState] Promoting plain state from=12623517 to=12623541 [INFO] [07-19|09:58:27.761] [6/7 HashState] Incremental promotion started from=12623517 to=12623541 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:27.763] [6/7 HashState] Incremental promotion started from=12623517 to=12623541 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:27.765] [6/7 HashState] Incremental promotion started from=12623517 to=12623541 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:27.770] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623541 [INFO] [07-19|09:58:27.858] Timings (slower than 50ms) Senders=51ms Execution=143ms IntermediateHashes=87ms [INFO] [07-19|09:58:27.858] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:27.929] [4/7 Senders] Started from=12623517 to=12623542 [INFO] [07-19|09:58:27.963] [5/7 Execution] Blocks execution from=12623517 to=12623542 [INFO] [07-19|09:58:28.113] [5/7 Execution] Completed on block=12623542 [INFO] [07-19|09:58:28.113] [6/7 HashState] Promoting plain state from=12623517 to=12623542 [INFO] [07-19|09:58:28.113] [6/7 HashState] Incremental promotion started from=12623517 to=12623542 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:28.116] [6/7 HashState] Incremental promotion started from=12623517 to=12623542 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:28.119] [6/7 HashState] Incremental promotion started from=12623517 to=12623542 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:28.125] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623542 [INFO] [07-19|09:58:28.185] Timings (slower than 50ms) Execution=150ms IntermediateHashes=59ms [INFO] [07-19|09:58:28.185] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:28.220] [4/7 Senders] Started from=12623517 to=12623543 [INFO] [07-19|09:58:28.253] [5/7 Execution] Blocks execution from=12623517 to=12623543 [INFO] [07-19|09:58:28.412] [5/7 Execution] Completed on block=12623543 [INFO] [07-19|09:58:28.412] [6/7 HashState] Promoting plain state from=12623517 to=12623543 [INFO] [07-19|09:58:28.412] [6/7 HashState] Incremental promotion started from=12623517 to=12623543 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:28.414] [6/7 HashState] Incremental promotion started from=12623517 to=12623543 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:28.416] [6/7 HashState] Incremental promotion started from=12623517 to=12623543 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:28.422] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623543 [INFO] [07-19|09:58:28.473] Timings (slower than 50ms) Execution=159ms IntermediateHashes=51ms [INFO] [07-19|09:58:28.473] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:28.560] [4/7 Senders] Started from=12623517 to=12623544 [INFO] [07-19|09:58:28.606] [5/7 Execution] Blocks execution from=12623517 to=12623544 [INFO] [07-19|09:58:28.785] [5/7 Execution] Completed on block=12623544 [INFO] [07-19|09:58:28.785] [6/7 HashState] Promoting plain state from=12623517 to=12623544 [INFO] [07-19|09:58:28.785] [6/7 HashState] Incremental promotion started from=12623517 to=12623544 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:28.788] [6/7 HashState] Incremental promotion started from=12623517 to=12623544 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:28.791] [6/7 HashState] Incremental promotion started from=12623517 to=12623544 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:28.797] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623544 [INFO] [07-19|09:58:28.889] Timings (slower than 50ms) Execution=179ms IntermediateHashes=92ms [INFO] [07-19|09:58:28.890] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:28.991] [4/7 Senders] Started from=12623517 to=12623545 [INFO] [07-19|09:58:29.051] [5/7 Execution] Blocks execution from=12623517 to=12623545 [INFO] [07-19|09:58:29.222] [5/7 Execution] Completed on block=12623545 [INFO] [07-19|09:58:29.223] [6/7 HashState] Promoting plain state from=12623517 to=12623545 [INFO] [07-19|09:58:29.223] [6/7 HashState] Incremental promotion started from=12623517 to=12623545 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:29.226] [6/7 HashState] Incremental promotion started from=12623517 to=12623545 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:29.229] [6/7 HashState] Incremental promotion started from=12623517 to=12623545 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:29.235] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623545 [INFO] [07-19|09:58:29.336] Timings (slower than 50ms) Senders=59ms Execution=171ms IntermediateHashes=100ms [INFO] [07-19|09:58:29.336] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:29.379] [4/7 Senders] Started from=12623517 to=12623546 [INFO] [07-19|09:58:29.414] [5/7 Execution] Blocks execution from=12623517 to=12623546 [INFO] [07-19|09:58:29.553] [5/7 Execution] Completed on block=12623546 [INFO] [07-19|09:58:29.553] [6/7 HashState] Promoting plain state from=12623517 to=12623546 [INFO] [07-19|09:58:29.553] [6/7 HashState] Incremental promotion started from=12623517 to=12623546 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:29.555] [6/7 HashState] Incremental promotion started from=12623517 to=12623546 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:29.558] [6/7 HashState] Incremental promotion started from=12623517 to=12623546 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:29.565] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623546 [INFO] [07-19|09:58:29.627] Timings (slower than 50ms) Execution=139ms IntermediateHashes=62ms [INFO] [07-19|09:58:29.627] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:29.703] [4/7 Senders] Started from=12623517 to=12623547 [INFO] [07-19|09:58:29.734] [5/7 Execution] Blocks execution from=12623517 to=12623547 [INFO] [07-19|09:58:29.937] [5/7 Execution] Completed on block=12623547 [INFO] [07-19|09:58:29.937] [6/7 HashState] Promoting plain state from=12623517 to=12623547 [INFO] [07-19|09:58:29.937] [6/7 HashState] Incremental promotion started from=12623517 to=12623547 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:29.939] [6/7 HashState] Incremental promotion started from=12623517 to=12623547 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:29.942] [6/7 HashState] Incremental promotion started from=12623517 to=12623547 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:29.949] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623547 [INFO] [07-19|09:58:30.051] Timings (slower than 50ms) Execution=203ms IntermediateHashes=101ms [INFO] [07-19|09:58:30.051] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:30.099] [4/7 Senders] Started from=12623517 to=12623548 [INFO] [07-19|09:58:30.138] [5/7 Execution] Blocks execution from=12623517 to=12623548 [INFO] [07-19|09:58:30.319] [5/7 Execution] Completed on block=12623548 [INFO] [07-19|09:58:30.319] [6/7 HashState] Promoting plain state from=12623517 to=12623548 [INFO] [07-19|09:58:30.319] [6/7 HashState] Incremental promotion started from=12623517 to=12623548 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:30.323] [6/7 HashState] Incremental promotion started from=12623517 to=12623548 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:30.325] [6/7 HashState] Incremental promotion started from=12623517 to=12623548 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:30.332] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623548 [INFO] [07-19|09:58:30.410] Timings (slower than 50ms) Execution=181ms IntermediateHashes=78ms [INFO] [07-19|09:58:30.410] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:30.452] [4/7 Senders] Started from=12623517 to=12623549 [INFO] [07-19|09:58:30.507] [5/7 Execution] Blocks execution from=12623517 to=12623549 [INFO] [07-19|09:58:30.696] [5/7 Execution] Completed on block=12623549 [INFO] [07-19|09:58:30.696] [6/7 HashState] Promoting plain state from=12623517 to=12623549 [INFO] [07-19|09:58:30.696] [6/7 HashState] Incremental promotion started from=12623517 to=12623549 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:30.698] [6/7 HashState] Incremental promotion started from=12623517 to=12623549 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:30.704] [6/7 HashState] Incremental promotion started from=12623517 to=12623549 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:30.711] [7/7 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623549 [INFO] [07-19|09:58:30.807] Timings (slower than 50ms) Senders=55ms Execution=188ms IntermediateHashes=96ms [INFO] [07-19|09:58:30.807] Tables PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B [INFO] [07-19|09:58:30.818] PoS headers verified and saved requestId=41 [INFO] [07-19|09:58:30.818] Timings (slower than 50ms) Headers=18.46s [INFO] [07-19|09:58:30.819] Tables PlainState=28.9GB AccountChangeSet=94.2MB StorageChangeSet=200.0MB BlockTransaction=769.9MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:58:30.832] Commit cycle in=12.88314ms [INFO] [07-19|09:58:30.833] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=746.803µs log sending=341ns [INFO] [07-19|09:58:30.833] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.865] Commit cycle in=30.479283ms [INFO] [07-19|09:58:30.866] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=61.212µs log sending=328ns [INFO] [07-19|09:58:30.866] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.871] Commit cycle in=2.493627ms [INFO] [07-19|09:58:30.872] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=190.52µs log sending=328ns [INFO] [07-19|09:58:30.872] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.906] Commit cycle in=3.455264ms [INFO] [07-19|09:58:30.907] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=326.184µs log sending=326ns [INFO] [07-19|09:58:30.916] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.919] Commit cycle in=1.649553ms [INFO] [07-19|09:58:30.919] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=31.687µs log sending=318ns [INFO] [07-19|09:58:30.919] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.922] Commit cycle in=1.604779ms [INFO] [07-19|09:58:30.922] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=25.715µs log sending=255ns [INFO] [07-19|09:58:30.922] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.925] Commit cycle in=2.418246ms [INFO] [07-19|09:58:30.925] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=25.01µs log sending=203ns [INFO] [07-19|09:58:30.925] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.928] Commit cycle in=1.98856ms [INFO] [07-19|09:58:30.929] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=37.781µs log sending=206ns [INFO] [07-19|09:58:30.929] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.932] Commit cycle in=1.207016ms [INFO] [07-19|09:58:30.932] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=40.463µs log sending=212ns [INFO] [07-19|09:58:30.933] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.936] Commit cycle in=1.492392ms [INFO] [07-19|09:58:30.944] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=8.26032ms log sending=283ns [INFO] [07-19|09:58:30.945] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.948] Commit cycle in=1.664967ms [INFO] [07-19|09:58:30.948] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=62.521µs log sending=209ns [INFO] [07-19|09:58:30.948] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.967] Commit cycle in=17.295462ms [INFO] [07-19|09:58:30.967] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=209.06µs log sending=322ns [INFO] [07-19|09:58:30.967] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.974] Commit cycle in=4.366677ms [INFO] [07-19|09:58:30.974] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=60.598µs log sending=256ns [INFO] [07-19|09:58:30.974] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:30.979] Commit cycle in=2.979079ms [INFO] [07-19|09:58:30.979] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=35.41µs log sending=264ns [INFO] [07-19|09:58:30.979] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:31.021] Commit cycle in=40.594298ms [INFO] [07-19|09:58:31.021] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=40.998µs log sending=378ns [INFO] [07-19|09:58:31.021] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:31.024] Commit cycle in=1.746119ms [INFO] [07-19|09:58:31.024] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=35.587µs log sending=192ns [INFO] [07-19|09:58:31.024] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:31.027] Commit cycle in=1.898572ms [INFO] [07-19|09:58:31.027] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=37.155µs log sending=199ns [INFO] [07-19|09:58:31.027] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:31.031] Commit cycle in=2.275878ms [INFO] [07-19|09:58:31.031] RPC Daemon notified of new headers from=12623517 to=12623556 header sending=65.243µs log sending=226ns [INFO] [07-19|09:58:31.031] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:31.042] Commit cycle in=9.373424ms [INFO] [07-19|09:58:31.042] RPC Daemon notified of new headers from=12623517 to=12623557 header sending=50.49µs log sending=257ns [INFO] [07-19|09:58:31.042] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:58:31.042] [1/16 Headers] Fork choice re-org headerNumber=12623557 forkingPoint=12623517 [INFO] [07-19|09:58:31.042] UnwindTo block=12623517 bad_block_hash=0x0000000000000000000000000000000000000000000000000000000000000000 [INFO] [07-19|09:58:31.042] [1/16 Headers] Unsettled forkchoice after unwind height=12623557 forkchoice="&{HeadBlockHash:0x09c1d5591e69ab22f95c803e8b13089de1378c3f0ef1eba4dec50813f83a8324 SafeBlockHash:0x327c812d50da46847191859c1bb9dcd94acadbddbfda0ea07b8e721f8e853f97 FinalizedBlockHash:0x115245f46073214d8a9245d0cb052b67e5b050c0003f616bcdced5931a98b756}" [INFO] [07-19|09:58:31.043] [4/16 Bodies] Processing bodies... from=12623517 to=12623557 [INFO] [07-19|09:58:31.240] [4/16 Bodies] Processed highest=12623557 [INFO] [07-19|09:58:31.241] [5/16 Senders] Started from=12623517 to=12623557 [INFO] [07-19|09:58:31.347] [6/16 Execution] Blocks execution from=12623517 to=12623557 [INFO] [07-19|09:58:31.808] [6/16 Execution] Completed on block=12623557 [INFO] [07-19|09:58:31.808] [8/16 HashState] Promoting plain state from=12623517 to=12623557 [INFO] [07-19|09:58:31.808] [8/16 HashState] Incremental promotion started from=12623517 to=12623557 codes=true csbucket=AccountChangeSet [INFO] [07-19|09:58:31.930] [8/16 HashState] Incremental promotion started from=12623517 to=12623557 codes=false csbucket=AccountChangeSet [INFO] [07-19|09:58:31.960] [8/16 HashState] Incremental promotion started from=12623517 to=12623557 codes=false csbucket=StorageChangeSet [INFO] [07-19|09:58:32.022] [9/16 IntermediateHashes] Generating intermediate hashes from=12623517 to=12623557 [INFO] [07-19|09:58:32.321] [10/16 CallTraces] Pruned call trace intermediate table from=12533517 to=12533556 [INFO] [07-19|09:58:32.506] [13/16 LogIndex] Running from blocks 12623518 to 12623557 endBlock=12623557 [INFO] [07-19|09:58:32.719] Timings (slower than 50ms) Bodies=197ms Senders=106ms Execution=460ms HashState=214ms IntermediateHashes=292ms CallTraces=91ms StorageHistoryIndex=75ms LogIndex=66ms TxLookup=54ms Prune TxLookup=82ms [INFO] [07-19|09:58:32.719] Tables PlainState=28.9GB AccountChangeSet=94.3MB StorageChangeSet=200.0MB BlockTransaction=770.7MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:58:35.810] Commit cycle in=3.090358859s [INFO] [07-19|09:58:35.810] RPC Daemon notified of new headers from=12623517 to=12623557 header sending=378.587µs log sending=376ns [INFO] [07-19|09:58:35.811] [1/16 Headers] Waiting for Beacon Chain... panic: runtime error: index out of range [-1] goroutine 4231 [running]: github.com/ledgerwatch/erigon-lib/txpool.(*bestSlice).Swap(...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:2025 github.com/ledgerwatch/erigon-lib/txpool.(*bestSlice).UnsafeRemove(...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:2030 github.com/ledgerwatch/erigon-lib/txpool.(*PendingPool).Remove(0xc0988ab140, 0xc0a84fb270) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:2071 +0x165 github.com/ledgerwatch/erigon-lib/txpool.onSenderStateChange.func1(0xc0a84fb270) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1163 +0x42f github.com/ledgerwatch/erigon-lib/txpool.(*BySenderAndNonce).ascend.func1(0xc04ef93458?) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1931 +0x3a github.com/google/btree.(*node[...]).iterate(0xc0a8347840, 0x1, {0xc0993744b0, 0xd8?}, {0x0?, 0x0?}, 0x1?, 0x0, 0xc04ef935e0) github.com/google/btree@v1.1.2/btree_generic.go:522 +0x349 github.com/google/btree.(*node[...]).iterate(0xc0a62b8940, 0x1, {0xc0993744b0, 0xd8?}, {0x0?, 0xf0?}, 0x1?, 0x0, 0xc04ef935e0) github.com/google/btree@v1.1.2/btree_generic.go:510 +0x1c9 github.com/google/btree.(*node[...]).iterate(0xc0a8347180, 0x1, {0xc0993744b0, 0x1?}, {0x0?, 0xf?}, 0x1?, 0x0, 0xc04ef935e0) github.com/google/btree@v1.1.2/btree_generic.go:510 +0x1c9 github.com/google/btree.(*BTreeG[...]).AscendGreaterOrEqual(0x18b0e40?, 0xc0a02ea000?, 0xc04ef93628?) github.com/google/btree@v1.1.2/btree_generic.go:770 +0x45 github.com/ledgerwatch/erigon-lib/txpool.(*BySenderAndNonce).ascend(0x96d1ab?, 0x0?, 0xc0a02da0c0?) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1927 +0x65 github.com/ledgerwatch/erigon-lib/txpool.onSenderStateChange(0xffffffffffffffff?, 0x1f4af70?, {0xa30aef0dbdc4ba28, 0x0, 0x0, 0x0}, 0xc0a02d6090?, 0xc04ef93820?, 0x16b31a0?, 0xab98a0?, ...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:1152 +0x13e github.com/ledgerwatch/erigon-lib/txpool.addTxsOnNewBlock(0xc09ec5, {0x1f4af70, 0xc0a02d6090}, 0xc0a00bec60, 0xc0993627a0, {{0x0, 0x0, 0x0}, {0x0, 0x0, ...}, ...}, ...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:996 +0x32c github.com/ledgerwatch/erigon-lib/txpool.(*TxPool).OnNewBlock(0xc099354780, {0x1f541e8, 0xc0014dd440}, 0xc0a00bec60, {{0x0, 0x0, 0x0}, {0x0, 0x0, 0x0}, ...}, ...) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/pool.go:442 +0x765 github.com/ledgerwatch/erigon-lib/txpool.(*Fetch).handleStateChanges.func3({0x1f60c20?, 0xc0a022bd70?}) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/fetch.go:470 +0xd2 github.com/ledgerwatch/erigon-lib/kv/mdbx.(*MdbxKV).View(0xc0a014b1d0?, {0x1f541e8?, 0xc0014dd440?}, 0xc0a02b8a80) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/kv/mdbx/kv_mdbx.go:676 +0xbe github.com/ledgerwatch/erigon-lib/txpool.(*Fetch).handleStateChanges(0xc0988a5680, {0x1f541e8?, 0xc0014dd440}, {0x1f42720, 0xc098013e50}) github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/fetch.go:469 +0x36e github.com/ledgerwatch/erigon-lib/txpool.(*Fetch).ConnectCore.func1() github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/fetch.go:117 +0x65 created by github.com/ledgerwatch/erigon-lib/txpool.(*Fetch).ConnectCore github.com/ledgerwatch/erigon-lib@v0.0.0-20220719040828-9ceeeac385ad/txpool/fetch.go:110 +0x56 [INFO] [07-19|09:58:38.945] Starting pprof server cpu="go tool pprof -lines -http=: http://0.0.0.0:6060/debug/pprof/profile?seconds=20" heap="go tool pprof -lines -http=: http://0.0.0.0:6060/debug/pprof/heap" [INFO] [07-19|09:58:38.946] Build info git_branch=devel git_tag=v2021.10.03-1482-gab2808958-dirty git_commit=ab28089583570217cea4e6cc389b429d69f1d558 [INFO] [07-19|09:58:38.946] Starting Erigon on Ropsten testnet... [INFO] [07-19|09:58:38.949] Maximum peer count ETH=100 total=100 [INFO] [07-19|09:58:38.949] torrent verbosity level=2 [INFO] [07-19|09:58:41.052] Set global gas cap cap=50000000 [INFO] [07-19|09:58:41.064] Opening Database label=chaindata path=/data/erigon-ropsten/chaindata [INFO] [07-19|09:58:41.096] Initialised chain configuration config="{ChainID: 3, Homestead: 0, DAO: , DAO Support: true, Tangerine Whistle: 0, Spurious Dragon: 10, Byzantium: 1700000, Constantinople: 4230000, Petersburg: 4939394, Istanbul: 6485846, Muir Glacier: 7117117, Berlin: 9812189, London: 10499401, Arrow Glacier: , Gray Glacier: , Terminal Total Difficulty: 50000000000000000, Engine: ethash}" genesis=0x41941023680923e0fe4d74a34bdac8141f2540e3ae90623718e47d66d1ca4a2d [INFO] [07-19|09:58:41.096] Initialising Ethereum protocol network=3 [INFO] [07-19|09:58:41.096] Using snapshots on=true [INFO] [07-19|09:58:41.097] Effective prune_flags="--prune.h.older=90000 --prune.t.older=90000 --prune.c.older=90000 --prune.r.before=12269948" snapshot_flags="--snapshots=true" [INFO] [07-19|09:58:49.673] Disk storage enabled for ethash DAGs dir=/data/erigon-ropsten/ethash-dags count=2 [INFO] [07-19|09:58:50.768] Starting private RPC server on=0.0.0.0:9090 [INFO] [07-19|09:58:50.769] rpc filters: subscribing to Erigon events [INFO] [07-19|09:58:50.773] new subscription to logs established [INFO] [07-19|09:58:50.774] new subscription to newHeaders established [INFO] [07-19|09:58:50.803] Reading JWT secret path=/secrets/jwtsecret [INFO] [07-19|09:58:50.803] HTTP endpoint opened for Engine API url=0.0.0.0:8551 ws=false [INFO] [07-19|09:58:50.803] HTTP endpoint opened url=0.0.0.0:8545 ws=false ws.compression=true grpc=false [INFO] [07-19|09:58:50.809] New txs subscriber joined [INFO] [07-19|09:58:50.813] [Snapshots] Fetching torrent files metadata [INFO] [07-19|09:58:50.824] Started P2P networking version=66 self=enode://4a9408ec0de8ef7597a655c341cec18ffa63d67e8104a68deb02317769b58faa75ec1a478fb70c45072df46fbf40fafa8abb160df98c154dea4466780d6220a4@127.0.0.1:30303 name=erigon/v2022.99.99-dev-ab280895/linux-amd64/go1.18.4 [INFO] [07-19|09:58:51.345] [txpool] Started [INFO] [07-19|09:58:57.015] [Snapshots] Stat blocks=12529999 segments=12529999 indices=12529999 alloc=2.2GB sys=4.2GB [INFO] [07-19|09:58:57.015] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:59:00.173] [1/16 Headers] New payload missing parent [INFO] [07-19|09:59:00.173] [1/16 Headers] Downloading PoS headers... height=12623560 hash=0x40abb4aa6944108748a85ad4df753191dc838b156809ffaf3302f85ab02dbf82 requestId=1 [INFO] [07-19|09:59:00.179] [snapshots] Retire Blocks range=12530k-12531k [INFO] [07-19|09:59:00.179] Timings (slower than 50ms) Headers=9.363s [INFO] [07-19|09:59:00.179] RPC Daemon notified of new headers from=12623557 to=12623558 header sending=12.759µs log sending=370ns [INFO] [07-19|09:59:00.179] [1/16 Headers] Waiting for Beacon Chain... [INFO] [07-19|09:59:00.277] [1/16 Headers] Fork choice missing header with hash 9286a885026d083748e32419f3f19f2587d9ecbb6e91f777957b1db504f444cb [INFO] [07-19|09:59:00.278] Timings (slower than 50ms) Headers=98ms [INFO] [07-19|09:59:00.278] Tables PlainState=28.9GB AccountChangeSet=94.3MB StorageChangeSet=200.0MB BlockTransaction=770.7MB TransactionLog=1.2GB FreeList=1.5MB ReclaimableSpace=1.5GB [INFO] [07-19|09:59:00.302] Commit cycle in=23.397062ms [INFO] [07-19|09:59:00.302] RPC Daemon notified of new headers from=12623557 to=12623558 header sending=12.013µs log sending=344ns [INFO] [07-19|09:59:00.303] [1/16 Headers] Waiting for Beacon Chain...