1. 程式人生 > >MySQL慢查詢日誌記錄和分析

MySQL慢查詢日誌記錄和分析

一、引言

在日常的開發中,有時候會收到使用者或者產品經理反饋說網站的響應速度有點慢,即使是管理系統頁面也會出現這種情況。導致網頁響應速度慢的原因有很多,比如:資料表的某些欄位沒有建立索引,或者說是建立了索引,但索引失效,又或者說肯能因為最近來了一個新人同事,把某一條的SQL語句寫的執行效率很低。這個時候,為了找到原因,看看是不是SQL的問題,這時我們可以通過開啟MySQL的慢查詢日誌,來監控測試環境或者生產環境上有沒有執行特別慢的SQL,如果有,我們可以定位到是哪一條SQL,從而進而優化,這就需要用到慢查詢日誌。

二、慢查詢日誌

MySQL的慢查詢日誌是MySQL提供的一種日誌記錄,它用來記錄在MySQL中響應時間超過閥值的SQL語句,具體是指執行時間超過 long_query_time 值的SQL,這樣的SQL則會被記錄到慢查詢日誌中。long_query_time 的預設值為10,意思是執行10S以上的SQL語句。

預設情況下,MySQL資料庫並沒有開啟慢查詢日誌,需要我們手動來設定這個引數。當然,如果不是調優需要的話,一般不建議啟動該引數,因為開啟慢查詢日誌會或多或少帶來一定的效能影響。
慢查詢日誌支援將日誌記錄寫入檔案,也支援將日誌記錄寫入資料庫表。

1、慢查詢日誌相關引數

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 :慢查詢閾值,當查詢時間多於設定的閾值時,該SQL會被記錄日誌中或者資料表中。

  • log_queries_not_using_indexes:未使用索引的查詢也被記錄到慢查詢日誌中(可選項)。

  • log_output:日誌儲存方式。log_output=‘FILE’表示將日誌存入檔案,預設值是’FILE’。log_output='TABLE’表示將日誌存入資料庫,這樣日誌資訊就會被寫入到mysql.slow_log表中。

    MySQL資料庫支援同時兩種日誌儲存方式,配置的時候以逗號隔開即可,如:log_output=‘FILE,TABLE’。日誌記錄到系統的專用日誌表中,要比記錄到檔案耗費更多的系統資源,因此對於需要啟用慢查詢日誌,又需要能夠獲得更高的系統性能,那麼建議優先記錄到檔案。

2、慢查詢日誌配置

在MySQL配置檔案中的【mysqld】節點下做如下配置:

slow_query_log=1;											//開啟慢查詢日誌
slow_query_log_file="/usr/local/mysql/data/"    			//慢查詢如日存放的路徑
long_query_time=3;											//慢查詢SQL記錄的時間
log_output=FILE												//以檔案方式記錄
3、引數配置說明
(1)、slow_query_log

預設情況下 slow_query_log 的值為OFF,表示慢查詢日誌是禁用的,可以通過設定 slow_query_log 的值來開啟,如下所示:
在這裡插入圖片描述
在這裡插入圖片描述
注意:
使用 set global slow_query_log=1 開啟了慢查詢日誌只對當前資料庫生效,MySQL重啟後該設定則會失效,必須再次重新設定。
如果要該設定永久生效,就必須修改配置檔案my.ini(其它系統變數也是如此,windows下MySQL配置檔案為my.ini檔案,linux下為my.cnf)

修改my.ini檔案,增加或修改引數 slow_query_log 和 slow_query_log_file 後,然後重啟MySQL伺服器,如下所示:

slow_query_log =1
slow_query_log_file=C:\ProgramData\MySQL\MySQL Server 5.1\data\Think-PC-slow.log

在這裡插入圖片描述

慢查詢的引數 slow_query_log_file ,它指定慢查詢日誌檔案的存放路徑,系統預設會給一個預設的檔案 host_name-slow.log(主機名-slow.log檔案)

(2)、long_query_time

