MySQL使用profile分析SQL执行状态

最新推荐文章于 2022-11-21 17:46:21 发布
最新推荐文章于 2022-11-21 17:46:21 发布
阅读量1k 收藏
点赞数
分类专栏： MySQL

打开profile

  1. mysql> select @@have_profiling;  
  2. +------------------+  
  3. | @@have_profiling |  
  4. +------------------+  
  5. | YES              |  
  6. +------------------+  
  7. 1 row in set (0.00 sec)  
  8.   
  9. mysql> select @@profiling;  
  10. +-------------+  
  11. | @@profiling |  
  12. +-------------+  
  13. |           0 |  
  14. +-------------+  
  15. 1 row in set (0.00 sec)  
  16.   
  17. mysql> set session profiling=1;  
  18. Query OK, 0 rows affected (0.00 sec)  
  19.   
  20. mysql> select @@profiling;  
  21. +-------------+  
  22. | @@profiling |  
  23. +-------------+  
  24. |           1 |  
  25. +-------------+  
  26. 1 row in set (0.00 sec)  

使用profile分析SQL，可以看到执行两次后,Send data和sending cached result to clien执行效率的变化

  1. mysql> select count(*) from sakila.payment;  
  2. +----------+  
  3. | count(*) |  
  4. +----------+  
  5. |    16049 |  
  6. +----------+  
  7. 1 row in set (0.03 sec)  
  8.   
  9. mysql> show profiles;  
  10. +----------+------------+-------------------------------------+  
  11. | Query_ID | Duration   | Query                               |  
  12. +----------+------------+-------------------------------------+  
  13. |        1 | 0.00020400 | select @@profiling                  |  
  14. |        2 | 0.00008900 | select count(*) from payment        |  
  15. |        3 | 0.00006800 | show databaes                       |  
  16. |        4 | 0.02102800 | show databases                      |  
  17. |        5 | 0.02847600 | select count(*) from sakila.payment |  
  18. +----------+------------+-------------------------------------+  
  19. 5 rows in set (0.00 sec)  
  1. mysql> show profile for query 5;  
  2. +--------------------------------+----------+  
  3. | Status                         | Duration |  
  4. +--------------------------------+----------+  
  5. | starting                       | 0.000030 |  
  6. | Waiting for query cache lock   | 0.000005 |  
  7. | checking query cache for query | 0.000043 |  
  8. | checking permissions           | 0.000007 |  
  9. | Opening tables                 | 0.000027 |  
  10. | System lock                    | 0.000010 |  
  11. | Waiting for query cache lock   | 0.000010 |  
  12. | init                           | 0.000000 |  
  13. | optimizing                     | 0.023255 |  
  14. | statistics                     | 0.000118 |  
  15. | preparing                      | 0.000041 |  
  16. | executing                      | 0.000033 |  
  17. | Sending data                   | 0.003833 |  
  18. | end                            | 0.000054 |  
  19. | query end                      | 0.000045 |  
  20. | closing tables                 | 0.000045 |  
  21. | freeing items                  | 0.000072 |  
  22. | Waiting for query cache lock   | 0.000033 |  
  23. | freeing items                  | 0.000785 |  
  24. | Waiting for query cache lock   | 0.000016 |  
  25. | freeing items                  | 0.000002 |  
  26. | storing result in query cache  | 0.000005 |  
  27. | logging slow query             | 0.000003 |  
  28. | cleaning up                    | 0.000004 |  
  29. +--------------------------------+----------+  
  30. 24 rows in set (0.00 sec)  
  31.   
  32. mysql> select count(*) from sakila.payment;  
  33. +----------+  
  34. | count(*) |  
  35. +----------+  
  36. |    16049 |  
  37. +----------+  
  38. 1 row in set (0.00 sec)  
  39.   
  40. mysql> show profiles;  
  41. +----------+------------+-------------------------------------+  
  42. | Query_ID | Duration   | Query                               |  
  43. +----------+------------+-------------------------------------+  
  44. |        1 | 0.00020400 | select @@profiling                  |  
  45. |        2 | 0.00008900 | select count(*) from payment        |  
  46. |        3 | 0.00006800 | show databaes                       |  
  47. |        4 | 0.02102800 | show databases                      |  
  48. |        5 | 0.02847600 | select count(*) from sakila.payment |  
  49. |        6 | 0.00006900 | select count(*) from sakila.payment |  
  50. +----------+------------+-------------------------------------+  
  51. 6 rows in set (0.00 sec)  
  52.   
  53. mysql> show profile for query 6;  
  54. +--------------------------------+----------+  
  55. | Status                         | Duration |  
  56. +--------------------------------+----------+  
  57. | starting                       | 0.000029 |  
  58. | Waiting for query cache lock   | 0.000004 |  
  59. | checking query cache for query | 0.000007 |  
  60. | checking privileges on cached  | 0.000004 |  
  61. | checking permissions           | 0.000008 |  
  62. | sending cached result to clien | 0.000012 |  
  63. | logging slow query             | 0.000002 |  
  64. | cleaning up                    | 0.000003 |  
  65. +--------------------------------+----------+  
  66. 8 rows in set (0.00 sec)  

