大家好，我是田维常，可以叫我老田，也可以叫我田哥

。2017年的时候，我刚去上海，朋友内推我去美团面试，之前我也写过一个一篇文章，也是在美团面试中遇到的：

美团面试题：String s = new String("111")会创建几个对象？

关于慢SQL，我和面试官扯了很久，面试官也是很谦虚的，总是点头，自己以为回答的还可以。最后的最后，还是说了“你先回去等通知吧！”。

所以，我决定把这个慢SQL技术点，好好和你分享分享。希望你下次在遇到类似的面试，能顺顺利利轻轻松松的斩获自己想要的offer。

人生最大的喜悦是每个人都说你做不到，你却完成它了!

什么是慢SQL?

MySQL的慢查询日志是MySQL提供的一种日志记录，它用来记录MySQL中查询时间超过（大于）设置阈值（long_query_time）的语句，记录到慢查询日志中。

其中，long_query_time的默认值是10，单位是秒，也就是说默认情况下，你的SQL查询时间超过10秒就算慢SQL了。

如何开启慢SQL日志？

在MySQL中，慢SQL日志默认是未开启的，也就说就算出现了慢SQL，也不会告诉你的，如果需要知道哪些SQL是慢SQL，需要我们手动开启慢SQL日志的。

关于慢SQL是否开启，我们可以通过下面这个命令来查看：

-- 查看慢查询日志是否开启
show variables like '%slow_query_log%';

在这里插入图片描述

通过命令，我们就可以看到slow_query_log项为OFF，说明我们的慢SQL日志并未开启。另外我们也可以看到我们慢SQL日志存放于哪个目录下和日志文件名。

下面我们来开启慢SQL日志，执行下面的命令：

set global slow_query_log = 1;

这里需要注意，这里开启的是我们当前的数据库，并且，我们重启数据库后会失效的。

开启慢SQL日志后，再次查看：

slow_query_log项已经变成ON，说明开启成功。

上面说过慢SQL默认时间是10秒，我们通过下面的命令就可以看到我们慢SQL的默认时间：

show variables like '%long_query_time%';

在这里插入图片描述

我们总不能一直使用这个默认值，可能很多业务需要时间更短或更长，所以此时，我们就需要对默认时间进行修改，修改命令如下：

set long_query_time = 3;

修改完了，我们再来看看是否已经改成了3秒。

这里需要注意：想要永久的生效，还需要修改MySQL下面的配置文件my.cnf 文件。

[mysqld]
slow_query_log=1
slow_query_log_file=/var/lib/mysql/atguigu-slow.log
long_query_time=3
log_output=FILE

注意：不同操作系统，配置有些区别。

Linux操作系统中

在mysql配置文件my.cnf中增加

log-slow-queries=/var/lib/mysql/slowquery.log (指定日志文件存放位置，可以为空，系统会给一个缺省的文件host_name-slow.log)

long_query_time=2 (记录超过的时间，默认为10s)

log-queries-not-using-indexes (log下来没有使用索引的query,可以根据情况决定是否开启)

log-long-format (如果设置了，所有没有使用索引的查询也将被记录)

Windows操作系统中

在my.ini的[mysqld]添加如下语句：

log-slow-queries = E:\web\mysql\log\mysqlslowquery.log

long_query_time = 3(其他参数如上)

执行一条慢SQL，因为我们前面已经设置好了慢SQL时间为3秒，所以，我们只要执行一条SQL时间超过3秒即可。

SELECT SLEEP(4);

该SQL耗时4.024秒，下面我们就来查看慢SQL出现了多少条。

使用命令：

show global status like '%Slow_queries%';

查询SQL历程

找到慢SQL日志文件，打开后就会出现类似下面这样的语句；

# Time: 2021-07-20T09:17:49.791767Z
# User@Host: root[root] @ localhost []  Id:   150
# Query_time: 0.002549  Lock_time: 0.000144 Rows_sent: 1  Rows_examined: 4079
SET timestamp=1566292669;
select * from city where Name = 'Salala';

简单说明：

1.Time 该日志记录的时间

2.User @Host MySQL登录的用户和登录的主机地址

3.Query_time一行 第一个时间是查询的时间、第二个是锁表的时间、第三个是返回的行数、第四个是扫描的行数

4.SET timestamp 这一个是MySQL查询的时间

5.sql语句 这一行就很明显了，表示的是我们执行的sql语句

切记

如果你将long_query_time=0 ，那就意味着，我们所有的查询SQL语句都会输出到慢SQL日志文件中。

如何定位慢SQL？

通常我们定位慢SQL有两种方式：

第一种：定位慢查询SQL可以通过两个表象进行判断

