一、慢查詢日志概念
MySQL的慢查詢日志是MySQL提供的一種日志記錄，它用來記錄在MySQL中響應時間超過閥值的語句，具體指運行時間超過long_query_time值的SQL，則會被記錄到慢查詢日志中。long_query_time的默認值為10，意思是運行10S以上的語句。默認情況下，Mysql數據庫並不啟動慢查詢日志，需要我們手動來設置這個參數，當然，如果不是調優需要的話，一般不建議啟動該參數，因為開啟慢查詢日志會或多或少帶來一定的性能影響。慢查詢日志支持將日志記錄寫入文件，也支持將日志記錄寫入數據庫表。
二、慢查詢日志相關參數
MySQL 慢查詢的相關參數解釋：
slow_query_log ：是否開啟慢查詢日志，1表示開啟，0表示關閉。 log-slow-queries ：舊版（5.6以下版本）MySQL數據庫慢查詢日志存儲路徑。可以不設置該參數，系統則會默認給一個缺省的文件host_name-slow.log slow-query-log-file：新版（5.6及以上版本）MySQL數據庫慢查詢日志存儲路徑。可以不設置該參數，系統則會默認給一個缺省的文件host_name-slow.log long_query_time ：慢查詢閾值，當查詢時間多於設定的閾值時，記錄日志。 log_queries_not_using_indexes：未使用索引的查詢也被記錄到慢查詢日志中（可選項）。 log_output：日志存儲方式。log_output='FILE'表示將日志存入文件，默認值是'FILE'。log_output='TABLE'表示將日志存入數據庫，這樣日志信息就會被寫入到mysql.slow_log表中。MySQL數據庫支持同時兩種日志存儲方式，配置的時候以逗號隔開即可，如：log_output='FILE,TABLE'。日志記錄到系統的專用日志表中，要比記錄到文件耗費更多的系統資源，因此對於需要啟用慢查詢日志，又需要能夠獲得更高的系統性能，那么建議優先記錄到文件。
三、慢查詢日志配置
1.默認情況下slow_query_log的值為OFF，表示慢查詢日志是禁用的，可以通過設置slow_query_log的值來開啟，如下所示：
mysql> show variables like '%slow_query_log%'; +---------------------+---------------------------------+ | Variable_name | Value | +---------------------+---------------------------------+ | slow_query_log | OFF | | slow_query_log_file | /var/run/mysqld/mysqld-slow.log | +---------------------+---------------------------------+ 2 rows in set (0.00 sec) mysql> set global slow_query_log=1; Query OK, 0 rows affected (0.00 sec) mysql> show variables like '%slow_query_log%'; +---------------------+---------------------------------+ | Variable_name | Value | +---------------------+---------------------------------+ | slow_query_log | ON | | slow_query_log_file | /var/run/mysqld/mysqld-slow.log | +---------------------+---------------------------------+ 2 rows in set (0.00 sec) mysql>
2.使用set global slow_query_log=1開啟了慢查詢日志只對當前數據庫生效，如果MySQL重啟后則會失效。如果要永久生效，就必須修改配置文件my.cnf（其它系統變量也是如此）。如下所示：
修改my.cnf文件，在mysqld下增加或修改參數slow_query_log 和slow_query_log_file后，然后重啟MySQL服務器，如下所示
slow_query_log =1 slow_query_log_file=/tmp/mysql_slow.log
查詢驗證已經是打開狀態
mysql> show variables like 'slow_query%'; +---------------------+---------------------------------+ | Variable_name | Value | +---------------------+---------------------------------+ | slow_query_log | ON | | slow_query_log_file | /var/run/mysqld/mysqld-slow.log | +---------------------+---------------------------------+ 2 rows in set (0.00 sec)
關於慢查詢的參數slow_query_log_file ，它指定慢查詢日志文件的存放路徑，如果沒有指定參數slow_query_log_file系統默認會給一個缺省的文件host_name-slow.log
mysql> show variables like 'slow_query_log_file'; +---------------------+---------------------------------+ | Variable_name | Value | +---------------------+---------------------------------+ | slow_query_log_file | /var/run/mysqld/mysqld-slow.log | +---------------------+---------------------------------+ 1 row in set (0.00 sec)
3.開啟了慢查詢日志后，什么樣的SQL才會記錄到慢查詢日志里面呢？
這個是由參數long_query_time控制，默認情況下long_query_time的值為10秒，可以使用命令修改，也可以在my.cnf參數里面修改。關於運行時間正好等於long_query_time的情況，並不會被記錄下來。也就是說，在mysql源碼里是判斷大於long_query_time，而非大於等於。從MySQL 5.1開始，long_query_time開始以微秒記錄SQL語句運行時間，之前僅用秒為單位記錄。如果記錄到表里面，只會記錄整數部分，不會記錄微秒部分。
mysql> show variables like 'slow_query_log_file'; +---------------------+---------------------------------+ | Variable_name | Value | +---------------------+---------------------------------+ | slow_query_log_file | /var/run/mysqld/mysqld-slow.log | +---------------------+---------------------------------+ 1 row in set (0.00 sec) mysql> show variables like 'long_query_time%'; +-----------------+-----------+ | Variable_name | Value | +-----------------+-----------+ | long_query_time | 10.000000 | +-----------------+-----------+ 1 row in set (0.00 sec) mysql> set global long_query_time=5; Query OK, 0 rows affected (0.00 sec) mysql> show variables like 'long_query_time%'; +-----------------+-----------+ | Variable_name | Value | +-----------------+-----------+ | long_query_time | 10.000000 | +-----------------+-----------+ 1 row in set (0.00 sec)
如上所示，修改了變量long_query_time，但是查詢變量long_query_time的值還是10，難道沒有修改到呢？注意：使用命令 set global long_query_time=5;修改后，需要重新連接或新開一個會話才能看到修改值。使用show variables like 'long_query_time'查看的為當前會話的變量值，可以不用重新連接會話，而是用show global variables like 'long_query_time'; 如下所示：
mysql> show variables like 'long_query_time%'; +-----------------+----------+ | Variable_name | Value | +-----------------+----------+ | long_query_time | 5.000000 | +-----------------+----------+ 1 row in set (0.00 sec) mysql> show global variables like 'long_query_time'; +-----------------+----------+ | Variable_name | Value | +-----------------+----------+ | long_query_time | 5.000000 | +-----------------+----------+ 1 row in set (0.00 sec) mysql> set global long_query_time=10; Query OK, 0 rows affected (0.00 sec) mysql> show variables like 'long_query_time%'; +-----------------+----------+ | Variable_name | Value | +-----------------+----------+ | long_query_time | 5.000000 | +-----------------+----------+ 1 row in set (0.00 sec) mysql> show global variables like 'long_query_time'; +-----------------+-----------+ | Variable_name | Value | +-----------------+-----------+ | long_query_time | 10.000000 | +-----------------+-----------+ 1 row in set (0.00 sec)
4.慢查詢驗證
mysql> select sleep(12); +-----------+ | sleep(12) | +-----------+ | 0 | +-----------+ 1 row in set (12.28 sec)
在MySQL里面執行上面的SQL語句，然后去檢查對應的慢查詢日志，就會發現類似下面這樣的信息。
[root@localhost ~]# cat /var/run/mysqld/mysqld-slow.log /usr/libexec/mysqld, Version: 5.1.73-log (Source distribution). started with: Tcp port: 0 Unix socket: /var/lib/mysql/mysql.sock Time Id Command Argument # Time: 210610 19:46:03 # User@Host: root[root] @ localhost [] # Query_time: 12.279037 Lock_time: 0.000000 Rows_sent: 1 Rows_examined: 0 SET timestamp=1623325563; select sleep(12);
5.log_output 參數指定日志的存儲方式。
log_output='FILE'表示將日志存入文件，默認值是'FILE'。log_output='TABLE'表示將日志存入數據庫，這樣日志信息就會被寫入到mysql.slow_log表中。MySQL數據庫同時支持兩種日志存儲方式，配置的時候以逗號隔開即可，如：log_output='FILE,TABLE'。日志記錄到系統的專用日志表中，要比記錄到文件耗費更多的系統資源，因此對於需要啟用慢查詢日志，又需要能夠獲得更高的系統性能，那么建議優先記錄到文件。
mysql> show variables like '%log_output%'; +---------------+-------+ | Variable_name | Value | +---------------+-------+ | log_output | FILE | +---------------+-------+ 1 row in set (0.00 sec) mysql> set global log_output='TABLE'; Query OK, 0 rows affected (0.00 sec) mysql> show variables like '%log_output%'; +---------------+-------+ | Variable_name | Value | +---------------+-------+ | log_output | TABLE | +---------------+-------+ 1 row in set (0.00 sec) mysql> select sleep(15); +-----------+ | sleep(15) | +-----------+ | 0 | +-----------+ 1 row in set (15.01 sec) mysql> select * from mysql.slow_log; +---------------------+---------------------------+------------+-----------+-----------+---------------+----+----------------+-----------+-----------+------------------+ | start_time | user_host | query_time | lock_time | rows_sent | rows_examined | db | last_insert_id | insert_id | server_id | sql_text | +---------------------+---------------------------+------------+-----------+-----------+---------------+----+----------------+-----------+-----------+------------------+ | 2021-06-10 19:52:32 | root[root] @ localhost [] | 00:00:15 | 00:00:00 | 1 | 0 | | 0 | 0 | 0 | select sleep(15) | +---------------------+---------------------------+------------+-----------+-----------+---------------+----+----------------+-----------+-----------+------------------+ 1 row in set (0.00 sec) mysql>
6.系統變量log-queries-not-using-indexes
系統變量log-queries-not-using-indexes表示：未使用索引的查詢也被記錄到慢查詢日志中（可選項）。如果調優的話，建議開啟這個選項。其次開啟了這個參數，其實使用full index scan的sql也會被記錄到慢查詢日志。
mysql> show variables like 'log_queries_not_using_indexes'; +-------------------------------+-------+ | Variable_name | Value | +-------------------------------+-------+ | log_queries_not_using_indexes | OFF | +-------------------------------+-------+ 1 row in set (0.00 sec) mysql> set global log_queries_not_using_indexes=1; Query OK, 0 rows affected (0.00 sec) mysql> show variables like 'log_queries_not_using_indexes'; +-------------------------------+-------+ | Variable_name | Value | +-------------------------------+-------+ | log_queries_not_using_indexes | ON | +-------------------------------+-------+ 1 row in set (0.00 sec)
7.系統變量log_slow_admin_statements
系統變量log_slow_admin_statements表示是否將慢管理語句例如OPTIMIZE、ANALYZE、ALTER等記入慢查詢日志
mysql> show variables like 'log-slow-admin-statements';
8.查詢有多少條慢查詢記錄
如果你想查詢有多少條慢查詢記錄，可以使用系統變量
mysql> show global status like '%Slow_queries%'; +---------------+-------+ | Variable_name | Value | +---------------+-------+ | Slow_queries | 2 | +---------------+-------+ 1 row in set (0.00 sec)
二、MySQL日志分析工具mysqldumpslow
mysqldumpslow是mysql自帶的慢查詢日志分析工具，可利用其在生產環境中分析日志，查找、分析SQL
[root@localhost ~]# 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: 訪問計數 l: 鎖定時間 r: 返回記錄 t: 查詢時間 al:平均鎖定時間 ar:平均返回記錄數 at:平均查詢時間 -t, 是top n的意思，即為只顯示前面n個查詢結果； -g, 后邊可以寫一個正則匹配模式，大小寫不敏感的；
實例：
1.獲取返回記錄集最多的5個SQL。
mysqldumpslow -s r -t 5 /var/run/mysqld/mysqld-slow.log
2.獲取訪問次數最多的5個SQL
mysqldumpslow -s c -t 5 /var/run/mysqld/mysqld-slow.log
3.獲取按照時間排序的前5條里面含有左連接的查詢語句
mysqldumpslow -s t -t 5 -g "left join" /var/run/mysqld/mysqld-slow.log