Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

v0.4.2 does not sync from scratch #117

Closed
astudnev opened this issue May 30, 2024 · 10 comments
Closed

v0.4.2 does not sync from scratch #117

astudnev opened this issue May 30, 2024 · 10 comments

Comments

@astudnev
Copy link

astudnev commented May 30, 2024 •
edited
Loading

System information

Network: mainnet

if you are running a local node, please provide the following information:
op-node version: 0.4.2
op-geth version: 0.4.2
OS & Version: Linux

Expected behaviour

v0.4.2 installed and run in snap or full sync with empty state

Actual behaviour

If node is updated with exisitng state database from 0.3.1 to 0.4.2, it runs and sync OK

If it is installed with empty state, it never reaches to the expected

INFO [05-30|09:52:32.625] Syncing beacon headers                   downloaded=388,608 left=24,920,353 eta=1h11m8.237s

when i downgraded, is starts syncing headers

Steps to reproduce the behaviour

Install v0.4.2 geth and bnbnode on new host
@welkin22
Copy link
Contributor

@astudnev Hello, could you provide the startup parameters for op-node and op-geth for easier problem localization?

@astudnev
Copy link
Author

op geth

"Path": "geth",
        "Args": [
            "--config",
            "/app/config/geth/config.toml",
            "--opBNBMainnet",
            "--nat=extip:65.109.157.157",
            "--bootnodes=enr:-KO4QHs5qh_kPFcjMgqkuN9dbxXT4C5Cjad4SAheaUxveCbJQ3XdeMMDHeHilHyqisyYQAByfdhzyKAdUp2SvyzWeBqGAYvRDf80g2V0aMfGhHFtSjqAgmlkgnY0gmlwhDaykUmJc2VjcDI1NmsxoQJUevTL3hJwj21IT2GC6VaNqVQEsJFPtNtO-ld5QTNCfIRzbmFwwIN0Y3CCdl-DdWRwgnZf,enr:-KO4QKIByq-YMjs6IL2YCNZEmlo3dKWNOy4B6sdqE3gjOrXeKdNbwZZGK_JzT1epqCFs3mujjg2vO1lrZLzLy4Rl7PyGAYvRA8bEg2V0aMfGhHFtSjqAgmlkgnY0gmlwhDbjSM6Jc2VjcDI1NmsxoQNQhJ5pqCPnTbK92gEc2F98y-u1OgZVAI1Msx-UiHezY4RzbmFwwIN0Y3CCdl-DdWRwgnZf",
            "--cache=32000",
            "--cache.preimages",
            "--state.scheme",
            "path"
        ],

[Eth]
NetworkId = 204
SyncMode = "snap"
EthDiscoveryURLs = []
SnapDiscoveryURLs = []
NoPruning = false
NoPrefetch = false
TxLookupLimit = 2350000
StateHistory = 90000
LightPeers = 100
DatabaseCache = 4096
DatabaseFreezer = ""
TrieCleanCache = 1228
TrieDirtyCache = 2048
TrieTimeout = 3600000000000
TrieCommitInterval = 0
SnapshotCache = 819
Preimages = true
FilterLogCacheSize = 32
EnablePreimageRecording = false
RPCGasCap = 50000000
RPCEVMTimeout = 5000000000
RPCTxFeeCap = 1e+000
RollupSequencerHTTP = "https://opbnb-mainnet-rpc.bnbchain.org"
RollupHistoricalRPC = ""
RollupHistoricalRPCTimeout = 0
RollupDisableTxPoolGossip = false
RollupDisableTxPoolAdmission = false
RollupHaltOnIncompatibleProtocolVersion = ""

[Eth.Miner]
GasFloor = 0
GasCeil = 150000000
GasPrice = 1000000000
Recommit = 2000000000
NewPayloadTimeout = 2000000000
RollupComputePendingBlock = false

