MySql開啟慢查詢日誌並使用pt-query-digest 分析

来源:https://www.cnblogs.com/lgjlife/archive/2019/02/27/10447675.html
-Advertisement-
Play Games

慢查詢日誌會將查詢過程中超出你設置的時間的查詢記錄下來，以便供開發者進行分析和優化。 1. 開啟慢查詢 1.1 查看當前設置 輸出 三個參數 slow_query_log ON/OFF ,使能開關 slow_query_log_file 慢查詢日誌目錄和文件名稱 long_query_time 超過 ...


慢查詢日誌會將查詢過程中超出你設置的時間的查詢記錄下來，以便供開發者進行分析和優化。

 

1. 開啟慢查詢

1.1 查看當前設置

mysql> show variables like "%query%";

輸出

+------------------------------+-----------------------------------------+
| Variable_name                | Value                                   |
+------------------------------+-----------------------------------------+
| binlog_rows_query_log_events | OFF                                     |
| ft_query_expansion_limit     | 20                                      |
| have_query_cache             | YES                                     |
| long_query_time              | 10.000000                               |
| query_alloc_block_size       | 8192                                    |
| query_cache_limit            | 1048576                                 |
| query_cache_min_res_unit     | 4096                                    |
| query_cache_size             | 16777216                                |
| query_cache_type             | OFF                                     |
| query_cache_wlock_invalidate | OFF                                     |
| query_prealloc_size          | 8192                                    |
| slow_query_log               | OFF                                     |
| slow_query_log_file          | /var/lib/mysql/lgj-Lenovo-G470-slow.log |
+------------------------------+-----------------------------------------+

三個參數

  • slow_query_log   ON/OFF ,使能開關
  • slow_query_log_file   慢查詢日誌目錄和文件名稱
  • long_query_time    超過該時間則進行記錄，5.1之前只設置到秒，5.1開始支持毫秒。

註意，開啟慢查詢會影響性能，因此應當在某一段時間內開啟，記錄一段時間後關閉掉。

1.2 配置

lgj@lgj-Lenovo-G470:~/db-analysis$ whereis mysql
mysql: /usr/bin/mysql /usr/lib/mysql /etc/mysql /usr/local/mysql /usr/share/mysql /usr/share/man/man1/mysql.1.gz

我的配置文件在/etc/mysql目錄下的my.cnf

配置

[mysqld]
port=3307
skip-grant-tables
!includedir /etc/mysql/conf.d/
!includedir /etc/mysql/mysql.conf.d/

#打開慢查詢
slow_query_log = ON
#設置超時時間為0，也就是記錄所有的查詢
long_query_time = 0

設置完後保存，重新啟動mysql

service mysql  restart

重新查看參數，已經更改。

mysql> show variables like "%query%";
+------------------------------+-----------------------------------------+
| Variable_name                | Value                                   |
+------------------------------+-----------------------------------------+
| binlog_rows_query_log_events | OFF                                     |
| ft_query_expansion_limit     | 20                                      |
| have_query_cache             | YES                                     |
| long_query_time              | 0.000000                                |
| query_alloc_block_size       | 8192                                    |
| query_cache_limit            | 1048576                                 |
| query_cache_min_res_unit     | 4096                                    |
| query_cache_size             | 16777216                                |
| query_cache_type             | OFF                                     |
| query_cache_wlock_invalidate | OFF                                     |
| query_prealloc_size          | 8192                                    |
| slow_query_log               | ON                                      |
| slow_query_log_file          | /var/lib/mysql/lgj-Lenovo-G470-slow.log |
+------------------------------+-----------------------------------------+

1.3 查詢

 隨便執行一條查詢語句，然後查看慢查詢日誌。

/usr/sbin/mysqld, Version: 5.7.25-0ubuntu0.18.04.2 ((Ubuntu)). started with:
Tcp port: 3306  Unix socket: /var/run/mysqld/mysqld.sock
Time                 Id Command    Argument
/usr/sbin/mysqld, Version: 5.7.25-0ubuntu0.18.04.2-log ((Ubuntu)). started with:
Tcp port: 3306  Unix socket: /var/run/mysqld/mysqld.sock
Time                 Id Command    Argument
/usr/sbin/mysqld, Version: 5.7.25-0ubuntu0.18.04.2-log ((Ubuntu)). started with:
Tcp port: 3306  Unix socket: /var/run/mysqld/mysqld.sock
Time                 Id Command    Argument
;
# Time: 2019-02-27T14:07:42.841770Z
# User@Host: skip-grants user[lgj] @ localhost []  Id:     2
# Query_time: 0.016232  Lock_time: 0.000000 Rows_sent: 0  Rows_examined: 0
SET timestamp=1551276462;
;
# Time: 2019-02-27T14:07:51.774192Z
# User@Host: skip-grants user[lgj] @ localhost []  Id:     2
# Query_time: 0.000485  Lock_time: 0.000221 Rows_sent: 1  Rows_examined: 1
SET timestamp=1551276471;
select * from user;
# Time: 2019-02-27T14:07:55.839223Z
# User@Host: skip-grants user[lgj] @ localhost []  Id:     2
# Query_time: 0.000564  Lock_time: 0.000259 Rows_sent: 1  Rows_examined: 1
SET timestamp=1551276475;
# 查詢語句
select * from user;
# 記錄時間
# Time: 2019-02-27T14:08:07.404666Z
# User@Host: skip-grants user[lgj] @ localhost []  Id:     2
#查詢時間   鎖表時間  
# Query_time: 0.006318  Lock_time: 0.000435 Rows_sent: 13  Rows_examined: 1026
SET timestamp=1551276487;

