[ethereum/go-ethereum] My private chain can not make block after running miner.start() (#17134)

As the title says, my geth mine failed.

#### System information
Version: 1.8.6-sta
Architecture: amd6
Go Version: go1.9.2
OS & Version: Centos

#### Expected behaviour
make new block after running miner.start()

#### Actual behaviour
blockNumber is always 0, no matter how long I mine.

#### Steps to reproduce the behaviour
I compiled geth from go-ethereum source code on github .
Config and generate genesis block .
start geth
create new account
run miner.start()

#### Backtrace
““
——————file content———-
genesis.json :
{
“config”: {
“chainId”: 9529,
“homesteadBlock”: 0,
“eip155Block”: 0,
“eip158Block”: 0
},
“coinbase” : “0x0000000000000000000000000000000000000000”,
“difficulty” : “0x30000”,
“extraData” : “0x”,
“gasLimit” : “0xfffffffffffff”,
“nonce” : “0x0000000000000001”,
“mixhash” : “0x0000000000000000000000000000000000000000000000000000000000000000”,
“parentHash” : “0x0000000000000000000000000000000000000000000000000000000000000000”,
“timestamp” : “0x00”,
“alloc”:
{
“0x0000000000000000000000000000000000000000”:{ “balance”: “20000000000000000000000” }
}
}

————————traceback—————————-
[root@izwz9gmt1v7v6drxmbp45ez node1]# ls
genesis.json
[root@izwz9gmt1v7v6drxmbp45ez node1]# geth –datadir chainData init genesis.json
INFO [07-06|19:37:53] Maximum peer count ETH=25 LES=0 total=25
INFO [07-06|19:37:53] Allocated cache and file handles database=/var/gopher/etherumNodes/node1/chainData/geth/chaindata cache=16 handles=16
INFO [07-06|19:37:53] Writing custom genesis block
INFO [07-06|19:37:53] Persisted trie from memory database nodes=1 size=202.00B time=48.921µs gcnodes=0 gcsize=0.00B gctime=0s livenodes=1 livesize=0.00B
INFO [07-06|19:37:53] Successfully wrote genesis state database=chaindata hash=23f100…6451d7
INFO [07-06|19:37:53] Allocated cache and file handles database=/var/gopher/etherumNodes/node1/chainData/geth/lightchaindata cache=16 handles=16
INFO [07-06|19:37:53] Writing custom genesis block
INFO [07-06|19:37:53] Persisted trie from memory database nodes=1 size=202.00B time=40.85µs gcnodes=0 gcsize=0.00B gctime=0s livenodes=1 livesize=0.00B
INFO [07-06|19:37:53] Successfully wrote genesis state database=lightchaindata hash=23f100…6451d7
[root@izwz9gmt1v7v6drxmbp45ez node1]#
[root@izwz9gmt1v7v6drxmbp45ez node1]# geth –identity “TestNode” –rpc –rpcport “8545” –datadir chainData –port “30303” –networkid 9529 –nodiscover –verbosity=5 console
INFO [07-06|19:38:08] Maximum peer count ETH=25 LES=0 total=25
DEBUG[07-06|19:38:08] FS scan times list=29.206µs set=2.27µs diff=1.963µs
INFO [07-06|19:38:08] Starting peer-to-peer node instance=Geth/TestNode/v1.8.6-stable/linux-amd64/go1.9.2
INFO [07-06|19:38:08] Allocated cache and file handles database=/var/gopher/etherumNodes/node1/chainData/geth/chaindata cache=768 handles=1024
TRACE[07-06|19:38:08] Started watching keystore folder path=/var/gopher/etherumNodes/node1/chainData/keystore
WARN [07-06|19:38:08] Upgrading database to use lookup entries
INFO [07-06|19:38:08] Initialised chain configuration config=”{ChainID: 9529 Homestead: 0 DAO: DAOSupport: false EIP150: EIP155: 0 EIP158: 0 Byzantium: Constantinople: Engine: unknown}”
INFO [07-06|19:38:08] Disk storage enabled for ethash caches dir=/var/gopher/etherumNodes/node1/chainData/geth/ethash count=3
INFO [07-06|19:38:08] Disk storage enabled for ethash DAGs dir=/root/.ethash count=2
INFO [07-06|19:38:08] Initialising Ethereum protocol versions=”[63 62]” network=9529
INFO [07-06|19:38:08] Loaded most recent local header number=0 hash=23f100…6451d7 td=196608
INFO [07-06|19:38:08] Loaded most recent local full block number=0 hash=23f100…6451d7 td=196608
INFO [07-06|19:38:08] Loaded most recent local fast block number=0 hash=23f100…6451d7 td=196608
DEBUG[07-06|19:38:08] Reinjecting stale transactions count=0
INFO [07-06|19:38:08] Regenerated local transaction journal transactions=0 accounts=0
INFO [07-06|19:38:08] Starting P2P networking
DEBUG[07-06|19:38:08] InProc registered service=&{node:0xc42041b680} namespace=admin
DEBUG[07-06|19:38:08] InProc registered service=&{node:0xc42041b680} namespace=admin
DEBUG[07-06|19:38:08] InProc registered service=”&{mu:{state:0 sema:0} cpuW: cpuFile: traceW: traceFile:}” namespace=debug
DEBUG[07-06|19:38:08] InProc registered service=&{node:0xc42041b680} namespace=debug
DEBUG[07-06|19:38:08] InProc registered service=&{stack:0xc42041b680} namespace=web3
DEBUG[07-06|19:38:08] InProc registered service=&{b:0xc4201d85d0} namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=&{b:0xc4201d85d0} namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=”&{b:0xc4201d85d0 nonceLock:0xc4201d8bf0}” namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=&{b:0xc4201d85d0} namespace=txpool
DEBUG[07-06|19:38:08] InProc registered service=&{b:0xc4201d85d0} namespace=debug
DEBUG[07-06|19:38:08] InProc registered service=&{b:0xc4201d85d0} namespace=debug
DEBUG[07-06|19:38:08] InProc registered service=&{am:0xc4202a1d40} namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=”&{am:0xc4202a1d40 nonceLock:0xc4201d8bf0 b:0xc4201d85d0}” namespace=personal
DEBUG[07-06|19:38:08] InProc registered service=&{e:0xc42024c000} namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=”&{e:0xc42024c000 agent:0xc42007a880}” namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=”&{d:0xc420063040 mux:0xc420426b70 installSyncSubscription:0xc42005fc20 uninstallSyncSubscription:0xc42005fc80}” namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=&{e:0xc42024c000} namespace=miner
DEBUG[07-06|19:38:08] InProc registered service=”&{backend:0xc4201d85d0 mux:0xc420426b70 quit: chainDb:0xc42008d290 events:0xc420051280 filtersMu:{state:0 sema:0} filters:map[]}” namespace=eth
DEBUG[07-06|19:38:08] InProc registered service=&{eth:0xc42024c000} namespace=admin
DEBUG[07-06|19:38:08] InProc registered service=&{eth:0xc42024c000} namespace=debug
DEBUG[07-06|19:38:08] InProc registered service=”&{config:0xc42007a080 eth:0xc42024c000}” namespace=debug
DEBUG[07-06|19:38:08] InProc registered service=”&{net:0xc42041b900 networkVersion:9529}” namespace=net
DEBUG[07-06|19:38:08] IPC registered namespace=admin
DEBUG[07-06|19:38:08] IPC registered namespace=admin
DEBUG[07-06|19:38:08] IPC registered namespace=debug
DEBUG[07-06|19:38:08] IPC registered namespace=debug
DEBUG[07-06|19:38:08] IPC registered namespace=web3
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=txpool
DEBUG[07-06|19:38:08] IPC registered namespace=debug
DEBUG[07-06|19:38:08] IPC registered namespace=debug
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=personal
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=miner
DEBUG[07-06|19:38:08] IPC registered namespace=eth
DEBUG[07-06|19:38:08] IPC registered namespace=admin
DEBUG[07-06|19:38:08] IPC registered namespace=debug
DEBUG[07-06|19:38:08] IPC registered namespace=debug
DEBUG[07-06|19:38:08] IPC registered namespace=net
INFO [07-06|19:38:08] IPC endpoint opened url=/var/gopher/etherumNodes/node1/chainData/geth.ipc
DEBUG[07-06|19:38:08] HTTP registered namespace=web3
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=eth
DEBUG[07-06|19:38:08] HTTP registered namespace=net
INFO [07-06|19:38:08] HTTP endpoint opened url=http://127.0.0.1:8545 cors= vhosts=localhost
INFO [07-06|19:38:08] Database deduplication successful deduped=0
DEBUG[07-06|19:38:08] Recalculated downloader QoS values rtt=20s confidence=1.000 ttl=1m0s
INFO [07-06|19:38:08] RLPx listener up self=”enode://0f9e6db136f5b5c2a2875a3a7ffa2a672d5065c292cdf0947a345ba47cb8a314236fe8b51107cb58728ac2a6d14badbafc925b5a004dfd6bbdbb3ea280bbdc7b@[::]:30303?discport=0″
TRACE[07-06|19:38:08] msg=”sending {“jsonrpc”:”2.0″,”id”:1,”method”:”rpc_modules”,”params”:null}”
TRACE[07-06|19:38:08] msg=” DEBUG[07-06|19:38:10] Couldn’t add port mapping proto=tcp extport=30303 intport=30303 interface=”UPnP or NAT-PMP” err=”no UPnP or NAT-PMP router discovered”
> personal.newAccount(“123456”)
TRACE[07-06|19:38:25] msg=”sending {“jsonrpc”:”2.0″,”id”:5,”method”:”personal_newAccount”,”params”:[“123456″]}”
TRACE[07-06|19:38:26] msg=” DEBUG[07-06|19:38:27] FS scan times list=125.329µs set=12.275µs diff=8.475µs
TRACE[07-06|19:38:27] Handled keystore changes time=97.415µs
DEBUG[07-06|19:38:28] Recalculated downloader QoS values rtt=20s confidence=1.000 ttl=1m0s
TRACE[07-06|19:38:29] Accepted connection addr=118.189.180.85:50562
> miner.start()TRACE[07-06|19:38:31] Failed RLPx handshake addr=118.189.180.85:50562 conn=inbound err=EOF
TRACE[07-06|19:38:31] Setting up connection failed id=0000000000000000 err=EOF

TRACE[07-06|19:38:36] msg=”sending {“jsonrpc”:”2.0″,”id”:6,”method”:”miner_start”,”params”:[null]}”
INFO [07-06|19:38:36] Updated mining threads threads=0
INFO [07-06|19:38:36] Transaction pool price threshold updated price=18000000000
INFO [07-06|19:38:36] Etherbase automatically configured address=0xB79C28934C9621990750A56915cE3dAbBf2a9b8C
TRACE[07-06|19:38:36] msg=” INFO [07-06|19:38:36] Starting mining operation
INFO [07-06|19:38:36] Commit new mining work number=1 txs=0 uncles=0 elapsed=1.800ms
TRACE[07-06|19:38:36] Requiring new ethash dataset epoch=0
TRACE[07-06|19:38:36] Requiring new future ethash dataset epoch=1
DEBUG[07-06|19:38:36] Loaded old ethash dataset from disk epoch=0
TRACE[07-06|19:38:36] Started ethash search for new nonces miner=0 seed=8563670943178849858
DEBUG[07-06|19:38:44] Loaded old ethash dataset from disk epoch=1
TRACE[07-06|19:38:44] Accepted connection addr=54.92.43.245:39016
> miner.start()
TRACE[07-06|19:38:46] msg=”sending {“jsonrpc”:”2.0″,”id”:7,”method”:”miner_start”,”params”:[null]}”
INFO [07-06|19:38:46] Updated mining threads threads=0
TRACE[07-06|19:38:46] msg=” TRACE[07-06|19:38:46] Ethash nonce search aborted miner=0 attempts=4
TRACE[07-06|19:38:46] Started ethash search for new nonces miner=0 seed=8423786292296075324
TRACE[07-06|19:38:46] Failed RLPx handshake addr=54.92.43.245:39016 conn=inbound err=EOF
TRACE[07-06|19:38:46] Setting up connection failed id=0000000000000000 err=EOF
DEBUG[07-06|19:38:48] Recalculated downloader QoS values rtt=20s confidence=1.000 ttl=1m0s
> eth.blockNumber
TRACE[07-06|19:38:57] msg=”sending {“jsonrpc”:”2.0″,”id”:8,”method”:”eth_blockNumber”,”params”:[]}”
TRACE[07-06|19:38:57] msg=” eth.blockNumber
TRACE[07-06|19:39:01] msg=”sending {“jsonrpc”:”2.0″,”id”:9,”method”:”eth_blockNumber”,”params”:[]}”
TRACE[07-06|19:39:01] msg=” DEBUG[07-06|19:39:08] Recalculated downloader QoS values rtt=20s confidence=1.000 ttl=1m0s
> eth.blockNumber
TRACE[07-06|19:39:15] msg=”sending {“jsonrpc”:”2.0″,”id”:10,”method”:”eth_blockNumber”,”params”:[]}”
TRACE[07-06|19:39:15] msg=” miner.stoTRACE[07-06|19:39:19] Accepted connection addr=54.187.146.43:44282
> TRACE[07-06|19:39:23] Failed RLPx handshake addr=54.187.146.43:44282 conn=inbound err=EOF
TRACE[07-06|19:39:23] Setting up connection failed id=0000000000000000 err=EOF
TRACE[07-06|19:39:25] Accepted connection addr=14.145.175.152:48872
DEBUG[07-06|19:39:28] Recalculated downloader QoS values rtt=20s confidence=1.000 ttl=1m0s
> eth.blockNumberTRACE[07-06|19:39:30] Failed RLPx handshake addr=14.145.175.152:48872 conn=inbound err=”read tcp 172.18.212.239:30303->14.145.175.152:48872: i/o timeout”
TRACE[07-06|19:39:30] Setting up connection failed id=0000000000000000 err=”read tcp 172.18.212.239:30303->14.145.175.152:48872: i/o timeout”
> eth.blockNumber
TRACE[07-06|19:39:34] msg=”sending {“jsonrpc”:”2.0″,”id”:11,”method”:”eth_blockNumber”,”params”:[]}”
TRACE[07-06|19:39:34] msg=” TRACE[07-06|19:39:35] Accepted connection addr=59.110.239.104:48730
TRACE[07-06|19:39:35] Failed RLPx handshake addr=59.110.239.104:48730 conn=inbound err=”ecies: invalid message”
TRACE[07-06|19:39:35] Setting up connection failed id=0000000000000000 err=”ecies: invalid message”
> miner.stop()
TRACE[07-06|19:39:38] msg=”sending {“jsonrpc”:”2.0″,”id”:12,”method”:”miner_stop”,”params”:[]}”
TRACE[07-06|19:39:38] msg=” TRACE[07-06|19:39:38] Ethash nonce search aborted miner=0 attempts=1132
> eth.blockNumber
TRACE[07-06|19:39:40] msg=”sending {“jsonrpc”:”2.0″,”id”:13,”method”:”eth_blockNumber”,”params”:[]}”
TRACE[07-06|19:39:40] msg=”

This post was last modified on July 6, 2018, 1:46 pm