Query Profiler可以定位出一条SQL语句执行的各种资源消耗情况,比如CPU,IO等,以及该SQL执行所耗费的时间等。该工具只有在MYSQL 5.0.37以及以上版本中才有实现。
默认的情况下,MYSQL的该功能没有打开,需要自己手动启动。可以通过如下方法查看当前mysql服务器是否开启了该功能。
mysql> show variables like ‘%profiling%’;
+————————+——-+
| Variable_name | Value |
+————————+——-+
| profiling | OFF |
| profiling_history_size | 15 |
+————————+——-+
2 rows in set (0.00 sec)
profiling参数值为OFF,说明没有打开该功能。
profiling_history_size参数值为15表示,记录最近15次的查询历史。该值可以修改。
下边说说如何打开profiling功能:
MYSQL提示符下执行如下命令:
mysql> set profiling=1;
Query OK, 0 rows affected (0.00 sec)
然后再次检验下执行的效果:
mysql> show variables like ‘%profiling%’;
+————————+——-+
| Variable_name | Value |
+————————+——-+
| profiling | ON |
| profiling_history_size | 15 |
+————————+——-+
2 rows in set (0.00 sec)
profiling值为ON说明已经启动该功能。
下边说说如何使用show profiles;
1.首先执行一查询语句:
mysql> select * from user;
+——+———–+
| id | name |
+——+———–+
| 22 | abc |
| 223 | dabc |
| 2232 | dddabc |
| 45 | asdsagd |
| 23 | ddddddddd |
| 22 | ddd |
| 28 | sssddd |
+——+———–+
7 rows in set (0.06 sec)
2.通过show profiles命令查看系统中多个query的概要信息:
mysql> show profiles;
+———-+————+———————————–+
| Query_ID | Duration | Query |
+———-+————+———————————–+
| 1 | 0.00013600 | set profiling=1 |
| 2 | 0.00092300 | show variables like ‘%profiling%’ |
| 3 | 0.08506075 | show databases |
| 4 | 0.02698550 | SELECT DATABASE() |
| 5 | 0.07408475 | show tables |
| 6 | 0.05769725 | select * from user |
+———-+————+———————————–+
6 rows in set (0.01 sec)
其中Query_ID表示查询ID,也就是个编号,Duration表示对应的query语句执行的时间,单位是秒,query表示具体的query语句。我们可以看到刚才我们最后执行的select * from user语句执行的时间是0.05769725,单位是秒,也就是57ms(听一个朋友说是秒,具体还没有找到MYSQL的开发手册证明)
3.获取单个query的详细profile信息,可以通过如下语句:
mysql> show profile cpu,block io for query 6;
+——————–+———-+———-+————+————–+—————+
| Status | Duration | CPU_user | CPU_system | Block_ops_in | Block_ops_out |
+——————–+———-+———-+————+————–+—————+
| starting | 0.014438 | NULL | NULL | NULL | NULL |
| Opening tables | 0.042860 | NULL | NULL | NULL | NULL |
| System lock | 0.000007 | NULL | NULL | NULL | NULL |
| Table lock | 0.000012 | NULL | NULL | NULL | NULL |
| init | 0.000019 | NULL | NULL | NULL | NULL |
| optimizing | 0.000005 | NULL | NULL | NULL | NULL |
| statistics | 0.000018 | NULL | NULL | NULL | NULL |
| preparing | 0.000011 | NULL | NULL | NULL | NULL |
| executing | 0.000004 | NULL | NULL | NULL | NULL |
| Sending data | 0.000232 | NULL | NULL | NULL | NULL |
| end | 0.000007 | NULL | NULL | NULL | NULL |
| query end | 0.000005 | NULL | NULL | NULL | NULL |
| freeing items | 0.000073 | NULL | NULL | NULL | NULL |
| logging slow query | 0.000003 | NULL | NULL | NULL | NULL |
| cleaning up | 0.000004 | NULL | NULL | NULL | NULL |
+——————–+———-+———-+————+————–+—————+
15 rows in set (0.02 sec)
至于为什么这里CPU,IO信息都显示NULL,我还不清楚为什么,需要研究下为什么?
总结:Query Profiler对于SQL性能分析和诊断费用有用。另外,该命令还有如下参数可以选择:
ALL – displays all information
BLOCK IO – displays counts for block input and output operations
CONTEXT SWITCHES – displays counts for voluntary and involuntary context switches
IPC – displays counts for messages sent and received
MEMORY – is not currently implemented
PAGE FAULTS – displays counts for major and minor page faults
SOURCE – displays the names of functions from the source code, together with the name and line number of the file in which the function occurs
SWAPS – displays swap counts