[Eth.TxPool]
Locals = []
NoLocals = false
Journal = "transactions.rlp"
Rejournal = 3600000000000
JournalRemote = false
PriceLimit = 1
PriceBump = 10
AccountSlots = 200
GlobalSlots = 10000
AccountQueue = 200
GlobalQueue = 5000
Lifetime = 10800000000000
ReannounceTime = 315360000000000000
ReannounceRemotes = false

[Eth.BlobPool]
Datadir = "blobpool"
Datacap = 10737418240
PriceBump = 100

[Eth.GPO]
Blocks = 20
Percentile = 60
MaxHeaderHistory = 1024
MaxBlockHistory = 1024
MaxPrice = 500000000000
IgnorePrice = 2
MinSuggestedPriorityFee = 1000000

[Node]
DataDir = "/app/data"
IPCPath = "geth.ipc"
HTTPHost = "0.0.0.0"
HTTPPort = 8545
HTTPVirtualHosts = ["*"]
HTTPModules = ["web3", "debug", "eth", "txpool", "net", "engine"]
AuthAddr = "0.0.0.0"
AuthPort = 8551
AuthVirtualHosts = ["*"]
WSHost = "0.0.0.0"
WSPort = 8546
WSModules = ["debug", "eth", "txpool", "net", "engine", "web3"]
GraphQLVirtualHosts = ["localhost"]
BatchRequestLimit = 1000
BatchResponseMaxSize = 25000000
JWTSecret = "/app/config/jwtsecret"
DBEngine = "pebble"

[Node.P2P]
MaxPeers = 10
NoDiscovery = false
DiscoveryV4 = true
StaticNodes = []
TrustedNodes = []
ListenAddr = ":30303"
DiscAddr = ""
EnableMsgEvents = false

[Node.HTTPTimeouts]
ReadTimeout = 30000000000
ReadHeaderTimeout = 30000000000
WriteTimeout = 30000000000
IdleTimeout = 120000000000

[Metrics]
HTTP = "127.0.0.1"
Port = 6060
InfluxDBEndpoint = "http://localhost:8086"
InfluxDBDatabase = "geth"
InfluxDBUsername = "test"
InfluxDBPassword = "test"
InfluxDBTags = "host=localhost"
InfluxDBToken = "test"
InfluxDBBucket = "geth"
InfluxDBOrganization = "geth"

op node

 "Path": "op-node",
        "Args": [
            "--l1.trustrpc",
            "--sequencer.l1-confs=15",
            "--verifier.l1-confs=15",
            "--l1.http-poll-interval",
            "3s",
            "--l1.epoch-poll-interval",
            "45s",
            "--l1.rpc-max-batch-size",
            "20",
            "--rollup.config=/app/config/chain/rollup.json",
            "--rpc.addr=0.0.0.0",
            "--rpc.port=8546",
            "--p2p.sync.req-resp",
            "--p2p.listen.ip=0.0.0.0",
            "--p2p.listen.tcp=9003",
            "--p2p.listen.udp=9003",
            "--snapshotlog.file=/app/data/snapshot.log",
            "--p2p.bootnodes=enr:-J24QA9sgVxbZ0KoJ7-1gx_szfc7Oexzz7xL2iHS7VMHGj2QQaLc_IQZmFthywENgJWXbApj7tw7BiouKDOZD4noWEWGAYppffmvgmlkgnY0gmlwhDbjSM6Hb3BzdGFja4PMAQCJc2VjcDI1NmsxoQKetGQX7sXd4u8hZr6uayTZgHRDvGm36YaryqZkgnidS4N0Y3CCIyuDdWRwgiMs,enr:-J24QPSZMaGw3NhO6Ll25cawknKcOFLPjUnpy72HCkwqaHBKaaR9ylr-ejx20INZ69BLLj334aEqjNHKJeWhiAdVcn-GAYv28FmZgmlkgnY0gmlwhDTDWQOHb3BzdGFja4PMAQCJc2VjcDI1NmsxoQJ-_5GZKjs7jaB4TILdgC8EwnwyL3Qip89wmjnyjvDDwoN0Y3CCIyuDdWRwgiMs",
            "--metrics.enabled",
            "--metrics.addr=0.0.0.0",
            "--metrics.port=7300",
            "--pprof.enabled",
            "--rpc.enable-admin",
            "--l1=https://bsc-dataseed.bnbchain.org",
            "--l2=http://op-geth:8551",
            "--l2.jwt-secret=/app/config/jwtsecret",
            "--l2.engine-sync=true",
            "--l2.skip-sync-start-check=true",
            "--log.level=info"
        ],