從上面可以獲知查詢時間和鎖表時間，但是如果文件比較大，查找時間最長的查詢將會非常麻煩，需要使用相關的工具來進行分析。

《高性能mysql》推薦使用 qt-query-digest 工具

2. qt-query-digest的使用

2.1 安裝

  •   創建目錄： mkdir db-analysis && cd db-analysis
  •  下載 pt-query-digest: curl -LO https://percona.com/get/pt-query-digest
  •   設置執行許可權：chmod +x pt-query-digest
  •   將慢查詢日誌的文件複製到當前的目錄下
  •   執行分析：  ./pt-query-digest lgj-Lenovo-G470-slow.log

結果

# 220ms user time, 10ms system time, 33.67M rss, 90.27M vsz
# Current date: Wed Feb 27 22:55:05 2019
# Hostname: lgj-Lenovo-G470
# Files: lgj-Lenovo-G470-slow.log
# Overall: 9 total, 7 unique, 0.24 QPS, 0.00x concurrency ________________
# Time range: 2019-02-27T14:07:29 to 2019-02-27T14:08:07
# Attribute          total     min     max     avg     95%  stddev  median
# ============     ======= ======= ======= ======= ======= ======= =======
# Exec time          185ms   225us   122ms    21ms   122ms    37ms     6ms
# Lock time            2ms       0   485us   186us   467us   166us   159us
# Rows sent             50       0      13    5.56   12.54    5.25    0.99
# Rows examine       2.03k       0   1.00k  230.44 1012.63  419.72    0.99
# Query size           198      11      32      22   31.70    7.35   17.65

# Profile
# Rank Query ID                          Response time Calls R/Call V/M   
# ==== ================================= ============= ===== ====== ===== 
#    1 0x751417D45B8E80EE5CBA2034458B...  0.1223 66.1%     1 0.1223  0.00 SHOW DATABASES
#    2 0xA11944C87A6A5C16FB38455BF703...  0.0320 17.3%     1 0.0320  0.00 SELECT
#    3 0x898255B1BE4F8C3044AE35A18286...  0.0155  8.4%     1 0.0155  0.00 ADMIN INIT DB
#    4 0xE77769C62EF669AA7DD5F6760F2D...  0.0134  7.3%     2 0.0067  0.00 SHOW VARIABLES
# MISC 0xMISC                             0.0018  1.0%     4 0.0004   0.0 <3 ITEMS>

# Query 1: 0 QPS, 0x concurrency, ID 0x751417D45B8E80EE5CBA2034458B5BC9 at byte 1471
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: all events occurred at 2019-02-27T14:07:42
# Attribute    pct   total     min     max     avg     95%  stddev  median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count         11       1
# Exec time     66   122ms   122ms   122ms   122ms   122ms       0   122ms
# Lock time      6   110us   110us   110us   110us   110us       0   110us
# Rows sent     20      10      10      10      10      10       0      10
# Rows examine   0      10      10      10      10      10       0      10
# Query size     7      14      14      14      14      14       0      14
# String:
# Databases    microblog
# Hosts        localhost
# Users        skip-grants user
# Query_time distribution
#   1us
#  10us
# 100us
#   1ms
#  10ms
# 100ms  ################################################################
#    1s
#  10s+
show databases\G

# Query 2: 0 QPS, 0x concurrency, ID 0xA11944C87A6A5C16FB38455BF7035609 at byte 1008
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: all events occurred at 2019-02-27T14:07:42
# Attribute    pct   total     min     max     avg     95%  stddev  median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count         11       1
# Exec time     17    32ms    32ms    32ms    32ms    32ms       0    32ms
# Lock time      0       0       0       0       0       0       0       0
# Rows sent      2       1       1       1       1       1       0       1
# Rows examine   0       0       0       0       0       0       0       0
# Query size     8      17      17      17      17      17       0      17
# String:
# Hosts        localhost
# Users        skip-grants user
# Query_time distribution
#   1us
#  10us
# 100us
#   1ms
#  10ms  ################################################################
# 100ms
#    1s
#  10s+
# EXPLAIN /*!50100 PARTITIONS*/
SELECT DATABASE()\G