sending data比较耗费cpu

  1. mysql> select min(seq) seq,state,count(*) numb_ops,  
  2.     ->     round(sum(duration),5) sum_dur, round(avg(duration),5) avg_dur,  
  3.     ->     round(sum(cpu_user),5) sum_cpu, round(avg(cpu_user),5) avg_cpu  
  4.     ->     from information_schema.profiling  
  5.     ->     where query_id = 5  
  6.     ->     group by state  
  7.     ->     order by seq;  
  8. +------+--------------------------------+----------+---------+---------+---------+---------+  
  9. | seq  | state                          | numb_ops | sum_dur | avg_dur | sum_cpu | avg_cpu |  
  10. +------+--------------------------------+----------+---------+---------+---------+---------+  
  11. |    2 | starting                       |        1 | 0.00003 | 0.00003 | 0.00000 | 0.00000 |  
  12. |    3 | Waiting for query cache lock   |        4 | 0.00006 | 0.00002 | 0.00100 | 0.00025 |  
  13. |    4 | checking query cache for query |        1 | 0.00004 | 0.00004 | 0.00000 | 0.00000 |  
  14. |    5 | checking permissions           |        1 | 0.00001 | 0.00001 | 0.00000 | 0.00000 |  
  15. |    6 | Opening tables                 |        1 | 0.00003 | 0.00003 | 0.00000 | 0.00000 |  
  16. |    7 | System lock                    |        1 | 0.00001 | 0.00001 | 0.00000 | 0.00000 |  
  17. |    9 | init                           |        1 | 0.00000 | 0.00000 | 0.00000 | 0.00000 |  
  18. |   10 | optimizing                     |        1 | 0.02326 | 0.02326 | 0.00000 | 0.00000 |  
  19. |   11 | statistics                     |        1 | 0.00012 | 0.00012 | 0.00000 | 0.00000 |  
  20. |   12 | preparing                      |        1 | 0.00004 | 0.00004 | 0.00000 | 0.00000 |  
  21. |   13 | executing                      |        1 | 0.00003 | 0.00003 | 0.00000 | 0.00000 |  
  22. |   14 | Sending data                   |        1 | 0.00383 | 0.00383 | 0.00300 | 0.00300 |  
  23. |   15 | end                            |        1 | 0.00005 | 0.00005 | 0.00000 | 0.00000 |  
  24. |   16 | query end                      |        1 | 0.00005 | 0.00005 | 0.00000 | 0.00000 |  
  25. |   17 | closing tables                 |        1 | 0.00005 | 0.00005 | 0.00000 | 0.00000 |  
  26. |   18 | freeing items                  |        3 | 0.00086 | 0.00029 | 0.00000 | 0.00000 |  
  27. |   23 | storing result in query cache  |        1 | 0.00001 | 0.00001 | 0.00000 | 0.00000 |  
  28. |   24 | logging slow query             |        1 | 0.00000 | 0.00000 | 0.00000 | 0.00000 |  
  29. |   25 | cleaning up                    |        1 | 0.00000 | 0.00000 | 0.00000 | 0.00000 |  
  30. +------+--------------------------------+----------+---------+---------+---------+---------+  
  31. 19 rows in set (0.00 sec)  
  32.   
  33. mysql> show profile cpu for query 5;  
  34. +--------------------------------+----------+----------+------------+  
  35. | Status                         | Duration | CPU_user | CPU_system |  
  36. +--------------------------------+----------+----------+------------+  
  37. | starting                       | 0.000030 | 0.000000 |   0.000000 |  
  38. | Waiting for query cache lock   | 0.000005 | 0.000000 |   0.000000 |  
  39. | checking query cache for query | 0.000043 | 0.000000 |   0.000000 |  
  40. | checking permissions           | 0.000007 | 0.000000 |   0.000000 |  
  41. | Opening tables                 | 0.000027 | 0.000000 |   0.000000 |  
  42. | System lock                    | 0.000010 | 0.000000 |   0.000000 |  
  43. | Waiting for query cache lock   | 0.000010 | 0.001000 |   0.000000 |  
  44. | init                           | 0.000000 | 0.000000 |   0.000000 |  
  45. | optimizing                     | 0.023255 | 0.000000 |   0.000000 |  
  46. | statistics                     | 0.000118 | 0.000000 |   0.000000 |  
  47. | preparing                      | 0.000041 | 0.000000 |   0.000000 |  
  48. | executing                      | 0.000033 | 0.000000 |   0.000000 |  
  49. | Sending data                   | 0.003833 | 0.002999 |   0.001000 |  
  50. | end                            | 0.000054 | 0.000000 |   0.000000 |  
  51. | query end                      | 0.000045 | 0.000000 |   0.000000 |  
  52. | closing tables                 | 0.000045 | 0.000000 |   0.000000 |  
  53. | freeing items                  | 0.000072 | 0.000000 |   0.000000 |  
  54. | Waiting for query cache lock   | 0.000033 | 0.000000 |   0.000000 |  
  55. | freeing items                  | 0.000785 | 0.000000 |   0.000000 |  
  56. | Waiting for query cache lock   | 0.000016 | 0.000000 |   0.000000 |  
  57. | freeing items                  | 0.000002 | 0.000000 |   0.000000 |  
  58. | storing result in query cache  | 0.000005 | 0.000000 |   0.000000 |  
  59. | logging slow query             | 0.000003 | 0.000000 |   0.000000 |  
  60. | cleaning up                    | 0.000004 | 0.000000 |   0.000000 |  
  61. +--------------------------------+----------+----------+------------+  
  62. 24 rows in set (0.00 sec)  
  63.   
  64. mysql> show profile block io for query 5;  
  65. +--------------------------------+----------+--------------+---------------+  
  66. | Status                         | Duration | Block_ops_in | Block_ops_out |  
  67. +--------------------------------+----------+--------------+---------------+  
  68. | starting                       | 0.000030 |            0 |             0 |  
  69. | Waiting for query cache lock   | 0.000005 |            0 |             0 |  
  70. | checking query cache for query | 0.000043 |            0 |             0 |  
  71. | checking permissions           | 0.000007 |            0 |             0 |  
  72. | Opening tables                 | 0.000027 |            0 |             0 |  
  73. | System lock                    | 0.000010 |            0 |             0 |  
  74. | Waiting for query cache lock   | 0.000010 |            0 |             0 |  
  75. | init                           | 0.000000 |            0 |             0 |  
  76. | optimizing                     | 0.023255 |            0 |             0 |  
  77. | statistics                     | 0.000118 |            0 |             0 |  
  78. | preparing                      | 0.000041 |            0 |             0 |  
  79. | executing                      | 0.000033 |            0 |             0 |  
  80. | Sending data                   | 0.003833 |            0 |             0 |  
  81. | end                            | 0.000054 |            0 |             0 |  
  82. | query end                      | 0.000045 |            0 |             0 |  
  83. | closing tables                 | 0.000045 |            0 |             0 |  
  84. | freeing items                  | 0.000072 |            0 |             0 |  
  85. | Waiting for query cache lock   | 0.000033 |            0 |             0 |  
  86. | freeing items                  | 0.000785 |            0 |             0 |  
  87. | Waiting for query cache lock   | 0.000016 |            0 |             0 |  
  88. | freeing items                  | 0.000002 |            0 |             0 |  
  89. | storing result in query cache  | 0.000005 |            0 |             0 |  
  90. | logging slow query             | 0.000003 |            0 |             0 |  
  91. | cleaning up                    | 0.000004 |            0 |             0 |  
  92. +--------------------------------+----------+--------------+---------------+  
  93. 24 rows in set (0.00 sec)  

