mysql性能优化之慢查询日志分析

一、慢查询

在我们的项目中, 有很多需要优化的sql语句, 比如多表连接查询, 通常很耗时, 那么怎么知道哪些sql很耗时从而有针对性地进行优化呢

mysql提供了记录慢查询日志的功能, 通过记录sql语句中超过指定阈值的查询语句, 找到耗时的sql, 从而对这些语句进行分析和优化

例如: 我们设置阈值为1s, 当某条sql语句的执行时间超过了1s, 那么这条语句就会被写入到慢查询配置的日志文件中

二、查看mysql慢查询功能的开启状态

  1. 查看是否已开启: show variables like '%slow_query%'
    image.png

可以看到, 已经开启了

  • slow_query_log: 是否开启慢日志查询功能, ON开启, OFF关闭
  • slow_query_log_file: 慢查询日志的记录文件
  1. 查看阈值: show variables like '%long_query_time%'
    image.png
  • long_query_time: 慢查询日志的时间阈值, 当超过这个阈值时, 慢日志才会被记录, 配置的值可以为0(任何的sql语句都记录下来), 或者>0(具体的阈值), 该配置项是以秒为单位的, 并且可以设置为小数

我这里已经修改为0了, 即所有语句都将记录到日志文件, 默认为10s

三、如何开启慢查询

有2种方式

  1. 临时开启, mysql重启后失效
set global slow_query_log = ON;
set global long_query_time = 0;
  1. 永久开启, 通过修改配置文件, 永久生效
    找到my.ini配置文件, 修改:
slow-query-log=1
slow_query_log_file="C:/ProgramData/MySQL/MySQL Server 5.7/slow_query_log/WANGZAIPLUS-slow.log"
long_query_time=0

说明:

  • slow-query-log=1: 1表示开启, 0表示关闭
  • slow_query_log_file: 即日志文件保存路径, 自己指定即可
  • long_query_time=0: 为了测试, 修改为0, 记录所有, 具体阈值根据项目实际情况设定
  • 关于mysql配置文件位置, windows系统是my.ini, 文件位置: C:\Program Files\MySQL\MySQL Server 5.7C:\ProgramData\MySQL\MySQL Server 5.7
  • linux系统是my.cnf, 文件位置: /etc/my.cnf
  1. 修改配置文件后, 需要重启mysql

四、查看慢查询日志文件

重启mysql后, 找到slow_query_log_file配置的日志文件, 由于阈值为0, 里面记录了所有sql, 测试一下, 写一个查询语句

image.png

再看log文件, 记录了一模一样的sql语句


image.png

简单解释下

  • # Time: sql执行的时间, 也是该日志记录的时间
  • # User@Host: 登录用户的连接信息, 用户和主机
  • 第三行:
    Query_time: sql执行消耗的时间
    Lock_time: 锁表的时间
    Rows_sent: 返回的行数
    Rows_examined: 扫描的行数
  • SET timestamp=1568771109;: 设置时间戳
  • SELECT id, username ...: 执行的sql语句

五、通过mysqldumpslow工具分析慢查询日志

1、安装perl环境

通过-slow.log日志文件可以看到, 每一条sql都用这样的格式记录着, 随着sql越来越多, 到时肯定看的眼花缭乱, 所以, 我们可以借助mysql提供的mysqldumpslow工具对慢查询日志进行统计和分析

找到mysql安装目录下的bin目录, 有一个mysqldumpslow.pl文件, 这是一个perl文件

image.png

运行它需要perl环境, 所以, 先安装环境, 具体安装教程可参考菜鸟教程: https://www.runoob.com/perl/perl-environment.html

我用的是windows系统, 直接通过: http://strawberryperl.com/下载perl文件安装就行了, 环境变量也自动配置好了

安装完成后查看: perl -version

image.png

搞定

2、运行mysqldumpslow.pl工具分析慢查询日志

首先说明各个文件路径

  • mysql安装目录: C:\Program Files\MySQL\MySQL Server 5.7\
  • 慢查询日志文件保存目录: C:\ProgramData\MySQL\MySQL Server 5.7\slow_query_log\

