Single-node privnet is broken

[AnnaShaleva]

System information

Commit hash : 2de874a

Expected behaviour

Single-node privnet is running normally.

Actual behaviour

Single-node privnet processes a single block and stales in awaiting. It's likely yet another consequence of sealing proposal awaiting refactoring.

anna@kiwi:~/Documents/GitProjects/bane-labs/go-ethereum$ cat privnet/single/node1/geth_node.log 
INFO [09-05|09:35:16.757] Enabling metrics collection
INFO [09-05|09:35:16.758] Maximum peer count                       ETH=50 total=50
INFO [09-05|09:35:16.759] Smartcard socket not found, disabling    err="stat /run/pcscd/pcscd.comm: no such file or directory"
INFO [09-05|09:35:16.761] Set global gas cap                       cap=50,000,000
INFO [09-05|09:35:16.761] Initializing the KZG library             backend=gokzg
INFO [09-05|09:35:16.814] Allocated trie memory caches             clean=154.00MiB dirty=256.00MiB
INFO [09-05|09:35:16.814] Using pebble as the backing database
INFO [09-05|09:35:16.814] Allocated cache and file handles         database=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/privnet/single/node1/geth/chaindata cache=512.00MiB handles=524,288
INFO [09-05|09:35:16.832] Opened ancient database                  database=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/privnet/single/node1/geth/chaindata/ancient/chain readonly=false
INFO [09-05|09:35:16.832] State scheme set to already existing     scheme=hash
INFO [09-05|09:35:16.832] Initialising Ethereum protocol           network=2,312,051,126 dbversion=<nil>
INFO [09-05|09:35:16.834] 
INFO [09-05|09:35:16.834] ---------------------------------------------------------------------------------------------------------------------------------------------------------
INFO [09-05|09:35:16.834] Chain ID:  2312051126 (unknown)
INFO [09-05|09:35:16.834] Consensus: dBFT (proof-of-authority)
INFO [09-05|09:35:16.834] 
INFO [09-05|09:35:16.834] Pre-Merge hard forks (block based):
INFO [09-05|09:35:16.834]  - Homestead:                   #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/homestead.md)
INFO [09-05|09:35:16.834]  - Tangerine Whistle (EIP 150): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/tangerine-whistle.md)
INFO [09-05|09:35:16.834]  - Spurious Dragon/1 (EIP 155): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/spurious-dragon.md)
INFO [09-05|09:35:16.834]  - Spurious Dragon/2 (EIP 158): #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/spurious-dragon.md)
INFO [09-05|09:35:16.834]  - Byzantium:                   #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/byzantium.md)
INFO [09-05|09:35:16.834]  - Constantinople:              #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/constantinople.md)
INFO [09-05|09:35:16.834]  - Petersburg:                  #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/petersburg.md)
INFO [09-05|09:35:16.834]  - Istanbul:                    #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/istanbul.md)
INFO [09-05|09:35:16.834]  - Muir Glacier:                #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/muir-glacier.md)
INFO [09-05|09:35:16.834]  - Berlin:                      #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/berlin.md)
INFO [09-05|09:35:16.834]  - London:                      #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/london.md)
INFO [09-05|09:35:16.834]  - Arrow Glacier:               #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/arrow-glacier.md)
INFO [09-05|09:35:16.834]  - Gray Glacier:                #0        (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/gray-glacier.md)
INFO [09-05|09:35:16.834] 
INFO [09-05|09:35:16.834] The Merge is not yet available for this network!
INFO [09-05|09:35:16.834]  - Hard-fork specification: https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/paris.md
INFO [09-05|09:35:16.834] 
INFO [09-05|09:35:16.834] Post-Merge hard forks (timestamp based):
INFO [09-05|09:35:16.834]  - Shanghai:                    @0          (https://github.com/ethereum/execution-specs/blob/master/network-upgrades/mainnet-upgrades/shanghai.md)
INFO [09-05|09:35:16.834]  - NeoXBurn:                      @0         
INFO [09-05|09:35:16.834] 
INFO [09-05|09:35:16.834] ---------------------------------------------------------------------------------------------------------------------------------------------------------
INFO [09-05|09:35:16.834] 
INFO [09-05|09:35:16.835] Loaded most recent local block           number=0 hash=baaacb..576094 td=1 age=55y5mo3w
WARN [09-05|09:35:16.835] Failed to load snapshot                  err="missing or corrupted snapshot"
INFO [09-05|09:35:16.839] Rebuilding state snapshot
INFO [09-05|09:35:16.840] Initialized transaction indexer          range="last 2350000 blocks"
INFO [09-05|09:35:16.840] Resuming state snapshot generation       root=f8bc8c..ec7a09 accounts=0 slots=0 storage=0.00B dangling=0 elapsed=1.517ms
INFO [09-05|09:35:16.842] Generated state snapshot                 accounts=27 slots=58 storage=6.86KiB dangling=0 elapsed=3.936ms
INFO [09-05|09:35:16.886] Enabled snap sync                        head=0 hash=baaacb..576094
INFO [09-05|09:35:16.886] Gasprice oracle is ignoring threshold set threshold=2
WARN [09-05|09:35:16.887] Engine API enabled                       protocol=eth
WARN [09-05|09:35:16.887] Engine API started but chain not configured for merge yet
INFO [09-05|09:35:16.887] Starting peer-to-peer node               instance=Geth/v0.3.0-unstable-2de874a6-20240826/linux-amd64/go1.22.0
INFO [09-05|09:35:16.902] New local node record                    seq=1,725,518,116,901 id=b8bec47c9a97d5f3 ip=127.0.0.1 udp=30306 tcp=30306
INFO [09-05|09:35:16.902] Started P2P networking                   self=enode://edf911d30e28fd8da74d7ba44465f2022d5fb1225a9bd5074796cd6faf94a165193f6ed7c998faca29fd61f648045ef2d56997797319842bb0564fca19d5bcda@127.0.0.1:30306
INFO [09-05|09:35:16.903] IPC endpoint opened                      url=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/privnet/single/node1/geth.ipc
INFO [09-05|09:35:16.903] Generated JWT secret                     path=/home/anna/Documents/GitProjects/bane-labs/go-ethereum/privnet/single/node1/geth/jwtsecret
INFO [09-05|09:35:16.903] HTTP server started                      endpoint=[::]:8562 auth=false prefix= cors= vhosts=*
INFO [09-05|09:35:16.903] WebSocket enabled                        url=ws://[::]:8572
INFO [09-05|09:35:16.904] WebSocket enabled                        url=ws://127.0.0.1:8552
INFO [09-05|09:35:16.904] HTTP server started                      endpoint=127.0.0.1:8552 auth=true  prefix= cors=localhost vhosts=localhost
INFO [09-05|09:35:17.600] Unlocked account                         address=0xD40110De287C8d55E72a28600630a40283E4b8aa
INFO [09-05|09:35:17.600] Legacy pool tip threshold updated        tip=0
INFO [09-05|09:35:17.600] Legacy pool tip threshold updated        tip=1,000,000,000
INFO [09-05|09:35:17.600] Initializing BFT consensus               account=0xD40110De287C8d55E72a28600630a40283E4b8aa
INFO [09-05|09:35:17.600] Snap sync complete, auto disabling
INFO [09-05|09:35:17.600] Fetching latest sealing proposal         "desired number"=1
INFO [09-05|09:35:17.600] Commit new sealing work                  number=1 sealhash=3ee2dc..c3df42 "parent hash"=baaacb..576094 etherbase=0x1212000000000000000000000000000000000003 txs=0 gas=0 fees=0 elapsed="218.759µs"
INFO [09-05|09:35:18.601] Sealing proposal updated                 number=1 sealhash=3ee2dc..c3df42 "parent hash"=baaacb..576094 txs=0
INFO [09-05|09:35:18.601] Starting dBFT engine                     "last height"=0 "last timestamp"=0
2024-09-05T09:35:18.602+0300	INFO	dbft@v0.2.0/dbft.go:107	initializing dbft	{"height": 1, "view": 0, "index": 0, "role": "Primary"}
2024-09-05T09:35:18.603+0300	DEBUG	dbft@v0.2.0/dbft.go:608	reset timer	{"h": 1, "v": 0, "delay": "0s"}
2024-09-05T09:35:18.604+0300	DEBUG	dbft@v0.2.0/send.go:8	broadcasting message	{"type": "PrepareRequest", "height": 1, "view": 0}
2024-09-05T09:35:18.605+0300	INFO	dbft@v0.2.0/send.go:35	sending PrepareRequest	{"height": 1, "view": 0}
2024-09-05T09:35:18.605+0300	DEBUG	dbft@v0.2.0/dbft.go:608	reset timer	{"h": 1, "v": 0, "delay": "5s"}
2024-09-05T09:35:18.605+0300	DEBUG	dbft@v0.2.0/check.go:28	check preparations	{"hasReq": true, "count": 1, "M": 1}
2024-09-05T09:35:18.606+0300	INFO	dbft@v0.2.0/send.go:124	sending Commit	{"height": 1, "view": 0}
2024-09-05T09:35:18.606+0300	DEBUG	dbft@v0.2.0/send.go:8	broadcasting message	{"type": "Commit", "height": 1, "view": 0}
2024-09-05T09:35:18.607+0300	DEBUG	dbft@v0.2.0/dbft.go:608	reset timer	{"h": 1, "v": 0, "delay": "5s"}
2024-09-05T09:35:18.607+0300	INFO	dbft@v0.2.0/check.go:65	approving block	{"height": 1, "hash": "0x3ee2dc38da47aa17541110623d7948ebb848ae0d9739c1a9b912c7f105c3df42", "tx_count": 0, "merkle": "0xf8bc8c9cde2b4093850f6cd8dbd54cc6fbdfafffc24eb99c7f4c9284e3ec7a09", "prev": "0xbaaacb351a9784a96439250ba99c5c7e79a6e3b3c6f7b54b821cc37cb5576094"}
INFO [09-05|09:35:18.621] Imported new chain segment               number=1 hash=449872..750844 blocks=1 txs=0 mgas=0.000 elapsed=13.655ms    mgasps=0.000 triedirty=0.00B
INFO [09-05|09:35:18.621] Successfully inserted new block          number=1 hash=449872..750844
INFO [09-05|09:35:18.621] dBFT event loop started
INFO [09-05|09:35:18.622] Commit new sealing work                  number=2 sealhash=7e118a..8c423c "parent hash"=449872..750844 etherbase=0x1212000000000000000000000000000000000003 txs=0 gas=0 fees=0 elapsed=1.520ms
INFO [09-05|09:35:18.623] Indexed transactions                     blocks=2 txs=0 tail=0 elapsed=1.570ms
INFO [09-05|09:35:26.923] Looking for peers                        peercount=1 tried=0 static=0
INFO [09-05|09:35:36.942] Looking for peers                        peercount=1 tried=0 static=0
INFO [09-05|09:35:46.962] Looking for peers                        peercount=1 tried=0 static=0
INFO [09-05|09:35:56.981] Looking for peers                        peercount=1 tried=0 static=0
INFO [09-05|09:36:07.001] Looking for peers                        peercount=1 tried=0 static=0
INFO [09-05|09:36:17.021] Looking for peers                        peercount=1 tried=0 static=0

Steps to reproduce the behaviour

Start single-node privnet from scratch with make privnet_start.

Backtrace

[backtrace]

When submitting logs: please submit them as text and not screenshots.

[qianhh]

I followed your reproduction steps make privnet_start to start a single node network with four processes bootnode, node0, node1, node2, where node0 and node1 are miners and node2 is a normal node, and the result is that the network runs fine.

After that I tested the following combinations:

1. start bootnode, node0, node2 three processes, the result is that the network is stuck, the same situation.
2. start bootnode, node1, node2 three processes, the result is that the network is running normally.

Then I checked the address of node0, node1 and the default CN node configuration of the chain configuration and concluded that node1 is the CN node, so the network is running fine after booting node1, otherwise the network is stuck.

go-ethereum/privnet/single/node0/node_address.txt

Line 1 in afdb68c

5e6D9680428e6fe62a09BBb6AC23Df5bFE069AE8

go-ethereum/privnet/single/node1/node_address.txt

Line 1 in afdb68c

74f4effb0b538baec703346b03b6d9292f53a4cd

go-ethereum/privnet/single/genesis_privnet.json

Lines 20 to 26 in afdb68c

	"dbft": {
	"period": 5,
	"standbyValidators": [
	"0x74f4effb0b538baec703346b03b6d9292f53a4cd"
	],
	"coinbase": "0x1212000000000000000000000000000000000003"
	}

Based on the CN node address specified by default in the chain configuration file, the single node address you're running 0xD40110De287C8d55E72a28600630a40283E4b8aa doesn't match, so the node doesn't have the block rights to generate and it's the only node in the network so it's stuck, which is as expected.

[txhsl]

I'm not sure if we've fixed this in one of our PRs, but yes, the 1-CN privnet works now.