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

[dev@348] panic: invalid number of list elements: 0 #354

Closed
meowsbits opened this issue Apr 7, 2021 · 4 comments
Closed

[dev@348] panic: invalid number of list elements: 0 #354

meowsbits opened this issue Apr 7, 2021 · 4 comments

Comments

@meowsbits
Copy link
Member

meowsbits commented Apr 7, 2021 •
edited
Loading

This issue found on running the currently latest version from #348. This is NOT on master.

./build/bin/geth version
CoreGeth
Version: 1.11.23-unstable
Git Commit: fd1278be50e741063bc7c37fa99d904373ff417a
Git Commit Date: 20210407
Architecture: amd64
Go Version: go1.16.2
Operating System: linux
GOPATH=/home/ia/go
GOROOT=/home/ia/go1.16.2.linux-amd64

Note that upon restart a head state is missing and a long-running repair is initiated, which drops ALL block data (newHead = 0, logged as head=0).

Apr 07 15:11:19 ubp52 sh[6036]: INFO [04-07|15:11:19.227] Unindexed transactions                   blocks=2048  txs=1802  tail=135546 elapsed=14.714ms                                                                                   
Apr 07 15:11:27 ubp52 sh[6036]: INFO [04-07|15:11:27.230] Imported new chain segment               blocks=1094  txs=17842 mgas=1594.059 elapsed=8.005s    mgasps=199.112 number=2486639 hash="b0488d…57a76a" age=3y3mo3d  dirty=735.91MiB
Apr 07 15:11:34 ubp52 sh[6036]: INFO [04-07|15:11:34.884] Imported new chain segment               blocks=954   txs=15897 mgas=1694.420 elapsed=7.654s    mgasps=221.375 number=2487593 hash="c6fa69…cff005" age=3y3mo3d  dirty=743.89MiB
Apr 07 15:11:35 ubp52 sh[6036]: INFO [04-07|15:11:35.021] Unindexed transactions                   blocks=2048  txs=2043  tail=137594 elapsed=136.278ms                                                                                  
Apr 07 15:11:42 ubp52 sh[6036]: INFO [04-07|15:11:42.902] Imported new chain segment               blocks=932   txs=14650 mgas=1401.080 elapsed=8.006s    mgasps=175.001 number=2488525 hash="5239ed…6c9ea6" age=3y3mo3d  dirty=746.70MiB
Apr 07 15:11:47 ubp52 sh[6036]: panic: node b60c21df6ac3f28f8b0456b1be412e1419492dddbd65c05b1f5838769dc08fe7: invalid number of list elements: 0      
Apr 07 15:11:47 ubp52 sh[6036]: goroutine 731 [running]:                                                                                                                                                                                 
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.mustDecodeNode(0xc0c7e63480, 0x20, 0x20, 0xc0d8a616c0, 0x194, 0x1a0, 0x20, 0xc0d8a616c0)                                                                            
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/node.go:111 +0x19f                                                            
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Database).node(0xc00027c000, 0x8ff2c36adf210cb6, 0x142e41beb156048b, 0x5bc065bddd2d4919, 0xe78fc09d7638581f, 0xc0f2fed710, 0x17fb9a8)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/database.go:378 +0x4c5                                                  
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).resolveHash(0xc0c0fafef0, 0xc06621fa20, 0x20, 0x20, 0xc0ec8cfd98, 0x5, 0x8, 0x0, 0x0, 0xc0fa1c81a0, ...)                                                    
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:482 +0xf4                                                                                                                           
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17fb9a8, 0xc0f2fed680, 0xc0ec8cfd98, 0x5, 0x8, 0xc0bff76dc5, 0x3c, 0x3c, 0x17fba20, ...)                                             
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:319 +0xd65                                       
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17f9a90, 0xc0c3264000, 0xc0ec8cfd98, 0x4, 0x8, 0xc0bff76dc4, 0x3d, 0x3d, 0x17fba20, ...)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:303 +0xab6                                                             
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17fb9a8, 0xc0f8183698, 0xc0ec8cfd98, 0x4, 0x8, 0xc0bff76dc4, 0x3d, 0x3d, 0x17fba20, ...)                                             
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:323 +0xe19                                                                                                                          
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17f9a90, 0xc0c3247e00, 0xc0ec8cfd98, 0x3, 0x8, 0xc0bff76dc3, 0x3e, 0x3e, 0x17fba20, ...)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:303 +0xab6                                                                                                                          
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17fb9a8, 0xc0f8245758, 0xc0ec8cfd98, 0x3, 0x8, 0xc0bff76dc3, 0x3e, 0x3e, 0x17fba20, ...)                                             
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:323 +0xe19                                       
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17f9a90, 0xc0c21f57c0, 0xc0ec8cfd98, 0x2, 0x8, 0xc0bff76dc2, 0x3f, 0x3f, 0x17fba20, ...)                                             
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:303 +0xab6                                                                                                                          
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17f9a90, 0xc0c32017c0, 0xc0ec8cfd98, 0x1, 0x8, 0xc0bff76dc1, 0x40, 0x40, 0x17fba20, ...)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:303 +0xab6                                                                                                                          
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).insert(0xc0c0fafef0, 0x17f9a90, 0xc0c3247cc0, 0x0, 0x0, 0x0, 0xc0bff76dc0, 0x41, 0x41, 0x17fba20, ...)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:303 +0xab6                                                                                                                          
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*Trie).TryUpdate(0xc0c0fafef0, 0xc0c0faff10, 0x20, 0x20, 0xc0bff76d70, 0x4e, 0x4e, 0x4e, 0x0)                                                                      
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/trie.go:250 +0x136                                                   
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/trie.(*SecureTrie).TryUpdate(0xc0c0fafef0, 0xc0ab3fa3a8, 0x14, 0x14, 0xc0bff76d70, 0x4e, 0x4e, 0x20, 0x20)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/trie/secure_trie.go:110 +0xbb                                                                                                                    
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/core/state.(*StateDB).updateStateObject(0xc0690636c0, 0xc0c16afd10)                                                                                                      
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/core/state/statedb.go:476 +0x137                               
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/core/state.(*StateDB).IntermediateRoot(0xc0690636c0, 0xc0c4fc6301, 0x0, 0x0, 0x0, 0x0)                                                                                   
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/core/state/statedb.go:879 +0x49b                                                                                
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/consensus/ethash.(*Ethash).Finalize(0xc000250540, 0x180af18, 0xc0001c0580, 0xc0ff5cd440, 0xc0690636c0, 0xc0262fea00, 0xe0, 0xe0, 0x2437760, 0x0, ...)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/consensus/ethash/consensus.go:541 +0x17f                                                                        
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/core.(*StateProcessor).Process(0xc00047fad0, 0xc0f261a240, 0xc0690636c0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...)                                                                                                
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/core/state_processor.go:90 +0xba2                                                                                   
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/core.(*BlockChain).insertChain(0xc0001c0580, 0xc026adc000, 0x800, 0x800, 0x8a05f45c36db9d01, 0x0, 0x0, 0x0)                                                                                                     
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/core/blockchain.go:2021 +0x1ec3                                                              
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/core.(*BlockChain).InsertChain(0xc0001c0580, 0xc026adc000, 0x800, 0x800, 0x0, 0x0, 0x0)                      
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/core/blockchain.go:1759 +0xcd3                                                                      
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/eth/downloader.(*Downloader).importBlockResults(0xc01edb2000, 0xc026acc000, 0x800, 0x800, 0x800, 0x0)
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/eth/downloader/downloader.go:1747 +0x50b                                                            
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/eth/downloader.(*Downloader).processFullSyncContent(0xc01edb2000, 0x0, 0xc082168a00)                   
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/eth/downloader/downloader.go:1721 +0x7d                                                             
Apr 07 15:11:47 ubp52 sh[6036]: github.com/ethereum/go-ethereum/eth/downloader.(*Downloader).spawnSync.func1(0xc01edb2000, 0xc08353a660, 0xc083d09400)          
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/eth/downloader/downloader.go:590 +0x5b                                                              
Apr 07 15:11:47 ubp52 sh[6036]: created by github.com/ethereum/go-ethereum/eth/downloader.(*Downloader).spawnSync
Apr 07 15:11:47 ubp52 sh[6036]:         /home/ia/go/src/github.com/ethereum/go-ethereum/eth/downloader/downloader.go:590 +0xaf                                                              



Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.984] Starting Geth on Ropsten testnet...
Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.984] Enabling metrics collection
Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.984] Enabling metrics export to InfluxDB
Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.985] Maximum peer count                       ETH=50 LES=0 total=50
Apr 07 15:11:50 ubp52 sh[11620]: WARN [04-07|15:11:50.986] The flag --rpc is deprecated and will be removed June 2021, please use --http
Apr 07 15:11:50 ubp52 sh[11620]: WARN [04-07|15:11:50.986] The flag --rpcport is deprecated and will be removed June 2021, please use --http.port
Apr 07 15:11:50 ubp52 sh[11620]: WARN [04-07|15:11:50.986] The flag --rpccorsdomain is deprecated and will be removed June 2021, please use --http.corsdomain
Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.986] Smartcard socket not found, disabling    err="stat /run/pcscd/pcscd.comm: no such file or directory"
Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.987] Set global gas cap                       cap=25000000
Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.987] Allocated trie memory caches             clean=614.00MiB dirty=1024.00MiB
Apr 07 15:11:50 ubp52 sh[11620]: INFO [04-07|15:11:50.987] Allocated cache and file handles         database=/home/ia/SAMSUNG_T5_3/e/clients/core-geth/ropsten/geth/chaindata cache=2.00GiB handles=2048
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.272] Opened ancient database                  database=/home/ia/SAMSUNG_T5_3/e/clients/core-geth/ropsten/geth/chaindata/ancient
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.282] Persisted trie from memory database      nodes=355 size=50.41KiB time="564.213µs" gcnodes=0 gcsize=0.00B gctime=0s livenodes=1 livesize=0.00B
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.289] Found stored genesis block               config="{ChainID: 3 Homestead: 0 DAO: <nil> DAOSupport: true EIP150: 0 EIP155: 10 EIP158: 10 Byzantium: 1700000 Constantinople: 4230000 ConstantinopleFix: 4939394 Istanbul:
 6485846 MuirGlacier: 7117117 Berlin: 9812189 Engine: ethash}"
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.289] Initialised chain configuration          config="{ChainID: 3 Homestead: 0 DAO: <nil> DAOSupport: true EIP150: 0 EIP155: 10 EIP158: 10 Byzantium: 1700000 Constantinople: 4230000 ConstantinopleFix: 4939394 Istanbul:
 6485846 MuirGlacier: 7117117 Berlin: 9812189 Engine: ethash}"
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.429] Disk storage enabled for ethash caches   dir=/home/ia/SAMSUNG_T5_3/e/clients/core-geth/ropsten/geth/ethash count=3
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.429] Disk storage enabled for ethash DAGs     dir=/root/.ethash count=2
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.429] Initialising Ethereum protocol           versions=[] network=3 dbversion=8
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.771] Loaded most recent local header          number=2489160 hash="092d1d…6afca1" td=7582346145108451 age=3y3mo3d
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.771] Loaded most recent local full block      number=2489160 hash="092d1d…6afca1" td=7582346145108451 age=3y3mo3d
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.771] Loaded most recent local fast block      number=2489160 hash="092d1d…6afca1" td=7582346145108451 age=3y3mo3d
Apr 07 15:11:56 ubp52 sh[11620]: WARN [04-07|15:11:56.771] Head state missing, repairing            number=2489160 hash="092d1d…6afca1" snaproot="532239…ef16be"
Apr 07 15:11:58 ubp52 sh[11620]: INFO [04-07|15:11:58.854] Deep froze chain segment                 blocks=5127 elapsed=2.575s number=2399160 hash="d24533…5e929c"
Apr 07 15:22:12 ubp52 sh[11620]: WARN [04-07|15:22:12.903] Force rewinding till ancient limit       head=0
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.042] Loaded most recent local header          number=0       hash="419410…ca4a2d" td=1048576          age=52y4d21h
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.042] Loaded most recent local full block      number=0       hash="419410…ca4a2d" td=1048576          age=52y4d21h
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.042] Loaded most recent local fast block      number=0       hash="419410…ca4a2d" td=1048576          age=52y4d21h
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.043] Enabling snapshot recovery               chainhead=0 diskbase=2488758
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.048] Loaded snapshot journal                  diskroot="532239…ef16be" diffs=unmatched
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.048] Snapshot is not continuous with chain    snaproot="532239…ef16be" chainroot="217b0b…62b77b"
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.049] Loaded local transaction journal         transactions=0 dropped=0
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.049] Regenerated local transaction journal    transactions=0 accounts=0
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.051] Unclean shutdown detected                booted=2021-04-07T14:45:13-0500 age=1h29m43s
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.051] Starting peer-to-peer node               instance=CoreGeth/v1.11.23-unstable-fd1278be-20210407/linux-amd64/go1.16.2
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.112] New local node record                    seq=676 id=8f6dbd9275475e38 ip=127.0.0.1 udp=30357 tcp=30357
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.112] Started P2P networking                   self=enode://f7af453db88e9d1cb29881269c27e0f11d476210426bc0fdf983774acabfeb04e6954db28191dbda19ff6826ebaa26466602ad4da30429047f3d64df6960ed3e@127.0.0.1:30357
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.113] IPC endpoint opened                      url=/tmp/core-geth_ropsten.ipc
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.113] HTTP server started                      endpoint=127.0.0.1:8005 prefix= cors=* vhosts=localhost
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.115] Mapped network port                      proto=tcp extport=30357 intport=30357 interface=NAT-PMP(192.168.86.1)
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.118] Mapped network port                      proto=udp extport=30357 intport=30357 interface=NAT-PMP(192.168.86.1)
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.121] New local node record                    seq=677 id=8f6dbd9275475e38 ip=75.134.144.252 udp=30357 tcp=30357
Apr 07 16:15:06 ubp52 sh[11620]: INFO [04-07|16:15:06.118] Looking for peers                        peercount=0 tried=47 static=0
Apr 07 16:15:16 ubp52 sh[11620]: INFO [04-07|16:15:16.319] Looking for peers                        peercount=0 tried=50 static=0