# Query 3: 0 QPS, 0x concurrency, ID 0x898255B1BE4F8C3044AE35A182869033 at byte 1225
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: all events occurred at 2019-02-27T14:07:42
# Attribute    pct   total     min     max     avg     95%  stddev  median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count         11       1
# Exec time      8    15ms    15ms    15ms    15ms    15ms       0    15ms
# Lock time      0       0       0       0       0       0       0       0
# Rows sent      0       0       0       0       0       0       0       0
# Rows examine   0       0       0       0       0       0       0       0
# Query size    15      30      30      30      30      30       0      30
# String:
# Databases    microblog
# Hosts        localhost
# Users        skip-grants user
# Query_time distribution
#   1us
#  10us
# 100us
#   1ms
#  10ms  ################################################################
# 100ms
#    1s
#  10s+
administrator command: Init DB\G

# Query 4: 0.05 QPS, 0.00x concurrency, ID 0xE77769C62EF669AA7DD5F6760F2D2EBB at byte 775
# This item is included in the report because it matches --limit.
# Scores: V/M = 0.00
# Time range: 2019-02-27T14:07:30 to 2019-02-27T14:08:07
# Attribute    pct   total     min     max     avg     95%  stddev  median
# ============ === ======= ======= ======= ======= ======= ======= =======
# Count         22       2
# Exec time      7    13ms     6ms     7ms     7ms     7ms   559us     7ms
# Lock time     54   920us   435us   485us   460us   485us    35us   460us
# Rows sent     52      26      13      13      13      13       0      13
# Rows examine  98   2.00k   1.00k   1.00k   1.00k   1.00k       0   1.00k
# Query size    29      58      29      29      29      29       0      29
# String:
# Databases    microblog
# Hosts        localhost
# Users        skip-grants user
# Query_time distribution
#   1us
#  10us
# 100us
#   1ms  ################################################################
#  10ms
# 100ms
#    1s
#  10s+
show variables like "%query%"\G

 


您的分享是我們最大的動力!

