Criação de tabela MySQL incrivelmente lenta

10

Uma simples criação de tabela em um dos meus bancos de dados MySQL leva uma eternidade:

mysql> CREATE TABLE blah (id BIGINT UNSIGNED NOT NULL PRIMARY KEY);
Query OK, 0 rows affected (16.58 sec)

A máquina está completamente ociosa:

01:21:26 PM       CPU     %user     %nice   %system   %iowait    %steal     %idle
01:21:27 PM       all      0.50      0.00      0.21      0.00      0.00     99.29

Alguma idéia de como investigar isso?

EDIT : Seguindo o conselho do DTest , este é o perfil de execução:

mysql> SHOW PROFILE FOR QUERY 1;
+----------------------+----------+
| Status               | Duration |
+----------------------+----------+
| starting             | 0.000044 |
| checking permissions | 0.000024 |
| creating table       | 8.668129 |
| After create         | 0.000014 |
| query end            | 0.000005 |
| freeing items        | 0.000028 |
| logging slow query   | 0.000004 |
| logging slow query   | 0.000206 |
| cleaning up          | 0.000006 |
+----------------------+----------+
Adam Matan
fonte
@ Phil Uma máquina física com cerca de 16 GB de memória.
31812 Adam Matan
@ Phil É um servidor de produção com muitas operações MySQL, portanto o disco não deve ficar ocioso.
31812 Adam Matan

Respostas:

10

Gostaria de ativar o perfil para ter uma idéia do que leva tanto tempo. Um exemplo usando a CLI do mysql:

SET profiling = 1;
CREATE TABLE blah (id BIGINT UNSIGNED NOT NULL PRIMARY KEY);
SET profiling = 1;

Você deve obter uma resposta mais ou menos assim:

mysql> SHOW PROFILES;
| Query_ID | Duration   | Query |
+----------+------------+-------------------------------------------------------------+
|        1 | 0.00913800 | CREATE TABLE blah (id BIGINT UNSIGNED NOT NULL PRIMARY KEY) |
+----------+------------+-------------------------------------------------------------+
1 row in set (0.00 sec)

mysql> SHOW PROFILE FOR QUERY 1;
+----------------------+----------+
| Status               | Duration |
+----------------------+----------+
| starting             | 0.000071 |
| checking permissions | 0.000007 |
| Opening tables       | 0.001698 |
| System lock          | 0.000043 |
| creating table       | 0.007260 |
| After create         | 0.000004 |
| query end            | 0.000004 |
| closing tables       | 0.000015 |
| freeing items        | 0.000031 |
| logging slow query   | 0.000002 |
| cleaning up          | 0.000003 |
+----------------------+----------+
11 rows in set (0.00 sec)
Derek Downey
fonte
11
O @AdamMatan não tem certeza se você leu a documentação de criação de perfil, mas existem outros sinalizadores para mostrar o perfil da consulta CPU, BLOCK IOetc. que podem ajudá-lo no estágio 'criando tabela'.
Derek Downey