開啟慢SQL記錄
- 為什么要開啟慢SQL記錄
mysql在運行過程中,某些SQL可能會執(zhí)行較長時間,我們通過配置一些東西,把這些SQL記錄下來,以便我們分析和優(yōu)化SQL。 - 首先先查看是否已經開啟了記錄設置
mysql> show variables like '%query%';
+------------------------------+-------------------------+
| Variable_name | Value |
+------------------------------+-------------------------+
| long_query_time | 1.000000 |
| slow_query_log | ON |
| slow_query_log_file | /var/log/mysql_slow.log |
+------------------------------+-------------------------+
slow_query_log ON為已開啟記錄慢SQL,OFF表示關閉
long_query_time 閾值時間,當執(zhí)行時間超過這個值的SQL會被記錄下來
slow_query_log_file 記錄的位置
使用命令行設置
set global slow_query_log = ON;
set long_query_time = 1;
set slow_query_log_file = '/var/log/mysql_slow.log';
通過這種方式設置,當mysql重新啟動后設置會丟失,需要重新設置通過修改my.conf設置
slow_query_log = ON
long_query_time = 1
slow_query_log_file = /var/log/mysql_slow.log
log_output=FILE,TABLE
在mysql的配置文件-my.cnf 的[mysqld]后面添加以上參數即可。log_output表示記錄的方式,FILE表示文件,TABLE表示數據庫(mysql.slow_log表)
- 需要注意的地方
必須保證slow_query_log_file文件存在,且mysql有讀寫權限才行。筆者在實驗的時候就是因為沒有權限,導致每次設置都不成功。
記錄格式
# Time: 2018-07-07T09:00:28.454918Z
# User@Host: huangzp[huangzp] @ [119.145.99.50] Id: 12
# Query_time: 45.012748 Lock_time: 0.000220 Rows_sent: 254 Rows_examined: 1356773
SET timestamp=1530954028;
select userId from chatting_log_201702 where apType = 2 and userId = 'anywillfour';
第一行:SQL開始執(zhí)行的時間
第二行:SQL執(zhí)行的用戶
第三行:Query_time(SQL查詢時間), Lock_time(鎖時間),Rows_sent(返回的行數), Rows_examined(查詢的行數)
第四行:SQL開始執(zhí)行的時間對應的時間戳
第五行:SQL詳情
記錄分析(mysqldumpslow)
mysqldumpslow -s t -t 10 -g 'log' mysql_slow.log
MySQL自帶的慢SQL分析工具
-s : 按照哪種規(guī)則排序
c: 訪問計數
l: 鎖定時間
r: 返回記錄
t: 查詢時間
al:平均鎖定時間
ar:平均返回記錄數
at:平均查詢時間
-t : 顯示前幾個記錄
-g : 有點像grep, 后跟正則
[root@VM_centos log]# mysqldumpslow -s t -t 10 mysql_slow.log
Reading mysql slow query log from mysql_slow.log
Count: 1 Time=157.27s (157s) Lock=0.00s (0s) Rows=1153145.0 (1153145), huangzp[huangzp]@[127.0.0.1]
select userId from chatting_log_201702 where apType = N
Count: 2 Time=69.46s (138s) Lock=0.00s (0s) Rows=1.0 (2), huangzp[huangzp]@[127.0.0.1]
select count(*) from chatting_log_201702 where apType = N and userId = 'S'
Count: 1 Time=45.01s (45s) Lock=0.00s (0s) Rows=254.0 (254), huangzp[huangzp]@[127.0.0.1]
select userId from chatting_log_201702 where apType = N and userId = 'S'
Count: 1 Time=42.18s (42s) Lock=0.00s (0s) Rows=49906.0 (49906), huangzp[huangzp]@[127.0.0.1]
select * from chatting_log_201702 where roomId = 'S'
Count: 1 Time=38.38s (38s) Lock=0.00s (0s) Rows=10732.0 (10732), huangzp[huangzp]@[127.0.0.1]
select userId from chatting_log_201702 where apType = N and roomId = 'S'
Count: 1 Time=8.02s (8s) Lock=0.00s (0s) Rows=1.0 (1), huangzp[huangzp]@[127.0.0.1]
select count(*) from chatting_log_201702 where userId = 'S'
以上就是顯示按照查詢時間排序前十的SQL。