MySQL慢查询开启、日志分析(转)

说明

• Mysql的查询讯日志是Mysql提供的一种日志记录，它用来记录在Mysql中响应时间超过阈值的语句
• 具体指运行时间超过long_query_time值得SQL，则会被记录到慢查询日志中。long_query_time的默认为10，意识是运行10秒以上的语句。
• 由它来看那些SQL语句超出了我们的最大忍耐值，比如一条SQL执行超过了5秒，我们就算慢查询，我们就可以结合Explain进行分析。

查看是否开启及如何开启

默认Mysql没有开启慢查询，需要我们说动设置这个参数。当然，如果不是调优需要的话，一般不建议开启该参数，因为开启慢查询日志会或多或少带来一定的性能影响。慢查询日志支持将日志写入文件。

进入mysql终端查看是否开启（我这里都是自己改过的）

mysql> show variables like ‘%slow_query_log%‘;
+---------------------+----------------------------+
| Variable_name       | Value                      |
+---------------------+----------------------------+
| slow_query_log      | ON                         |
| slow_query_log_file | /data/mysql/mysql-slow.log |
+---------------------+----------------------------+

上面查询结果第一行，这里是开启的，第二行是默认查询路径文件名。

mysql> set global slow_query_log = 0|1; //设置开启或者关闭，0为关闭，1为开启

如果使用set global slow_query_log 命令开启慢查询日志，只对当前数据库生效，如果Mysql重启后则会失效。

补充：如果通过终端命令设定的话，再查询是看不到修改结果的，需要新开启一个窗口查看即可。

如果要永久生效，必须修改my.cnf配置文件(其他系统变量也是如此)，

修改my.cnf文件，[mysqld]下增加和修改参数slow_query_log和slow_query_log_file后，然后重启MySQL服务器，也即将如下两行配置进my.cnf文件
slow_query_log = 1

slow_query_log_file = /data/mysql/mysql-slow.log

[mysqld]
slow_query_log = 1
long_query_time = 1
slow_query_log_file = /data/mysql/mysql-slow.log

关于慢查询的参数slow_query_log_file，它指定慢查询日志文件的存放路径，系统默认会给一个缺省的host_name_show.log（如果没有指定参数slow_query_log_file的话）。

那么开启了慢日志后，怎么样的SQL才会记录到慢查询当中呢？

• 这个是由参数long_query_time控制，默认情况下long_query_time的值是10秒。
• 命令SHOW VARIABLES LIKE ‘long_query_time‘;

mysql> show variables like ‘long_query_time‘;
+-----------------+----------+
| Variable_name   | Value    |
+-----------------+----------+
| long_query_time | 10.00000 |
+-----------------+----------+

可以使用命令修改，也可以在my.cnf参数里面修改，修改完记得习惯性重启服务。还是那句话，不建议生产环境开启。

mysql> show variables like ‘long_query_time‘;
+-----------------+----------+
| Variable_name   | Value    |
+-----------------+----------+
| long_query_time | 1.000000 |
+-----------------+----------+

假如SQL运行时间刚好等于long_query_time的情况，并不会被记录下来，也就是说，在Mysql源码里是判断大于long_query_time，而非大于等于。

Case

• 查看当前多少秒算慢：SHOW VARIABLES LIKE ‘long_query_time%‘;
• 设置慢的阙值时间：set global long_query_tim

• 为什么设置后看不出变化：

1. 需要重新连接或新开一个回话才能看到修改值； SHOW VARIABLES LIKE ‘long_query_time%‘;
2. SHOW GLOBAL VARIABLES LIKE ‘long_query_time%‘;

• 记录慢SQL并后续分析：select sleep(4);

查看慢查询日志文件

# cat /data/mysql/mysql-slow.log 
# 
# select sleep(5);
# Time: 2018-01-20T18:10:24.443517Z
# : root[root] @ localhost [] Id: 3
# Query_time: 5.000564 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0
use test_db;   #使用的数据库
SET timestamp=1524276624;  #时间戳
select sleep(5); #问题SQL

以上信息包括实际查询时间5.000564秒，时间戳timestamp=1524276624，还有库test_db，出问题的SQL。

• 查看当前系统中多少条满记录：show global status like ‘%Slow_queries%‘;

mysql> show global status like ‘%Slow_queries%‘;
+---------------+-------+
| Variable_name | Value |
+---------------+-------+
| Slow_queries  | 1     |
+---------------+-------+
1 row in set (0.03 sec)

可以作为系统健康检查度来查询。(什么意思：如果系统里面充满了大量的慢SQL，都超过阙值，这时系统就需要及时优化)

配置版

show_query_log = 1;
show_query_log_file=/var/lib/mysql/mysql_slow.log
log_query_time=3;
log_output=FILE

mysqldumpslow用法讲解

• 在生产环境中，如果要手动分析日志，查找、分析SQL，显然是一个体力活，MySQL提供了日志分析工具mysqldumpslow。

上面测试的慢查询SQL只有一条，假如在实际的生产环境中，慢查询SQL远远高于测试的数量，十几条甚至几十条，假如几条慢查询出现的频率很高，我们能做到根据轻重优先级来分析并排除那是不是更好？那么就用到了mysqldumpslow

[ mysql]# mysqldumpslow --help   ----------------------------------------------------//执行命令
Usage: mysqldumpslow [ OPTS... ] [ LOGS... ]

Parse and summarize the MySQL slow query log. Options are

  --verbose    verbose
  --debug      debug
  --help       write this text to standard output

  -v           verbose
  -d           debug
  -s ORDER     what to sort by (al, at, ar, c, l, r, t), ‘at‘ is default
                al: average lock time
                ar: average rows sent
                at: average query time
                 c: count
                 l: lock time
                 r: rows sent
                 t: query time  
  -r           reverse the sort order (largest last instead of first)
  -t NUM       just show the top n queries
  -a           don‘t abstract all numbers to N and strings to ‘S‘
  -n NUM       abstract numbers with at least n digits within names
  -g PATTERN   grep: only consider stmts that include this string
  -h HOSTNAME  hostname of db server for *-slow.log filename (can be wildcard),
               default is ‘*‘, i.e. match all
  -i NAME      name of server instance (if using mysql.server startup script)
  -l           don‘t subtract lock time from total time

s:是表示按照何种方式排序

c:访问次数

i：锁定时间

r：返回记录

t：查询时间

al:平均锁定时间

ar：平均返回记录数

at：平均查询时间

t：即为返回前面多少条数据

g：后边搭配一个正则匹配模式，大小写不敏感

mysqldumpslow -s r -t 10 /data/mysql/mysql-slow.log  //得到返回记录集最多的10个SQL
mysqldumpslow -s c -t 10 /data/mysql/mysql-slow.log //得到访问次数最多的10个SQL 
mysqldumpslow -s t -t 10 -g "left join" /data/mysql/mysql-slow.log  //得到按照时间排序的前10条里面含有做了连接的查询SQL
mysqldumpslow -s r -t 10 /data/mysql/mysql-slow.log | more  //另外建议在使用这些命令时结合|