erigon icon indicating copy to clipboard operation
erigon copied to clipboard

Staged Sync runtime error: invalid memory address or nil pointer dereference

Open linhuatan opened this issue 3 years ago • 1 comments

System information

Erigon version: ./erigon --version erigon version 2022.08.1-alpha-7dabdc32 OS & Version: Windows/Linux/OSX Linux Commit hash : 7dabdc32

Expected behaviour

no runtime error

Actual behaviour

  |   | 2022-08-18 18:28:20 | [INFO] [08-18\|10:28:20.641] [1/16 Headers] New payload missing parent
  |   | 2022-08-18 18:28:20 | [INFO] [08-18\|10:28:20.639] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:28:20 | [INFO] [08-18\|10:28:20.639] RPC Daemon notified of new headers       from=7426331 to=7426332 header sending=13.771µs log sending=800ns
  |   | 2022-08-18 18:28:20 | [INFO] [08-18\|10:28:20.638] Commit cycle                             in=8.372881ms
  |   | 2022-08-18 18:28:20 | [INFO] [08-18\|10:28:20.629] [1/16 Headers] New payload missing parent
  |   | 2022-08-18 18:28:20 | [INFO] [08-18\|10:28:20.627] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:28:20 | [INFO] [08-18\|10:28:20.145] [txpool] stat                            block=7426331 pending=2577 baseFee=317 queued=16873 alloc=1.5GB sys=3.7GB
  |   | 2022-08-18 18:28:10 | [EROR] [08-18\|10:28:10.986] Staged Sync                              err="runtime error: invalid memory address or nil pointer dereference, trace: [stageloop.go:126 panic.go:844 panic.go:220 signal_unix.go:818 fork_validator.go:281 fork_validator.go:206 stage_headers.go:652 collector.go:268 collector.go:143 stage_headers.go:665 stage_headers.go:711 stage_headers.go:174 stage_headers.go:142 default_stages.go:20 sync.go:347 sync.go:255 stageloop.go:160 stageloop.go:81 asm_amd64.s:1571]"
  |   | 2022-08-18 18:28:10 | [INFO] [08-18\|10:28:10.897] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB
  |   | 2022-08-18 18:28:10 | [INFO] [08-18\|10:28:10.897] Timings (slower than 50ms)               Bodies=1m40.001s
  |   | 2022-08-18 18:28:10 | [INFO] [08-18\|10:28:10.896] [2/7 Bodies] DONE                        in=1m40.001718441s
  |   | 2022-08-18 18:28:10 | [INFO] [08-18\|10:28:10.896] [2/7 Bodies] Wrote block bodies          block_num=7426300 delivery/sec=0B wasted/sec=0B alloc=1.4GB sys=3.7GB
  |   | 2022-08-18 18:27:50 | [INFO] [08-18\|10:27:50.899] [2/7 Bodies] Wrote block bodies          block_num=7426300 delivery/sec=0B wasted/sec=0B alloc=1.4GB sys=3.7GB
  |   | 2022-08-18 18:27:30 | [INFO] [08-18\|10:27:30.895] [2/7 Bodies] Wrote block bodies          block_num=7426300 delivery/sec=0B wasted/sec=0B alloc=1.7GB sys=3.7GB
  |   | 2022-08-18 18:27:13 | [INFO] [08-18\|10:27:13.612] [txpool] stat                            block=7426331 pending=2447 baseFee=317 queued=16873 alloc=1.7GB sys=3.7GB
  |   | 2022-08-18 18:27:10 | [INFO] [08-18\|10:27:10.895] [2/7 Bodies] Wrote block bodies          block_num=7426300 delivery/sec=0B wasted/sec=0B alloc=1.7GB sys=3.7GB
  |   | 2022-08-18 18:27:02 | [INFO] [08-18\|10:27:02.135] [p2p] GoodPeers                          eth66=33
  |   | 2022-08-18 18:26:50 | [INFO] [08-18\|10:26:50.896] [2/7 Bodies] Wrote block bodies          block_num=7426300 delivery/sec=0B wasted/sec=14.8KB alloc=1.6GB sys=3.7GB
  |   | 2022-08-18 18:26:33 | [WARN] [08-18\|10:26:33.447] Served                                   conn=10.80.3.64:34384 method=eth_call reqid=39483 t=51.062972ms err="execution reverted"
  |   | 2022-08-18 18:26:33 | [WARN] [08-18\|10:26:33.224] Served                                   conn=10.80.3.64:34384 method=eth_call reqid=145487 t=51.136574ms err="execution reverted"
  |   | 2022-08-18 18:26:30 | [INFO] [08-18\|10:26:30.314] [5/7 Execution] Unwind Execution         from=7426331 to=7426300
  |   | 2022-08-18 18:26:30 | [INFO] [08-18\|10:26:30.104] [7/7 IntermediateHashes] Trie root       hash=0xd2946d45d44a6d7c57fb566dc9760aae7b4224a2707d9974cfa92ed2d340a690
  |   | 2022-08-18 18:26:29 | [INFO] [08-18\|10:26:29.729] [7/7 IntermediateHashes] Unwinding       from=7426331 to=7426300 csbucket=StorageChangeSet
  |   | 2022-08-18 18:26:29 | [INFO] [08-18\|10:26:29.708] [7/7 IntermediateHashes] Unwinding       from=7426331 to=7426300 csbucket=AccountChangeSet
  |   | 2022-08-18 18:26:29 | [INFO] [08-18\|10:26:29.585] [6/7 HashState] Unwinding started        from=7426331 to=7426300 storage=true codes=false
  |   | 2022-08-18 18:26:29 | [INFO] [08-18\|10:26:29.461] [6/7 HashState] Unwinding started        from=7426331 to=7426300 storage=false codes=false
  |   | 2022-08-18 18:26:29 | [INFO] [08-18\|10:26:29.453] [6/7 HashState] Unwinding started        from=7426331 to=7426300 storage=false codes=true
  |   | 2022-08-18 18:26:29 | [INFO] [08-18\|10:26:29.453] UnwindTo                                 block=7426300 bad_block_hash=0x0000000000000000000000000000000000000000000000000000000000000000
  |   | 2022-08-18 18:26:27 | [INFO] [08-18\|10:26:27.096] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:26:27 | [INFO] [08-18\|10:26:27.095] RPC Daemon notified of new headers       from=7426330 to=7426331 header sending=21.286µs log sending=262ns
  |   | 2022-08-18 18:26:27 | [INFO] [08-18\|10:26:27.094] Commit cycle                             in=253.836805ms
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.841] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.841] Timings (slower than 50ms)               Headers=249ms CallTraces=65ms StorageHistoryIndex=149ms
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.330] Flushing in-memory state
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.275] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.262] RPC Daemon notified of new headers       from=7426330 to=7426331 header sending=18.327µs log sending=229ns
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.261] Commit cycle                             in=7.090413ms
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.254] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.254] Timings (slower than 50ms)               Headers=1.842s
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.244] Tables                                   PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B
  |   | 2022-08-18 18:26:26 | [INFO] [08-18\|10:26:26.244] Timings (slower than 50ms)               Execution=397ms IntermediateHashes=629ms
  |   | 2022-08-18 18:26:25 | [INFO] [08-18\|10:26:25.601] [5/7 Execution] Completed on             block=7426331
  |   | 2022-08-18 18:26:24 | [INFO] [08-18\|10:26:24.411] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:26:24 | [INFO] [08-18\|10:26:24.408] RPC Daemon notified of new headers       from=7426330 to=7426331 header sending=54.328µs log sending=289ns
  |   | 2022-08-18 18:26:24 | [INFO] [08-18\|10:26:24.406] Commit cycle                             in=5.894389ms
  |   | 2022-08-18 18:26:24 | [INFO] [08-18\|10:26:24.400] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:26:24 | [INFO] [08-18\|10:26:24.399] Timings (slower than 50ms)               Headers=11.175s
  |   | 2022-08-18 18:26:24 | [INFO] [08-18\|10:26:24.291] [1/16 Headers] Downloading PoS headers... height=7426301 hash=0x74cd95fadfdbb29304e35d13c2fb764c2a2f171c50b7900f09696cb9d9d7092a requestId=5408
  |   | 2022-08-18 18:26:24 | [INFO] [08-18\|10:26:24.291] [1/16 Headers] New payload missing parent
  |   | 2022-08-18 18:26:13 | [INFO] [08-18\|10:26:13.618] [txpool] stat                            block=7426330 pending=2364 baseFee=325 queued=16828 alloc=1.5GB sys=3.7GB
  |   | 2022-08-18 18:26:13 | [INFO] [08-18\|10:26:13.220] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:26:13 | [INFO] [08-18\|10:26:13.217] RPC Daemon notified of new headers       from=7426329 to=7426330 header sending=47.068µs log sending=355ns
  |   | 2022-08-18 18:26:13 | [INFO] [08-18\|10:26:13.216] Commit cycle                             in=40.884118ms
  |   | 2022-08-18 18:26:13 | [INFO] [08-18\|10:26:13.174] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:26:13 | [INFO] [08-18\|10:26:13.174] Timings (slower than 50ms)               Headers=115ms
  |   | 2022-08-18 18:26:13 | [INFO] [08-18\|10:26:13.049] Flushing in-memory state
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.992] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.991] RPC Daemon notified of new headers       from=7426329 to=7426330 header sending=350.064µs log sending=288ns
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.988] Commit cycle                             in=13.809965ms
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.974] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.973] Timings (slower than 50ms)               Headers=8.955s
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.968] Tables                                   PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.966] Timings (slower than 50ms)               IntermediateHashes=94ms
  |   | 2022-08-18 18:26:12 | [INFO] [08-18\|10:26:12.866] [5/7 Execution] Completed on             block=7426330
  |   | 2022-08-18 18:26:09 | [WARN] [08-18\|10:26:09.671] Served                                   conn=10.80.3.64:34384 method=eth_call reqid=148987 t=51.672177ms err="execution reverted"
  |   | 2022-08-18 18:26:09 | [WARN] [08-18\|10:26:09.608] Served                                   conn=10.80.3.64:34384 method=eth_call reqid=120154 t=51.131162ms err="execution reverted"
  |   | 2022-08-18 18:26:09 | [WARN] [08-18\|10:26:09.547] Served                                   conn=10.80.3.64:34384 method=eth_call reqid=39476 t=51.468309ms err="execution reverted"
  |   | 2022-08-18 18:26:09 | [WARN] [08-18\|10:26:09.318] Served                                   conn=10.80.3.64:34384 method=eth_call reqid=145459 t=51.111087ms err="execution reverted"
  |   | 2022-08-18 18:26:09 | [WARN] [08-18\|10:26:09.097] Served                                   conn=10.80.3.64:34384 method=eth_call reqid=144264 t=50.62513ms err="execution reverted"
  |   | 2022-08-18 18:26:04 | [INFO] [08-18\|10:26:04.016] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:26:03 | [INFO] [08-18\|10:26:03.964] RPC Daemon notified of new headers       from=7426328 to=7426329 header sending=16.046µs log sending=276ns
  |   | 2022-08-18 18:26:03 | [INFO] [08-18\|10:26:03.888] Commit cycle                             in=171.085058ms
  |   | 2022-08-18 18:26:03 | [INFO] [08-18\|10:26:03.717] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:26:03 | [INFO] [08-18\|10:26:03.717] Timings (slower than 50ms)               Headers=287ms CallTraces=63ms StorageHistoryIndex=125ms
  |   | 2022-08-18 18:26:03 | [INFO] [08-18\|10:26:03.243] Flushing in-memory state
  |   | 2022-08-18 18:26:03 | [INFO] [08-18\|10:26:03.133] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:26:03 | [INFO] [08-18\|10:26:03.030] RPC Daemon notified of new headers       from=7426328 to=7426329 header sending=14.65µs log sending=232ns
  |   | 2022-08-18 18:26:02 | [INFO] [08-18\|10:26:02.956] Commit cycle                             in=5.353835ms
  |   | 2022-08-18 18:26:02 | [INFO] [08-18\|10:26:02.951] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:26:02 | [INFO] [08-18\|10:26:02.951] Timings (slower than 50ms)               Headers=9.954s
  |   | 2022-08-18 18:26:02 | [INFO] [08-18\|10:26:02.949] Tables                                   PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B
  |   | 2022-08-18 18:26:02 | [INFO] [08-18\|10:26:02.949] Timings (slower than 50ms)               Execution=275ms IntermediateHashes=543ms
  |   | 2022-08-18 18:26:02 | [INFO] [08-18\|10:26:02.399] [5/7 Execution] Completed on             block=7426329
  |   | 2022-08-18 18:26:01 | [WARN] [08-18\|10:26:01.771] Served                                   conn=10.80.3.64:37552 method=eth_call reqid=145457 t=51.817307ms err="execution reverted"
  |   | 2022-08-18 18:26:01 | [WARN] [08-18\|10:26:01.548] Served                                   conn=10.80.3.64:37552 method=eth_call reqid=144213 t=50.894291ms err="execution reverted"
  |   | 2022-08-18 18:26:01 | [WARN] [08-18\|10:26:01.303] Served                                   conn=10.80.3.64:37552 method=eth_call reqid=148937 t=52.264445ms err="execution reverted"
  |   | 2022-08-18 18:26:01 | [WARN] [08-18\|10:26:01.068] Served                                   conn=10.80.3.64:37552 method=eth_call reqid=39731 t=51.833159ms err="execution reverted"
  |   | 2022-08-18 18:25:52 | [INFO] [08-18\|10:25:52.996] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:25:52 | [INFO] [08-18\|10:25:52.995] RPC Daemon notified of new headers       from=7426327 to=7426328 header sending=22.226µs log sending=257ns
  |   | 2022-08-18 18:25:52 | [INFO] [08-18\|10:25:52.992] Commit cycle                             in=575.07713ms
  |   | 2022-08-18 18:25:52 | [INFO] [08-18\|10:25:52.416] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:25:52 | [INFO] [08-18\|10:25:52.416] Timings (slower than 50ms)               Headers=452ms CallTraces=176ms AccountHistoryIndex=178ms StorageHistoryIndex=219ms LogIndex=57ms TxLookup=154ms
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.227] Flushing in-memory state
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.177] [1/16 Headers] Waiting for Beacon Chain...
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.167] RPC Daemon notified of new headers       from=7426327 to=7426328 header sending=15.316µs log sending=244ns
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.167] Commit cycle                             in=11.469205ms
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.156] Tables                                   PlainState=10.9GB AccountChangeSet=6.9GB StorageChangeSet=19.3GB BlockTransaction=7.0GB TransactionLog=33.2GB FreeList=15.9MB ReclaimableSpace=15.9GB
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.154] Timings (slower than 50ms)               Headers=25.227s
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.149] Tables                                   PlainState=0B AccountChangeSet=0B StorageChangeSet=0B BlockTransaction=0B TransactionLog=0B FreeList=0B
  |   | 2022-08-18 18:25:51 | [INFO] [08-18\|10:25:51.147] Timings (slower than 50ms)               Execution=458ms IntermediateHashes=1.057s
  |   | 2022-08-18 18:25:50 | [INFO] [08-18\|10:25:50.084] [5/7 Execution] Completed on             block=7426328
  |   | 2022-08-18 18:25:41 | [EROR] [08-18\|10:25:41.757] [NewPayload] invalid block hash          stated=0x8061da1973c983001cc471e527048ab4fdec36cbc3c892d4f97fd5a29f877c70 actual=0x0eaeebedcc1d421ddae12abfbd17cfc48569c5efef9de116564e13ad3ec56272

linhuatan avatar Aug 19 '22 04:08 linhuatan

Do you have the error stack?

enriavil1 avatar Aug 24 '22 16:08 enriavil1

This issue is stale because it has been open for 40 days with no activity. Remove stale label or comment, or this will be closed in 7 days.

github-actions[bot] avatar Oct 04 '22 03:10 github-actions[bot]

This issue was closed because it has been stalled for 7 days with no activity.

github-actions[bot] avatar Oct 11 '22 03:10 github-actions[bot]