Running full sync on Ropsten.

cat core-geth.ropsten.service
[Unit]
Description=core-geth ropsten service

[Service]
Type=simple
User=root
Restart=always
RestartSec=3
TimeoutStopSec=10min
ExecStart=/bin/sh -c '/home/ia/go/src/github.com/ethereum/go-ethereum/.builds/systemd/ropsten/geth \
        --ropsten \
        --syncmode=full \
        --cache 4096 \
        --rpc --rpcport 8005 --rpccorsdomain=\'*\' \
        --port 30357 \
        --verbosity 3 \
        --datadir /home/ia/SAMSUNG_T5_3/e/clients/core-geth/ropsten \
        --ipcpath /tmp/core-geth_ropsten.ipc \
        --metrics --metrics.expensive \
        '

[Install]
WantedBy=default.target
@meowsbits
Copy link
Member Author

meowsbits commented Apr 7, 2021 •
edited
Loading

The restarted instance resulted in additional error-filled logs, eg:

Apr 07 16:15:32 ubp52 sh[11620]: WARN [04-07|16:15:32.384] tx iteration error                       error="unexpected EOF"

Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.429] Initialising Ethereum protocol           versions=[] network=3 dbversion=8                                                                                                                                           
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.771] Loaded most recent local header          number=2489160 hash="092d1d…6afca1" td=7582346145108451 age=3y3mo3d                                                                                                         
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.771] Loaded most recent local full block      number=2489160 hash="092d1d…6afca1" td=7582346145108451 age=3y3mo3d                                                                                                         
Apr 07 15:11:56 ubp52 sh[11620]: INFO [04-07|15:11:56.771] Loaded most recent local fast block      number=2489160 hash="092d1d…6afca1" td=7582346145108451 age=3y3mo3d                                                                                                         
Apr 07 15:11:56 ubp52 sh[11620]: WARN [04-07|15:11:56.771] Head state missing, repairing            number=2489160 hash="092d1d…6afca1" snaproot="532239…ef16be"                                                                                                                
Apr 07 15:11:58 ubp52 sh[11620]: INFO [04-07|15:11:58.854] Deep froze chain segment                 blocks=5127 elapsed=2.575s number=2399160 hash="d24533…5e929c"                                                                                                              
Apr 07 15:22:12 ubp52 sh[11620]: WARN [04-07|15:22:12.903] Force rewinding till ancient limit       head=0                                                                                                                                                                      
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.042] Loaded most recent local header          number=0       hash="419410…ca4a2d" td=1048576          age=52y4d21h                                                                                                        
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.042] Loaded most recent local full block      number=0       hash="419410…ca4a2d" td=1048576          age=52y4d21h
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.042] Loaded most recent local fast block      number=0       hash="419410…ca4a2d" td=1048576          age=52y4d21h                                                            
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.043] Enabling snapshot recovery               chainhead=0 diskbase=2488758                                                                                                     
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.048] Loaded snapshot journal                  diskroot="532239…ef16be" diffs=unmatched          
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.048] Snapshot is not continuous with chain    snaproot="532239…ef16be" chainroot="217b0b…62b77b"                                                                              
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.049] Loaded local transaction journal         transactions=0 dropped=0                                                                                                        
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.049] Regenerated local transaction journal    transactions=0 accounts=0                                                                                                         
Apr 07 16:14:56 ubp52 sh[11620]: WARN [04-07|16:14:56.051] Unclean shutdown detected                booted=2021-04-07T14:45:13-0500 age=1h29m43s                                                                                     
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.051] Starting peer-to-peer node               instance=CoreGeth/v1.11.23-unstable-fd1278be-20210407/linux-amd64/go1.16.2                                                      
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.112] New local node record                    seq=676 id=8f6dbd9275475e38 ip=127.0.0.1 udp=30357 tcp=30357                                                                      
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.112] Started P2P networking                   self=enode://f7af453db88e9d1cb29881269c27e0f11d476210426bc0fdf983774acabfeb04e6954db28191dbda19ff6826ebaa26466602ad4da30429047f3d64df6960ed3e@127.0.0.1:30357
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.113] IPC endpoint opened                      url=/tmp/core-geth_ropsten.ipc
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.113] HTTP server started                      endpoint=127.0.0.1:8005 prefix= cors=* vhosts=localhost
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.115] Mapped network port                      proto=tcp extport=30357 intport=30357 interface=NAT-PMP(192.168.86.1)
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.118] Mapped network port                      proto=udp extport=30357 intport=30357 interface=NAT-PMP(192.168.86.1)                                                           
Apr 07 16:14:56 ubp52 sh[11620]: INFO [04-07|16:14:56.121] New local node record                    seq=677 id=8f6dbd9275475e38 ip=75.134.144.252 udp=30357 tcp=30357                                                                
Apr 07 16:15:06 ubp52 sh[11620]: INFO [04-07|16:15:06.118] Looking for peers                        peercount=0 tried=47 static=0
Apr 07 16:15:16 ubp52 sh[11620]: INFO [04-07|16:15:16.319] Looking for peers                        peercount=0 tried=50 static=0                     
Apr 07 16:15:25 ubp52 sh[11620]: INFO [04-07|16:15:25.658] Block synchronisation started                                                                                                                                            
Apr 07 16:15:26 ubp52 sh[11620]: INFO [04-07|16:15:26.319] Looking for peers                        peercount=2 tried=58 static=0                                                                                                   
Apr 07 16:15:27 ubp52 sh[11620]: INFO [04-07|16:15:27.819] Downloader queue stats                   receiptTasks=0 blockTasks=179 itemSize=372.18B throttle=8192                                                                     
Apr 07 16:15:29 ubp52 sh[11620]: INFO [04-07|16:15:29.551] Imported new chain segment               blocks=10   txs=0 mgas=0.000 elapsed=1.732s mgasps=0.000 number=10      hash="b3074f…dde93e" age=4y5mo1w  dirty=15.64KiB                 
Apr 07 16:15:29 ubp52 sh[11620]: WARN [04-07|16:15:29.552] tx iteration error                       error="unexpected EOF"                                                                                                          
Apr 07 16:15:29 ubp52 sh[11620]: WARN [04-07|16:15:29.552] tx iteration error                       error="unexpected EOF"                                                                                                           
Apr 07 16:15:29 ubp52 sh[11620]: WARN [04-07|16:15:29.552] tx iteration error                       error="unexpected EOF"                                                                                                          
Apr 07 16:15:29 ubp52 sh[11620]: INFO [04-07|16:15:29.552] Indexed transactions                     blocks=0    txs=0 tail=137594 elapsed="953.807µs" 
Apr 07 16:15:29 ubp52 sh[11620]: INFO [04-07|16:15:29.594] New local node record                    seq=678 id=8f6dbd9275475e38 ip=75.134.144.252 udp=30357 tcp=30357                                                               
Apr 07 16:15:29 ubp52 sh[11620]: INFO [04-07|16:15:29.608] Imported new chain segment               blocks=78   txs=22 mgas=1.258 elapsed=56.122ms    mgasps=22.423 number=88      hash="7848e1…aee66f" age=4y5mo1w  dirty=144.34KiB 
Apr 07 16:15:29 ubp52 sh[11620]: WARN [04-07|16:15:29.608] tx iteration error                       error="unexpected EOF"                                                                                                       
Apr 07 16:15:29 ubp52 sh[11620]: WARN [04-07|16:15:29.608] tx iteration error                       error="unexpected EOF"                                                                                                          
Apr 07 16:15:29 ubp52 sh[11620]: WARN [04-07|16:15:29.608] tx iteration error                       error="unexpected EOF"                                                                                                           
Apr 07 16:15:29 ubp52 sh[11620]: INFO [04-07|16:15:29.608] Indexed transactions                     blocks=0    txs=0  tail=137594 elapsed="362.615µs"                                                                              
Apr 07 16:15:30 ubp52 sh[11620]: INFO [04-07|16:15:30.031] Imported new chain segment               blocks=378  txs=0  mgas=0.000 elapsed=319.376ms   mgasps=0.000  number=466     hash="c11081…ea4469" age=4y5mo1w  dirty=195.09KiB
Apr 07 16:15:30 ubp52 sh[11620]: WARN [04-07|16:15:30.031] tx iteration error                       error="unexpected EOF"                                                          
Apr 07 16:15:30 ubp52 sh[11620]: WARN [04-07|16:15:30.031] tx iteration error                       error="unexpected EOF"                                                                                                          
Apr 07 16:15:30 ubp52 sh[11620]: WARN [04-07|16:15:30.031] tx iteration error                       error="unexpected EOF"                                                                                                           
Apr 07 16:15:30 ubp52 sh[11620]: INFO [04-07|16:15:30.031] Indexed transactions                     blocks=0    txs=0  tail=137594 elapsed="393.636µs"
Apr 07 16:15:31 ubp52 sh[11620]: INFO [04-07|16:15:31.157] Imported new chain segment               blocks=302  txs=0  mgas=0.000 elapsed=220.529ms   mgasps=0.000  number=768     hash="0ce2af…8c392c" age=4y5mo1w  dirty=191.13KiB
Apr 07 16:15:31 ubp52 sh[11620]: WARN [04-07|16:15:31.158] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:31 ubp52 sh[11620]: INFO [04-07|16:15:31.158] Indexed transactions                     blocks=0    txs=0  tail=137594 elapsed="344.914µs"
Apr 07 16:15:31 ubp52 sh[11620]: INFO [04-07|16:15:31.227] Imported new chain segment               blocks=6    txs=0  mgas=0.000 elapsed=20.368ms    mgasps=0.000  number=774     hash="856036…5f2eae" age=4y5mo1w  dirty=190.87KiB
Apr 07 16:15:31 ubp52 sh[11620]: WARN [04-07|16:15:31.227] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:31 ubp52 sh[11620]: WARN [04-07|16:15:31.227] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:31 ubp52 sh[11620]: WARN [04-07|16:15:31.228] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:31 ubp52 sh[11620]: WARN [04-07|16:15:31.227] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:31 ubp52 sh[11620]: WARN [04-07|16:15:31.228] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:31 ubp52 sh[11620]: INFO [04-07|16:15:31.228] Indexed transactions                     blocks=0    txs=0  tail=137594 elapsed=1.355ms
Apr 07 16:15:32 ubp52 sh[11620]: INFO [04-07|16:15:32.234] Imported new chain segment               blocks=578  txs=0  mgas=0.000 elapsed=411.704ms   mgasps=0.000  number=1352    hash="0b8925…a4edd2" age=4y5mo1w  dirty=196.95KiB
Apr 07 16:15:32 ubp52 sh[11620]: WARN [04-07|16:15:32.234] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:32 ubp52 sh[11620]: WARN [04-07|16:15:32.234] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:32 ubp52 sh[11620]: WARN [04-07|16:15:32.234] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:32 ubp52 sh[11620]: INFO [04-07|16:15:32.234] Indexed transactions                     blocks=0    txs=0  tail=137594 elapsed="307.791µs"
Apr 07 16:15:32 ubp52 sh[11620]: INFO [04-07|16:15:32.383] Imported new chain segment               blocks=200  txs=0  mgas=0.000 elapsed=148.696ms   mgasps=0.000  number=1552    hash="e48401…2a4f31" age=4y5mo1w  dirty=195.98KiB
Apr 07 16:15:32 ubp52 sh[11620]: WARN [04-07|16:15:32.384] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:32 ubp52 sh[11620]: WARN [04-07|16:15:32.384] tx iteration error                       error="unexpected EOF"
Apr 07 16:15:32 ubp52 sh[11620]: WARN [04-07|16:15:32.384] tx iteration error                       error="unexpected EOF"

@meowsbits
Copy link
Member Author

Related to

@meowsbits meowsbits mentioned this issue Apr 7, 2021
Closed
@meowsbits meowsbits mentioned this issue Apr 27, 2021
Closed
30 tasks
@meowsbits
Copy link
Member Author

Closing since this was't reproducible with #357, so assuming is fixed.

@ziogaschr
Copy link
Member

I wasn't able to reproduce it on v1.10.2. LGTM to be closed.

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
@ziogaschr @meowsbits