Backtrace

INFO [05-31|06:43:08.673] Starting geth on an opBNB Mainnet  network... network=true
INFO [05-31|06:43:08.674] Maximum peer count                       ETH=10 total=10
INFO [05-31|06:43:08.676] Smartcard socket not found, disabling    err="stat /run/pcscd/pcscd.comm: no such file or directory"
INFO [05-31|06:43:08.682] Set global gas cap                       cap=50,000,000
INFO [05-31|06:43:08.957] Initializing the KZG library             backend=gokzg
INFO [05-31|06:43:08.969] Allocated trie memory caches             clean=4.69GiB dirty=7.81GiB
INFO [05-31|06:43:08.969] Using pebble as the backing database
INFO [05-31|06:43:08.969] Allocated cache and file handles         database=/app/data/geth/chaindata cache=15.62GiB handles=524,288 "memory table"=3.91GiB
INFO [05-31|06:43:09.021] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:09.028] Opened ancient database                  database=/app/data/geth/chaindata/ancient/chain readonly=false
INFO [05-31|06:43:09.028] State scheme set by user                 scheme=path
INFO [05-31|06:43:09.029] Succeed to init proof keeper             options="&{enable:false keepProofBlockSpan:90000 gcInterval:3600 watchStartKeepCh:0xc005d1f6e0 notifyFinishKeepCh:0xc005d1f740}"
WARN [05-31|06:43:09.029] Sanitizing invalid node buffer size      provided=7.81GiB updated=256.00MiB
ERROR[05-31|06:43:09.029] Zero trie root hash!
INFO [05-31|06:43:09.029] init trie node buffer                    type=async
INFO [05-31|06:43:09.029] new async node buffer                    limit=256.00MiB layers=0
INFO [05-31|06:43:09.035] Opened ancient database                  database=/app/data/geth/chaindata/ancient/state readonly=false
WARN [05-31|06:43:09.036] Path-based state scheme is an experimental feature
INFO [05-31|06:43:09.036] Writing custom genesis block
INFO [05-31|06:43:09.334]
INFO [05-31|06:43:09.334] ---------------------------------------------------------------------------------------------------------------------------------------------------------
INFO [05-31|06:43:09.335] Chain ID:  204 (opBNBMainnet)
INFO [05-31|06:43:09.335] Consensus: Optimism
INFO [05-31|06:43:09.335]
INFO [05-31|06:43:09.335] Pre-Merge hard forks (block based):
INFO [05-31|06:43:09.335]  - Homestead:                   #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/homestead.md)
INFO [05-31|06:43:09.335]  - Tangerine Whistle (EIP 150): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/tangerine-whistle.md)
INFO [05-31|06:43:09.335]  - Spurious Dragon/1 (EIP 155): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/spurious-dragon.md)
INFO [05-31|06:43:09.335]  - Spurious Dragon/2 (EIP 158): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/spurious-dragon.md)
INFO [05-31|06:43:09.335]  - Byzantium:                   #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/byzantium.md)
INFO [05-31|06:43:09.335]  - Constantinople:              #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/constantinople.md)
INFO [05-31|06:43:09.335]  - Petersburg:                  #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/petersburg.md)
INFO [05-31|06:43:09.335]  - Istanbul:                    #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/istanbul.md)
INFO [05-31|06:43:09.335]  - Muir Glacier:                #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/muir-glacier.md)
INFO [05-31|06:43:09.335]  - Berlin:                      #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/berlin.md)
INFO [05-31|06:43:09.335]  - London:                      #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/london.md)
INFO [05-31|06:43:09.335]  - Arrow Glacier:               #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/arrow-glacier.md)
INFO [05-31|06:43:09.335]  - Gray Glacier:                #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/gray-glacier.md)
INFO [05-31|06:43:09.335]
INFO [05-31|06:43:09.335] Merge configured:
INFO [05-31|06:43:09.335]  - Hard-fork specification:    https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/paris.md
INFO [05-31|06:43:09.335]  - Network known to be merged: true
INFO [05-31|06:43:09.335]  - Total terminal difficulty:  0
INFO [05-31|06:43:09.335]  - Merge netsplit block:       #0
INFO [05-31|06:43:09.335]
INFO [05-31|06:43:09.335] Post-Merge hard forks (timestamp based):
INFO [05-31|06:43:09.335]  - Shanghai:                    @1718870400 (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/shanghai.md)
INFO [05-31|06:43:09.335]  - Cancun:                      @1718871600
INFO [05-31|06:43:09.335]  - Regolith:                    @0
INFO [05-31|06:43:09.335]  - Canyon:                      @1718870400
INFO [05-31|06:43:09.335]  - Ecotone:                     @1718871600
INFO [05-31|06:43:09.335] OPBNB hard forks (block based):
INFO [05-31|06:43:09.335]  - Fermat:              #9397477  (https://github.com/bnb-chain/BEPs/blob/master/BEPs/BEP-293.md)
INFO [05-31|06:43:09.335] OPBNB hard forks (timestamp based):
INFO [05-31|06:43:09.335]  - Haber:                    @1718872200
INFO [05-31|06:43:09.335]
INFO [05-31|06:43:09.335] ---------------------------------------------------------------------------------------------------------------------------------------------------------
INFO [05-31|06:43:09.335]
INFO [05-31|06:43:09.335] Loaded most recent local block           number=0 hash=4dd611..dd6df4 td=0 age=9mo3w2d
WARN [05-31|06:43:09.335] Failed to load snapshot                  err="missing or corrupted snapshot"
INFO [05-31|06:43:09.336] Rebuilding state snapshot
INFO [05-31|06:43:09.337] Initialising Ethereum protocol           network=204  dbversion=<nil>
INFO [05-31|06:43:09.337] Resuming state snapshot generation       root=5cc58a..27bf2c accounts=0 slots=0 storage=0.00B dangling=0 elapsed=1.116ms
INFO [05-31|06:43:09.337] Initialized transaction indexer          limit=2,350,000
INFO [05-31|06:43:09.337] Entered PoS stage
INFO [05-31|06:43:09.337] Regenerated local transaction journal    transactions=0 accounts=0
INFO [05-31|06:43:09.337] Enabled snap sync                        head=0 hash=4dd611..dd6df4
INFO [05-31|06:43:09.337] Chain post-merge, sync via beacon client
INFO [05-31|06:43:09.337] Gasprice oracle is ignoring threshold set threshold=2
WARN [05-31|06:43:09.338] Engine API enabled                       protocol=eth
INFO [05-31|06:43:09.338] Starting peer-to-peer node               instance=Geth/v0.1.0-unstable-fe052ac4/linux-amd64/go1.21.10
INFO [05-31|06:43:09.342] New local node record                    seq=1,717,137,789,341 id=6fa23caa588b6ad3 ip=65.109.157.157 udp=30303 tcp=30303
INFO [05-31|06:43:09.342] Started P2P networking                   self=enode://e6bbcbe448b2eb4559ef407e605b90d980fb32e773649a80cdfd55b2386bc312dc3ca105ab3a8046aae51cad00682b00fe63ddc64d8e434228a812122d1d6966@65.109.157.157:30303
INFO [05-31|06:43:09.343] IPC endpoint opened                      url=/app/data/geth.ipc
INFO [05-31|06:43:09.344] Loaded JWT secret file                   path=/app/config/jwtsecret crc32=0xf39b779f
INFO [05-31|06:43:09.344] HTTP server started                      endpoint=[::]:8545 auth=false prefix= cors= vhosts=*
INFO [05-31|06:43:09.344] WebSocket enabled                        url=ws://[::]:8546
INFO [05-31|06:43:09.344] WebSocket enabled                        url=ws://[::]:8551
INFO [05-31|06:43:09.344] HTTP server started                      endpoint=[::]:8551 auth=true  prefix= cors=localhost vhosts=*
INFO [05-31|06:43:09.377] Generated state snapshot                 accounts=2064 slots=2076 storage=381.44KiB dangling=0 elapsed=41.482ms
INFO [05-31|06:43:12.022] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:15.022] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:18.023] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:21.024] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:24.024] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:24.343] Looking for peers                        peercount=0 tried=1 static=0
INFO [05-31|06:43:27.025] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:30.025] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:33.025] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:36.026] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:39.027] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:42.028] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:44.343] Looking for peers                        peercount=0 tried=1 static=0
INFO [05-31|06:43:45.029] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:48.030] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:51.030] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:54.031] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:43:57.032] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:00.033] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:03.034] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:06.034] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:09.035] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:12.036] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:15.037] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
WARN [05-31|06:44:16.989] Served eth_getBlockByNumber              conn=172.16.232.197:44768 reqid=2 duration="59.034µs" err="finalized block not found"
WARN [05-31|06:44:16.992] Ignoring payload while snap syncing      number=25,384,133 hash=8774a9..3c3c85 reason="forced head needed for startup"
INFO [05-31|06:44:16.992] Forkchoice requested sync to new head    number=25,384,133 hash=8774a9..3c3c85
INFO [05-31|06:44:17.225] Forkchoice requested sync to new head    number=25,384,134 hash=57bf73..f522bb
INFO [05-31|06:44:18.037] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:18.167] Forkchoice requested sync to new head    number=25,384,135 hash=fdb22f..9d745e
INFO [05-31|06:44:19.168] Forkchoice requested sync to new head    number=25,384,136 hash=5d5a61..7ec0ad
INFO [05-31|06:44:20.163] Forkchoice requested sync to new head    number=25,384,137 hash=0017c9..4a7767
INFO [05-31|06:44:21.038] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:21.146] Forkchoice requested sync to new head    number=25,384,138 hash=b3e24c..12462b
INFO [05-31|06:44:22.147] Forkchoice requested sync to new head    number=25,384,139 hash=4689f1..103b27
INFO [05-31|06:44:23.148] Forkchoice requested sync to new head    number=25,384,140 hash=afc57f..b53823
INFO [05-31|06:44:24.039] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:24.147] Forkchoice requested sync to new head    number=25,384,141 hash=d1e055..dcf209
INFO [05-31|06:44:25.147] Forkchoice requested sync to new head    number=25,384,142 hash=bbf1e0..d80505
INFO [05-31|06:44:26.148] Forkchoice requested sync to new head    number=25,384,143 hash=f32c40..02863d
INFO [05-31|06:44:27.040] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:27.148] Forkchoice requested sync to new head    number=25,384,144 hash=f08a88..948924
INFO [05-31|06:44:28.147] Forkchoice requested sync to new head    number=25,384,145 hash=2ee228..23f3bc
INFO [05-31|06:44:29.148] Forkchoice requested sync to new head    number=25,384,146 hash=fafa4c..19a21d
INFO [05-31|06:44:30.040] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:30.151] Forkchoice requested sync to new head    number=25,384,147 hash=2886bc..fa550a
INFO [05-31|06:44:31.165] Forkchoice requested sync to new head    number=25,384,148 hash=5fc52f..8b0c94
INFO [05-31|06:44:32.153] Forkchoice requested sync to new head    number=25,384,149 hash=c6ea49..837bdd
INFO [05-31|06:44:33.040] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:33.149] Forkchoice requested sync to new head    number=25,384,150 hash=a0fed0..e32720
INFO [05-31|06:44:34.146] Forkchoice requested sync to new head    number=25,384,151 hash=c24902..ad4c36
INFO [05-31|06:44:35.147] Forkchoice requested sync to new head    number=25,384,152 hash=47af6f..f29127
INFO [05-31|06:44:36.040] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:36.146] Forkchoice requested sync to new head    number=25,384,153 hash=1ce178..b7cb32
INFO [05-31|06:44:37.148] Forkchoice requested sync to new head    number=25,384,154 hash=6ae96d..eb6038
INFO [05-31|06:44:38.150] Forkchoice requested sync to new head    number=25,384,155 hash=ca625f..25700a
INFO [05-31|06:44:39.041] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:39.151] Forkchoice requested sync to new head    number=25,384,156 hash=daa19e..6c1a09
INFO [05-31|06:44:40.149] Forkchoice requested sync to new head    number=25,384,157 hash=818333..9b6856
INFO [05-31|06:44:41.147] Forkchoice requested sync to new head    number=25,384,158 hash=230676..cbb41e
INFO [05-31|06:44:42.042] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:42.147] Forkchoice requested sync to new head    number=25,384,159 hash=6c7108..317cce
INFO [05-31|06:44:43.148] Forkchoice requested sync to new head    number=25,384,160 hash=8289b9..05f5db
INFO [05-31|06:44:44.147] Forkchoice requested sync to new head    number=25,384,161 hash=685f45..876354
INFO [05-31|06:44:45.043] loop print db stats                      database=/app/data/geth/chaindata comp_time=0 write_delay_count=0 write_delay_time=0 non_level0_comp_count=0 level0_comp_count=0
INFO [05-31|06:44:45.165] Forkchoice requested sync to new head    number=25,384,162 hash=d1cc15..849ce2

