慢日志简单介绍:
MySQL的慢查询日志用来记录执行时间超过阈值的sql语句,是数据库调优的有效切入点。慢查询日志通过slow_query_log参数来控制是否开启,默认情况下该参数为0,所以需要手动开启慢查询日志。
下面是我在官方文档摘取的一部分关于慢查询日志的介绍:
The slow query log consists of SQL statements that took more than long_query_time seconds to execute and required at least min_examined_row_limit rows to be examined. The minimum and default values of long_query_time are 0 and 10, respectively. The value can be specified to a resolution of microseconds. For logging to a file, times are written including the microseconds part. For logging to tables, only integer times are written; the microseconds part is ignored.
相关参数:
slow_query_log:是否开启慢查询日志功能,默认不开启
slow_query_log_file:指定慢查询日志的存放位置
long_query_time:设置阈值,可以精确到微妙
log_queries_not_using_indexes:设置为1的话则把未使用索引的语句也记录到慢查询日志中,即使未超过阈值
log_output:设置日志存储的方式。‘FILE’表示存储到文件;‘TABLE’表示存储到表中,位置是mysql库中的slow_log表;MySQL支持同时开启两种记录方式,log_output=‘FILE,TABLE’。
日志的读取:
以下操作均使用mysql5.7版本进行
首先开启慢日志功能并设置阈值
mysql> show variables like 'slow_query_log';
+----------------+-------+
| Variable_name | Value |
+----------------+-------+
| slow_query_log | ON |
+----------------+-------+
mysql> show variables like '%long_query%';
+-----------------+----------+
| Variable_name | Value |
+-----------------+----------+
| long_query_time | 5.000000 |
+-----------------+----------+
我们把时间修改为5秒,日志记录方式为文件,存放位置为默认,执行一条语句进行测试(可以使用tail -f命令来对慢查询日志进行监控,看是否有新内容写入)
mysql> select count(1) from ceshi a,test b where a.id=b.id;
+----------+
| count(1) |
+----------+
| 5431 |
+----------+
1 row in set (13.19 sec)
查看慢查询日志中的记录
可以看到,其中记录了所执行的语句、执行语句的用户、耗费时间、返回行数等等内容。其中时间是精确到微秒的。
执行下面的语句,耗费时间很短,所以不会被记录到慢查询日志中。
mysql> select count(1) from test where id=5001;
+----------+
| count(1) |
+----------+
| 1 |
+----------+
1 row in set (0.01 sec)
看一下测试表的结构,表中未建立索引,在执行语句的时候并未走索引,但因为执行时间为超过所设置的阈值,所以不会被记录到慢日志中。
mysql> desc test;
+-------+-------------+------+-----+---------+-------+
| Field | Type | Null | Key | Default | Extra |
+-------+-------------+------+-----+---------+-------+
| id | int(11) | YES | | NULL | |
| name | varchar(20) | YES | | NULL | |
+-------+-------------+------+-----+---------+-------+
接下来我们设置参数log_queries_not_using_indexes为1,从而将未走索引的语句也写入到慢日志中。
mysql> set global log_queries_not_using_indexes=1;
mysql> show variables like 'log_queries_not_using_indexes';
+-------------------------------+-------+
| Variable_name | Value |
+-------------------------------+-------+
| log_queries_not_using_indexes | ON |
+-------------------------------+-------+
再次执行语句进行测试
mysql> select count(1) from test where id=5001;
+----------+
| count(1) |
+----------+
| 1 |
+----------+
1 row in set (0.01 sec)
这次,语句被记录到了慢日志中。
这个参数很有用,因为没走索引的语句可能效率并不高,执行时间短可能只是因为当前表中的数据较少。
慢日志分析工具:
在很多情况下,我们需要对MySQL日志进行各种分析,从而了解系统运行的方方面面。mysqlslow是MySQL自带的慢日志分析工具,但是功能相对单一,因此出现了很多三方的工具,其中mysqlsla是使用较广泛的一个。
mysqlsla不仅能分析慢日志,对于其它的日志(如binlog)也同样支持,基本用法如下
mysqlsla --log-type slow LOG
mysqlsla --log-type general LOG
mysqlbinlog LOG | mysqlsla --log-type binary //binlog需要先使用mysqlbinlog进行转换
......
下面是我截取的mysqlsla的部分输出:
开头部分是对分析结果的一个总体介绍,其中包括了日志类型、总的查询次数、唯一的查询次数、结果的排序方式等等。
下面的第二部分是按照排序方式排序好后(从大到小进行排序)各个SQL的统计信息,默认显示前10条SQL。每个SQL的统计内容主要包括以下几项:
Count:次类型SQL执行的次数
Time:包括总的执行时间、平均执行时间、最小执行时间、最大执行时间
Lock time:锁时间
Rows sent:返回行数,包括平均返回行数和最大最小返回行数
Rows examined:执行时访问的行数
Database:执行操作的数据库
Users:执行操作的用户,如果多个用户执行过,会按照用户的执行比例显示
Query abstract:SQL语句抽象后的结果
Query sample:SQL带入实际值后的例子
以上只是对mysqlsla的简单介绍,如果想要更详细的了解可以通过man mysqlsla命令进行查看,或者通过查阅资料进行学习。