再看同结构数据的MyISAM表

  1. mysql> create table payment_myisam like payment;  
  2. Query OK, 0 rows affected (0.03 sec)  
  3.   
  4. mysql> alter table payment_myisam engine=myisam;  
  5. Query OK, 0 rows affected (0.03 sec)  
  6. Records: 0  Duplicates: 0  Warnings: 0  
  7.   
  8. mysql> show create table payment_myisam\G  
  9. *************************** 1. row ***************************  
  10.        Table: payment_myisam  
  11. Create Table: CREATE TABLE `payment_myisam` (  
  12.   `payment_id` smallint(5) unsigned NOT NULL AUTO_INCREMENT,  
  13.   `customer_id` smallint(5) unsigned NOT NULL,  
  14.   `staff_id` tinyint(3) unsigned NOT NULL,  
  15.   `rental_id` int(11) DEFAULT NULL,  
  16.   `amount` decimal(5,2) NOT NULL,  
  17.   `payment_date` datetime NOT NULL,  
  18.   `last_update` timestamp NOT NULL DEFAULT CURRENT_TIMESTAMP ON UPDATE CURRENT_TIMESTAMP,  
  19.   PRIMARY KEY (`payment_id`),  
  20.   KEY `idx_fk_staff_id` (`staff_id`),  
  21.   KEY `idx_fk_customer_id` (`customer_id`),  
  22.   KEY `fk_payment_rental` (`rental_id`)  
  23. ) ENGINE=MyISAM DEFAULT CHARSET=utf8  
  24. 1 row in set (0.00 sec)  
  25.   
  26. mysql> insert into payment_myisam select * from payment;  
  27. Query OK, 16049 rows affected (0.10 sec)  
  28. Records: 16049  Duplicates: 0  Warnings: 0  
  1. mysql> select count(*) from payment_myisam;  
  2. +----------+  
  3. | count(*) |  
  4. +----------+  
  5. |    16049 |  
  6. +----------+  
  7. 1 row in set (0.01 sec)  
  8.   
  9. mysql> show profiles;  
  10.                                                                                                                                                                           
  11. |       23 | 0.09449600 | insert into payment_myisam select * from payment                                                                                                                                                                                                                                        |  
  12. |       24 | 0.00023500 | select count(*) from payment_myisam                                                                                                                                                                                                                                                     |  
  13.   
  14. 15 rows in set (0.00 sec)  
  1. mysql> show profile for query 24;  
  2. +--------------------------------+----------+  
  3. | Status                         | Duration |  
  4. +--------------------------------+----------+  
  5. | starting                       | 0.000026 |  
  6. | Waiting for query cache lock   | 0.000003 |  
  7. | checking query cache for query | 0.000037 |  
  8. | checking permissions           | 0.000006 |  
  9. | Opening tables                 | 0.000015 |  
  10. | System lock                    | 0.000009 |  
  11. | Waiting for query cache lock   | 0.000019 |  
  12. | init                           | 0.000011 |  
  13. | optimizing                     | 0.000006 |  
  14. | executing                      | 0.000006 |  
  15. | end                            | 0.000000 |  
  16. | query end                      | 0.000001 |  
  17. | closing tables                 | 0.000008 |  
  18. | freeing items                  | 0.000019 |  
  19. | Waiting for query cache lock   | 0.000008 |  
  20. | freeing items                  | 0.000013 |  
  21. | Waiting for query cache lock   | 0.000008 |  
  22. | freeing items                  | 0.000008 |  
  23. | storing result in query cache  | 0.000015 |  
  24. | logging slow query             | 0.000008 |  
  25. | cleaning up                    | 0.000009 |  
  26. +--------------------------------+----------+  