t=2024-05-31T06:44:09+0000 lvl=warn msg="Found a deprecated flag which will be removed in a future version" flag_name=l2.engine-sync
t=2024-05-31T06:44:09+0000 lvl=warn msg="Found a deprecated flag which will be removed in a future version" flag_name=l2.skip-sync-start-check
t=2024-05-31T06:44:09+0000 lvl=info msg="Key manager " keyType=OP_NODE_P2P_SEQUENCER_KEY
t=2024-05-31T06:44:09+0000 lvl=info msg="Key manager is skipped"
t=2024-05-31T06:44:09+0000 lvl=warn msg="using preset rollup config of" !BADKEY=204 !BADKEY="overwrite rollup file"
t=2024-05-31T06:44:09+0000 lvl=info msg="Not opted in to ProtocolVersions signal loading, disabling ProtocolVersions contract now."
t=2024-05-31T06:44:09+0000 lvl=eror msg="l2.engine-sync is deprecated and will be removed in a future release. Use --syncmode=execution-layer instead."
t=2024-05-31T06:44:09+0000 lvl=info msg="Rollup Config" l2_chain_id=204 l2_network="unknown L2" l1_chain_id=56 l1_network=bscMainnet l2_start_time=1691753723 l2_block_hash=0x4dd61178c8b0f01670c231597e7bcb368e84545acd46d940a896d6a791dd6df4 l2_block_number=0 l1_block_hash=0x29443b21507894febe7700f7c5cd3569cc8bf1ba535df0489276d8004af81044 l1_block_number=30758357 regolith_time="@ genesis" canyon_time="@ 1718870400 ~ Thu Jun 20 08:00:00 UTC 2024" delta_time="@ 1718871000 ~ Thu Jun 20 08:10:00 UTC 2024" ecotone_time="@ 1718871600 ~ Thu Jun 20 08:20:00 UTC 2024" fjord_time="(not configured)" interop_time="(not configured)" fermat=9397477 snow_time="@ 1713160800 ~ Mon Apr 15 06:00:00 UTC 2024"
t=2024-05-31T06:44:09+0000 lvl=info msg="Initializing rollup node" version=v0.0.0-7b9db84b-1716807646
t=2024-05-31T06:44:10+0000 lvl=info msg="loaded new runtime config values!" p2p_seq_address=0x92c545a17D14DdE2b46f871f7ED334062a4250bB
t=2024-05-31T06:44:10+0000 lvl=info msg="started p2p host" addrs="[/ip4/127.0.0.1/tcp/9003 /ip4/172.16.232.197/tcp/9003]" peerID=16Uiu2HAm1EzQbqUi1GJnMAny8SbFmRsz7LiumMTJsdBUiwADvv7F
t=2024-05-31T06:44:10+0000 lvl=info msg="New local node record" seq=1717137850048 id=f5d70ed497ae48cd3fc79ef8d0963735cf4a72fd52015161c0a29fa5f0198a11 ip=<nil> udp=9003 tcp=9003
t=2024-05-31T06:44:10+0000 lvl=info msg="started discovery service" p2p=discv5 enr=enr:-JW4QM46M9SOihRgU4kiRtsLnD-OTJv7GJ2-xE0YGWfGMTzeIjXDk51GTozPp68AHfcbDSjMf7Bd1k1MKNNBXZTdf2yGAY_NZD7AgmlkgnY0h29wc3RhY2uDzAEAiXNlY3AyNTZrMaECVmmRpsIkOsJbvkr2hxFdIc7ytUcI46VHZ3TVjkeU0oaDdGNwgiMrg3VkcIIjKw id=f5d70ed497ae48cd3fc79ef8d0963735cf4a72fd52015161c0a29fa5f0198a11
t=2024-05-31T06:44:10+0000 lvl=info msg="Admin RPC enabled"
t=2024-05-31T06:44:10+0000 lvl=info msg="Starting JSON-RPC server"
t=2024-05-31T06:44:10+0000 lvl=info msg="started metrics server" addr=[::]:7300
t=2024-05-31T06:44:10+0000 lvl=info msg="started pprof server" addr=[::]:6060
t=2024-05-31T06:44:10+0000 lvl=info msg="Starting execution engine driver"
t=2024-05-31T06:44:10+0000 lvl=info msg="Starting driver" sequencerEnabled=false sequencerStopped=false
t=2024-05-31T06:44:10+0000 lvl=info msg="Rollup node started"
t=2024-05-31T06:44:10+0000 lvl=info msg="State loop started"
t=2024-05-31T06:44:12+0000 lvl=info msg="Received first L1 head signal" l1_head=0xfbef0d37c1b5e4a43a49ec7217bd94ceb0006bf06c894a0d3cb945578672b9ce:39196431
t=2024-05-31T06:44:15+0000 lvl=info msg="attempting connection" peer=16Uiu2HAm1tB4A5krrZvCNcj3y2W14h37fuJv4UknYhP2t4mh7S9d
t=2024-05-31T06:44:15+0000 lvl=info msg="attempting connection" peer=16Uiu2HAmAY2UsJyqmUzCftPzaQr1dRaxrVBYWtfjUBZhMaC9PLYc
t=2024-05-31T06:44:15+0000 lvl=info msg="attempting connection" peer=16Uiu2HAmC9SoHtcTdLQJVuUNoYFG4ZjG7u38rnkwbcqP3TQWngcT
t=2024-05-31T06:44:15+0000 lvl=info msg="attempting connection" peer=16Uiu2HAmMadk1g4z11B9d8CbAsrstdsSoeu1fesKm7Y74NXG4WBm
t=2024-05-31T06:44:15+0000 lvl=info msg="connected to peer" peer=16Uiu2HAmC9SoHtcTdLQJVuUNoYFG4ZjG7u38rnkwbcqP3TQWngcT addr=/ip4/64.31.7.155/tcp/9003 peerCount=1
t=2024-05-31T06:44:15+0000 lvl=info msg="Starting P2P sync client event loop" peer=16Uiu2HAmC9SoHtcTdLQJVuUNoYFG4ZjG7u38rnkwbcqP3TQWngcT
t=2024-05-31T06:44:15+0000 lvl=info msg="attempting connection" peer=16Uiu2HAmSDQYV7jnXRVNPgbM7VeCfGWBCfNngpbEmQ3rDTtzM6o4
t=2024-05-31T06:44:15+0000 lvl=info msg="connected to peer" peer=16Uiu2HAm1tB4A5krrZvCNcj3y2W14h37fuJv4UknYhP2t4mh7S9d addr=/ip4/52.195.105.192/tcp/9003 peerCount=2
t=2024-05-31T06:44:15+0000 lvl=info msg="Starting P2P sync client event loop" peer=16Uiu2HAm1tB4A5krrZvCNcj3y2W14h37fuJv4UknYhP2t4mh7S9d
t=2024-05-31T06:44:16+0000 lvl=info msg="connected to peer" peer=12D3KooWRDwQv4Unx5v18DmRAqyahHFnFcpb3MBcj323bUaxXUHg addr=/ip4/64.31.7.155/tcp/11928 peerCount=3
t=2024-05-31T06:44:16+0000 lvl=info msg="disconnected from peer" peer=12D3KooWRDwQv4Unx5v18DmRAqyahHFnFcpb3MBcj323bUaxXUHg addr=/ip4/64.31.7.155/tcp/11928 peerCount=2
t=2024-05-31T06:44:16+0000 lvl=info msg="Starting P2P sync client event loop" peer=12D3KooWRDwQv4Unx5v18DmRAqyahHFnFcpb3MBcj323bUaxXUHg
t=2024-05-31T06:44:16+0000 lvl=info msg="attempting connection" peer=16Uiu2HAkxkoGTFzDEqjAuzvVqzgQ5puBArbLmeZTkFRCTdxnJYza
t=2024-05-31T06:44:16+0000 lvl=info msg="connected to peer" peer=16Uiu2HAmSDQYV7jnXRVNPgbM7VeCfGWBCfNngpbEmQ3rDTtzM6o4 addr=/ip4/52.193.218.151/tcp/9003 peerCount=3

