Closed xhliu closed 5 years ago
INFO[0004] minersAddrs: [b1ndoQmEd83y4Fza5PzbUQDYpT3mV772J5o b1b8bzyci5VYUJVKRU2HRMMQiUXnoULkKAJ b1jh8DSdB6kB7N7RanrudV1hzzMCCcoX6L7 b1UP5pbfJgZrF1ezoSHLdvkxvgF2BYLtGva b1ZWSdrg48g145VdcmBwMPVuDFdaxDLoktk b1fRtRnKF4qhQG7bSwqbgR2BMw9VfM2XpT4] source="integration_tests/main.go:69" tag=integration INFO[0004] collAccounts = 10 source="utils/config.go:56" tag=integration_utils INFO[0004] collUnitAccounts = 5 source="utils/config.go:62" tag=integration_utils INFO[0004] circuAccounts = 10 source="utils/config.go:68" tag=integration_utils INFO[0004] circuUnitAccounts = 5 source="utils/config.go:74" tag=integration_utils INFO[0004] tokenAccounts = 3 source="utils/config.go:80" tag=integration_utils INFO[0004] tokenUnitAccounts = 3 source="utils/config.go:86" tag=integration_utils INFO[0004] circuRepeatTxTimes = 200 source="utils/config.go:93" tag=integration_utils INFO[0004] tokenRepeatTxTimes = 200 source="utils/config.go:99" tag=integration_utils INFO[0004] TxTestEnable = true source="utils/config.go:105" tag=integration_utils INFO[0004] tokenTestEnable = true source="utils/config.go:111" tag=integration_utils INFO[0004] tickerDurationTxs = 5 source="utils/config.go:117" tag=integration_utils INFO[0004] circuWorkers = 1 source="utils/config.go:123" tag=integration_utils INFO[0004] tokenWorkers = 1 source="utils/config.go:129" tag=integration_utils INFO[0004] prepare test workspace source="utils/util.go:131" tag=integration_utils INFO[0004] clean ./.devconfig/ws1 database and logs source="utils/util.go:141" tag=integration_utils INFO[0004] configure file ./.devconfig/.box-1.yaml and keyfile ./.devconfig/keyfile/key1.keystore exists source="utils/util.go:163" tag=integration_utils INFO[0004] clean ./.devconfig/ws2 database and logs source="utils/util.go:141" tag=integration_utils INFO[0004] configure file ./.devconfig/.box-2.yaml and keyfile ./.devconfig/keyfile/key2.keystore exists source="utils/util.go:163" tag=integration_utils INFO[0004] clean ./.devconfig/ws3 database and logs source="utils/util.go:141" tag=integration_utils INFO[0004] configure file ./.devconfig/.box-3.yaml and keyfile ./.devconfig/keyfile/key3.keystore exists source="utils/util.go:163" tag=integration_utils INFO[0004] clean ./.devconfig/ws4 database and logs source="utils/util.go:141" tag=integration_utils INFO[0004] configure file ./.devconfig/.box-4.yaml and keyfile ./.devconfig/keyfile/key4.keystore exists source="utils/util.go:163" tag=integration_utils INFO[0004] clean ./.devconfig/ws5 database and logs source="utils/util.go:141" tag=integration_utils INFO[0004] configure file ./.devconfig/.box-5.yaml and keyfile ./.devconfig/keyfile/key5.keystore exists source="utils/util.go:163" tag=integration_utils INFO[0004] clean ./.devconfig/ws6 database and logs source="utils/util.go:141" tag=integration_utils INFO[0004] configure file ./.devconfig/.box-6.yaml and keyfile ./.devconfig/keyfile/key6.keystore exists source="utils/util.go:163" tag=integration_utils INFO[0004] startLocalNodes: [../box start --config=./.devconfig/.box-1.yaml &] source="utils/util.go:65" tag=integration_utils INFO[0004] startLocalNodes: [../box start --config=./.devconfig/.box-2.yaml &] source="utils/util.go:65" tag=integration_utils INFO[0004] startLocalNodes: [../box start --config=./.devconfig/.box-3.yaml &] source="utils/util.go:65" tag=integration_utils INFO[0004] startLocalNodes: [../box start --config=./.devconfig/.box-4.yaml &] source="utils/util.go:65" tag=integration_utils INFO[0004] startLocalNodes: [../box start --config=./.devconfig/.box-5.yaml &] source="utils/util.go:65" tag=integration_utils INFO[0004] startLocalNodes: [../box start --config=./.devconfig/.box-6.yaml &] source="utils/util.go:65" tag=integration_utils INFO[0004] start to gen 3 address for token test source="integration_tests/test_token.go:36" tag=integration INFO[0004] txs ticker for main start source="integration_tests/main.go:111" tag=integration INFO[0004] start to gen 10 tests address for tx collection source="integration_tests/collection.go:44" tag=integration WARN[0002] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0002] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0003] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0003] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0004] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0004] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync INFO[0009] wait for block height of all nodes reach 5, timeout 1m0s source="integration_tests/main.go:255" tag=integration ERRO[0005] Failed to new stream to 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza, addrs={<peer.ID 12*7p8Cza> [/ip4/127.0.0.1/tcp/19199]}, err = dial attempt failed: <peer.ID 12*ByZDpA> --> <peer.ID 12*7p8Cza> dial attempt failed: context deadline exceeded source="p2p/conn.go:88" tag=p2p ERRO[0005] Failed to new stream to 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza, addrs={<peer.ID 12*7p8Cza> [/ip4/127.0.0.1/tcp/19199]}, err = dial attempt failed: <peer.ID 12*ByZDpA> --> <peer.ID 12*7p8Cza> dial attempt failed: context deadline exceeded source="p2p/conn.go:88" tag=p2p ERRO[0005] Failed to new stream to 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza, addrs={<peer.ID 12*7p8Cza> [/ip4/127.0.0.1/tcp/19199]}, err = dial attempt failed: <peer.ID 12*ByZDpA> --> <peer.ID 12*7p8Cza> dial attempt failed: context deadline exceeded source="p2p/conn.go:88" tag=p2p ERRO[0005] Failed to new stream to 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza, addrs={<peer.ID 12*7p8Cza> [/ip4/127.0.0.1/tcp/19199]}, err = dial attempt failed: <peer.ID 12*ngzFSJ> --> <peer.ID 12*7p8Cza> dial attempt failed: context deadline exceeded source="p2p/conn.go:88" tag=p2p ERRO[0005] Failed to new stream to 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza, addrs={<peer.ID 12*7p8Cza> [/ip4/127.0.0.1/tcp/19199]}, err = dial attempt failed: <peer.ID 12*ngzFSJ> --> <peer.ID 12*7p8Cza> dial attempt failed: context deadline exceeded source="p2p/conn.go:88" tag=p2p ERRO[0005] Failed to new stream to 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza, addrs={<peer.ID 12*7p8Cza> [/ip4/127.0.0.1/tcp/19199]}, err = dial attempt failed: <peer.ID 12*ngzFSJ> --> <peer.ID 12*7p8Cza> dial attempt failed: context deadline exceeded source="p2p/conn.go:88" tag=p2p WARN[0005] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0005] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0006] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0006] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0007] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0007] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0008] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0008] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0009] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0009] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0010] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0010] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0011] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0011] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0012] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0012] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0013] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync WARN[0013] locateHashes error: no peer to sync source="blocksync/manager.go:229" tag=blocksync INFO[0018] start to gen 10 address for circulation source="integration_tests/circulation.go:39" tag=integration INFO[0031] wait for block height of all nodes reach 5, timeout 1m0s source="integration_tests/main.go:205" tag=integration WARN[0030] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0030] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p ERRO[0030] Failed to sync route table from peer: 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza err: Handshaking timeout source="p2p/table.go:153" tag=p2p WARN[0030] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p ERRO[0030] Failed to sync route table from peer: 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza err: Handshaking timeout source="p2p/table.go:153" tag=p2p WARN[0030] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0030] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0030] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0030] The block already exists. Hash: 53f92f2513f7f447e9073fe35fbb192793320832886b5fa84e274ddc05369a27, Height: 4 source="chain/blockchain.go:240" tag=chain WARN[0030] The block already exists. Hash: 53f92f2513f7f447e9073fe35fbb192793320832886b5fa84e274ddc05369a27, Height: 4 source="chain/blockchain.go:240" tag=chain ERRO[0031] Failed to sync route table from peer: 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza err: Handshaking timeout source="p2p/table.go:153" tag=p2p ERRO[0031] Failed to sync route table from peer: 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza err: Handshaking timeout source="p2p/table.go:153" tag=p2p ERRO[0034] Failed to sync route table from peer: 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza err: Handshaking timeout source="p2p/table.go:153" tag=p2p ERRO[0034] Failed to sync route table from peer: 12D3KooWFQ2naj8XZUVyGhFzBTEMrMc6emiCEDKLjaJMsK7p8Cza err: Handshaking timeout source="p2p/table.go:153" tag=p2p INFO[0039] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration ERRO[0035] Failed to verify miner epoch. Hash: 07eb322e0d2b8b8a495ec3e491788ddef14767136eaf045f2d67eeecfb6ec83e, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0035] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0035] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0044] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration ERRO[0040] Failed to verify miner epoch. Hash: 80cf806f64ffdd8215ce2316640f7e1159036432fa592cfe478672a21265cf16, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0040] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0040] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0049] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration ERRO[0045] Failed to verify miner epoch. Hash: e1a0f2261c5941a24ca6d8a0ee514b5c5963d3f9d33602ee96a7916030092cd8, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0045] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0045] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0054] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration ERRO[0050] Failed to verify miner epoch. Hash: 5a9ab7e9fe7cefdc417abb1232fc5a0962063230e21c4fdeb9c3e2442819e517, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0050] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0050] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0059] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration ERRO[0055] Failed to verify miner epoch. Hash: 2c087cadcd2b83af2e4fc28df69cafd785660103a0ca127a4569b774f556131e, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0055] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0055] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0064] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration WARN[0060] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0060] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0060] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0060] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0060] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p WARN[0060] TrimOpenConns was called, but not implemented. source="p2p/connmgr.go:266" tag=p2p ERRO[0060] Failed to verify miner epoch. Hash: 91a5ed9e097ab29669e4922059338d420edda0f218bc3be6ffb3663518a134cd, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0060] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0060] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0069] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration PANI[0069] timeout for waiting for node 127.0.0.1:19111's block height reach 5 source="integration_tests/main.go:259" tag=integration ERRO[0065] Failed to verify miner epoch. Hash: 7a023ab4769f101bb22c9091cae0b8603802a2300d84294d1467eddc1d0ce39b, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0065] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0065] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0074] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration ERRO[0070] Failed to verify miner epoch. Hash: 80c13387eb45ae38c7d7e0b06ae173d1e3236c7ffef36162eaca10cf1b5fab0a, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0070] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0070] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos INFO[0079] TPS = 0.00 during last 5s, total txs = 0 source="integration_tests/main.go:127" tag=integration ERRO[0075] Failed to verify miner epoch. Hash: bff1e1dcb70ab5bc40a68f7b65b7b1de38f2f4bc726791c86f63f9d23b6d1342, Height: 5, Err: Invalid miner epoch source="chain/blockchain.go:324" tag=chain ERRO[0075] Failed to accept the block into the main chain. Err: Failed to verify block with consensus source="chain/blockchain.go:272" tag=chain WARN[0075] Failed to process block. err: Failed to verify block with consensus source="dpos/dpos.go:235" tag=dpos ^CINFO[0083] txs ticker for main exit source="integration_tests/main.go:131" tag=integration WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p WARN[0079] ReadMessage occurs error. Err: stream reset source="p2p/conn.go:110" tag=p2p PANI[0083] rpc error: code = Unavailable desc = all SubConns are in TransientFailure, latest connection error: connection error: desc = "transport: Error while dialing dial tcp 127.0.0.1:19111: connect: connection refused" source="integration_tests/main.go:209" tag=integration INFO[0083] remove 10 keystore files source="utils/util.go:215" tag=integration_utils INFO[0083] remove 10 keystore files source="utils/util.go:215" tag=integration_utils ERRO[0083] &{0xc0000d4820 map[tag:integration source:integration_tests/main.go:259] 2018-12-10 21:03:54.940584 +0800 CST m=+69.293661690 panic timeout for waiting for node 127.0.0.1:19111's block height reach 5 <nil> } source="integration_tests/main.go:176" tag=integration ERRO[0083] &{0xc0000d4820 map[tag:integration source:integration_tests/main.go:209] 2018-12-10 21:04:09.603626 +0800 CST m=+83.956751299 panic rpc error: code = Unavailable desc = all SubConns are in TransientFailure, latest connection error: connection error: desc = "transport: Error while dialing dial tcp 127.0.0.1:19111: connect: connection refused" <nil> } source="integration_tests/main.go:176" tag=integration PANI[0083] integration tests exits with 2 errors source="integration_tests/main.go:180" tag=integration INFO[0083] process[89915] exit status 0 source="utils/util.go:96" tag=integration_utils INFO[0083] process[89913] exit status 0 source="utils/util.go:96" tag=integration_utils INFO[0083] process[89912] exit status 0 source="utils/util.go:96" tag=integration_utils INFO[0083] process[89910] exit status 0 source="utils/util.go:96" tag=integration_utils INFO[0083] process[89914] exit status 0 source="utils/util.go:96" tag=integration_utils INFO[0083] process[89911] exit status 0 source="utils/util.go:96" tag=integration_utils
./integration_tests
Commit: 015ccc07d15ed8b2768a53eaa523ad73bd58a740
015ccc07d15ed8b2768a53eaa523ad73bd58a740
It is a normal report about a peer exiting from the p2p network.
Expected Behavior
Current Behavior
Possible Solution
Steps to Reproduce
./integration_tests
. First run is fine.Context (Environment)
Commit:
015ccc07d15ed8b2768a53eaa523ad73bd58a740
Detailed Description
Possible Implementation