search

getamis / istanbul-tools

Istanbul BFT tools
GNU Lesser General Public License v3.0
87 stars 49 forks source link

ERROR[07-12|19:36:03] not a valid address err="recovery failed" #108

Open michaelkunzmann-sap opened 6 years ago

michaelkunzmann-sap commented 6 years ago

Using the istanbul tool, I generated the following:

genesis.json

{
    "config": {
        "chainId": 2017,
        "homesteadBlock": 1,
        "eip150Block": 2,
        "eip150Hash": "0x0000000000000000000000000000000000000000000000000000000000000000",
        "eip155Block": 3,
        "eip158Block": 3,
        "istanbul": {
            "epoch": 30000,
            "policy": 0
        },
        "isQuorum": true
    },
    "nonce": "0x0",
    "timestamp": "0x5b47921d",
    "extraData": "0x0000000000000000000000000000000000000000000000000000000000000000f89af85494403a286b2f3e897deae61774270bf932b6101f0894f6e5d9850e5bfdd9df0d971be7f8f12d7845a8db9466feeaaafc27c57b818e95c51865ca004385eebe94f5aefe121fa4f748037787cd56087b5c5c5c78ceb8410000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000c0",
    "gasLimit": "0x47b760",
    "difficulty": "0x1",
    "mixHash": "0x63746963616c2062797a616e74696e65206661756c7420746f6c6572616e6365",
    "coinbase": "0x0000000000000000000000000000000000000000",
    "alloc": {
        "403a286b2f3e897deae61774270bf932b6101f08": {
            "balance": "0x446c3b15f9926687d2c40534fdb564000000000000"
        },
        "66feeaaafc27c57b818e95c51865ca004385eebe": {
            "balance": "0x446c3b15f9926687d2c40534fdb564000000000000"
        },
        "f5aefe121fa4f748037787cd56087b5c5c5c78ce": {
            "balance": "0x446c3b15f9926687d2c40534fdb564000000000000"
        },
        "f6e5d9850e5bfdd9df0d971be7f8f12d7845a8db": {
            "balance": "0x446c3b15f9926687d2c40534fdb564000000000000"
        }
    },
    "number": "0x0",
    "gasUsed": "0x0",
    "parentHash": "0x0000000000000000000000000000000000000000000000000000000000000000"
}

static-nodes.json:

[
    "enode://3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da@0.0.0.0:30303?discport=0",
    "enode://d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca@0.0.0.0:30303?discport=0",
    "enode://0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440@0.0.0.0:30303?discport=0",
    "enode://8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233@0.0.0.0:30303?discport=0"
]

Node keys:

0: 22e31c03cd1aff9eb1534518c13ef410e2b0992b2a4f4bf55d369b04ef09c19b
1: 55ab6d8d42616402875fab0e2e2b55fab344f4eb3ef216202ce06ee4615456b1
2: 73f9851f3f2ee28ff8d07a634c2ae9dd0e1f3baf2bfbc276b03f1f4499f4cff8
3: 26003844a57dfd0e97e08407a49a1d964c424f83b694f9aeb11766d7f0697785

I'm starting quorum-geth using:

geth --datadir /qdata/dd --port 30303 --identity tblrc --mine --minerthreads 1 --istanbul.requesttimeout 10000 --istanbul.blockperiod 1 --emitcheckpoints --rpc --rpcport 8545 --rpcaddr 0.0.0.0 --rpcapi admin,db,eth,debug,miner,net,shh,txpool,personal,web3,quorum --networkid 2017 --permissioned --nodiscover --verbosity 5 --unlock 0 --password /k8s/secrets/accountPassword

However, istanbul (in Quorum) is crashing:

