mysql 通用日志分析

简介：

        通用日志里记录了，mysql每一个连接线程的执行情况和异常退出等信息，有助于分析代码执行流程。

 

配置介绍

    #查看日志情况

    show variables like '%general%';

    #开启日志

    SET GLOBAL general_log = 'On';

 

显示结果分析

 

2019-09-28T01:58:20.247567Z    33541 Query    SET AUTOCOMMIT=0

2019-09-28T01:58:20.247698Z    33541 Query    start transaction

2019-09-28T01:58:20.247822Z    33541 Query    UPDATE node_products SET flag=0 WHERE uid='test_4'

2019-09-28T01:58:20.248208Z    33541 Query    DELETE FROM node_global WHERE uid='test_4'

2019-09-28T01:58:20.248504Z    33541 Query    COMMIT

2019-09-28T01:58:20.287023Z    33541 Query    ROLLBACK

2019-09-28T01:58:20.291930Z    33542 Query    SET AUTOCOMMIT=0

2019-09-28T01:58:20.292277Z    33542 Query    start transaction

2019-09-28T01:58:20.292643Z    33542 Query    UPDATE node_products SET flag=0 WHERE uid='test_5'

2019-09-28T01:58:20.293646Z    33542 Query    DELETE FROM node_global WHERE uid='test_5'

2019-09-28T01:58:20.294296Z    33542 Query    COMMIT

2019-09-28T01:58:20.337153Z    33542 Query    ROLLBACK

2019-09-28T01:58:20.343315Z    33543 Query    SET AUTOCOMMIT=0

2019-09-28T01:58:20.343756Z    33543 Query    start transaction

2019-09-28T01:58:20.344051Z    33543 Query    UPDATE node_products SET flag=0 WHERE uid='test_6'

2019-09-28T01:58:20.345202Z    33543 Query    DELETE FROM node_global WHERE uid='test_6'

2019-09-28T01:58:20.345856Z    33543 Query    COMMIT

2019-09-28T01:58:20.387181Z    33543 Query    ROLLBACK

 

第一列:时间列,前面一个是日期,后面一个是小时和分钟,有一些不显示的原因是因为这些sql语句几乎是同时执行的,所以就不另外记录时间了.

第二列:ID列,就是show processlist出来的第一列的线程ID,对于长连接和一些比较耗时的sql语句,你可以精确找出究竟是那一条那一个线程在运行.

第三列:操作类型,Connect就是连接数据库,Query就是查询数据库(增删查改都显示为查询),可以特定过虑一些操作.

第四列:详细信息,

例如下面例子Connect的详细信息就是 root@localhost on kvdata,意思就是root@localhost连上kvdata库,如此类推,

2019-09-28T01:58:20.867646Z    33541 Query    SET AUTOCOMMIT=0

2019-09-28T01:58:20.867903Z    33541 Query    start transaction

2019-09-28T01:58:20.868216Z    33541 Query    UPDATE node_products SET flag=0 WHERE uid='test_14'

2019-09-28T01:58:20.870254Z    33553 Connect    root@localhost on kvdata using TCP/IP

2019-09-28T01:58:20.870560Z    33553 Query    SET AUTOCOMMIT = 0

2019-09-28T01:58:20.870849Z    33541 Quit    

2019-09-28T01:58:20.871027Z    33553 Query    DELETE FROM node_global WHERE uid='test_14'

2019-09-28T01:58:20.871811Z    33553 Query    COMMIT

 

与上面的显示分析比较，这里的数据库操作明显有问题，作为一个事务处理的语句被两个线程处理，且第一个线程退出后，第二个线程没有从第一条语句开始执行，造成了不可预知的错误。