erigon icon indicating copy to clipboard operation
erigon copied to clipboard

Client RPC failed to retrieve ForkChoiceUpdate response, err: unknown ancestor

Open exodus-justinz opened this issue 3 months ago • 3 comments

System information

Erigon version: ./erigon --version erigon version 2.60.9-501a53b7 OS & Version: Linux

Commit hash: https://github.com/erigontech/erigon/commit/501a53b7aa51a89bbcefc6a50eb44317b4a5a888

Erigon Command (with flags/config):

Consensus Layer:

Consensus Layer Command (with flags/config):

--nat=extip:x.x.x.x --identity=xxxxxx --ethash.dagdir=/home/eth/data/erigon-ethash --torrent.download.slots=10000 --http.corsdomain=* --torrent.download.rate=3000mb --gpo.percentile=65 --txpool.nolocals=true --http.api=admin,eth,net,web3,debug,engine,erigon --rpc.batch.limit=3000 --txpool.disable=true --http.addr=0.0.0.0 --internalcl=true --datadir=/home/eth/data/eth --ws --rpc.batch.concurrency=100 --http.vhosts=*

Chain/Network: Ethereum/Mainnet

Expected behaviour

Erigon syncs normally

Actual behaviour

Erigon starts up with the following logs, but the RPC port won't open

[WARN] [11-04|02:35:52.099] [1/12 Snapshots] Some blocks are not in snapshots and not in db max_in_snapshots=19799999 min_in_db=19900000
[INFO] [11-04|02:35:52.099] [snapshots:download] Stat                blocks=19800k indices=19800k alloc=4.5GB sys=6.5GB
[INFO] [11-04|02:36:43.784] Static peers                             len=0
[INFO] [11-04|02:36:43.786] [Sentinel] Sentinel started              app=caplin enr=enr:-Ly4QEzPv8w4FnGq4Z88Nhx6VMJUek-u1VppoSrm6k_jCsU9QeJPdCnhP7Hs6GCHtPms38kmOAUko_yRpFd0xz43h9sBh2F0dG5ldHOIAAAAAAAAAACEZXRoMpBqlaGpBAAAAAAAAAAAAAAAgmlkgnY0gmlwhH8AAAGJc2VjcDI1NmsxoQMMNdr0E1NNllVhxS2i9PQTR4o4MrcXjMNVlKwoew9zX4hzeW5jbmV0cwCDdGNwgg-hg3VkcIIPoA
[INFO] [11-04|02:36:43.787] Started Ethereum 2.0 Gossip Service      app=caplin
[INFO] [11-04|02:36:43.884] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/attester_slashing/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/blob_sidecar_3/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/blob_sidecar_4/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/proposer_slashing/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/blob_sidecar_5/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/beacon_block/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/blob_sidecar_0/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/beacon_aggregate_and_proof/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/blob_sidecar_1/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/blob_sidecar_2/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/sync_committee_contribution_and_proof/ssz_snappy
[INFO] [11-04|02:36:43.885] [Gossip] Subscribed to topic             topic=/eth2/6a95a1a9/bls_to_execution_change/ssz_snappy
mdbx_meta_unsteady:9961 wipe txn #4, meta 2
[INFO] [11-04|02:37:03.497] [Caplin] starting clstages loop          app=caplin
[INFO] [11-04|02:37:03.498] Starting downloading History             app=caplin stage=DownloadHistoricalBlocks from=10321888
[INFO] [11-04|02:37:06.935] Missing blocks download finished (note: this does not mean that the history is complete, only that the missing blocks need for sync have been downloaded) app=caplin stage=DownloadHistoricalBlocks
[INFO] [11-04|02:37:08.503] Ready to insert history, waiting for sync cycle to finish app=caplin stage=DownloadHistoricalBlocks
[INFO] [11-04|02:37:08.507] [Caplin] Forward Sync                    app=caplin stage=ForwardSync from=10321888 to=10321983
[INFO] [11-04|02:37:41.351] [Caplin] Forward Sync                    app=caplin stage=ForwardSync progress=10321940 distance-from-chain-tip=8m36s estimated-time-remaining=24s
[INFO] [11-04|02:37:43.788] P2P                                      app=caplin peers=25
[WARN] [11-04|02:38:14.660] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:14.666] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:14.671] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:14.675] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:14.681] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:14.685] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:14.689] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:25.790] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:25.955] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:25.963] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:31.548] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:36.829] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[INFO] [11-04|02:38:37.154] [p2p] GoodPeers                          eth68=2
[WARN] [11-04|02:38:37.613] Could not set forkchoice                 app=caplin stage=ForkChoice err="execution Client RPC failed to retrieve ForkChoiceUpdate response, err: unknown ancestor"
[WARN] [11-04|02:38:37.613] error executing clstage                  app=caplin stage=ForkChoice err="execution Client RPC failed to retrieve ForkChoiceUpdate response, err: unknown ancestor"
[INFO] [11-04|02:38:37.739] [mem] memory stats                       Rss=11.6GB Size=0B Pss=11.6GB SharedClean=2.8MB SharedDirty=0B PrivateClean=727.8MB PrivateDirty=10.9GB Referenced=11.6GB Anonymous=10.9GB Swap=0B alloc=6.0GB sys=11.0GB
[INFO] [11-04|02:38:43.788] P2P                                      app=caplin peers=49
[WARN] [11-04|02:38:50.845] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"
[WARN] [11-04|02:38:54.917] Could not set forkchoice                 app=caplin stage=ForkChoice err="execution Client RPC failed to retrieve ForkChoiceUpdate response, err: unknown ancestor"

And eventually it would loop with

[WARN] [11-04|02:44:26.761] Could not set forkchoice                 app=caplin stage=ForkChoice err="execution Client RPC failed to retrieve ForkChoiceUpdate response, err: unknown ancestor"
[WARN] [11-04|02:44:26.761] error executing clstage                  app=caplin stage=ForkChoice err="execution Client RPC failed to retrieve ForkChoiceUpdate response, err: unknown ancestor"
[WARN] [11-04|02:44:36.593] [engine] CurrentHeader                   err="ethereumExecutionModule.CurrentHeader: no current header yet - probabably node not synced yet"

Steps to reproduce the behaviour

Backtrace

[backtrace]

exodus-justinz avatar Nov 04 '24 03:11 exodus-justinz