MySQLの実際のクエリ実行時間を測定する方法

MySQLで実際のクエリ実行時間を正確に測定したい場合、「プロファイリング（profiling）」機能を利用するのが有効です。この機能は、クエリを実行する前に値を「1」に設定しておく必要があります。

プロファイリングを使った測定手順

測定は、以下の順序で行います。

SET profiling を 1 に設定
クエリを実行
SHOW PROFILES で結果を確認

実際の操作例

まず、プロファイリングを有効にします。

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

次に、測定対象となるクエリを実行します。ここでは例として、次のSELECT文を使用します。

mysql> SELECT * from MilliSecondDemo;

実行結果は以下のとおりです。

+-------------------------+
| MyTimeInMillSec         |
+-------------------------+
| 2018-10-08 15:19:50.202 |
+-------------------------+
1 row in set (0.00 sec)

表示上の所要時間は「0.00秒」となっていますが、これだけでは実際の処理時間はわかりません。そこで、次のクエリを実行して実際の所要時間を確認します。

mysql> SHOW PROFILES;

このクエリを実行すると、以下のような出力が得られます。

+----------+------------+------------------------------+
| Query_ID | Duration   | Query                        |
+----------+------------+------------------------------+
| 1        | 0.00051725 | SELECT * from MilliSecondDemo|
+----------+------------+------------------------------+
1 row in set, 1 warning (0.00 sec)

この結果から、先ほどのSELECT文の実際の実行時間が「0.00051725秒」だったことがわかります。SHOW PROFILESでは、セッション内で実行された各クエリにIDが割り当てられ、それぞれの所要時間（Duration）が高い精度で表示されるため、パフォーマンスのボトルネックとなっているクエリを特定する際にも非常に役立ちます。