mysql> show profiles; +----------+--------------+---------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+ | Query_ID | Duration | Query | +----------+--------------+---------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+ | 1 | 62.47276625 | select sql_no_cache count(*) from tbl1 , tbl2 where tbl1.str1 = tbl2.str1 group by tbl2.str3, tbl1.str2 with rollup having str2 is not null and str3 is not null into outfile '1.frm' | | 2 | 154.22246475 | select sql_no_cache count(*) from tbl1 , tbl2 where tbl1.str1 = tbl2.str1 group by tbl2.str3, tbl1.str2 having str2 is not null and str3 is not null into outfile '2.frm' | +----------+--------------+---------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+ 2 rows in set (0.00 sec) mysql> show profile all for query 1; +------------------------------+-----------+-----------+------------+-------------------+---------------------+--------------+---------------+---------------+-------------------+-------------------+-------------------+-------+-------------------------+----------------------+-------------+ | Status | Duration | CPU_user | CPU_system | Context_voluntary | Context_involuntary | Block_ops_in | Block_ops_out | Messages_sent | Messages_received | Page_faults_major | Page_faults_minor | Swaps | Source_function | Source_file | Source_line | +------------------------------+-----------+-----------+------------+-------------------+---------------------+--------------+---------------+---------------+-------------------+-------------------+-------------------+-------+-------------------------+----------------------+-------------+ | starting | 0.000125 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | NULL | NULL | NULL | | checking permissions | 0.000008 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 1 | 0 | check_access | /home/sbester/build/ | 4633 | | checking permissions | 0.000007 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | check_access | /home/sbester/build/ | 4633 | | Opening tables | 0.000056 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 1 | 0 | open_tables | /home/sbester/build/ | 4732 | | System lock | 0.000047 | 0.001000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 1 | 0 | mysql_lock_tables | /home/sbester/build/ | 299 | | init | 0.000115 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_select | /home/sbester/build/ | 2546 | | optimizing | 0.000026 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 859 | | statistics | 0.000046 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 1050 | | preparing | 0.000025 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 1072 | | Creating tmp table | 0.000045 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 1588 | | executing | 0.000005 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | exec | /home/sbester/build/ | 1819 | | Copying to tmp table | 17.817453 | 17.763299 | 0.011999 | 40 | 2319 | 0 | 0 | 0 | 0 | 0 | 4126 | 0 | exec | /home/sbester/build/ | 1966 | | converting HEAP to MyISAM | 0.613994 | 0.607908 | 0.004999 | 1 | 78 | 0 | 21768 | 0 | 0 | 0 | 33 | 0 | create_myisam_from_heap | /home/sbester/build/ | 11192 | | Copying to tmp table on disk | 38.823066 | 38.657123 | 0.056991 | 95 | 5254 | 0 | 47576 | 0 | 0 | 0 | 0 | 0 | create_myisam_from_heap | /home/sbester/build/ | 11258 | | Sorting result | 2.818482 | 2.571609 | 0.244963 | 8 | 323 | 0 | 364000 | 0 | 0 | 0 | 3490 | 0 | exec | /home/sbester/build/ | 2245 | | Sending data | 2.382200 | 2.371640 | 0.008999 | 6 | 263 | 0 | 2112 | 0 | 0 | 0 | 0 | 0 | exec | /home/sbester/build/ | 2357 | | end | 0.000015 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_select | /home/sbester/build/ | 2582 | | removing tmp table | 0.016879 | 0.000000 | 0.016997 | 0 | 3 | 0 | 8 | 0 | 0 | 0 | 0 | 0 | free_tmp_table | /home/sbester/build/ | 11125 | | end | 0.000015 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | free_tmp_table | /home/sbester/build/ | 11150 | | query end | 0.000020 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_execute_command | /home/sbester/build/ | 4330 | | closing tables | 0.000039 | 0.000000 | 0.000000 | 1 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_execute_command | /home/sbester/build/ | 4382 | | freeing items | 0.000067 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_parse | /home/sbester/build/ | 5522 | | logging slow query | 0.000004 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | log_slow_statement | /home/sbester/build/ | 1451 | | logging slow query | 0.000004 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | log_slow_statement | /home/sbester/build/ | 1460 | | cleaning up | 0.000028 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | dispatch_command | /home/sbester/build/ | 1407 | +------------------------------+-----------+-----------+------------+-------------------+---------------------+--------------+---------------+---------------+-------------------+-------------------+-------------------+-------+-------------------------+----------------------+-------------+ 25 rows in set (0.00 sec) mysql> show profile all for query 2; +------------------------------+------------+------------+------------+-------------------+---------------------+--------------+---------------+---------------+-------------------+-------------------+-------------------+-------+-------------------------+----------------------+-------------+ | Status | Duration | CPU_user | CPU_system | Context_voluntary | Context_involuntary | Block_ops_in | Block_ops_out | Messages_sent | Messages_received | Page_faults_major | Page_faults_minor | Swaps | Source_function | Source_file | Source_line | +------------------------------+------------+------------+------------+-------------------+---------------------+--------------+---------------+---------------+-------------------+-------------------+-------------------+-------+-------------------------+----------------------+-------------+ | starting | 0.000126 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | NULL | NULL | NULL | | checking permissions | 0.000005 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | check_access | /home/sbester/build/ | 4633 | | checking permissions | 0.000006 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | check_access | /home/sbester/build/ | 4633 | | Opening tables | 0.000033 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | open_tables | /home/sbester/build/ | 4732 | | System lock | 0.000019 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_lock_tables | /home/sbester/build/ | 299 | | init | 0.000094 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_select | /home/sbester/build/ | 2546 | | optimizing | 0.000020 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 859 | | statistics | 0.000036 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 1050 | | preparing | 0.000018 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 1072 | | Creating tmp table | 0.000040 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | optimize | /home/sbester/build/ | 1588 | | executing | 0.000004 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | exec | /home/sbester/build/ | 1819 | | Copying to tmp table | 20.308130 | 20.254920 | 0.019997 | 46 | 2418 | 0 | 0 | 0 | 0 | 0 | 4116 | 0 | exec | /home/sbester/build/ | 1966 | | converting HEAP to MyISAM | 18.741861 | 18.703157 | 0.008999 | 44 | 2227 | 0 | 22816 | 0 | 0 | 0 | 32 | 0 | create_myisam_from_heap | /home/sbester/build/ | 11192 | | Copying to tmp table on disk | 113.825348 | 108.889446 | 4.678288 | 269 | 14304 | 0 | 110424 | 0 | 0 | 0 | 0 | 0 | create_myisam_from_heap | /home/sbester/build/ | 11258 | | Sorting result | 0.772382 | 0.738888 | 0.032995 | 3 | 83 | 0 | 64344 | 0 | 0 | 0 | 1231 | 0 | exec | /home/sbester/build/ | 2245 | | Sending data | 0.562329 | 0.553916 | 0.005000 | 3 | 90 | 0 | 2112 | 0 | 0 | 0 | 0 | 0 | exec | /home/sbester/build/ | 2357 | | end | 0.000012 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_select | /home/sbester/build/ | 2582 | | removing tmp table | 0.011846 | 0.003999 | 0.007998 | 0 | 1 | 0 | 8 | 0 | 0 | 0 | 0 | 0 | free_tmp_table | /home/sbester/build/ | 11125 | | end | 0.000010 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | free_tmp_table | /home/sbester/build/ | 11150 | | query end | 0.000019 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_execute_command | /home/sbester/build/ | 4330 | | closing tables | 0.000036 | 0.000000 | 0.000000 | 1 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_execute_command | /home/sbester/build/ | 4382 | | freeing items | 0.000062 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | mysql_parse | /home/sbester/build/ | 5522 | | logging slow query | 0.000003 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | log_slow_statement | /home/sbester/build/ | 1451 | | logging slow query | 0.000003 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | log_slow_statement | /home/sbester/build/ | 1460 | | cleaning up | 0.000025 | 0.000000 | 0.000000 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | 0 | dispatch_command | /home/sbester/build/ | 1407 | +------------------------------+------------+------------+------------+-------------------+---------------------+--------------+---------------+---------------+-------------------+-------------------+-------------------+-------+-------------------------+----------------------+-------------+ 25 rows in set (0.00 sec) mysql> select version(); +--------------------------+ | version() | +--------------------------+ | 5.5.9-valgrind-max-debug | +--------------------------+ 1 row in set (0.00 sec)