MYSQL中SQL执行分析

今天本来想看下mysql的一条语句在MYSQL引擎中是如何的处理，无意中发现了很多其他的东西，就整理下吧。

 

查看SQL的执行情况：

1.通过show processlist 来查看系统的执行情况！

 

   mysql> show processlist ;
+----+------+-----------+-------+---------+------+-------+------------------+
| Id | User | Host      | db    | Command | Time | State | Info             |
+----+------+-----------+-------+---------+------+-------+------------------+
| 41 | root | localhost | mysql | Query   |    0 | NULL  | show processlist |
+----+------+-----------+-------+---------+------+-------+------------------+
1 row in set (0.00 sec)

 

 

该命令一般用于实时的去查看系统中运行较慢的SQL。

 

2.通过profiling来进行查看

这个命令是查看SQL的执行时间，能很直观的看出快慢。

 

2.1查看系统值：0代表还是关闭着分析功能

mysql> select @@profiling;

+-------------+

| @@profiling |

+-------------+

|           0 |

+-------------+

1 row in set (0.00 sec)

 

2.2打开工具

 

mysql> set profiling=1;
Query OK, 0 rows affected (0.00 sec)

mysql> select @@profiling;
+-------------+
| @@profiling |
+-------------+
|           1 |
+-------------+
1 row in set (0.00 sec)

 

 

2.3准备基础数据

 

create table name
(
id int not null auto_increment,
first_name varchar(30) not null,
last_name  varchar(30) not null,
primary key (id),
index (last_name,first_name)
)

insert into name(first_name,last_name) values("xue","zhaoming")
insert into name(first_name,last_name) values("xue","zhaoyue") 
commit;
select * from name ;
select * from name ;

 

 

2.4 好戏开始了

 

mysql> show profiles;
+----------+------------+----------------------------------------------------------------+
| Query_ID | Duration   | Query                                                          |
+----------+------------+----------------------------------------------------------------+
|        8 | 0.00074500 | insert into name(first_name,last_name) values("xue","zhaoyue") |
|        9 | 0.00021200 | commit                                                         |
|       10 | 0.00063700 | select * from name                                             |
|       11 | 0.00026100 | select * from name  

 

 

通过上面的大家可以很清晰的看到在执行两边的select * from name语句的时候实际执行的效率是不一样。那么我们来具体看看为什么不一样的。

 

mysql> SHOW PROFILE FOR QUERY 10;
+--------------------------------+----------+
| Status                         | Duration |
+--------------------------------+----------+
| starting                       | 0.000048 |
| checking query cache for query | 0.000231 |                  ---检查是否在缓存中
| Opening tables                 | 0.000024 |                  ---打开表
| System lock                    | 0.000014 |                  ---锁系统
| Table lock                     | 0.000032 |                  ---锁表 
| init                           | 0.000027 |                  ---初始化
| optimizing                     | 0.000013 |                  ---优化查询
| statistics                     | 0.000019 |
| preparing                      | 0.000018 |                  ---准备
| executing                      | 0.000011 |                  ---执行
| Sending data                   | 0.000075 |
| end                            | 0.000014 |
| query end                      | 0.000010 |
| freeing items                  | 0.000057 |
| storing result in query cache  | 0.000022 |                  ---将结果放到缓存中
| logging slow query             | 0.000010 |                  ---
| cleaning up                    | 0.000012 |
+--------------------------------+----------+
17 rows in set (0.00 sec)

 

 

具体的查询过程如上面所示,那么为什么第二次的查看就快了呢？简单，看下第二次的查询信息

 

mysql> SHOW PROFILE FOR QUERY 11;
+--------------------------------+----------+
| Status                         | Duration |
+--------------------------------+----------+
| starting                       | 0.000084 |
| checking query cache for query | 0.000028 |
| checking privileges on cached  | 0.000028 |
| sending cached result to clien | 0.000081 |
| logging slow query             | 0.000019 |
| cleaning up                    | 0.000021 |
+--------------------------------+----------+
6 rows in set (0.00 sec)

 

 

对比两个执行过程，我们可以很清晰的看到为什么第二次的查看快了，因为是直接从缓存中查找数据了。

 

 

以上具体的信息都是从 INFORMATION_SCHEMA.PROFILING 这张表中取得的。这张表记录了所有的各个步骤的执行时间及相关信息。若是希望得到所有的执行结果。

select * from INFORMATION_SCHEMA.PROFILING where query_id = 11;

 

 

查看一次查询消耗的总时间：

select min(seq), sum(duration) from information_schema.profiling where query_id = 2

 

3.查看slow_log表 + mysqldumpslow 工具查看慢日志

内容以后再补上。详细的说明可以看下面的文档：

http://dev.mysql.com/doc/refman/5.1/en/slow-query-log.html

 

 

4.第三方工具了

这个就是自己写脚本来监控了，可以采用perl来搞。

 

5.还有就是用explain来查看具体的执行计划。

 

mysql> explain select * from name;
+----+-------------+-------+------+---------------+------+---------+------+------+-------+
| id | select_type | table | type | possible_keys | key  | key_len | ref  | rows | Extra |
+----+-------------+-------+------+---------------+------+---------+------+------+-------+
|  1 | SIMPLE      | name  | ALL  | NULL          | NULL | NULL    | NULL |    2 |       |
+----+-------------+-------+------+---------------+------+---------+------+------+-------+
1 row in set (0.00 sec)

 

 

 

针对这个会在后面补上一个完整的如何查看计划的文档。