TRACE[07-12|19:34:45] Started watching keystore folder         path=/qdata/dd/keystore
DEBUG[07-12|19:34:45] FS scan times                            list=498.056µs set=6.386µs diff=6.673µs
TRACE[07-12|19:34:45] Handled keystore changes                 time=116.458µs
DEBUG[07-12|19:34:45] FS scan times                            list=55.512µs  set=5.333µs diff=47.191µs
TRACE[07-12|19:34:45] Handled keystore changes                 time=2.695µs
INFO [07-12|19:34:45] Starting peer-to-peer node               instance=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2
INFO [07-12|19:34:45] Allocated cache and file handles         database=/qdata/dd/geth/chaindata cache=128 handles=1024
INFO [07-12|19:34:45] Initialised chain configuration          config="{ChainID: 2017 Homestead: 1 DAO: <nil> DAOSupport: false EIP150: 2 EIP155: 3 EIP158: 3 Byzantium: <nil> IsQuorum: true Engine: istanbul}"
INFO [07-12|19:34:45] Initialising Ethereum protocol           versions="[63 62]" network=2017
INFO [07-12|19:34:45] Loaded most recent local header          number=0 hash=39ee97…249d9b td=1
INFO [07-12|19:34:45] Loaded most recent local full block      number=0 hash=39ee97…249d9b td=1
INFO [07-12|19:34:45] Loaded most recent local fast block      number=0 hash=39ee97…249d9b td=1
DEBUG[07-12|19:34:45] Reinjecting stale transactions           count=0
INFO [07-12|19:34:45] Loaded local transaction journal         transactions=0 dropped=0
INFO [07-12|19:34:45] Regenerated local transaction journal    transactions=0 accounts=0
DEBUG[07-12|19:34:45] Recalculated downloader QoS values       rtt=20s confidence=1.000 ttl=1m0s
TRACE[07-12|19:34:45] Loaded voting snapshot form disk         number=0 hash=39ee97…249d9b
INFO [07-12|19:34:45] Starting P2P networking 
DEBUG[07-12|19:34:45] InProc registered *node.PrivateAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] InProc registered *node.PublicAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] InProc registered *debug.HandlerT under 'debug' 
DEBUG[07-12|19:34:45] InProc registered *node.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] InProc registered *node.PublicWeb3API under 'web3' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PublicEthereumAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PublicBlockChainAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PublicTransactionPoolAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PublicTxPoolAPI under 'txpool' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PrivateDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PublicAccountAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PrivateAccountAPI under 'personal' 
DEBUG[07-12|19:34:45] InProc registered *backend.API under 'istanbul' 
DEBUG[07-12|19:34:45] InProc registered *eth.PublicEthereumAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *eth.PublicMinerAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *downloader.PublicDownloaderAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *eth.PrivateMinerAPI under 'miner' 
INFO [07-12|19:34:45] RLPx listener up                         self="enode://3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da@[::]:30303?discport=0"
DEBUG[07-12|19:34:45] InProc registered *filters.PublicFilterAPI under 'eth' 
DEBUG[07-12|19:34:45] InProc registered *eth.PrivateAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] InProc registered *eth.PublicDebugAPI under 'debug' 
TRACE[07-12|19:34:45] New dial task                            task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:34:45] New dial task                            task="staticdial d25bf65162bfde47 100.67.134.175:30303"
TRACE[07-12|19:34:45] New dial task                            task="staticdial 0ea9aeb4becdce9b 100.64.19.101:30303"
TRACE[07-12|19:34:45] New dial task                            task="staticdial 8dd0af45bdbe0a3f 100.65.148.159:30303"
DEBUG[07-12|19:34:45] InProc registered *eth.PrivateDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] InProc registered *ethapi.PublicNetAPI under 'net' 
DEBUG[07-12|19:34:45] IPC registered *node.PrivateAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] IPC registered *node.PublicAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] IPC registered *debug.HandlerT under 'debug' 
DEBUG[07-12|19:34:45] IPC registered *node.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] IPC registered *node.PublicWeb3API under 'web3' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PublicEthereumAPI under 'eth' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PublicBlockChainAPI under 'eth' 
DEBUG[07-12|19:34:45] Executing EVM call finished              runtime=423.363µs
DEBUG[07-12|19:34:45] Release oracle not found                 contract=0xFA7B9770Ca4cb04296Cac84F37736d4041251CDF
DEBUG[07-12|19:34:45] IPC registered *ethapi.PublicTransactionPoolAPI under 'eth' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PublicTxPoolAPI under 'txpool' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PrivateDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PublicAccountAPI under 'eth' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PrivateAccountAPI under 'personal' 
DEBUG[07-12|19:34:45] IPC registered *backend.API under 'istanbul' 
DEBUG[07-12|19:34:45] IPC registered *eth.PublicEthereumAPI under 'eth' 
DEBUG[07-12|19:34:45] IPC registered *eth.PublicMinerAPI under 'eth' 
DEBUG[07-12|19:34:45] IPC registered *downloader.PublicDownloaderAPI under 'eth' 
DEBUG[07-12|19:34:45] IPC registered *eth.PrivateMinerAPI under 'miner' 
DEBUG[07-12|19:34:45] IPC registered *filters.PublicFilterAPI under 'eth' 
DEBUG[07-12|19:34:45] IPC registered *eth.PrivateAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] IPC registered *eth.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] IPC registered *eth.PrivateDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] IPC registered *ethapi.PublicNetAPI under 'net' 
DEBUG[07-12|19:34:45] HTTP registered *node.PrivateAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] HTTP registered *node.PublicAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] HTTP registered *debug.HandlerT under 'debug' 
DEBUG[07-12|19:34:45] HTTP registered *node.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] HTTP registered *node.PublicWeb3API under 'web3' 
INFO [07-12|19:34:45] IPC endpoint opened: /qdata/dd/geth.ipc 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PublicEthereumAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PublicBlockChainAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PublicTransactionPoolAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PublicTxPoolAPI under 'txpool' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PrivateDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PublicAccountAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PrivateAccountAPI under 'personal' 
DEBUG[07-12|19:34:45] HTTP registered *eth.PublicEthereumAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *eth.PublicMinerAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *downloader.PublicDownloaderAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *eth.PrivateMinerAPI under 'miner' 
DEBUG[07-12|19:34:45] HTTP registered *filters.PublicFilterAPI under 'eth' 
DEBUG[07-12|19:34:45] HTTP registered *eth.PrivateAdminAPI under 'admin' 
DEBUG[07-12|19:34:45] HTTP registered *eth.PublicDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] HTTP registered *eth.PrivateDebugAPI under 'debug' 
DEBUG[07-12|19:34:45] HTTP registered *ethapi.PublicNetAPI under 'net' 
INFO [07-12|19:34:45] HTTP endpoint opened: http://0.0.0.0:8545 
TRACE[07-12|19:34:45] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest="enode://8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233@100.65.148.159:30303?discport=0" Connection ID=8dd0af45bdbe0a3f Connection String=8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233
TRACE[07-12|19:34:45] Node Permissioning is Enabled. 
TRACE[07-12|19:34:45] Node Permissioning                       Connection Direction=OUTGOING
DEBUG[07-12|19:34:45] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:34:45] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:34:45] isNodePermissioned                       connection=OUTGOING nodename=8dd0af45bdbe0a3f712a67c12cdfb18e DENIED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:45] isNodePermissioned                       connection=OUTGOING nodename=8dd0af45bdbe0a3f712a67c12cdfb18e DENIED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:45] isNodePermissioned                       connection=OUTGOING nodename=8dd0af45bdbe0a3f712a67c12cdfb18e DENIED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:45] isNodePermissioned                       connection=OUTGOING nodename=8dd0af45bdbe0a3f712a67c12cdfb18e ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:45] Adding p2p peer                          id=8dd0af45bdbe0a3f name=Geth/hloej/v1.7.2-st... addr=100.65.148.159:30303 peers=1
TRACE[07-12|19:34:45] Dial task done                           task="staticdial 8dd0af45bdbe0a3f 100.65.148.159:30303"
TRACE[07-12|19:34:45] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest="enode://d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca@100.67.134.175:30303?discport=0" Connection ID=d25bf65162bfde47 Connection String=d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca
TRACE[07-12|19:34:45] Node Permissioning is Enabled. 
TRACE[07-12|19:34:45] Node Permissioning                       Connection Direction=OUTGOING
DEBUG[07-12|19:34:45] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:34:45] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:34:45] isNodePermissioned                       connection=OUTGOING nodename=d25bf65162bfde4753e58e82b1a20e9c DENIED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:45] isNodePermissioned                       connection=OUTGOING nodename=d25bf65162bfde4753e58e82b1a20e9c ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:45] Adding p2p peer                          id=d25bf65162bfde47 name=Geth/rtvhf/v1.7.2-st... addr=100.67.134.175:30303 peers=2
TRACE[07-12|19:34:45] Dial task done                           task="staticdial d25bf65162bfde47 100.67.134.175:30303"
TRACE[07-12|19:34:45] Starting protocol istanbul/64            id=8dd0af45bdbe0a3f conn=staticdial
DEBUG[07-12|19:34:45] Ethereum peer connected                  id=8dd0af45bdbe0a3f conn=staticdial name=Geth/hloej/v1.7.2-stable/linux-amd64/go1.9.2
TRACE[07-12|19:34:45] Starting protocol istanbul/64            id=d25bf65162bfde47 conn=staticdial
DEBUG[07-12|19:34:45] Ethereum peer connected                  id=d25bf65162bfde47 conn=staticdial name=Geth/rtvhf/v1.7.2-stable/linux-amd64/go1.9.2
TRACE[07-12|19:34:45] Registering sync peer                    peer=d25bf65162bfde47
TRACE[07-12|19:34:45] Registering sync peer                    peer=8dd0af45bdbe0a3f
DEBUG[07-12|19:34:45] Relaxed downloader QoS values            rtt=20s confidence=0.500 ttl=1m0s
INFO [07-12|19:34:46] Unlocked account                         address=0x403A286b2F3E897dEAE61774270bf932B6101F08
INFO [07-12|19:34:46] Transaction pool price threshold updated price=18000000000
INFO [07-12|19:34:46] Starting mining operation 
TRACE[07-12|19:34:46] Start to the initial round               address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=-1 old_seq=0
DEBUG[07-12|19:34:46] New round                                address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=-1 old_seq=0 old_proposer=0x403A286b2F3E897dEAE61774270bf932B6101F08 new_round=0 new_seq=1 new_proposer=0x403A286b2F3E897dEAE61774270bf932B6101F08 valSet="[0x403A286b2F3E897dEAE61774270bf932B6101F08 0x66fEEaaAFc27c57B818e95c51865ca004385eEBe 0xf5aEFE121fA4F748037787cD56087b5c5c5C78Ce 0xf6e5d9850E5BfDd9df0D971BE7f8f12D7845A8db]" size=4 isProposer=true
INFO [07-12|19:34:46] Commit new mining work                   number=1 txs=0 uncles=0 elapsed=128.975µs
TRACE[07-12|19:34:46] handleRequest                            address=0x403A286b2F3E897dEAE61774270bf932B6101F08 state="Accept request" seq=1 number=1 hash=04eacd…e3dfe3
DEBUG[07-12|19:34:47] Couldn't add port mapping                proto=tcp extport=30303 intport=30303 interface="UPnP or NAT-PMP" err="no UPnP or NAT-PMP router discovered"
TRACE[07-12|19:34:53] Accepted connection                      addr=100.96.0.77:46378
TRACE[07-12|19:34:53] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest=nil                                                                                                                                                                        Connection ID=0ea9aeb4becdce9b Connection String=0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440
TRACE[07-12|19:34:53] Node Permissioning is Enabled. 
DEBUG[07-12|19:34:53] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:34:53] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:34:53] isNodePermissioned                       connection=INCOMING nodename=0ea9aeb4becdce9b971996a43c871e47 DENIED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:53] isNodePermissioned                       connection=INCOMING nodename=0ea9aeb4becdce9b971996a43c871e47 DENIED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:53] isNodePermissioned                       connection=INCOMING nodename=0ea9aeb4becdce9b971996a43c871e47 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
DEBUG[07-12|19:34:53] Adding p2p peer                          id=0ea9aeb4becdce9b name=Geth/hoikz/v1.7.2-st...                      addr=100.96.0.77:46378    peers=3
TRACE[07-12|19:34:53] Starting protocol istanbul/64            id=0ea9aeb4becdce9b conn=inbound
DEBUG[07-12|19:34:53] Ethereum peer connected                  id=0ea9aeb4becdce9b conn=inbound    name=Geth/hoikz/v1.7.2-stable/linux-amd64/go1.9.2
TRACE[07-12|19:34:53] Registering sync peer                    peer=0ea9aeb4becdce9b
DEBUG[07-12|19:34:53] Relaxed downloader QoS values            rtt=20s confidence=0.333 ttl=1m0s
TRACE[07-12|19:34:56] Catch up round                           address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=0  old_seq=1 old_proposer=0x403A286b2F3E897dEAE61774270bf932B6101F08 new_round=1 new_seq=1 new_proposer="&{validators:[0xc4201e1ae0 0xc4201e1b00 0xc4201e1b20 0xc4201e1b40] policy:0 proposer:0xc4201e1ae0 validatorMu:{w:{state:0 sema:0} writerSem:0 readerSem:0 readerCount:0 readerWait:0} selector:0xbbf1e0}"
TRACE[07-12|19:35:00] Dial error                               task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303" err="dial tcp 100.68.165.149:30303: i/o timeout"
DEBUG[07-12|19:35:00] Can't resolve node                       id=3c1d6e1cbff5a9e5 err="discovery is disabled"
TRACE[07-12|19:35:00] Dial task done                           task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:35:00] Dial error                               task="staticdial 0ea9aeb4becdce9b 100.64.19.101:30303"  err="dial tcp 100.64.19.101:30303: i/o timeout"
DEBUG[07-12|19:35:00] Can't resolve node                       id=0ea9aeb4becdce9b err="discovery is disabled"
TRACE[07-12|19:35:00] Dial task done                           task="staticdial 0ea9aeb4becdce9b 100.64.19.101:30303"
TRACE[07-12|19:35:00] New dial task                            task="wait for dial hist expire (15.018219124s)"
DEBUG[07-12|19:35:05] Recalculated downloader QoS values       rtt=20s confidence=0.667 ttl=1m0s
TRACE[07-12|19:35:08] Catch up round                           address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=1  old_seq=1 old_proposer=0x403A286b2F3E897dEAE61774270bf932B6101F08 new_round=2 new_seq=1 new_proposer="&{validators:[0xc4201e1ae0 0xc4201e1b00 0xc4201e1b20 0xc4201e1b40] policy:0 proposer:0xc4201e1ae0 validatorMu:{w:{state:0 sema:0} writerSem:0 readerSem:0 readerCount:0 readerWait:0} selector:0xbbf1e0}"
TRACE[07-12|19:35:15] Dial task done                           task="wait for dial hist expire (15.018219124s)"
TRACE[07-12|19:35:15] New dial task                            task="wait for dial hist expire (1.263042ms)"
TRACE[07-12|19:35:15] Dial task done                           task="wait for dial hist expire (1.263042ms)"
TRACE[07-12|19:35:15] New dial task                            task="wait for dial hist expire (14.979546227s)"
TRACE[07-12|19:35:21] Catch up round                           address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=2  old_seq=1 old_proposer=0x403A286b2F3E897dEAE61774270bf932B6101F08 new_round=5 new_seq=1 new_proposer="&{validators:[0xc4201e1ae0 0xc4201e1b00 0xc4201e1b20 0xc4201e1b40] policy:0 proposer:0xc4201e1ae0 validatorMu:{w:{state:0 sema:0} writerSem:0 readerSem:0 readerCount:0 readerWait:0} selector:0xbbf1e0}"
DEBUG[07-12|19:35:21] New round                                address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=5  old_seq=1 old_proposer=0x403A286b2F3E897dEAE61774270bf932B6101F08 new_round=5 new_seq=1 new_proposer=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe                                                                                                                                                                 valSet="[0x403A286b2F3E897dEAE61774270bf932B6101F08 0x66fEEaaAFc27c57B818e95c51865ca004385eEBe 0xf5aEFE121fA4F748037787cD56087b5c5c5C78Ce 0xf6e5d9850E5BfDd9df0D971BE7f8f12D7845A8db]" size=4 isProposer=false
INFO [07-12|19:35:21] Committed                                address=0x403A286b2F3E897dEAE61774270bf932B6101F08 hash=654791…55e569 number=1
DEBUG[07-12|19:35:21] Queued propagated block                  peer=istanbul         number=1 hash=654791…55e569 queued=1
DEBUG[07-12|19:35:21] Importing propagated block               peer=istanbul         number=1 hash=654791…55e569
WARN [07-12|19:35:21] Discarded bad propagated block           number=1 hash=654791…55e569
TRACE[07-12|19:35:21] Propagated block                         hash=654791…55e569 recipients=1 duration=2562047h47m16.854s
DEBUG[07-12|19:35:21] Queued propagated block                  peer=d25bf65162bfde47 number=1 hash=654791…55e569 queued=1
DEBUG[07-12|19:35:21] Importing propagated block               peer=d25bf65162bfde47 number=1 hash=654791…55e569
WARN [07-12|19:35:21] Discarded bad propagated block           number=1 hash=654791…55e569
TRACE[07-12|19:35:21] Propagated block                         hash=654791…55e569 recipients=0 duration=3.189ms
DEBUG[07-12|19:35:25] Recalculated downloader QoS values       rtt=20s confidence=0.833 ttl=1m0s
TRACE[07-12|19:35:30] Dial task done                           task="wait for dial hist expire (14.979546227s)"
TRACE[07-12|19:35:30] New dial task                            task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:35:30] Accepted connection                      addr=10.250.15.37:44896
TRACE[07-12|19:35:30] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest="enode://3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da@100.68.165.149:30303?discport=0" Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:35:30] Node Permissioning is Enabled. 
TRACE[07-12|19:35:30] Node Permissioning                       Connection Direction=OUTGOING
DEBUG[07-12|19:35:30] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
TRACE[07-12|19:35:30] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest=nil                                                                                                                                                                        Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:35:30] Node Permissioning is Enabled. 
DEBUG[07-12|19:35:30] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:35:30] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:35:30] isNodePermissioned                       connection=OUTGOING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:35:30] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=100.68.165.149:30303 conn=staticdial err="connected to self"
TRACE[07-12|19:35:30] Dial task done                           task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:35:30] New dial task                            task="wait for dial hist expire (29.999995666s)"
DEBUG[07-12|19:35:30] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:35:30] isNodePermissioned                       connection=INCOMING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:35:30] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=10.250.15.37:44896   conn=inbound    err="connected to self"
TRACE[07-12|19:35:31] Store future message                     address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state=Committed
TRACE[07-12|19:35:43] Store future message                     address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state=Committed
DEBUG[07-12|19:35:45] Recalculated downloader QoS values       rtt=20s confidence=0.917 ttl=1m0s
TRACE[07-12|19:35:57] Store future message                     address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state=Committed
TRACE[07-12|19:36:00] Dial task done                           task="wait for dial hist expire (29.999995666s)"
TRACE[07-12|19:36:00] New dial task                            task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:36:00] Accepted connection                      addr=10.250.15.37:45002
TRACE[07-12|19:36:00] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest=nil                                                                                                                                                                        Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:36:00] Node Permissioning is Enabled. 
DEBUG[07-12|19:36:00] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:36:00] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:36:00] isNodePermissioned                       connection=INCOMING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:36:00] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=10.250.15.37:45002   conn=inbound    err="connected to self"
TRACE[07-12|19:36:00] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest="enode://3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da@100.68.165.149:30303?discport=0" Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:36:00] Node Permissioning is Enabled. 
TRACE[07-12|19:36:00] Node Permissioning                       Connection Direction=OUTGOING
DEBUG[07-12|19:36:00] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:36:00] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:36:00] isNodePermissioned                       connection=OUTGOING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:36:00] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=100.68.165.149:30303 conn=staticdial err="connected to self"
TRACE[07-12|19:36:00] Dial task done                           task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:36:00] New dial task                            task="wait for dial hist expire (29.999995466s)"
TRACE[07-12|19:36:03] Catch up round                           address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=5  old_seq=1 old_proposer=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe new_round=6 new_seq=1 new_proposer="&{validators:[0xc4201e1ae0 0xc4201e1b00 0xc4201e1b20 0xc4201e1b40] policy:0 proposer:0xc4201e1b00 validatorMu:{w:{state:0 sema:0} writerSem:0 readerSem:0 readerCount:0 readerWait:0} selector:0xbbf1e0}"
TRACE[07-12|19:36:03] Stop processing backlog                  address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state="Accept request" msg="{Code: 3, Address: 0x66fEEaaAFc27c57B818e95c51865ca004385eEBe}"
DEBUG[07-12|19:36:03] New round                                address=0x403A286b2F3E897dEAE61774270bf932B6101F08 old_round=6  old_seq=1 old_proposer=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe new_round=6 new_seq=1 new_proposer=0xf5aEFE121fA4F748037787cD56087b5c5c5C78Ce                                                                                                                                                                 valSet="[0x403A286b2F3E897dEAE61774270bf932B6101F08 0x66fEEaaAFc27c57B818e95c51865ca004385eEBe 0xf5aEFE121fA4F748037787cD56087b5c5c5C78Ce 0xf6e5d9850E5BfDd9df0D971BE7f8f12D7845A8db]" size=4 isProposer=false
TRACE[07-12|19:36:03] Stop processing backlog                  address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state=Prepared         msg="{Code: 3, Address: 0x66fEEaaAFc27c57B818e95c51865ca004385eEBe}"
TRACE[07-12|19:36:03] Stop processing backlog                  address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state=Committed        msg="{Code: 3, Address: 0x66fEEaaAFc27c57B818e95c51865ca004385eEBe}"
INFO [07-12|19:36:03] Committed                                address=0x403A286b2F3E897dEAE61774270bf932B6101F08 hash=654791…55e569 number=1
DEBUG[07-12|19:36:03] Queued propagated block                  peer=istanbul         number=1 hash=654791…55e569 queued=1
DEBUG[07-12|19:36:03] Importing propagated block               peer=istanbul         number=1 hash=654791…55e569
ERROR[07-12|19:36:03] not a valid address                      err="recovery failed"
DEBUG[07-12|19:36:03] Propagated block verification failed     peer=istanbul         number=1 hash=654791…55e569 err="invalid signature"
DEBUG[07-12|19:36:05] Recalculated downloader QoS values       rtt=20s confidence=0.958 ttl=1m0s
TRACE[07-12|19:36:15] Store future message                     address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state=Committed
DEBUG[07-12|19:36:25] Recalculated downloader QoS values       rtt=20s confidence=0.979 ttl=1m0s
TRACE[07-12|19:36:30] Dial task done                           task="wait for dial hist expire (29.999995466s)"
TRACE[07-12|19:36:30] New dial task                            task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:36:30] Accepted connection                      addr=10.250.15.37:45102
TRACE[07-12|19:36:30] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest=nil                                                                                                                                                                        Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:36:30] Node Permissioning is Enabled. 
DEBUG[07-12|19:36:30] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:36:30] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:36:30] isNodePermissioned                       connection=INCOMING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:36:30] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=10.250.15.37:45102   conn=inbound    err="connected to self"
TRACE[07-12|19:36:30] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest="enode://3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da@100.68.165.149:30303?discport=0" Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:36:30] Node Permissioning is Enabled. 
TRACE[07-12|19:36:30] Node Permissioning                       Connection Direction=OUTGOING
DEBUG[07-12|19:36:30] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:36:30] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:36:30] isNodePermissioned                       connection=OUTGOING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:36:30] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=100.68.165.149:30303 conn=staticdial err="connected to self"
TRACE[07-12|19:36:30] Dial task done                           task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:36:30] New dial task                            task="wait for dial hist expire (29.999995451s)"
TRACE[07-12|19:36:41] Store future message                     address=0x403A286b2F3E897dEAE61774270bf932B6101F08 from=0x66fEEaaAFc27c57B818e95c51865ca004385eEBe state=Committed
DEBUG[07-12|19:36:45] Recalculated downloader QoS values       rtt=20s confidence=0.990 ttl=1m0s
TRACE[07-12|19:37:00] Dial task done                           task="wait for dial hist expire (29.999995451s)"
TRACE[07-12|19:37:00] New dial task                            task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:37:00] Accepted connection                      addr=10.250.15.37:45204
TRACE[07-12|19:37:00] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest=nil                                                                                                                                                                        Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:37:00] Node Permissioning is Enabled. 
DEBUG[07-12|19:37:00] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:37:00] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:37:00] isNodePermissioned                       connection=INCOMING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:37:00] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=10.250.15.37:45204   conn=inbound    err="connected to self"
TRACE[07-12|19:37:00] Quorum permissioning                     EnableNodePermission=true DataDir=/qdata/dd Current Node ID=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da Node Name=Geth/tblrc/v1.7.2-stable/linux-amd64/go1.9.2 Dialed Dest="enode://3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da@100.68.165.149:30303?discport=0" Connection ID=3c1d6e1cbff5a9e5 Connection String=3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da
TRACE[07-12|19:37:00] Node Permissioning is Enabled. 
TRACE[07-12|19:37:00] Node Permissioning                       Connection Direction=OUTGOING
DEBUG[07-12|19:37:00] parsePermissionedNodes                   DataDir=/qdata/dd file=permissioned-nodes.json
DEBUG[07-12|19:37:00] isNodePermissioned                       permissionedList="[3c1d6e1cbff5a9e57dbc00e9f5a0235454643f7641b52d59014edeea3d25153b818523b7786fad58116bb1d1bbd8e1281fb71c81b1165964821497328302a8da d25bf65162bfde4753e58e82b1a20e9c50c66713f796a0169119649b7ab9c29a888a611e729cb58710e845d8569c66a44e627bff40f1f0ff90407fbee85b81ca 0ea9aeb4becdce9b971996a43c871e47df079f03dbbad8757302f2dd4ae96ed6059ebc5835d878aa7f805a201ac2ce1ffde42d39de5375f23aaa7b010491a440 8dd0af45bdbe0a3f712a67c12cdfb18ee4c2888619896eb1835d93f4f87a703c4bf173e27bfdd3c69cb5057b58eee992e317813215e80fef77b8906c57885233]"
DEBUG[07-12|19:37:00] isNodePermissioned                       connection=OUTGOING nodename=3c1d6e1cbff5a9e57dbc00e9f5a02354 ALLOWED-BY=3c1d6e1cbff5a9e57dbc00e9f5a02354
TRACE[07-12|19:37:00] Rejected peer before protocol handshake  id=3c1d6e1cbff5a9e5 addr=100.68.165.149:30303 conn=staticdial err="connected to self"
TRACE[07-12|19:37:00] Dial task done                           task="staticdial 3c1d6e1cbff5a9e5 100.68.165.149:30303"
TRACE[07-12|19:37:00] New dial task                            task="wait for dial hist expire (29.9999959s)"
IronGauntlets commented 6 years ago

@michaelkunzmann-sap Hi I am having trouble with setting up a network, can you please let me what commands you used to generate the genesis.json, static-nodes.json and node keys especially how you passed the password. I noticed when calling geth you -unlock 0 is that to unlock the 0th nodekey? When you created the genesis.json, static-nodes.json and nodekeys did you pass a password file and did the tool create keystores under $(pwd)0/,$(pwd)1/...? Because every time I use the setup command the keystores are not created and some random addresses show up when running the client to which I don't have access to. BTW, I am looking at IBFT for geth, not Quorum.