• 系统级表象:
• 使用sar命令和top命令查看当前系统的状态
• 也可以使用Prometheus和Grafana监控工具查看当前系统状态
• CPU消耗严重
• IO等待严重
• 页面响应时间过长
• 项目日志出现超时等错误
• SQL语句表象:
• SQL语句冗长
• SQL语句执行时间过长
• SQL从全表扫描中获取数据
• 执行计划中的rows和cost很大

第二种：根据不同的数据库使用不同的方式获取问题SQL

• MySQL:
• 慢查询日志
• 测试工具loadrunner
• ptquery工具
• Oracle:
• AWR报告
• 测试工具loadrunner
• 相关内部视图vsession_wait
• GRID CONTROL监控工具

熟悉慢SQL日志分析工具吗？

如果开启了慢SQL日志后，可能会有大量的慢SQL日志产生，此时再用肉眼看，那是不太现实的，所以大佬们就给我搞了个工具：mysqldumpslow。

mysqldumpslow能将相同的慢SQL归类，并统计出相同的SQL执行的次数，每次执行耗时多久、总耗时，每次返回的行数、总行数，以及客户端连接信息等。

通过命令

mysqldumpslow --help

可以看到相关参数的说明：

~# 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 指定输出的排序方式
   t  : 根据query time(执行时间)进行排序；
   at : 根据average query time(平均执行时间)进行排序；（默认使用的方式）
   l  : 根据lock time(锁定时间)进行排序；
   al : 根据average lock time(平均锁定时间)进行排序；
   r  : 根据rows(扫描的行数)进行排序；
   ar : 根据average rows(扫描的平均行数)进行排序；
   c  : 根据日志中出现的总次数进行排序；
-t 指定输出的sql语句条数；
-a 不进行抽象显示(默认会将数字抽象为N，字符串抽象为S)；
-g 满足指定条件，与grep相似；
-h 用来指定主机名(指定打开文件，通常慢查询日志名称为“主机名-slow.log”，用-h exp则表示打开exp-slow.log文件)；

使用方式

mysqldumpslow常用的使用方式如下：

# mysqldumpslow -s c slow.log

如上一条命令，应该是mysqldumpslow最简单的一种形式，其中-s参数是以什么方式排序的意思，c指代的是以总数从大到小的方式排序。-s的常用子参数有：c: 相同查询以查询条数和从大到小排序。t: 以查询总时间的方式从大到小排序。l: 以查询锁的总时间的方式从大到小排序。at: 以查询平均时间的方式从大到小排序。al: 以查询锁平均时间的方式从大到小排序。

同样的，还可以增加其他参数，实际使用的时候，按照自己的情况来。

其他常用方式：

# 得到返回记录集最多的10 个SQL
mysqldumpslow -s r -t 10 /var/lib/mysql/atguigu-slow.log

# 得到访问次数最多的10 个SQL
mysqldumpslow -s c -t 10 /var/lib/mysql/atguigu-slow.log

# 得到按照时间排序的前10 条里面含有左连接的查询语句
mysqldumpslow -s t -t 10 -g "left join" /var/lib/mysql/atguigu-slow.log

# 另外建议在使用这些命令时结合| 和more 使用，否则有可能出现爆屏情况
mysqldumpslow -s r -t 10 /var/lib/mysql/atguigu-slow.log | more

接下，我们来个实际操作。

实操

root@yunzongjitest1:~# mysqldumpslow -s t -t 3

Reading mysql slow query log from /var/lib/mysql/exp-slow.log /var/lib/mysql/yunzongjitest1-slow.log
Count: 464  Time=18.35s (8515s)  Lock=0.01s (3s)  Rows=90884.0 (42170176), root[root]@localhost
  select ************

Count: 38  Time=11.22s (426s)  Lock=0.00s (0s)  Rows=1.0 (38), root[root]@localhost
  select *********** not like 'S'

Count: 48  Time=5.07s (243s)  Lock=0.02s (1s)  Rows=1.0 (48), root[root]@localhost
  select ********='S'

这其中的SQL语句因为涉及某些信息，所以我都用*号将主体替换了，如果希望得到具体的值，使用-a参数。

使用mysqldumpslow查询出来的摘要信息，包含了这些内容：

Count: 464 ：表示慢查询日志总共记录到这条sql语句执行的次数；

Time=18.35s (8515s)：18.35s表示平均执行时间（-s at)，8515s表示总的执行时间(-s t)；

Lock=0.01s (3s)：与上面的Time相同，第一个表示平均锁定时间(-s al)，括号内的表示总的锁定时间(-s l)（也有另一种说法，说是表示的等待锁释放的时间）；

Rows=90884.0 (42170176): 第一个值表示扫描的平均行数(-s ar)，括号内的值表示扫描的总行数(-s r)。

是不是

so easy!!!!

来源：
https://mp.weixin.qq.com/s/Cuv8zwDmFSzp9DGqEw8zhg

作者：田维常