Как использовать SHOW PROFILE в MySQL?

SHOW PROFILE позволяет получить детальную картинку по используемым ресурсам запроса в MySQL.

Для начала нужно включить профилирование (работаем в консольном клиенте mysql):

mysql> SET profiling = 1;

• • SHOW PROFILES будет показывать список самых последних запросов

• • SHOW PROFILE покажет детальную информацию о выбранном Query_ID из таблицы SHOW PROFILES

Создадим тестовую таблицу
mysql> SET profiling = 1;

Query OK, 0 rows affected (0.00 sec)

mysql> DROP TABLE IF EXISTS t1;

Query OK, 0 rows affected, 1 warning (0.00 sec)

mysql> CREATE TABLE T1 (id INT);

Query OK, 0 rows affected (0.01 sec)

Теперь посмотрим на доступные профили:

mysql> SHOW PROFILES;
+----------+----------+--------------------------+
| Query_ID | Duration | Query                    |
+----------+----------+--------------------------+
|        0 | 0.000088 | SET PROFILING = 1        |
|        1 | 0.000136 | DROP TABLE IF EXISTS t1  |
|        2 | 0.011947 | CREATE TABLE t1 (id INT) |
+----------+----------+--------------------------+
3 rows in set (0.00 sec)

## Значения колонки Query_ID нужно использовать для просмотра детального профиля по запросу

Посмотрим детальный профиль последнего запроса, который был выполнен

mysql> SHOW PROFILE;
+----------------------+----------+
| Status               | Duration |
+----------------------+----------+
| checking permissions | 0.000040 |
| creating table       | 0.000056 |
| After create         | 0.011363 |
| query end            | 0.000375 |
| freeing items        | 0.000089 |
| logging slow query   | 0.000019 |
| cleaning up          | 0.000005 |
+----------------------+----------+
7 rows in set (0.00 sec)

Чтобы посмотреть профиль для любого другого запроса, необходимо указать его ID из колонки Query_ID:

mysql> SHOW PROFILE FOR QUERY 1;
+--------------------+----------+
| Status             | Duration |
+--------------------+----------+
| query end          | 0.000107 |
| freeing items      | 0.000008 |
| logging slow query | 0.000015 |
| cleaning up        | 0.000006 |
+--------------------+----------+
4 rows in set (0.00 sec)

При просмотре профиля можно выбрать дополнительную информацию, например:

mysql> SHOW PROFILE CPU FOR QUERY 2;
+----------------------+----------+----------+------------+
| Status               | Duration | CPU_user | CPU_system |
+----------------------+----------+----------+------------+
| checking permissions | 0.000040 | 0.000038 |   0.000002 |
| creating table       | 0.000056 | 0.000028 |   0.000028 |
| After create         | 0.011363 | 0.000217 |   0.001571 |
| query end            | 0.000375 | 0.000013 |   0.000028 |
| freeing items        | 0.000089 | 0.000010 |   0.000014 |
| logging slow query   | 0.000019 | 0.000009 |   0.000010 |
| cleaning up          | 0.000005 | 0.000003 |   0.000002 |
+----------------------+----------+----------+------------+
7 rows in set (0.00 sec)

Доступны такие дополнительные типы:

• ALL покажет всю доступную информацию
• BLOCK IO покажет количество блочных I/O операций
• CONTEXT SWITCHES покажет количество переключений контекстов
• CPU покажет процессорное время
• IPC покажет количество полученных и отправленных сообщений
• PAGE FAULTS покажет количество page faults
• SOURCE покажет имена функций исходного кода, которые были выполнены
• SWAPS покажет размеры используемых свопов