@astudnev
Copy link
Author

I added logs for comparison at #118

@astudnev
Copy link
Author

I also tried set

[Eth]
SyncMode = "full"

but that did not change anything

@astudnev astudnev changed the title v0.4.2 does not sync in snap mode from scratch v0.4.2 does not sync from scratch May 31, 2024
@welkin22
Copy link
Contributor

@astudnev Hello, can you provide longer logs for op-node to help troubleshoot? The current logs are not sufficient for identifying the issue.

@astudnev
Copy link
Author

opbnb-logs.txt

@welkin22
Copy link
Contributor

welkin22 commented Jun 3, 2024

@astudnev Hello, the issue has been fixed, please try again.

@astudnev
Copy link
Author

astudnev commented Jun 4, 2024

Yes, it started to sync, but from the 1st block ... now it is on 13M - is it expected behavior?

INFO [06-04|16:45:03.491] Imported new chain segment               number=13,866,488 hash=21b50f..5dc768 blocks=948  txs=11832     mgas=755.625  elapsed=8.004s    mgasps=94.404  age=4mo2w3d   snapdiffs=1.45MiB    triediffs=10.65MiB   triedirty=242.97MiB  trieimutabledirty=0.00B

@welkin22
Copy link
Contributor

welkin22 commented Jun 5, 2024

Yes, it started to sync, but from the 1st block ... now it is on 13M - is it expected behavior?

INFO [06-04|16:45:03.491] Imported new chain segment               number=13,866,488 hash=21b50f..5dc768 blocks=948  txs=11832     mgas=755.625  elapsed=8.004s    mgasps=94.404  age=4mo2w3d   snapdiffs=1.45MiB    triediffs=10.65MiB   triedirty=242.97MiB  trieimutabledirty=0.00B

@astudnev Yes, this is the expected behavior if you have configured SyncMode = "full".

@astudnev
Copy link
Author

astudnev commented Jun 5, 2024

looks good, thank you!

@astudnev astudnev closed this as completed Jun 5, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@astudnev @welkin22