iroha-log

[2018-09-07 02:05:17.374967752][th:10][info] MAIN start
[2018-09-07 02:05:17.375792856][th:10][info] MAIN config initialized
[2018-09-07 02:05:17.376857761][th:10][info] IROHAD created
[2018-09-07 02:05:17.377521364][th:10][info] StorageImpl:initConnection Start storage creation
[2018-09-07 02:05:17.378290568][th:10][info] StorageImpl:initConnection block store created
NOTICE:  relation "role" already exists, skipping
NOTICE:  relation "domain" already exists, skipping
NOTICE:  relation "signatory" already exists, skipping
NOTICE:  relation "account" already exists, skipping
NOTICE:  relation "account_has_signatory" already exists, skipping
NOTICE:  relation "peer" already exists, skipping
NOTICE:  relation "asset" already exists, skipping
NOTICE:  relation "account_has_asset" already exists, skipping
NOTICE:  relation "role_has_permissions" already exists, skipping
NOTICE:  relation "account_has_roles" already exists, skipping
NOTICE:  relation "account_has_grantable_permissions" already exists, skipping
NOTICE:  relation "height_by_hash" already exists, skipping
NOTICE:  relation "height_by_account_set" already exists, skipping
NOTICE:  relation "index_by_creator_height" already exists, skipping
NOTICE:  relation "index_by_id_height_asset" already exists, skipping
[2018-09-07 02:05:17.408070506][th:10][info] IROHAD [Init] => storage
[2018-09-07 02:05:17.409764714][th:10][error] MAIN Block store not empty. Use '--overwrite_ledger' to force overwrite it. Shutting down...
root@cc7d98869b99:/opt/iroha_data# irohad --config config.docker --genesis_block genesis.block --keypair_name node0 --overwrite_ledger
[2018-09-07 02:05:29.120353499][th:11][info] MAIN start
[2018-09-07 02:05:29.121179603][th:11][info] MAIN config initialized
[2018-09-07 02:05:29.122249508][th:11][info] IROHAD created
[2018-09-07 02:05:29.122861711][th:11][info] StorageImpl:initConnection Start storage creation
[2018-09-07 02:05:29.123630614][th:11][info] StorageImpl:initConnection block store created
NOTICE:  relation "role" already exists, skipping
NOTICE:  relation "domain" already exists, skipping
NOTICE:  relation "signatory" already exists, skipping
NOTICE:  relation "account" already exists, skipping
NOTICE:  relation "account_has_signatory" already exists, skipping
NOTICE:  relation "peer" already exists, skipping
NOTICE:  relation "asset" already exists, skipping
NOTICE:  relation "account_has_asset" already exists, skipping
NOTICE:  relation "role_has_permissions" already exists, skipping
NOTICE:  relation "account_has_roles" already exists, skipping
NOTICE:  relation "account_has_grantable_permissions" already exists, skipping
NOTICE:  relation "height_by_hash" already exists, skipping
NOTICE:  relation "height_by_account_set" already exists, skipping
NOTICE:  relation "index_by_creator_height" already exists, skipping
NOTICE:  relation "index_by_id_height_asset" already exists, skipping
[2018-09-07 02:05:29.152756049][th:11][info] IROHAD [Init] => storage
[2018-09-07 02:05:29.154340457][th:11][info] StorageImpl drop wsv records from db tables
[2018-09-07 02:05:29.166459213][th:11][info] StorageImpl drop blocks from disk
[2018-09-07 02:05:29.167238316][th:11][info] StorageImpl create ordering service persistent state
[2018-09-07 02:05:29.167837019][th:11][info] PostgresOrderingServicePersistentState Drop storage
[2018-09-07 02:05:29.184143195][th:11][info] StorageImpl create ordering service persistent state
[2018-09-07 02:05:29.184874498][th:11][info] PostgresOrderingServicePersistentState Drop storage
[2018-09-07 02:05:29.201041173][th:11][info] MAIN Block is parsed
[2018-09-07 02:05:29.201865877][th:11][info] StorageImpl create mutable storage
[2018-09-07 02:05:29.202553480][th:11][info] FlatFile get(0) file not found
[2018-09-07 02:05:29.213453331][th:11][info] StorageImpl block inserted: true
[2018-09-07 02:05:29.220566264][th:11][info] MAIN Genesis block inserted, number of transactions: 1
[2018-09-07 02:05:29.221739269][th:11][info] StorageImpl drop wsv records from db tables
[2018-09-07 02:05:29.228646901][th:11][info] StorageImpl drop blocks from disk
[2018-09-07 02:05:29.229357205][th:11][info] StorageImpl create mutable storage
[2018-09-07 02:05:29.229951207][th:11][info] FlatFile get(0) file not found
[2018-09-07 02:05:29.241751662][th:11][info] StorageImpl insert blocks finished
[2018-09-07 02:05:29.242350465][th:11][info] IROHAD [Init] => crypto provider
[2018-09-07 02:05:29.242954568][th:11][info] IROHAD [Init] => validators
[2018-09-07 02:05:29.244451575][th:11][info] OrderingInit Ordering gate is at 0.0.0.0:10001
[2018-09-07 02:05:29.245488979][th:11][info] StorageImpl create ordering service persistent state
[2018-09-07 02:05:29.246924286][th:11][info] OrderingInit Creating Ordering Gate with initial height 1
[2018-09-07 02:05:29.247548589][th:11][info] AsyncGrpcClient Subscribe
[2018-09-07 02:05:29.248067291][th:11][info] IROHAD [Init] => init ordering gate - [true]
[2018-09-07 02:05:29.248657994][th:11][info] IROHAD [Init] => init simulator
[2018-09-07 02:05:29.249183496][th:11][info] IROHAD [Init] => init consensus block cache
[2018-09-07 02:05:29.249776299][th:11][info] IROHAD [Init] => block loader
[2018-09-07 02:05:29.252645613][th:11][info] IROHAD [Init] => consensus gate
[2018-09-07 02:05:29.253213715][th:11][info] IROHAD [Init] => synchronizer
[2018-09-07 02:05:29.253768118][th:11][info] OrderingGate setPcs
[2018-09-07 02:05:29.254317420][th:11][info] IROHAD [Init] => pcs
[2018-09-07 02:05:29.254930323][th:11][info] IROHAD [Init] => Tx status bus
[2018-09-07 02:05:29.255512126][th:11][info] IROHAD [Init] => MST processor
[2018-09-07 02:05:29.256012128][th:11][warning] MstProcessor Multisig transactions are disabled, so MstProcessor observable won't emit any events
[2018-09-07 02:05:29.256657531][th:11][warning] MstProcessor Multisig transactions are disabled, so MstProcessor observable won't emit any events
[2018-09-07 02:05:29.257279334][th:11][warning] MstProcessor Multisig transactions are disabled, so MstProcessor observable won't emit any events
[2018-09-07 02:05:29.257929237][th:11][info] IROHAD [Init] => pending transactions storage
[2018-09-07 02:05:29.258530840][th:11][warning] MstProcessor Multisig transactions are disabled, so MstProcessor observable won't emit any events
[2018-09-07 02:05:29.259195643][th:11][warning] MstProcessor Multisig transactions are disabled, so MstProcessor observable won't emit any events
[2018-09-07 02:05:29.259872346][th:11][info] IROHAD [Init] => command service
[2018-09-07 02:05:29.260162447][th:11][info] IROHAD [Init] => query service
[2018-09-07 02:05:29.260207248][th:11][info] MAIN Running iroha
[2018-09-07 02:05:29.261102652][th:11][info] IROHAD Torii server bound on port 50051
[2018-09-07 02:05:29.261836955][th:11][info] IROHAD Internal server bound on port 10001
[2018-09-07 02:05:29.262172257][th:11][info] IROHAD ===> iroha initialized
[2018-09-07 02:06:24.272958656][th:19][info] PCS propagate batch
[2018-09-07 02:06:24.272986856][th:19][info] OrderingGate propagate batch, account_id: admin@test
[2018-09-07 02:06:24.272999156][th:19][info] AsyncGrpcClient Propagate transaction batch (on transport)
[2018-09-07 02:06:24.273735860][th:20][info] AsyncGrpcClient OrderingServiceTransportGrpc::onBatch
[2018-09-07 02:06:24.274188162][th:20][info] OrderingServiceImpl Queue size is 1
[2018-09-07 02:06:29.245274069][th:15][info] OrderingServiceImpl Start proposal generation
[2018-09-07 02:06:29.245546570][th:15][info] StorageImpl create ordering service persistent state
[2018-09-07 02:06:29.245563170][th:15][info] PostgresOrderingServicePersistentState Save proposal_height in ordering_service_state 3
[2018-09-07 02:06:29.253752309][th:15][info] AsyncGrpcClient OrderingServiceTransportGrpc::publishProposal
[2018-09-07 02:06:29.254232911][th:20][info] AsyncGrpcClient receive proposal
[2018-09-07 02:06:29.254533912][th:20][info] AsyncGrpcClient transactions in proposal: 1
[2018-09-07 02:06:29.254550812][th:20][info] OrderingGate Received new proposal, height: 2
[2018-09-07 02:06:29.254635813][th:17][info] OrderingGate Pass the proposal to pipeline height 2
[2018-09-07 02:06:29.254656313][th:17][info] Simulator process proposal
[2018-09-07 02:06:29.255232415][th:17][info] SFV transactions in proposal: 1
[2018-09-07 02:06:29.269610682][th:17][info] SFV transactions in verified proposal: 1
[2018-09-07 02:06:29.270224585][th:17][info] Simulator process verified proposal
[2018-09-07 02:06:29.271035689][th:17][info] YacGate vote for block (58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2, Hash: [58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2 ])
[2018-09-07 02:06:29.272315195][th:17][info] YAC Order for voting: {0.0.0.0:10001}
[2018-09-07 02:06:29.273082498][th:17][info] YAC Vote for hash (58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2, 58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2)
[2018-09-07 02:06:29.273993703][th:20][info] AsyncGrpcClient Receive votes[size=1] from ipv4:127.0.0.1:44576
[2018-09-07 02:06:29.274771106][th:20][info] ProposalStorage Vote [58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2, 58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2] looks valid
[2018-09-07 02:06:29.275345109][th:20][info] YacBlockStorage Vote (58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2, 58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2) inserted
[2018-09-07 02:06:29.275952512][th:20][info] YacBlockStorage Votes in storage [1/1]
[2018-09-07 02:06:29.276444114][th:20][info] YAC Propagate state 58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2 to whole network
[2018-09-07 02:06:29.277119017][th:20][info] AsyncGrpcClient Send votes bundle[size=1] to 0.0.0.0:10001
[2018-09-07 02:06:29.277670720][th:17][info] AsyncGrpcClient Send votes bundle[size=1] to 0.0.0.0:10001
[2018-09-07 02:06:29.278283423][th:17][info] TxProcessor on stateful validation success: 99d597068541cdfc00cb632c35668261f051f9ed1ec9d2c8de6f0fffdbb67a98
[2018-09-07 02:06:29.279074526][th:17][info] IROHAD ~~~~~~~~~| PROPOSAL ^_^ |~~~~~~~~~
[2018-09-07 02:06:29.279667529][th:17][info] TxProcessor on proposal stateless success: 99d597068541cdfc00cb632c35668261f051f9ed1ec9d2c8de6f0fffdbb67a98
[2018-09-07 02:06:29.280435533][th:35][info] AsyncGrpcClient Receive votes[size=1] from ipv4:127.0.0.1:44576
[2018-09-07 02:06:29.280932835][th:35][info] YAC Pass outcome for 58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2 to pipeline
[2018-09-07 02:06:29.281523738][th:35][info] YacGate consensus: commit top block: height 2, hash 58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2
[2018-09-07 02:06:29.281846839][th:35][info] synchronizer processing commit
[2018-09-07 02:06:29.282851144][th:35][info] ChainValidator validate block: height 2, hash 58f18f0ab583cb92bc9291f5daaea893839a0f156a23d7b3542dfc2a846ac3b2
[2018-09-07 02:06:29.290569680][th:35][info] IROHAD ~~~~~~~~~| COMMIT =^._.^= |~~~~~~~~~
[2018-09-07 02:06:29.291233483][th:35][info] TxProcessor on commit committed: 99d597068541cdfc00cb632c35668261f051f9ed1ec9d2c8de6f0fffdbb67a98