-Advertisement-
Play Games
更多相關文章
  • rsync + git發佈項目
    前言： 更新項目的時候需要將更改的文件一一上傳，這樣比較麻煩，用版本控制器git +rsync 搭建一個發佈伺服器，以後發佈文件非常方便 首先說下，我這邊的更新流程，本地寫完之後，git push 到測試服，測試服測試完畢，運行腳本rsync.sh（此腳本先將代碼 git pull到測試服，再rsy ...
  • Linux問題分析或解決_更新
    1. ldd - 檢查依賴庫是否存在問題 問題：ssh連接不上，之前一直沒有問題，最近別人安裝了其他桌面（系統Ubuntu） 解決：通過ps -ef | grep ssh查看進程，發現沒有啟動； 重新安裝(sudo apt-get install openssh-server --reinstall ...
  • 進程與線程的創建
    // TmpThread.cpp : 定義控制台應用程式的入口點。 // #include "stdafx.h" #include #include using namespace std; //線程函數 DWORD WINAPI StartAddress(LPVOID lpParameter) {... ...
  • Linux如何查找文件的創建時間
    Linux的文件能否找到文件的創建時間取決於文件系統類型，在ext4之前的早期文件系統中（ext、ext2、ext3），文件的元數據不會記錄文件的創建時間，它只會記錄訪問時間、修改時間、該表時間。 Access 是訪問時間 Modify 是修改時間 Change 是改變時間 所以，對於ext、ext... ...
  • LVM 磁碟分區擴容
    前提：將磁碟中未分區磁碟進行分區操作 https://www.cnblogs.com/guoxiangyue/p/10033367.html 然後進行vg擴容 1 pvcreate /dev/sdc 2 lvs 3 vgextend centos /dev/sdc 4 lvextend -L +99 ...
  • Docker網路
    一、概述 docker的網路驅動有很多種方式，按照docker官網給出的網路解決方案就有6種，分別是：bridge、host、overlay、macvlan、none、Network plugins，每個網路都有自己的特點，當然應用場景也不同，比如當有多台主機上的docker容器需要容器間進行跨宿主 ...
  • 執行 sql 報錯未緩衝查詢，錯誤碼 2014
    ##sql語句為```SELECT COUNT(id) AS tp_count FROM `tableName` WHERE `status` = 0 AND `source` = 1 AND ( `end_time`-`add_time` > 2592000 AND `end_time`-`add... ...
  • Thinkphp5多資料庫切換
    在項目開發中需要Thinkphp5讀取多個資料庫的數據，本文詳細介紹Thinkphp5多資料庫切換 一、在database.php配置預設資料庫連接 'type' => 'mysql','hostname' => '伺服器IP地址','database' => '資料庫名','username' = ...
一周排行
    -Advertisement-
    Play Games
  • 移動開發（一）：使用.NET MAUI開發第一個安卓APP
    移動開發（一）：使用.NET MAUI開發第一個安卓APP 對於工作多年的C#程式員來說，近來想嘗試開發一款安卓APP，考慮了很久最終選擇使用.NET MAUI這個微軟官方的框架來嘗試體驗開發安卓APP，畢竟是使用Visual Studio開發工具，使用起來也比較的順手，結合微軟官方的教程進行了安卓 ...
  • wpf ToggleButton選中效果和一個登錄界面
    前言 QuestPDF 是一個開源 .NET 庫，用於生成 PDF 文檔。使用了C# Fluent API方式可簡化開發、減少錯誤並提高工作效率。利用它可以輕鬆生成 PDF 報告、發票、導出文件等。 項目介紹 QuestPDF 是一個革命性的開源 .NET 庫，它徹底改變了我們生成 PDF 文檔的方 ...
  • 彈幕樹洞項目功能新增篇
    項目地址 項目後端地址： https://github.com/ZyPLJ/ZYTteeHole 項目前端頁面地址： ZyPLJ/TreeHoleVue (github.com) https://github.com/ZyPLJ/TreeHoleVue 目前項目測試訪問地址： http://tree ...
  • 第27篇 sqlserver2022詳細安裝步驟
    話不多說，直接開乾 一.下載 1.官方鏈接下載： https://www.microsoft.com/zh-cn/sql-server/sql-server-downloads 2.在下載目錄中找到下麵這個小的安裝包 SQL2022-SSEI-Dev.exe，運行開始下載SQL server； 二. ...
  • .NET 開源高性能 MQTT 類庫
    前言 隨著物聯網（IoT）技術的迅猛發展，MQTT（消息隊列遙測傳輸）協議憑藉其輕量級和高效性，已成為眾多物聯網應用的首選通信標準。 MQTTnet 作為一個高性能的 .NET 開源庫，為 .NET 平臺上的 MQTT 客戶端與伺服器開發提供了強大的支持。 本文將全面介紹 MQTTnet 的核心功能 ...
  • Serilog文檔翻譯系列（六） - 可用的接收器、增強器、格式化輸出
    Serilog支持多種接收器用於日誌存儲，增強器用於添加屬性，LogContext管理動態屬性，支持多種輸出格式包括純文本、JSON及ExpressionTemplate。還提供了自定義格式化選項，適用於不同需求。 ...
  • 警惕 Visual Studio 屬性求值副作用導致邏輯不符合預期
    目錄簡介獲取 HTML 文檔解析 HTML 文檔測試參考文章 簡介 動態內容網站使用 JavaScript 腳本動態檢索和渲染數據，爬取信息時需要模擬瀏覽器行為，否則獲取到的源碼基本是空的。 本文使用的爬取步驟如下： 使用 Selenium 獲取渲染後的 HTML 文檔 使用 HtmlAgility ...
  • [使用目前最新版]HybridCLR6.9.0+YooAsset2.2.4實現純C# Unity熱更新方案 (一)
    1.前言 什麼是熱更新 游戲或者軟體更新時，無需重新下載客戶端進行安裝，而是在應用程式啟動的情況下，在內部進行資源或者代碼更新 Unity目前常用熱更新解決方案 HybridCLR，Xlua，ILRuntime等 Unity目前常用資源管理解決方案 AssetBundles，Addressable， ...
  • 在 ASP.NET Core Web API 中使用操作篩選器統一處理通用操作
    本文章主要是在C# ASP.NET Core Web API框架實現向手機發送驗證碼簡訊功能。這裡我選擇是一個互億無線簡訊驗證碼平臺，其實像阿裡雲，騰訊雲上面也可以。 首先我們先去 互億無線 https://www.ihuyi.com/api/sms.html 去註冊一個賬號 註冊完成賬號後，它會送 ...
  • 第28篇 如何.net中實現高效可靠數據同步api
    通過以下方式可以高效，並保證數據同步的可靠性 1.API設計 使用RESTful設計，確保API端點明確，並使用適當的HTTP方法（如POST用於創建，PUT用於更新）。 設計清晰的請求和響應模型，以確保客戶端能夠理解預期格式。 2.數據驗證 在伺服器端進行嚴格的數據驗證，確保接收到的數據符合預期格 ...
所有分類