可以看到在count操作MyISAM表时没有sending data这个状态，和Innodb不同，MyISAM不需要访问表数据，所以count操作比Innodb快。

原文链接：http://blog.csdn.net/staricqxyz/article/details/18943001

bit-caffe
关注
  • 0
    点赞
  • 踩
  • 0
    收藏
    觉得还不错? 一键收藏
  • 0
    评论
专栏目录
Windows下Neor Profile SQL 配置监控centos（linux）mysql详细步骤
11-16
Windows下Neor Profile SQL 配置监控centos（linux）mysql详细步骤
mysql qcache inserts_MySQL查询缓存的小奥秘
weixin_35076078的博客
02-07 237
有情怀，有干货，微信搜索【三太子敖丙】关注这个不一样的程序员。本文 GitHub https://github.com/JavaFamily 已收录，有一线大厂面试完整考点、资料以及我的系列文章。前言我们知道，缓存的设计思想在RDBMS数据库中无处不在，就拿号称2500w行代码，bug堆积如山的Oracle数据库来说，SQL的执行计划可以缓存在library cache中避免再次执行相同SQL发...
mysql profile 工具Neor Profile
码农的专栏
01-16 1686
一、下载Download - Neor Profile SQL http://www.profilesql.com/files/download/sqlprofiler-4.1.1.exe Neor Profile 这款免费的mysql 分析工具，这个工具类似于一个代理 本地启动一个mysql 代理服务，类似于MyCat 二、安装完成配置 三、代码连接代理 using System; using System.Collections.Generic; using System...
mysql监控工具Neor Profile Sql的使用和配置
weixin_30563917的博客
07-25 1966
在老旧项目优化过程中，sql优化总绕不过去，要优化mysql索引和语句优化，但是项目大，创建了sql日志，也不可能每一条手动去分析， 这里推荐一款工具Neor Profile Sql工具，开始是看一篇博客写的这个工具，但是没有介绍具体使用方法。 下面是我自己搞得一个汉化文件，如果各位觉得哪里汉化的有问题，可以在评论回复一下，我在进行修改。 放上工具的安装文件和汉化文件下载地址 链...
mysql profile工具_mysql監控工具Neor Profile Sql的使用和配置
weixin_29387991的博客
01-27 756
在老舊項目優化過程中，sql優化總繞不過去，要優化mysql索引和語句優化，但是項目大，創建了sql日志，也不可能每一條手動去分析，這里推薦一款工具Neor Profile Sql工具，開始是看一篇博客寫的這個工具，但是沒有介紹具體使用方法。下面是我自己搞得一個漢化文件，如果各位覺得哪里漢化的有問題，可以在評論回復一下，我在進行修改。放上工具的安裝文件和漢化文件下載地址鏈接：https://pan...
MySQL性能分析工具profile使用教程
01-19
分析SQL执行带来的开销是优化SQL的重要手段。在MySQL数据库中，可以通过...本文描述了如何使用MySQL profile，不涉及具体的样例分析。 1、有关profile的描述 代码如下: –当前版本  root@localhost[sakila]> show v
MySQL利用profile分析慢sql详解（group left join效率高于子查询）
09-09
最近因为一个用了子查询的sql语句查询很慢，严重影响了性能，所以需要进行优化，下面这篇文章主要跟大家介绍了关于MySQL利用profile分析慢sql的相关资料，文中介绍的非常详细，需要的朋友们可以参考借鉴，下面来一起...
mysql 显示SQL语句执行时间的代码
01-21
MySQL 的 SQL 語法調整主要都是使用 EXPLAIN , 但是這個並沒辦法知道詳細的 Ram(Memory)/CPU 等使用量. 於 MySQL 5.0.37 以上開始支援 MySQL Query Profiler, 可以查詢到此 SQL 會執行多少時間, 並看出 CPU/Memory ...
MySQL优化篇：show profile进行SQL分析
12-14
文章目录1、SQL分析、调优和排查的大体流程2、show profile是什么3、show profile分析步骤（1）是否支持，看看当前的MySQL版本是否支持（2）开启功能，默认是关闭，使用前需要开启（3）运行SQL（4）查看结果，show ...
SQL Profiler 模板配置和简单的使用
01-16
SQL Profiler 模板配置和简单的使用 跟踪模板设置，设置之后以后都可以使用此模板 简单的设置跟踪条件，筛选条件
android profile分析器,Android性能优化之分析工具Profile的使用
weixin_35510761的博客
05-25 5026
Profile是AndroidStudio3.0之后新加的功能，Profile分为三大块分析：CPU、内存和网络，本篇主要介绍内存分析。一.首先我们开下Profile怎么开始使用?点击下图按钮运行APP就会开启Profile分析： 运行后如下： 二.内存分析：如下图，选中MEMORY: (1)各按钮说明： 其中：标注1对应的按键用于强制内存回收。标注2对应的按键用于抓取进程内存的dump信息。标...
MySQL中的profile性能分析工具
BlueLotuss的博客
08-26 610
MySQL中的profile性能分析工具 profile简述 profile是mysql提供可以用来分析当前会话中语句执行的资源消耗情况。可以用于SQL的调优测量。默认情况下，保存最近15次的运行结果。其默认是关闭，使用前需要开启，可以通过SET profiling = 1命令进行开启。 mysql> SELECT @@profiling; +-------------+ | @@profiling | +-------------+ | 0 | +-------------+ 1
mysql- profile使用
Frankltf的博客
05-03 392

 Mysql分析-profile详解 简介 Profiling是从 mysql5.0.3版本以后才开放的。 启动profile之后，所有查询包括错误的语句都会记录在内。 此工具可用来查询SQL执行状态，System lock和Table lock 花多少时间等等， 对定位一条语句的I/O消耗和CPU消耗 非常重要。(SQL 语句执行所消耗的最大两部分资源就是IO和...
『Mysql』Mysql中自带的性能分析工具Profile
老陈聊架构
10-31 1224
📣读完这篇文章里你能收获到， profile的操作步骤， profile的命令详细说明
mysql profile查询sql执行的每个过程执行时间
桀骜浮沉的博客
11-21 476
show profiles 能够在做SQL优化时帮助我们了解时间都耗费到哪里去了。
数据库优化
weixin_30530939的博客
02-26 176
昨天遇到一个问题, 200万的表里查询9万条数据, 耗时达63秒. 200万数据不算多, 查询9万也还好. 怎么用了这么长的时间呢? 问题是一句非常简单的sql. select * from tk_template_product t WHERE t.product_id=1135　 前提: product_id已经添加了索引, 可依然慢的无法接受. 目标: 优化sql, 至少要在5...
分析mysql的QueryCache在相同语义SQL是否可以使用查询缓存
hongshun0651140的博客
11-30 661
首先开启QueryCache，在配置文件my.cnf query_cache_type   = 1 问题：在SELECT * FROM test.USER;已经进入QueryCache的情况下SELECT       * FROM test.USER;以及SELECT * FROM test.USER limit 1是否会使用缓存数据? set profiling=1; reset
查看mysql正在执行的SQL语句，使用profile分析SQL执行状态
xianyoudianxueyuan的专栏
07-28 3185
http://qq85609655.iteye.com/blog/2113960   1)我们先通过status命令查看Mysql运行状态 mysql> status; -------------- mysql Ver 14.14 Distrib 5.1.73, for redhat-linux-gnu (x86_64) using readline 5.1 Connection
mysql索引无效且sending data耗时巨大原因分析
weixin_34114823的博客
06-14 237
　一朋友最近新上线一个项目，本地测试环境跑得好好的，部署到线上却慢得像蜗牛一样。后来查询了一下发现一个sql执行了16秒，有些长的甚至80秒。本地运行都是毫秒级别的查询。下面记录一下困扰了两天的，其中一条sql的优化。 　　表结构及现象描述： CREATE TABLE `wp_goods` ( `id` bigint(20) unsigned NOT NULL AUTO_INCR...
mysql查看sql执行效率
最新发布
05-19
可以通过使用MySQL的内置工具`EXPLAIN`和`SHOW PROFILE`来查看SQL语句的执行效率。 1. `EXPLAIN`命令可以用于查看MySQL执行SELECT语句的查询计划，它会返回一张表格，其中包含了MySQL执行查询的详细信息，如使用了...

“相关推荐”对你有帮助么？

  • 非常没帮助
  • 没帮助
  • 一般
  • 有帮助
  • 非常有帮助
提交
评论
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

当前余额3.43元 前往充值 >
需支付：10.00元
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付元
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明：

1.余额是钱包充值的虚拟货币，按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载，可以购买VIP、付费专栏及课程。

余额充值