mysql 慢日志DBS_MySQL慢日志分析工具pt-query-digest

slow log 分析：

报告最近半个小时的慢查询：

pt-query-digest --report --since 1800s slow.log

报告一个时间段的慢查询：

pt-query-digest --report --since '2014-12-30 14:50:00' --until '2014-12-30 14:59:59' slow.log

报告只含select语句的慢查询:

pt-query-digest --filter '$event->{fingerprint} =~ m/^select/i' slow.log

报告针对某个用户的慢查询:

pt-query-digest --filter '($event->{user} || "") =~ m/^root/i' slow.log

报告所有的全表扫描或full join的慢查询:

pt-query-digest --filter '(($event->{Full_scan} || "") eq "yes") || (($event->{Full_join} || "") eq "yes")' slow.log

下面是一个输出结果：

# 7.8s user time, 110ms system time, 22.48M rss, 177.75M vsz

pt-query-digest占用的用户时间和系统时间，rss内存，虚拟内存

# Current date: Thu Jan  1 11:00:16 2015

# Hostname: machine1

# Files: slow.log

# Overall: 437 total, 28 unique, 0.76 QPS, 0.64x concurrency _____________

总共语句数量，唯一语句数量，qps，并发数

# Time range: 2014-12-30 14:50:01 to 14:59:33

时间范围

# Attribute          total     min     max     avg     95%  stddev  median

# ============     ======= ======= ======= ======= ======= ======= =======

总计      最小    最大    平均 百分比

# Exec time           368s    88us    126s   842ms    34ms     10s     1ms

语句执行时间

# Lock time           78ms    39us     2ms   179us   445us   189us    98us

锁时间

# Rows sent          2.91k       0      95    6.82   65.89   17.33    0.99

传输到客户端的行数

# Rows examine     159.23M       0  52.94M 373.11k   1.53k   4.24M    4.96

扫描的行数大小

# Query size       471.95k      90   4.14k   1.08k   3.19k   1.33k  258.32

查询语句大小

# Profile

# Rank Query ID           Response time  Calls R/Call   V/M   Item

# ==== ================== ============== ===== ======== ===== ============

#    1 0xE2D730FF50E6F592 364.5363 99.1%     3 121.5121  0.17 SELECT * FROM TEST

rank：语句排名，默认根据响应时间拍下，可以通过--order by修改

query id：查询语句的hash值

response time：响应时间

calls：查询语句执行次数

r/call：平均每次执行的响应时间

V/M：离差指数，执行时间变化较大。

# MISC 0xMISC               3.2034  0.9%   434   0.0074   0.0 <27 items="">

单个语句的各项指标：

# Query 1: 0.14 QPS, 16.57x concurrency, ID 0xE2D730FF50E6F592 at byte 14050954

QPS：每秒查询数

concurrency：并发数

ID：查询语句hash值

byte：查询语句在slow log文件中的位置

# This item is included in the report because it matches --limit.

# Scores: V/M = 0.17

# Time range: 2014-12-30 14:52:41 to 14:53:03

# Attribute    pct   total     min     max     avg     95%  stddev  median

# ============ === ======= ======= ======= ======= ======= ======= =======

# Count          0       3

执行次数

# Exec time     99    365s    116s    126s    122s    124s      5s    118s

# Lock time      1     1ms   365us   463us   401us   445us    41us   366us

# Rows sent      0       0       0       0       0       0       0       0

# Rows examine  99 158.83M  52.94M  52.94M  52.94M  52.94M       0  52.94M

# Query size     0   1.32k     450     450     450     450       0     450

# String:

# Databases    test

# Hosts        10.1.1.44

客户端机器名

# Users        testuser

执行语句的用户名

# Query_time distribution

#   1us

#  10us

# 100us

#   1ms

#  10ms

# 100ms

#    1s

#  10s+  ################################################################

执行时间的分布图

# Tables

#    SHOW TABLE STATUS FROM `test` LIKE 'TEST'\G

表统计信息

#    SHOW CREATE TABLE `test`.`TEST`\G

# EXPLAIN /*!50100 PARTITIONS*/

select * from test\G

查询执行计划