那麼開啟了慢查詢日誌後,什麼樣的SQL才會記錄到慢查詢日誌裡面呢? 這個是由引數long_query_time 控制,預設情況下 long_query_time 的值為10秒,可以使用命令修改,也可以在 my.ini 或 my.cnf 引數裡面修改。關於執行時間正好等於 long_query_time 的情況,並不會被記錄下來。也就是說,在mysql原始碼裡是判斷大於 long_query_time,而非大於等於。從MySQL 5.1開始,long_query_time 開始以微秒記錄SQL語句執行時間,之前僅用秒為單位記錄。如果記錄到表裡面,只會記錄整數部分,不會記錄微秒部分。
在這裡插入圖片描述
在這裡插入圖片描述

如上圖所示,修改了變數 long_query_time,但是再次查詢變數 long_query_time 的值還是10,難道沒有修改到呢?注意:使用命令 set global long_query_time=4 修改後,需要重新連線或新開一個會話才能看到修改值。你也可以不用重新連線會話,而是用 show global variables like ‘long_query_time’; 如下所示:

方式一:退出此次連線,重新進入
在這裡插入圖片描述

方式二:使用show global variables like ‘long_query_time’;命令
在這裡插入圖片描述

(3)、log_output

log_output 引數是指定日誌的儲存方式。log_output=‘FILE’ 表示將日誌存入檔案,預設值是’FILE’。log_output=‘TABLE’ 表示將日誌存入資料庫,這樣日誌資訊就會被寫入到 mysql.slow_log 表中。MySQL資料庫支援同時兩種日誌儲存方式,配置的時候以逗號隔開即可,如:log_output=‘FILE,TABLE’。日誌記錄到系統的專用日誌表中,要比記錄到檔案耗費更多的系統資源,因此對於需要啟用慢查詢日誌,又需要能夠獲得更高的系統性能,那麼建議優先記錄到檔案。
在這裡插入圖片描述

(4)、log-queries-not-using-indexes

系統變數 log-queries-not-using-indexes:未使用索引的查詢也被記錄到慢查詢日誌中(可選項)。如果調優的話,建議開啟這個選項。另外,開啟了這個引數,其實使用 full index scan 的SQL也會被記錄到慢查詢日誌。
在這裡插入圖片描述
在這裡插入圖片描述

(5)、slow_queries

可以使用下面命令來檢視慢查詢日誌裡面有幾條被記錄下來的慢SQL,初始時為0:

show global status like '%slow_queries%';

在這裡插入圖片描述

下面我們用select sleep()來模擬慢查詢SQL,多執行幾次;
在這裡插入圖片描述

執行完後,執行上面的命令檢視一下有幾條慢SQL被記錄,再去存放了慢查詢日誌路徑下面,去找日誌,日誌記錄如下圖(只截了一條):
在這裡插入圖片描述
在這裡插入圖片描述

三、日誌分析工具mysqldumpslow

在生產環境中,如果要手工分析日誌,查詢、分析SQL,顯然是個體力活,MySQL提供了日誌分析工具mysqldumpslow。

1、引數的意義
引數 意義
s 表示按照何種方式排序
c 訪問計數
l 鎖定時間
r 返回記錄
t 查詢時間
al 平均鎖定時間
ar 平均返回記錄數
at 平均查詢時間
-t 是top n的意思,即為返回前面多少條的資料
-g 後邊可以寫一個正則匹配模式,大小寫不敏感的
2、示例

(1)、得到返回記錄集最多的10個SQL

mysqldumpslow -s r -t 10 /usr/mysql/mysql_slow.log

(2)、得到訪問次數最多的10個SQL

mysqldumpslow -s c -t 10 /usr/mysql/mysql_slow.log

(3)、得到按照時間排序的前10條裡面含有左連線的查詢語句

mysqldumpslow -s t -t 10 -g “left join” /usr/mysql/mysql_slow.log

(4)、另外建議在使用這些命令時結合 | 和more 使用 ,否則有可能出現刷屏的情況

mysqldumpslow -s r -t 20 /usr/mysql/mysql-slow.log | more

四、小結

(1)、預設情況下,MySQL資料庫沒有開啟慢查詢日誌,需要我們手動來設定這個引數。
(2)、如果不是調優需要的話,一般不建議啟動該引數,因為開啟慢查詢日誌會或多或少帶來一定的效能影響。