Are there too many logs by default? #8132

amaury1093 · 2020-12-10T11:09:33Z

Summary of Bug

There are too many logs by default.

Version

02fb3a3

Steps to Reproduce

See logs on a fresh chain after 3 blocks:

Click to expand

➜  cosmos-sdk git:(am-change-log-level) ./build/simd start
11:55AM INF starting ABCI with Tendermint
11:55AM INF Starting multiAppConn service impl={"Logger":{}} module=proxy
11:55AM INF Starting localClient service connection=query impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
11:55AM INF Starting localClient service connection=snapshot impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
11:55AM INF Starting localClient service connection=mempool impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
11:55AM INF Starting localClient service connection=consensus impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
11:55AM INF Starting EventBus service impl={"Logger":{}} module=events
11:55AM INF Starting PubSub service impl={"Logger":{}} module=pubsub
11:55AM INF Starting IndexerService service impl={"Logger":{}} module=txindex
11:55AM INF ABCI Handshake App Info hash= height=0 module=consensus protocol-version=0 software-version=
11:55AM INF ABCI Replay Blocks appHeight=0 module=consensus stateHeight=0 storeHeight=0
11:55AM INF asserting crisis invariants inv=0/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=1/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=2/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=3/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=4/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=5/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=6/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=7/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=8/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=9/11 module=x/crisis
11:55AM INF asserting crisis invariants inv=10/11 module=x/crisis
11:55AM INF asserted all invariants duration=1.577599 height=0 module=x/crisis
11:55AM INF created new capability module=ibc name=ports/transfer
11:55AM INF port binded module=x/ibc/port port=transfer
11:55AM INF claimed capability capability=1 module=transfer name=ports/transfer
11:55AM INF Completed ABCI Handshake - Tendermint and App are synced appHash= appHeight=0 module=consensus
11:55AM INF Version info block=11 p2p=8 software=
11:55AM INF This node is a validator addr=DEDA206694EFB974BD5404A31166F297F1C1FEF3 module=consensus pubKey=kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=
11:55AM INF P2P Node ID ID=8f62c0bfbdfd7baf2ec2904db94aca5d295ec1ef file=/Users/amaury/.simapp/config/node_key.json module=p2p
11:55AM INF Adding persistent peers addrs=[] module=p2p
11:55AM INF Adding unconditional peer ids ids=[] module=p2p
11:55AM INF Add our address to book addr={"id":"8f62c0bfbdfd7baf2ec2904db94aca5d295ec1ef","ip":"0.0.0.0","port":26656} book=/Users/amaury/.simapp/config/addrbook.json module=p2p
11:55AM INF Starting Node service impl={"Logger":{}}
11:55AM INF Starting pprof server laddr=localhost:6060
11:55AM INF Starting RPC HTTP server on 127.0.0.1:26657 module=rpc-server
11:55AM INF Starting P2P Switch service impl={"Logger":{}} module=p2p
11:55AM INF Starting Mempool service impl={"Logger":{},"Switch":{"Logger":{}}} module=mempool
11:55AM INF Starting BlockchainReactor service impl={"Logger":{},"Switch":{"Logger":{}}} module=blockchain
11:55AM INF Starting Consensus service impl={"Logger":{},"Metrics":{"BlockIntervalSeconds":{},"BlockParts":{},"BlockSizeBytes":{},"ByzantineValidators":{},"ByzantineValidatorsPower":{},"CommittedHeight":{},"FastSyncing":{},"Height":{},"MissingValidators":{},"MissingValidatorsPower":{},"NumTxs":{},"Rounds":{},"StateSyncing":{},"TotalTxs":{},"ValidatorLastSignedHeight":{},"ValidatorMissedBlocks":{},"ValidatorPower":{},"Validators":{},"ValidatorsPower":{}},"Switch":{"Logger":{}}} module=consensus
11:55AM INF Reactor  module=consensus waitSync=false
11:55AM INF Starting State service impl={"Logger":{},"commit_round":-1,"commit_time":"0001-01-01T00:00:00Z","height":1,"last_commit":null,"last_validators":{"proposer":null,"validators":null},"locked_block":null,"locked_block_parts":null,"locked_round":-1,"proposal":null,"proposal_block":null,"proposal_block_parts":null,"round":0,"start_time":"2020-12-10T10:55:49.881718Z","step":1,"triggered_timeout_precommit":false,"valid_block":null,"valid_block_parts":null,"valid_round":-1,"validators":{"proposer":{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000},"validators":[{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000}]},"votes":[{"precommits":["nil-Vote"],"precommits_bit_array":"BA{1:_} 0/1000 = 0.00","prevotes":["nil-Vote"],"prevotes_bit_array":"BA{1:_} 0/1000 = 0.00","round":0}]} module=consensus
11:55AM INF Starting baseWAL service impl={"Logger":{}} module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
11:55AM INF Starting Group service impl={"Dir":"/Users/amaury/.simapp/data/cs.wal","Head":{"ID":"Z9r1Ihu0iGnC:/Users/amaury/.simapp/data/cs.wal/wal","Path":"/Users/amaury/.simapp/data/cs.wal/wal"},"ID":"group:Z9r1Ihu0iGnC:/Users/amaury/.simapp/data/cs.wal/wal","Logger":{}} module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
11:55AM INF Searching for height height=1 max=0 min=0 module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
11:55AM INF Searching for height height=0 max=0 min=0 module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
11:55AM INF Found height=0 index=0 module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
11:55AM INF Catchup by replaying consensus messages height=1 module=consensus
11:55AM INF Replay: Done module=consensus
11:55AM INF Starting TimeoutTicker service impl={"Logger":{}} module=consensus
11:55AM INF Starting Evidence service impl={"Logger":{},"Switch":{"Logger":{}}} module=evidence
11:55AM INF Starting StateSync service impl={"Logger":{},"Switch":{"Logger":{}}} module=statesync
11:55AM INF Starting PEX service impl={"Logger":{},"Switch":{"Logger":{}}} module=pex
11:55AM INF Starting AddrBook service book=/Users/amaury/.simapp/config/addrbook.json impl={"Logger":{}} module=p2p
11:55AM INF Saving AddrBook to file book=/Users/amaury/.simapp/config/addrbook.json module=p2p size=0
11:55AM INF Ensure peers module=pex numDialing=0 numInPeers=0 numOutPeers=0 numToDial=10
11:55AM INF No addresses to dial. Falling back to seeds module=pex
^C
➜  cosmos-sdk git:(am-change-log-level) ./build/simd start
12:04PM INF starting ABCI with Tendermint
12:04PM INF Starting multiAppConn service impl={"Logger":{}} module=proxy
12:04PM INF Starting localClient service connection=query impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
12:04PM INF Starting localClient service connection=snapshot impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
12:04PM INF Starting localClient service connection=mempool impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
12:04PM INF Starting localClient service connection=consensus impl="marshaling error: json: unsupported type: abcicli.Callback" module=abci-client
12:04PM INF Starting EventBus service impl={"Logger":{}} module=events
12:04PM INF Starting PubSub service impl={"Logger":{}} module=pubsub
12:04PM INF Starting IndexerService service impl={"Logger":{}} module=txindex
12:04PM INF ABCI Handshake App Info hash= height=0 module=consensus protocol-version=0 software-version=
12:04PM INF ABCI Replay Blocks appHeight=0 module=consensus stateHeight=0 storeHeight=0
12:04PM INF asserting crisis invariants inv=0/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=1/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=2/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=3/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=4/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=5/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=6/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=7/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=8/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=9/11 module=x/crisis
12:04PM INF asserting crisis invariants inv=10/11 module=x/crisis
12:04PM INF asserted all invariants duration=5.830996 height=0 module=x/crisis
12:04PM INF created new capability module=ibc name=ports/transfer
12:04PM INF port binded module=x/ibc/port port=transfer
12:04PM INF claimed capability capability=1 module=transfer name=ports/transfer
12:04PM INF Completed ABCI Handshake - Tendermint and App are synced appHash= appHeight=0 module=consensus
12:04PM INF Version info block=11 p2p=8 software=
12:04PM INF This node is a validator addr=DEDA206694EFB974BD5404A31166F297F1C1FEF3 module=consensus pubKey=kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=
12:04PM INF P2P Node ID ID=8f62c0bfbdfd7baf2ec2904db94aca5d295ec1ef file=/Users/amaury/.simapp/config/node_key.json module=p2p
12:04PM INF Adding persistent peers addrs=[] module=p2p
12:04PM INF Adding unconditional peer ids ids=[] module=p2p
12:04PM INF Add our address to book addr={"id":"8f62c0bfbdfd7baf2ec2904db94aca5d295ec1ef","ip":"0.0.0.0","port":26656} book=/Users/amaury/.simapp/config/addrbook.json module=p2p
12:04PM INF Starting Node service impl={"Logger":{}}
12:04PM INF Starting pprof server laddr=localhost:6060
12:04PM INF Starting P2P Switch service impl={"Logger":{}} module=p2p
12:04PM INF Starting BlockchainReactor service impl={"Logger":{},"Switch":{"Logger":{}}} module=blockchain
12:04PM INF Starting Consensus service impl={"Logger":{},"Metrics":{"BlockIntervalSeconds":{},"BlockParts":{},"BlockSizeBytes":{},"ByzantineValidators":{},"ByzantineValidatorsPower":{},"CommittedHeight":{},"FastSyncing":{},"Height":{},"MissingValidators":{},"MissingValidatorsPower":{},"NumTxs":{},"Rounds":{},"StateSyncing":{},"TotalTxs":{},"ValidatorLastSignedHeight":{},"ValidatorMissedBlocks":{},"ValidatorPower":{},"Validators":{},"ValidatorsPower":{}},"Switch":{"Logger":{}}} module=consensus
12:04PM INF Reactor  module=consensus waitSync=false
12:04PM INF Starting State service impl={"Logger":{},"commit_round":-1,"commit_time":"0001-01-01T00:00:00Z","height":1,"last_commit":null,"last_validators":{"proposer":null,"validators":null},"locked_block":null,"locked_block_parts":null,"locked_round":-1,"proposal":null,"proposal_block":null,"proposal_block_parts":null,"round":0,"start_time":"2020-12-10T11:04:08.857478Z","step":1,"triggered_timeout_precommit":false,"valid_block":null,"valid_block_parts":null,"valid_round":-1,"validators":{"proposer":{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000},"validators":[{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000}]},"votes":[{"precommits":["nil-Vote"],"precommits_bit_array":"BA{1:_} 0/1000 = 0.00","prevotes":["nil-Vote"],"prevotes_bit_array":"BA{1:_} 0/1000 = 0.00","round":0}]} module=consensus
12:04PM INF Starting RPC HTTP server on 127.0.0.1:26657 module=rpc-server
12:04PM INF Starting baseWAL service impl={"Logger":{}} module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
12:04PM INF Starting Group service impl={"Dir":"/Users/amaury/.simapp/data/cs.wal","Head":{"ID":"XVTxH18rCGDZ:/Users/amaury/.simapp/data/cs.wal/wal","Path":"/Users/amaury/.simapp/data/cs.wal/wal"},"ID":"group:XVTxH18rCGDZ:/Users/amaury/.simapp/data/cs.wal/wal","Logger":{}} module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
12:04PM INF Searching for height height=1 max=0 min=0 module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
12:04PM INF Searching for height height=0 max=0 min=0 module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
12:04PM INF Found height=0 index=0 module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
12:04PM INF Catchup by replaying consensus messages height=1 module=consensus
12:04PM INF Replay: Done module=consensus
12:04PM INF Starting TimeoutTicker service impl={"Logger":{}} module=consensus
12:04PM INF Starting Evidence service impl={"Logger":{},"Switch":{"Logger":{}}} module=evidence
12:04PM INF Starting StateSync service impl={"Logger":{},"Switch":{"Logger":{}}} module=statesync
12:04PM INF Starting PEX service impl={"Logger":{},"Switch":{"Logger":{}}} module=pex
12:04PM INF Starting AddrBook service book=/Users/amaury/.simapp/config/addrbook.json impl={"Logger":{}} module=p2p
12:04PM INF Starting Mempool service impl={"Logger":{},"Switch":{"Logger":{}}} module=mempool
12:04PM INF Saving AddrBook to file book=/Users/amaury/.simapp/config/addrbook.json module=p2p size=0
12:04PM INF Ensure peers module=pex numDialing=0 numInPeers=0 numOutPeers=0 numToDial=10
12:04PM INF No addresses to dial. Falling back to seeds module=pex
12:04PM INF Timed out dur=4994.307 height=1 module=consensus round=0 step=1
12:04PM INF enterNewRound(1/0). Current: 1/0/RoundStepNewHeight height=1 module=consensus round=0
12:04PM INF enterPropose(1/0). Current: 1/0/RoundStepNewRound height=1 module=consensus round=0
12:04PM INF enterPropose: Our turn to propose height=1 module=consensus privValidator={"Key":{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","priv_key":"rM/FPAUgGKLMIIwRyB9nInHwD2mSmaQ4KS/kkukCKiySTwtW/c5dI91iip8ffVtdjOv/r96siDvi3GOid2cy/w==","pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8="},"LastSignState":{"height":0,"round":0,"step":0}} proposer=DEDA206694EFB974BD5404A31166F297F1C1FEF3 round=0
12:04PM INF Signed proposal height=1 module=consensus proposal={"Type":32,"block_id":{"hash":"573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4","parts":{"hash":"7A72C421056CFD88EB79EF68B49E583768D36CC4FC160C11CF6C945435BA55C5","total":1}},"height":1,"pol_round":-1,"round":0,"signature":"wDVaoQe03H8YzFA1770mD86cAgZHAGIqmVvtEgrz9QvVJkMQmgP7dY3zmNZ4RIOYh/b4N4gfv3Qf+2p30jYfDQ==","timestamp":"2020-12-10T11:04:08.864779Z"} round=0
12:04PM INF Received proposal module=consensus proposal={"Type":32,"block_id":{"hash":"573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4","parts":{"hash":"7A72C421056CFD88EB79EF68B49E583768D36CC4FC160C11CF6C945435BA55C5","total":1}},"height":1,"pol_round":-1,"round":0,"signature":"wDVaoQe03H8YzFA1770mD86cAgZHAGIqmVvtEgrz9QvVJkMQmgP7dY3zmNZ4RIOYh/b4N4gfv3Qf+2p30jYfDQ==","timestamp":"2020-12-10T11:04:08.864779Z"}
12:04PM INF Received complete proposal block hash=573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4 height=1 module=consensus
12:04PM INF enterPrevote(1/0). Current: 1/0/RoundStepPropose module=consensus
12:04PM INF enterPrevote: ProposalBlock is valid height=1 module=consensus round=0
12:04PM INF Signed and pushed vote err=null height=1 module=consensus round=0 vote={"block_id":{"hash":"573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4","parts":{"hash":"7A72C421056CFD88EB79EF68B49E583768D36CC4FC160C11CF6C945435BA55C5","total":1}},"height":1,"round":0,"signature":"m6vapauE+QCq2yWBit+qferr1+USt2y9HICxmJQI1AXiRLzI/h6HAHJEvduITUcGUqk/ZfdMe56KCA8j0k07AA==","timestamp":"2020-12-10T11:04:08.883773Z","type":1,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Added to prevote module=consensus prevotes="VoteSet{H:1 R:0 T:SIGNED_MSG_TYPE_PREVOTE +2/3:573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4:1:7A72C421056C(1) BA{1:x} map[]}" vote={"block_id":{"hash":"573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4","parts":{"hash":"7A72C421056CFD88EB79EF68B49E583768D36CC4FC160C11CF6C945435BA55C5","total":1}},"height":1,"round":0,"signature":"m6vapauE+QCq2yWBit+qferr1+USt2y9HICxmJQI1AXiRLzI/h6HAHJEvduITUcGUqk/ZfdMe56KCA8j0k07AA==","timestamp":"2020-12-10T11:04:08.883773Z","type":1,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Updating ValidBlock because of POL. POLRound=0 module=consensus validRound=-1
12:04PM INF enterPrecommit(1/0). Current: 1/0/RoundStepPrevote height=1 module=consensus round=0
12:04PM INF enterPrecommit: +2/3 prevoted proposal block. Locking hash=573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4 height=1 module=consensus round=0
12:04PM INF Signed and pushed vote err=null height=1 module=consensus round=0 vote={"block_id":{"hash":"573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4","parts":{"hash":"7A72C421056CFD88EB79EF68B49E583768D36CC4FC160C11CF6C945435BA55C5","total":1}},"height":1,"round":0,"signature":"E8iERHNYtK/iT9sJIQ1cHIz0wwZSQe2BGC3wG3SgD3WenPw72GD7IG/711lgsaAtvdcRD+4QM9LTpBDe8syYDw==","timestamp":"2020-12-10T11:04:08.897876Z","type":2,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Added to precommit module=consensus precommits="VoteSet{H:1 R:0 T:SIGNED_MSG_TYPE_PRECOMMIT +2/3:573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4:1:7A72C421056C(1) BA{1:x} map[]}" vote={"block_id":{"hash":"573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4","parts":{"hash":"7A72C421056CFD88EB79EF68B49E583768D36CC4FC160C11CF6C945435BA55C5","total":1}},"height":1,"round":0,"signature":"E8iERHNYtK/iT9sJIQ1cHIz0wwZSQe2BGC3wG3SgD3WenPw72GD7IG/711lgsaAtvdcRD+4QM9LTpBDe8syYDw==","timestamp":"2020-12-10T11:04:08.897876Z","type":2,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF enterCommit(1/0). Current: 1/0/RoundStepPrecommit commitRound=0 height=1 module=consensus
12:04PM INF Commit is for locked block. Set ProposalBlock=LockedBlock blockHash=573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4 commitRound=0 height=1 module=consensus
12:04PM INF Finalizing commit of block with N txs N=0 hash=573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4 height=1 module=consensus root=E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
12:04PM INF Block{
  Header{
    Version:        {11 0}
    ChainID:        my-chain
    Height:         1
    Time:           2020-12-10 10:51:33.657226 +0000 UTC
    LastBlockID:    :0:000000000000
    LastCommit:     E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Data:           E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Validators:     1BC82A8D1BDB6ED2920B7F52C4976F0820D1CFB00064583FA3F06E2B3E9CB4BD
    NextValidators: 1BC82A8D1BDB6ED2920B7F52C4976F0820D1CFB00064583FA3F06E2B3E9CB4BD
    App:            E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Consensus:      048091BC7DDC283F77BFBF91D73C44DA58C3DF8A9CBC867405D8B7F3DAADA22F
    Results:        E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Evidence:       E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Proposer:       DEDA206694EFB974BD5404A31166F297F1C1FEF3
  }#573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4
  Data{
    
  }#E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
  EvidenceData{
    
  }#E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
  Commit{
    Height:     0
    Round:      0
    BlockID:    :0:000000000000
    Signatures:
      
  }#E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
}#573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4 module=consensus
12:04PM INF minted coins from module account amount=205stake from=mint module=x/bank
12:04PM INF Executed block height=1 invalidTxs=0 module=state validTxs=0
12:04PM INF commit synced commit=436F6D6D697449447B5B313636203232372031363020313536203737203139382039372039332031303820323430203234302032312031302032333820383820353520313532203235203130203834203135392031353620323137203138322031343920363120333020313733203220313931203133332037305D3A317D
12:04PM INF Committed state appHash=A6E3A09C4DC6615D6CF0F0150AEE583798190A549F9CD9B6953D1EAD02BF8546 height=1 module=state txs=0
12:04PM INF Updating evidence pool last_block_height=1 last_block_time=2020-12-10T10:51:33Z module=evidence
12:04PM INF Indexed block height=1 module=txindex
12:04PM INF Timed out dur=4967.016 height=2 module=consensus round=0 step=1
12:04PM INF enterNewRound(2/0). Current: 2/0/RoundStepNewHeight height=2 module=consensus round=0
12:04PM INF enterPropose(2/0). Current: 2/0/RoundStepNewRound height=2 module=consensus round=0
12:04PM INF enterPropose: Our turn to propose height=2 module=consensus privValidator={"Key":{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","priv_key":"rM/FPAUgGKLMIIwRyB9nInHwD2mSmaQ4KS/kkukCKiySTwtW/c5dI91iip8ffVtdjOv/r96siDvi3GOid2cy/w==","pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8="},"LastSignState":{"height":1,"round":0,"signature":"E8iERHNYtK/iT9sJIQ1cHIz0wwZSQe2BGC3wG3SgD3WenPw72GD7IG/711lgsaAtvdcRD+4QM9LTpBDe8syYDw==","signbytes":"6D080211010000000000000022480A20573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA41224080112207A72C421056CFD88EB79EF68B49E583768D36CC4FC160C11CF6C945435BA55C52A0C08A881C8FE0510A08092AC0332086D792D636861696E","step":3}} proposer=DEDA206694EFB974BD5404A31166F297F1C1FEF3 round=0
12:04PM INF Signed proposal height=2 module=consensus proposal={"Type":32,"block_id":{"hash":"E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD","parts":{"hash":"FA0938048671625F004DEB5F0416D7E472AD8514E54BB08C0D32C3673BEC9EDC","total":1}},"height":2,"pol_round":-1,"round":0,"signature":"sZtzJN1/6C3nHlWCNi2JNzslaMunxGjfd+94TCFyP+QZ7B6VWZO95pBC5yDq2uls5IxGNvDzDO9C5NWMBOPrAw==","timestamp":"2020-12-10T11:04:13.920961Z"} round=0
12:04PM INF Received proposal module=consensus proposal={"Type":32,"block_id":{"hash":"E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD","parts":{"hash":"FA0938048671625F004DEB5F0416D7E472AD8514E54BB08C0D32C3673BEC9EDC","total":1}},"height":2,"pol_round":-1,"round":0,"signature":"sZtzJN1/6C3nHlWCNi2JNzslaMunxGjfd+94TCFyP+QZ7B6VWZO95pBC5yDq2uls5IxGNvDzDO9C5NWMBOPrAw==","timestamp":"2020-12-10T11:04:13.920961Z"}
12:04PM INF Received complete proposal block hash=E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD height=2 module=consensus
12:04PM INF enterPrevote(2/0). Current: 2/0/RoundStepPropose module=consensus
12:04PM INF enterPrevote: ProposalBlock is valid height=2 module=consensus round=0
12:04PM INF Signed and pushed vote err=null height=2 module=consensus round=0 vote={"block_id":{"hash":"E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD","parts":{"hash":"FA0938048671625F004DEB5F0416D7E472AD8514E54BB08C0D32C3673BEC9EDC","total":1}},"height":2,"round":0,"signature":"RuqcYjGcNfVw+1p0YrrtF5yQlVsird+U5nM+fBYYlN5QGDX6W921mT9nGgd1Cm01acOBkeIjLA/EPuMxdKC5Ag==","timestamp":"2020-12-10T11:04:13.940465Z","type":1,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Added to prevote module=consensus prevotes="VoteSet{H:2 R:0 T:SIGNED_MSG_TYPE_PREVOTE +2/3:E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD:1:FA0938048671(1) BA{1:x} map[]}" vote={"block_id":{"hash":"E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD","parts":{"hash":"FA0938048671625F004DEB5F0416D7E472AD8514E54BB08C0D32C3673BEC9EDC","total":1}},"height":2,"round":0,"signature":"RuqcYjGcNfVw+1p0YrrtF5yQlVsird+U5nM+fBYYlN5QGDX6W921mT9nGgd1Cm01acOBkeIjLA/EPuMxdKC5Ag==","timestamp":"2020-12-10T11:04:13.940465Z","type":1,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Updating ValidBlock because of POL. POLRound=0 module=consensus validRound=-1
12:04PM INF enterPrecommit(2/0). Current: 2/0/RoundStepPrevote height=2 module=consensus round=0
12:04PM INF enterPrecommit: +2/3 prevoted proposal block. Locking hash=E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD height=2 module=consensus round=0
12:04PM INF Signed and pushed vote err=null height=2 module=consensus round=0 vote={"block_id":{"hash":"E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD","parts":{"hash":"FA0938048671625F004DEB5F0416D7E472AD8514E54BB08C0D32C3673BEC9EDC","total":1}},"height":2,"round":0,"signature":"sD1sLIBHCTLQ45owvl+HiWX5Ye8RB9DqWEYID8BgBtMyWMhCjFlQ5uI7pS71heuJDl3KDdBn4ZNQgG+179BYAA==","timestamp":"2020-12-10T11:04:13.953065Z","type":2,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Added to precommit module=consensus precommits="VoteSet{H:2 R:0 T:SIGNED_MSG_TYPE_PRECOMMIT +2/3:E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD:1:FA0938048671(1) BA{1:x} map[]}" vote={"block_id":{"hash":"E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD","parts":{"hash":"FA0938048671625F004DEB5F0416D7E472AD8514E54BB08C0D32C3673BEC9EDC","total":1}},"height":2,"round":0,"signature":"sD1sLIBHCTLQ45owvl+HiWX5Ye8RB9DqWEYID8BgBtMyWMhCjFlQ5uI7pS71heuJDl3KDdBn4ZNQgG+179BYAA==","timestamp":"2020-12-10T11:04:13.953065Z","type":2,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF enterCommit(2/0). Current: 2/0/RoundStepPrecommit commitRound=0 height=2 module=consensus
12:04PM INF Commit is for locked block. Set ProposalBlock=LockedBlock blockHash=E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD commitRound=0 height=2 module=consensus
12:04PM INF Finalizing commit of block with N txs N=0 hash=E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD height=2 module=consensus root=A6E3A09C4DC6615D6CF0F0150AEE583798190A549F9CD9B6953D1EAD02BF8546
12:04PM INF Block{
  Header{
    Version:        {11 0}
    ChainID:        my-chain
    Height:         2
    Time:           2020-12-10 11:04:08.897876 +0000 UTC
    LastBlockID:    573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4:1:7A72C421056C
    LastCommit:     BAF625EC800DE301AF55AECFCE95D21E04386ABF33EA4A9881FF6EBBE65BE64F
    Data:           E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Validators:     1BC82A8D1BDB6ED2920B7F52C4976F0820D1CFB00064583FA3F06E2B3E9CB4BD
    NextValidators: 1BC82A8D1BDB6ED2920B7F52C4976F0820D1CFB00064583FA3F06E2B3E9CB4BD
    App:            A6E3A09C4DC6615D6CF0F0150AEE583798190A549F9CD9B6953D1EAD02BF8546
    Consensus:      048091BC7DDC283F77BFBF91D73C44DA58C3DF8A9CBC867405D8B7F3DAADA22F
    Results:        E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Evidence:       E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Proposer:       DEDA206694EFB974BD5404A31166F297F1C1FEF3
  }#E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD
  Data{
    
  }#E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
  EvidenceData{
    
  }#E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
  Commit{
    Height:     1
    Round:      0
    BlockID:    573BA75B62FFC744D04BA0D046439FE42A046493544A3D7D4F447E3250F79EA4:1:7A72C421056C
    Signatures:
      CommitSig{13C884447358 by DEDA206694EF on 2 @ 2020-12-10T11:04:08.897876Z}
  }#BAF625EC800DE301AF55AECFCE95D21E04386ABF33EA4A9881FF6EBBE65BE64F
}#E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD module=consensus
12:04PM INF minted coins from module account amount=205stake from=mint module=x/bank
12:04PM INF Executed block height=2 invalidTxs=0 module=state validTxs=0
12:04PM INF commit synced commit=436F6D6D697449447B5B383320313437203136342035392031313520313934203235302032343720323333203136332032352031313320313520313538203234342031353920393920313533203233362035342034332032323020323235203230312031372031333220383220373220313920313538203139332032355D3A327D
12:04PM INF Committed state appHash=5393A43B73C2FAF7E9A319710F9EF49F6399EC362BDCE1C911845248139EC119 height=2 module=state txs=0
12:04PM INF Updating evidence pool last_block_height=2 last_block_time=2020-12-10T11:04:08Z module=evidence
12:04PM INF Indexed block height=2 module=txindex
12:04PM INF Timed out dur=4965.556 height=3 module=consensus round=0 step=1
12:04PM INF enterNewRound(3/0). Current: 3/0/RoundStepNewHeight height=3 module=consensus round=0
12:04PM INF enterPropose(3/0). Current: 3/0/RoundStepNewRound height=3 module=consensus round=0
12:04PM INF enterPropose: Our turn to propose height=3 module=consensus privValidator={"Key":{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","priv_key":"rM/FPAUgGKLMIIwRyB9nInHwD2mSmaQ4KS/kkukCKiySTwtW/c5dI91iip8ffVtdjOv/r96siDvi3GOid2cy/w==","pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8="},"LastSignState":{"height":2,"round":0,"signature":"sD1sLIBHCTLQ45owvl+HiWX5Ye8RB9DqWEYID8BgBtMyWMhCjFlQ5uI7pS71heuJDl3KDdBn4ZNQgG+179BYAA==","signbytes":"6D080211020000000000000022480A20E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD122408011220FA0938048671625F004DEB5F0416D7E472AD8514E54BB08C0D32C3673BEC9EDC2A0C08AD81C8FE0510A8BCBAC60332086D792D636861696E","step":3}} proposer=DEDA206694EFB974BD5404A31166F297F1C1FEF3 round=0
12:04PM INF Signed proposal height=3 module=consensus proposal={"Type":32,"block_id":{"hash":"830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542","parts":{"hash":"F4BE4D28C5B05EFC0B4C1C299ABFD148860FE6B4A0187E1831A340E0903E13E4","total":1}},"height":3,"pol_round":-1,"round":0,"signature":"TbgmFRZOFgdiNwuZavd458RJtOT8QikBGRHcMFksbH18DchAXmt7jBrg9iE3deEAco5HeB0k+sDvxhbe5a6UCw==","timestamp":"2020-12-10T11:04:18.976442Z"} round=0
12:04PM INF Received proposal module=consensus proposal={"Type":32,"block_id":{"hash":"830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542","parts":{"hash":"F4BE4D28C5B05EFC0B4C1C299ABFD148860FE6B4A0187E1831A340E0903E13E4","total":1}},"height":3,"pol_round":-1,"round":0,"signature":"TbgmFRZOFgdiNwuZavd458RJtOT8QikBGRHcMFksbH18DchAXmt7jBrg9iE3deEAco5HeB0k+sDvxhbe5a6UCw==","timestamp":"2020-12-10T11:04:18.976442Z"}
12:04PM INF Received complete proposal block hash=830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542 height=3 module=consensus
12:04PM INF enterPrevote(3/0). Current: 3/0/RoundStepPropose module=consensus
12:04PM INF enterPrevote: ProposalBlock is valid height=3 module=consensus round=0
12:04PM INF Signed and pushed vote err=null height=3 module=consensus round=0 vote={"block_id":{"hash":"830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542","parts":{"hash":"F4BE4D28C5B05EFC0B4C1C299ABFD148860FE6B4A0187E1831A340E0903E13E4","total":1}},"height":3,"round":0,"signature":"BqvVmeKEmaZin/McbJDOzILz+hvCNZjVyV3LVW/saM4pXrsz6nk0Enhfv2WIqIP8xgruCwzwQGaJiHuQGYBfCA==","timestamp":"2020-12-10T11:04:18.993558Z","type":1,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Added to prevote module=consensus prevotes="VoteSet{H:3 R:0 T:SIGNED_MSG_TYPE_PREVOTE +2/3:830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542:1:F4BE4D28C5B0(1) BA{1:x} map[]}" vote={"block_id":{"hash":"830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542","parts":{"hash":"F4BE4D28C5B05EFC0B4C1C299ABFD148860FE6B4A0187E1831A340E0903E13E4","total":1}},"height":3,"round":0,"signature":"BqvVmeKEmaZin/McbJDOzILz+hvCNZjVyV3LVW/saM4pXrsz6nk0Enhfv2WIqIP8xgruCwzwQGaJiHuQGYBfCA==","timestamp":"2020-12-10T11:04:18.993558Z","type":1,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Updating ValidBlock because of POL. POLRound=0 module=consensus validRound=-1
12:04PM INF enterPrecommit(3/0). Current: 3/0/RoundStepPrevote height=3 module=consensus round=0
12:04PM INF enterPrecommit: +2/3 prevoted proposal block. Locking hash=830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542 height=3 module=consensus round=0
12:04PM INF Signed and pushed vote err=null height=3 module=consensus round=0 vote={"block_id":{"hash":"830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542","parts":{"hash":"F4BE4D28C5B05EFC0B4C1C299ABFD148860FE6B4A0187E1831A340E0903E13E4","total":1}},"height":3,"round":0,"signature":"CkANAcYWz/VOt5w2LCn8dCsRrP5MssZfGCpH3fkR0VS+xw9HfvahKOBlUMGvn9O/psmv3VDsRCBDtoAvrJ2eAw==","timestamp":"2020-12-10T11:04:19.006657Z","type":2,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF Added to precommit module=consensus precommits="VoteSet{H:3 R:0 T:SIGNED_MSG_TYPE_PRECOMMIT +2/3:830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542:1:F4BE4D28C5B0(1) BA{1:x} map[]}" vote={"block_id":{"hash":"830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542","parts":{"hash":"F4BE4D28C5B05EFC0B4C1C299ABFD148860FE6B4A0187E1831A340E0903E13E4","total":1}},"height":3,"round":0,"signature":"CkANAcYWz/VOt5w2LCn8dCsRrP5MssZfGCpH3fkR0VS+xw9HfvahKOBlUMGvn9O/psmv3VDsRCBDtoAvrJ2eAw==","timestamp":"2020-12-10T11:04:19.006657Z","type":2,"validator_address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","validator_index":0}
12:04PM INF enterCommit(3/0). Current: 3/0/RoundStepPrecommit commitRound=0 height=3 module=consensus
12:04PM INF Commit is for locked block. Set ProposalBlock=LockedBlock blockHash=830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542 commitRound=0 height=3 module=consensus
12:04PM INF Finalizing commit of block with N txs N=0 hash=830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542 height=3 module=consensus root=5393A43B73C2FAF7E9A319710F9EF49F6399EC362BDCE1C911845248139EC119
12:04PM INF Block{
  Header{
    Version:        {11 0}
    ChainID:        my-chain
    Height:         3
    Time:           2020-12-10 11:04:13.953065 +0000 UTC
    LastBlockID:    E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD:1:FA0938048671
    LastCommit:     9A7E8A9212039D3A9373449155470F1600A140B2E3FF2F10D4BE37B87C68E132
    Data:           E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Validators:     1BC82A8D1BDB6ED2920B7F52C4976F0820D1CFB00064583FA3F06E2B3E9CB4BD
    NextValidators: 1BC82A8D1BDB6ED2920B7F52C4976F0820D1CFB00064583FA3F06E2B3E9CB4BD
    App:            5393A43B73C2FAF7E9A319710F9EF49F6399EC362BDCE1C911845248139EC119
    Consensus:      048091BC7DDC283F77BFBF91D73C44DA58C3DF8A9CBC867405D8B7F3DAADA22F
    Results:        E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Evidence:       E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
    Proposer:       DEDA206694EFB974BD5404A31166F297F1C1FEF3
  }#830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542
  Data{
    
  }#E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
  EvidenceData{
    
  }#E3B0C44298FC1C149AFBF4C8996FB92427AE41E4649B934CA495991B7852B855
  Commit{
    Height:     2
    Round:      0
    BlockID:    E019D1FF1F69A7E62C1B6130A76BB0894B50B94DB01D143999EF752B1BC6FDFD:1:FA0938048671
    Signatures:
      CommitSig{B03D6C2C8047 by DEDA206694EF on 2 @ 2020-12-10T11:04:13.953065Z}
  }#9A7E8A9212039D3A9373449155470F1600A140B2E3FF2F10D4BE37B87C68E132
}#830B45435E62A6826EEBE131C0D30350F3E4709AFAA666967957C774B30FE542 module=consensus
12:04PM INF minted coins from module account amount=205stake from=mint module=x/bank
12:04PM INF Executed block height=3 invalidTxs=0 module=state validTxs=0
12:04PM INF commit synced commit=436F6D6D697449447B5B31343920313136203132203133352039352035362032333320323320323137203131332031363220323134203234352031373720323120323035203230203232332031383020313930203230302039332031333120372034382035312032323420373420313930203133203838203138335D3A337D
12:04PM INF Committed state appHash=95740C875F38E917D971A2D6F5B115CD14DFB4BEC85D83073033E04ABE0D58B7 height=3 module=state txs=0
12:04PM INF Updating evidence pool last_block_height=3 last_block_time=2020-12-10T11:04:13Z module=evidence
12:04PM INF Indexed block height=3 module=txindex
^C12:04PM INF Stopping Node service impl={"Logger":{}}
12:04PM INF Stopping Node
12:04PM INF Stopping EventBus service impl={"Logger":{}} module=events
12:04PM INF Stopping PubSub service impl={"Logger":{}} module=pubsub
12:04PM INF Stopping IndexerService service impl={"Logger":{}} module=txindex
12:04PM INF Stopping P2P Switch service impl={"Logger":{}} module=p2p
12:04PM INF Stopping Evidence service impl={"Logger":{},"Switch":{"Logger":{}}} module=evidence
12:04PM INF Stopping StateSync service impl={"Logger":{},"Switch":{"Logger":{}}} module=statesync
12:04PM INF Stopping PEX service impl={"Logger":{},"Switch":{"Logger":{}}} module=pex
12:04PM INF Stopping AddrBook service book=/Users/amaury/.simapp/config/addrbook.json impl={"Logger":{}} module=p2p
12:04PM INF Stopping Mempool service impl={"Logger":{},"Switch":{"Logger":{}}} module=mempool
12:04PM INF Stopping BlockchainReactor service impl={"Logger":{},"Switch":{"Logger":{}}} module=blockchain
12:04PM INF Saving AddrBook to file book=/Users/amaury/.simapp/config/addrbook.json module=p2p size=0
12:04PM INF Stopping Consensus service impl={"Logger":{},"Metrics":{"BlockIntervalSeconds":{},"BlockParts":{},"BlockSizeBytes":{},"ByzantineValidators":{},"ByzantineValidatorsPower":{},"CommittedHeight":{},"FastSyncing":{},"Height":{},"MissingValidators":{},"MissingValidatorsPower":{},"NumTxs":{},"Rounds":{},"StateSyncing":{},"TotalTxs":{},"ValidatorLastSignedHeight":{},"ValidatorMissedBlocks":{},"ValidatorPower":{},"Validators":{},"ValidatorsPower":{}},"Switch":{"Logger":{}}} module=consensus
12:04PM INF Stopping State service impl={"Logger":{},"commit_round":-1,"commit_time":"2020-12-10T11:04:19.018718Z","height":4,"last_commit":{"peer_maj_23s":{},"votes":["Vote{0:DEDA206694EF 3/00/SIGNED_MSG_TYPE_PRECOMMIT(Precommit) 830B45435E62 0A400D01C616 @ 2020-12-10T11:04:19.006657Z}"],"votes_bit_array":"BA{1:x} 1000/1000 = 1.00"},"last_validators":{"proposer":{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000},"validators":[{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000}]},"locked_block":null,"locked_block_parts":null,"locked_round":-1,"proposal":null,"proposal_block":null,"proposal_block_parts":null,"round":0,"start_time":"2020-12-10T11:04:24.018718Z","step":1,"triggered_timeout_precommit":false,"valid_block":null,"valid_block_parts":null,"valid_round":-1,"validators":{"proposer":{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000},"validators":[{"address":"DEDA206694EFB974BD5404A31166F297F1C1FEF3","proposer_priority":0,"pub_key":"kk8LVv3OXSPdYoqfH31bXYzr/6/erIg74txjondnMv8=","voting_power":1000}]},"votes":[{"precommits":["nil-Vote"],"precommits_bit_array":"BA{1:_} 0/1000 = 0.00","prevotes":["nil-Vote"],"prevotes_bit_array":"BA{1:_} 0/1000 = 0.00","round":0}]} module=consensus
12:04PM INF Stopping TimeoutTicker service impl={"Logger":{}} module=consensus
12:04PM INF Stopping baseWAL service impl={"Logger":{}} module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
12:04PM INF Stopping Group service impl={"Dir":"/Users/amaury/.simapp/data/cs.wal","Head":{"ID":"XVTxH18rCGDZ:/Users/amaury/.simapp/data/cs.wal/wal","Path":"/Users/amaury/.simapp/data/cs.wal/wal"},"ID":"group:XVTxH18rCGDZ:/Users/amaury/.simapp/data/cs.wal/wal","Logger":{}} module=consensus wal=/Users/amaury/.simapp/data/cs.wal/wal
12:04PM ERR Stopped accept routine, as transport is closed module=p2p numPeers=0
12:04PM INF Closing rpc listener listener={"Listener":{}}
12:04PM INF RPC HTTP server stopped err="accept tcp 127.0.0.1:26657: use of closed network connection" module=rpc-server
12:04PM ERR Error serving server err="accept tcp 127.0.0.1:26657: use of closed network connection"
12:04PM INF exiting...

Proposals

The default log level was switched recently:

- main:info,state:info,statesync:info,*:error
+ info

1. either switch back to the old log level (is that possible?)

2. walk through the code, and put some logs as debug instead of info.

For Admin Use

Not duplicate issue
Appropriate labels applied
Appropriate contributors tagged
Contributor assigned/self-assigned

The text was updated successfully, but these errors were encountered:

alessio · 2020-12-10T12:46:49Z

Can't we just set WARN as default level?

alexanderbez · 2020-12-10T14:27:12Z

Please not (1) -- this was intentional. Logs are meant to be verbose. See my PR point (1) under log_level for the rationale.

Note, most of these logs are from Tendermint anyway. However, some of the SDK logs, especially the invariance checks can be DEBUG.

amaury1093 · 2020-12-10T14:41:40Z

Can't we just set WARN as default level?

That won't show any logs at startup, in general. Warn logs are quite rare.

alexanderbez · 2020-12-10T14:44:44Z

For the actionable item here, I would suggest we groom the SDK for all logs and ensure the levels are correct. This should be pretty straightforward because there aren't too many logs.

We can also collect a list of logs that we think should be debug/warn in Tendermint and I can make those changes if there is agreement.

robert-zaremba · 2020-12-14T22:14:01Z

Sounds like we should revisit logs and "downgrade" some of that logs to Debug.
What's the rational to print info logs from tendermint, rather than warn+ only?

alessio · 2020-12-14T22:24:50Z

Sounds like we should revisit logs and "downgrade" some of that logs to Debug.

I agree. We should revisit all logs and ensure that each and every of them matches their respective level as defined by RFC5424 (also known as the Syslog Protocol) - page 10.

allthatjazzleo · 2021-01-05T04:32:01Z

logs grow extremely fast
And it seems log level only honor the flag --log_level instead of log_level field in config.toml

alexanderbez · 2021-01-05T13:44:12Z

And it seems log level only honor the flag --log_level instead of log_level field in config.toml

That is a bug it seems.

alexanderbez · 2021-01-05T14:15:07Z

I tried to fiddle around with it and call rootViper.MergeInConfig() in interceptConfigs but that didn't work. Tbh, I'm not really sure how the overriding logic works in config/flags. The entire flow and structure of configs, flags and bootstrapping it very messy.

I would suggest to SDK devs to re-evaluate this and adopt a more explicit and easier to understand flow such as the one used in Atlas.

alessio · 2021-01-05T15:09:42Z

I would suggest to SDK devs to re-evaluate this and adopt a more explicit and easier to understand flow such as the one used in Atlas.

I couldn't agree more. Plus, we should start transitioning to config file-based binaries (when flags become numerous, UX could be impacted heavily).

robert-zaremba · 2021-01-05T16:04:20Z

config file-based binaries

You mean a binary format for config file? That sounds as a bad idea.

alessio · 2021-01-05T16:46:27Z

Sorry Robert, I didn't explain myself correctly. We used to support reading client configuration from file. We should reintroduce that feature, AFAIK users did use it quite a lot.

johnnyluo · 2021-01-07T10:45:51Z

I think at least seperate tendermint logging and cosmos logging into two different flags , so we can turn it on / off seperately
In my case, we do use ctx.Logger().Info to log some useful information we need . However there is not much case we need to look into tendermint log

alexanderbez · 2021-01-07T20:16:20Z

I think at least seperate tendermint logging and cosmos logging into two different flags , so we can turn it on / off seperately
In my case, we do use ctx.Logger().Info to log some useful information we need . However there is not much case we need to look into tendermint log

Since most of the noisy and verbose logs (most of which are useless at the info level) come from Tendermint, this isn't a terrible idea.

alessio · 2021-01-07T20:42:50Z

Yeah I agree

ethanfrey · 2021-01-14T16:18:55Z

Sorry Robert, I didn't explain myself correctly. We used to support reading client configuration from file. We should reintroduce that feature, AFAIK users did use it quite a lot.

Yes please!

alexanderbez · 2021-01-21T18:09:46Z

I've started a PR in Tendermint where we downgrade a bunch of logs to DEBUG. If you find any logs that you believe need to be downgraded or are outright superfluous and not needed, please mention them there so I can tackle them.

ref: tendermint/tendermint#5917

gituser · 2021-02-19T11:03:34Z

Getting massive logs daily (e.g. 1GB) in new gaia v4.0.3, in previous gaia v2.0.x releases logs were few megabytes.

Compared old configuration vs new they both have identical loglevel set to:
log_level = "main:info,state:info,*:error"

Also changing in the configuration loglevel seems to not affect the actual logging - the spam still going on, so you need to pass on the commandline to the gaia.

RyanHendricks · 2021-02-21T04:10:21Z

Getting massive logs daily (e.g. 1GB) in new gaia v4.0.3, in previous gaia v2.0.x releases logs were few megabytes.

Compared old configuration vs new they both have identical loglevel set to:
log_level = "main:info,state:info,*:error"

Also changing in the configuration loglevel seems to not affect the actual logging - the spam still going on, so you need to pass on the commandline to the gaia.

Confirming these issues. Changing either of the below fields in the config.toml for gaia v4.0.3 has no effect.

# Output level for logging, including package level options
log_level = "main:info,state:info,*:error"

log_format = "plain"
``

Having the default log_level=info is manageable but ideally this can be adjusted via the config file rather than via flag.

alexanderbez · 2021-03-09T13:22:44Z

I would consider closing this issue. Many of the logs have already been groomed and included in the latest Tendermint 0.34 release.

alessio · 2021-03-09T13:26:53Z

Closing this as per @alexanderbez's suggestion.

gituser · 2021-03-11T10:24:08Z

@alessio @alexanderbez

I think you're too fast to close this issue, even in the latest gaia v4.1.0 there are multiple problems:

logs output are using color codes with format = plain
there are many logs from cosmos-sdk with loglevel = info

in previous gaia v2.x.x versions there were no problems with logs with default settings.

Please consider re-opening this issue.

alessio · 2021-03-11T14:36:42Z

@alessio @alexanderbez

I think you're too fast to close this issue, even in the latest gaia v4.1.0 there are multiple problems:

logs output are using color codes with format = plain

Yes, that's painful to me too. It's a tendermint issue I believe? @alexanderbez knows better

there are many logs from cosmos-sdk with loglevel = info

Set loglevel to warning and the redundant logs will go away. Else, mind giving me some examples, please?

gituser · 2021-03-11T15:16:31Z

@alessio

Set loglevel to warning and the redundant logs will go away. Else, mind giving me some examples, please?

This doesn't help much, I still get errors like:

Mar 11 18:14:44 cosmos-prod gaiad[539]: #033[90m6:14PM#033[0m #033[1m#033[31mERR#033[0m#033[0m dialing failed (attempts: 1): auth failure: secret conn failed: read tcp 10.2.40.10:33088->157.230.31.160:26656: i/o timeout #033[36maddr=#033[0m{"id":"626caa2a2eba32db9218a6930a93c2b1ea294eae","ip":"157.230.31.160","port":26656} #033[36mmodule=#033[0mpex
Mar 11 18:14:48 cosmos-prod gaiad[539]: #033[90m6:14PM#033[0m #033[1m#033[31mERR#033[0m#033[0m failed to process message #033[36merr=#033[0m"error adding vote" #033[36mheight=#033[0m5455703 #033[36mmodule=#033[0mconsensus #033[36mmsg=#033[0m{"Vote":{"block_id":{"hash":"77511AB1A42164AD7F7DB3B89E054652D4C3BE8E17B251E40F32BD3D89B54FEE","parts":{"hash":"DC9078ECA31740A11F25778B5BF20B6E877D1D43BD7C10083AAAF029F0108162","total":1}},"height":5455702,"round":0,"signature":"NOd2zSuDohPY8qdiYCwEG5cOa31lNRS7Kz/rwgXFKNN5N34sJ0Vpzq2IkSQRz5DRonF1ftFmGtFejdtajs4GCw==","timestamp":"2021-03-11T15:14:47.539737306Z","type":2,"validator_address":"D540AB022088612AC74B287D076DBFBC4A377A2E","validator_index":60}} #033[36mpeer=#033[0m1045cff0d1666f1bcc722551369e842cd6ac94f7 #033[36mround=#033[0m0
Mar 11 18:14:55 cosmos-prod gaiad[539]: #033[90m6:14PM#033[0m #033[1m#033[31mERR#033[0m#033[0m failed to process message #033[36merr=#033[0m"error adding vote" #033[36mheight=#033[0m5455704 #033[36mmodule=#033[0mconsensus #033[36mmsg=#033[0m{"Vote":{"block_id":{"hash":"A02F258812C1361911312226CFCE4D24F2A51B195BCC6D5B9886EE9D3831381F","parts":{"hash":"878025B937E4FA9365A97EE20FD6513E96AA68DB5C5E05B32309CB83D8EACB1C","total":1}},"height":5455703,"round":0,"signature":"kTVJlJZaxJBtVaNAAbO5eXUsXD0giybv9UNtY66jjLTkLeuieX+cYE0XHrUEc9nEzXD1fIsMxg5NMc5zuVPJCA==","timestamp":"2021-03-11T15:14:54.635765416Z","type":2,"validator_address":"D540AB022088612AC74B287D076DBFBC4A377A2E","validator_index":60}} #033[36mpeer=#033[0m1045cff0d1666f1bcc722551369e842cd6ac94f7 #033[36mround=#033[0m0
Mar 11 18:14:55 cosmos-prod gaiad[539]: #033[90m6:14PM#033[0m #033[1m#033[31mERR#033[0m#033[0m failed to process message #033[36merr=#033[0m"error adding vote" #033[36mheight=#033[0m5455704 #033[36mmodule=#033[0mconsensus #033[36mmsg=#033[0m{"Vote":{"block_id":{"hash":"A02F258812C1361911312226CFCE4D24F2A51B195BCC6D5B9886EE9D3831381F","parts":{"hash":"878025B937E4FA9365A97EE20FD6513E96AA68DB5C5E05B32309CB83D8EACB1C","total":1}},"height":5455703,"round":0,"signature":"kTVJlJZaxJBtVaNAAbO5eXUsXD0giybv9UNtY66jjLTkLeuieX+cYE0XHrUEc9nEzXD1fIsMxg5NMc5zuVPJCA==","timestamp":"2021-03-11T15:14:54.635765416Z","type":2,"validator_address":"D540AB022088612AC74B287D076DBFBC4A377A2E","validator_index":60}} #033[36mpeer=#033[0m1045cff0d1666f1bcc722551369e842cd6ac94f7 #033[36mround=#033[0m0
Mar 11 18:15:11 cosmos-prod gaiad[539]: #033[90m6:15PM#033[0m #033[1m#033[31mERR#033[0m#033[0m dialing failed (attempts: 2): dial tcp [2a05:d014:60:7301:c5a:7461:c52c:5367]:26656: connect: network is unreachable #033[36maddr=#033[0m{"id":"35244a0564f21a189809eef81fa9be7379ce883f","ip":"2a05:d014:60:7301:c5a:7461:c52c:5367","port":26656} #033[36mmodule=#033[0mpex
Mar 11 18:15:11 cosmos-prod gaiad[539]: #033[90m6:15PM#033[0m #033[1m#033[31mERR#033[0m#033[0m dialing failed (attempts: 1): dial tcp 85.214.84.224:26656: connect: connection refused #033[36maddr=#033[0m{"id":"4173a31e168b139989d87d2a3e6462ba138c6e74","ip":"85.214.84.224","port":26656} #033[36mmodule=#033[0mpex
Mar 11 18:15:11 cosmos-prod gaiad[539]: #033[90m6:15PM#033[0m #033[1m#033[31mERR#033[0m#033[0m dialing failed (attempts: 2): auth failure: conn.ID (366ac852255c3ac8de17e11ae9ec814b8c68bddb) dialed ID (8ea0afb3f863935ecebd9a7e972bdd519a3ec7af) mismatch #033[36maddr=#033[0m{"id":"8ea0afb3f863935ecebd9a7e972bdd519a3ec7af","ip":"51.15.94.196","port":26656} #033[36mmodule=#033[0mpex
Mar 11 18:15:12 cosmos-prod gaiad[539]: #033[90m6:15PM#033[0m #033[1m#033[31mERR#033[0m#033[0m dialing failed (attempts: 1): dial tcp 91.205.173.168:26656: i/o timeout #033[36maddr=#033[0m{"id":"3028c6ee9be21f0d34be3e97a59b093e15ec0658","ip":"91.205.173.168","port":26656} #033[36mmodule=#033[0mpex
Mar 11 18:15:12 cosmos-prod gaiad[539]: #033[90m6:15PM#033[0m #033[1m#033[31mERR#033[0m#033[0m dialing failed (attempts: 1): dial tcp 3.84.76.99:26656: i/o timeout #033[36maddr=#033[0m{"id":"8142d3fec843ecb6eac0915013d99c978d67f1aa","ip":"3.84.76.99","port":26656} #033[36mmodule=#033[0mpex
Mar 11 18:15:12 cosmos-prod gaiad[539]: #033[90m6:15PM#033[0m #033[1m#033[31mERR#033[0m#033[0m dialing failed (attempts: 1): dial tcp 34.243.237.158:26656: i/o timeout #033[36maddr=#033[0m{"id":"ac13b3e1fa3512402c664d30b609e4f00218cf13","ip":"34.243.237.158","port":26656} #033[36mmodule=#033[0mpex
Mar 11 18:15:16 cosmos-prod gaiad[539]: #033[90m6:15PM#033[0m #033[1m#033[31mERR#033[0m#033[0m failed to process message #033[36merr=#033[0m"error adding vote" #033[36mheight=#033[0m5455707 #033[36mmodule=#033[0mconsensus #033[36mmsg=#033[0m{"Vote":{"block_id":{"hash":"29EBD747118C566DFC0E926656B3B7C3117FA0AAB383AB236A0F9E490497CB94","parts":{"hash":"D6BA6DF1E4117980C94DA4388CCEA12D16CAE5A89DC29F3C0FCD3A73D4A9404C","total":1}},"height":5455706,"round":0,"signature":"vl13heXhLaZbx0/9SIKEoMfLydj3j9MKeAJ5xvaB+li+831MQjLvgw1RTCywKvia2nQYlEz6REVfUTDB6R9mDg==","timestamp":"2021-03-11T15:15:15.753698286Z","type":2,"validator_address":"D540AB022088612AC74B287D076DBFBC4A377A2E","validator_index":60}} #033[36mpeer=#033[0m1045cff0d1666f1bcc722551369e842cd6ac94f7 #033[36mround=#033[0m0

and logs are growing very fast.

the main issue is with the new release that logs are growing much more comparing to the old one: #8132 (comment)

tac0turtle · 2022-01-19T11:01:37Z

this is encapsulated in #9034. Closing this issue

gituser · 2022-08-23T17:25:06Z

@marbar3778 the issue I've reported in #8132 (comment) is still relevant for gaia v7.0.3 (which is the latest release at the moment).

Logs are ugly and growing very fast.

Still no option to disable coloring on human format.
I'd like to grep logs without color codes and yes I don't want a json format either.
I think this feature has been requested since forever and still wasn't implemented even though it should be trivial to implement such.

Thank you.

amaury1093 added C:CLI T: UX labels Dec 10, 2020

This was referenced Dec 10, 2020

make it exit gracefully. #8101

Merged

Switch LogFormat to Plain by default #8131

Merged

amaury1093 added this to the v0.40.1 milestone Dec 10, 2020

yihuang mentioned this issue Dec 19, 2020

Problem: test_min_self_delegate don't work in recent version crypto-org-chain/chain-main#299

Merged

13 tasks

amaury1093 mentioned this issue Jan 6, 2021

Environment variables are not populated to cmd flags #8179

Closed

4 tasks

aaronc modified the milestones: v0.40.1, v0.42 Jan 6, 2021

RyanHendricks mentioned this issue Feb 21, 2021

Groom Tendermint Logs tendermint/tendermint#5912

Closed

7 tasks

clevinson added the backlog label Feb 24, 2021

clevinson mentioned this issue Feb 24, 2021

feat: file watcher for cosmovisor #8590

Merged

9 tasks

amaury1093 mentioned this issue Mar 1, 2021

add back cli config option for flags #8529

Closed

4 tasks

alessio closed this as completed Mar 9, 2021

aaronc removed the backlog label Mar 9, 2021

alessio reopened this Mar 11, 2021

amaury1093 mentioned this issue Mar 31, 2021

Refactor client & server Configs #9034

Open

4 tasks

khoslaventures mentioned this issue Jul 20, 2021

Problem: evm debugging message displayed with fatal log option evmos/ethermint#286

Closed

fedekunze mentioned this issue Aug 4, 2021

Problem: with info option, log display is too verbose evmos/ethermint#393

Closed

tac0turtle closed this as completed Jan 19, 2022

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Are there too many logs by default? #8132

Are there too many logs by default? #8132

amaury1093 commented Dec 10, 2020

alessio commented Dec 10, 2020

alexanderbez commented Dec 10, 2020

amaury1093 commented Dec 10, 2020

alexanderbez commented Dec 10, 2020

robert-zaremba commented Dec 14, 2020

alessio commented Dec 14, 2020 •

edited

Loading

allthatjazzleo commented Jan 5, 2021

alexanderbez commented Jan 5, 2021

alexanderbez commented Jan 5, 2021 •

edited

Loading

alessio commented Jan 5, 2021

robert-zaremba commented Jan 5, 2021

alessio commented Jan 5, 2021

johnnyluo commented Jan 7, 2021

alexanderbez commented Jan 7, 2021

alessio commented Jan 7, 2021

ethanfrey commented Jan 14, 2021

alexanderbez commented Jan 21, 2021

gituser commented Feb 19, 2021

RyanHendricks commented Feb 21, 2021

alexanderbez commented Mar 9, 2021

alessio commented Mar 9, 2021

gituser commented Mar 11, 2021

alessio commented Mar 11, 2021

gituser commented Mar 11, 2021

tac0turtle commented Jan 19, 2022

gituser commented Aug 23, 2022 •

edited

Loading

Are there too many logs by default? #8132

Are there too many logs by default? #8132

Comments

amaury1093 commented Dec 10, 2020

Summary of Bug

Version

Steps to Reproduce

Proposals

1. either switch back to the old log level (is that possible?)

2. walk through the code, and put some logs as debug instead of info.

For Admin Use

alessio commented Dec 10, 2020

alexanderbez commented Dec 10, 2020

amaury1093 commented Dec 10, 2020

alexanderbez commented Dec 10, 2020

robert-zaremba commented Dec 14, 2020

alessio commented Dec 14, 2020 • edited Loading

allthatjazzleo commented Jan 5, 2021

alexanderbez commented Jan 5, 2021

alexanderbez commented Jan 5, 2021 • edited Loading

alessio commented Jan 5, 2021

robert-zaremba commented Jan 5, 2021

alessio commented Jan 5, 2021

johnnyluo commented Jan 7, 2021

alexanderbez commented Jan 7, 2021

alessio commented Jan 7, 2021

ethanfrey commented Jan 14, 2021

alexanderbez commented Jan 21, 2021

gituser commented Feb 19, 2021

RyanHendricks commented Feb 21, 2021

alexanderbez commented Mar 9, 2021

alessio commented Mar 9, 2021

gituser commented Mar 11, 2021

alessio commented Mar 11, 2021

gituser commented Mar 11, 2021

tac0turtle commented Jan 19, 2022

gituser commented Aug 23, 2022 • edited Loading

alessio commented Dec 14, 2020 •

edited

Loading

alexanderbez commented Jan 5, 2021 •

edited

Loading

gituser commented Aug 23, 2022 •

edited

Loading