进入到mysql安装目录下的bin目录: cd /d C:\Program Files\MySQL\MySQL Server 5.7\bin

运行命令: mysqldumpslow.pl -a -s at C:\ProgramData\MySQL\MySQL Server 5.7\slow_query_log\WANGZAIPLUS-slow.log > C:\Users\wangzaiplus\Desktop\slow.txt

运行命令说明:

  • 格式: mysqldumpslow.pl + 输出参数 + 慢查询日志路径 + 文件保存路径
  • 输出参数说明: -a: 禁止将sql中参数转换为NS, 如不加此参数, 类似于select * from user where username = 'wangzai'中的参数wangzai就会直接被替换为S, 即: select * from user where username = 'S'
  • -s at: -s: 排序, a: 倒序, t: 执行时间, 合起来就是按sql的执行时间倒序排序
  • 参数还有很多, 可以通过mysqldumpslow.pl --help查看

运行后发现出错, 如下图:

image.png

原因: 慢查询日志文件C:\ProgramData\MySQL\MySQL Server 5.7\slow_query_log\WANGZAIPLUS-slow.log路径包含空格... 找不到该文件, 坑, 将-slow.log文件保存到一个不含空格的目录就行了, 如:
D:\WANGZAIPLUS-slow.log, 或者, 最好的是在my.ini文件指定slow_query_log_file时不要包含空格

再来执行一遍: mysqldumpslow.pl -a -s at D:\WANGZAIPLUS-slow.log > C:\Users\wangzaiplus\Desktop\slow.txt

image.png

执行成功, 桌面也生成了slow.txt文件

3、分析slow.txt文件

查看slow.txt文件, 如图

image.png

说明:

  • Count: 6: 该sql执行了多少次
  • Time=12.42s: 每次执行消耗的时间
  • (74s): 总共执行的时间
  • Lock=0.00s (0s): 锁表的时间
  • Rows=36354.0: 每次返回的记录数
  • (218124) : 总共返回的记录数
  • root[root]@localhost: username[password]@[127.0.0.1]
  • select * from tbcategory ...: 执行的sql语句

可以看到, 该slow.txt文件是按照sql的执行时间倒序排序的, 可以通过输出参数指定排序规则或其他规则

OK, 目前为止, 我们已经开启慢查询日志功能, 并通过mysql工具mysqldumpslow.pl对慢查询日志文件进行统计和输出, 当然, 还有其他分析工具, 这里就不多说了

六、explain优化sql

五.3分析slow.txt文件中, 可以看到

SELECT
*
FROM
`tbcategory` t1
LEFT JOIN tbcategory t2 
ON t1._id = t2.parentId

这条查询语句执行了11.75s, 通过explain SELECT * FROM tbcategory t1 LEFT JOIN tbcategory t2 ON t1._id = t2.parentId发现, 没有使用任何索引, 查询太慢

image.png

parentId添加索引: create index idx_pid on tbcategory(parentId), 再次查询, 执行时间: 0.082s, 再次explain:

image.png

可以看到, 通过对慢sql进行优化, 可以大幅减少查询时间

七、总结

本文思路很简单:

  1. 修改my.ini配置文件开启慢查询日志功能
  2. 直接查看慢查询日志文件
  3. 通过mysql系统工具mysqldumpslow.pl统计分析日志文件, 找出耗时长的慢sql语句
  4. 通过explain分析慢查询语句, 并对其进行优化

关于mysql性能优化有很多可以深入的, 这里就分享慢查询的相关知识, 如有不对的地方, 欢迎小伙伴指出, 谢谢

©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 155,704评论 4 358
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 66,084评论 1 286
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 105,560评论 0 237
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 43,360评论 0 201
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 51,686评论 3 285
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 40,150评论 1 204
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 31,528评论 2 306
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 30,256评论 0 193
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 33,878评论 1 235
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 30,215评论 2 239
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 31,757评论 1 255
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 28,121评论 2 249
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 32,659评论 3 228
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 25,932评论 0 8
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 26,638评论 0 191
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 35,159评论 2 263
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 35,112评论 2 258

推荐阅读更多精彩内容