actiontech / dble

A High Scalability Middle-ware for MySQL Sharding
https://opensource.actionsky.com
GNU General Public License v2.0
1.09k stars 317 forks source link

Spend too much time on INFO log #133

Open actiontech-bot opened 7 years ago

actiontech-bot commented 7 years ago

Raised by: helingyun Steps:

  1. Prepare: 2 data-nodes, 2 tables on each node, 50000000 rows in each table
  2. Sysbench 2048 threads: select ... id between a and a+100 order by money
  3. log level is INFO, found many logs like:
    2017-05-15 15:18:04.146  INFO [BusinessExecutor51] (io.mycat.backend.mysql.nio.handler.query.impl.MultiNodeMergeHandler.rowEofResponse(MultiNodeMergeHandler.java:177)) - MySQLConnection [id=1384, lastTime=1494832683860, user=action, schema=test_hly, old shema=test_hly, borrowed=true, fromSlaveDB=false, threadId=4284, charset=latin1, txIsolation=3, autocommit=true, attachment=dn1{select `user_creditcard_info`.`id`,`user_creditcard_info`.`my_creditcard`,`user_creditcard_info`.`my_comsume_money`,`user_creditcard_info`.`my_date`,`user_creditcard_info`.`my_credit_state`,`user_creditcard_info`.`my_max_money` from  `user_creditcard_info` where id BETWEEN 31299641 AND 31299741 ORDER BY `user_creditcard_info`.`id` ASC LIMIT 50}.0, respHandler=io.mycat.backend.mysql.nio.handler.query.impl.BaseSelectHandler@25e79cfe, host=10.186.17.101, port=3309, statusSync=null, writeQueue=0, modifiedSQLExecuted=false] 's rowEof is reached.
    2017-05-15 15:18:04.146  INFO [BusinessExecutor19] (io.mycat.backend.mysql.nio.handler.query.impl.OutputHandler.rowEofResponse(OutputHandler.java:163)) - --------sql execute end!
actiontech-bot commented 7 years ago

image