mysql文の実行時間を表示する2つの方法

4943 ワード

ウェブサイトの运行が遅い时、私は特に起きてどうしてこんなに遅いことを知っていて、だから私は调べて、データベースは绝対にとても重要な一部で、中で运行するsqlは绝対に逃してはいけません.普段プロジェクトをするとき、私もsql文の書くことに注意して、いくつかの効率的なsqlを書くことができて、だから私はいつも自分の書いたsql文をテストします.私が知っている二つの方法をまとめて出します.
一、show profilesなどの文を見て
1、profileが開いているかどうか調べて、デフォルトは開かない.
mysql> show profiles; 
Empty set (0.02 sec) 
mysql> show variables like "%pro%"; 
+---------------------------+-------+ 
| Variable_name | Value | 
+---------------------------+-------+ 
| profiling | OFF | 
| profiling_history_size | 15 | 
| protocol_version | 10 | 
| slave_compressed_protocol | OFF | 
+---------------------------+-------+ 
4 rows in set (0.00 sec)

私はprofilesの中に何も入っていないのを見て、会社のパソコンの中でprofileは開いていないので、mysql変数を見てみましたが、やはりオフです.
2 profileをオンにしてテスト
プロファイルを開く
mysql> set profiling=1; 
Query OK, 0 rows affected (0.00 sec)

テストは次のとおりです.
mysql> show tables; 
+----------------+ 
| Tables_in_test | 
+----------------+ 
| aa | 
| bb | 
| comment | 
| string_test | 
| user | 
+----------------+ 
5 rows in set (0.00 sec) 
mysql> select * from aa; 
+----+------+------------+------+ 
| id | name | nname | sex | 
+----+------+------------+------+ 
| 2 | tank | bbbb,4bbbb | NULL | 
| 3 | zhang| 3,c,u | NULL | 
+----+------+------------+------+ 
2 rows in set (0.00 sec) 
mysql> update aa set name='d'; 
Query OK, 2 rows affected (0.00 sec) 
Rows matched: 2 Changed: 2 Warnings: 0 
mysql> delete from bb; 
Query OK, 2 rows affected (0.00 sec) 
mysql> show profiles; 
+----------+------------+------------------------+ 
| Query_ID | Duration | Query | 
+----------+------------+------------------------+ 
| 1 | 0.00054775 | show tables | 
| 2 | 0.00022400 | select * from aa | 
| 3 | 0.00026275 | update aa set name='d' | 
| 4 | 0.00043000 | delete from bb | 
+----------+------------+------------------------+ 
4 rows in set (0.00 sec) 
mysql> show profile; 
+----------------------+-----------+ 
| Status | Duration | 
+----------------------+-----------+ 
| (initialization) | 0.0000247 | 
| checking permissions | 0.0000077 | 
| Opening tables | 0.0000099 | 
| System lock | 0.000004 | 
| Table lock | 0.000005 | 
| init | 0.0003057 | 
| query end | 0.0000062 | 
| freeing items | 0.000057 | 
| closing tables | 0.000008 | 
| logging slow query | 0.0000015 | 
+----------------------+-----------+ 
10 rows in set (0.00 sec) 
mysql> show profile for query 1; 
+----------------------+-----------+ 
| Status | Duration | 
+----------------------+-----------+ 
| (initialization) | 0.000028 | 
| checking permissions | 0.000007 | 
| Opening tables | 0.0000939 | 
| System lock | 0.0000017 | 
| Table lock | 0.0000055 | 
| init | 0.000009 | 
| optimizing | 0.0000027 | 
| statistics | 0.0000085 | 
| preparing | 0.0000065 | 
| executing | 0.000004 | 
| checking permissions | 0.000258 | 
| Sending data | 0.000049 | 
| end | 0.0000037 | 
| query end | 0.0000027 | 
| freeing items | 0.0000307 | 
| closing tables | 0.0000032 | 
| removing tmp table | 0.0000275 | 
| closing tables | 0.0000037 | 
| logging slow query | 0.000002 | 
+----------------------+-----------+ 
19 rows in set (0.00 sec) 
mysql> show profile for query 3; 
+----------------------+-----------+ 
| Status | Duration | 
+----------------------+-----------+ 
| (initialization) | 0.0000475 | 
| checking permissions | 0.0000077 | 
| Opening tables | 0.000026 | 
| System lock | 0.0000042 | 
| Table lock | 0.0000045 | 
| init | 0.0000205 | 
| Updating | 0.0000787 | 
| end | 0.0000567 | 
| query end | 0.000004 | 
| freeing items | 0.0000067 | 
| closing tables | 0.000004 | 
| logging slow query | 0.000002 | 
+----------------------+-----------+ 
12 rows in set (0.00 sec)

二、timestampdiffはテスト時間を表示します
mysql> set @d=now(); 
Query OK, 0 rows affected (0.00 sec) 
mysql> select * from comment; 
+------+-----+------+------------+---------------------+ 
| c_id | mid | name | content | datetime | 
+------+-----+------+------------+---------------------+ 
| 1 | 1 | ?? | 2222222211 | 2010-05-12 00:00:00 | 
| 2 | 1 | ?? | ????(??) | 2010-05-13 00:00:00 | 
| 3 | 2 | tank | ?????? | 0000-00-00 00:00:00 | 
+------+-----+------+------------+---------------------+ 
3 rows in set (0.00 sec) 
mysql> select timestampdiff(second,@d,now()); 
+--------------------------------+ 
| timestampdiff(second,@d,now()) | 
+--------------------------------+ 
| 0 | 
+--------------------------------+ 
1 row in set (0.00 sec)

この方法には、3つのsql文をできるだけ一緒に実行しなければならないことに注意してください.そうしないと、誤差が大きすぎて、まったく正確ではありません.
set @d=now(); 
select * from comment; 
select timestampdiff(second,@d,now());

コマンドラインで実行する場合は、select timestampdiff(second,@d,now()に注意してください.後ろに、必ずもっとcopyで空行しなければなりません.そうしないと、最後のsqlは自分で車を押して実行しなければなりません.そうしないと、許可されません.
実は私は、本当に私たちが関心を持っているのは、検索が遅いsqlだと思います.本当に速度に影響を与えるのは彼らなので、遅い検索について、書く暇があります.