个人中心
文章发布
退出
作者热门文章
- ubuntu12.04环境下使用kvm ioctl接口实现最简单的虚拟机
- Ubuntu 通过无线网络安装Ubuntu Server启动系统后连接无线网络的方法
- 在Ubuntu上搭建网桥的方法
- ubuntu 虚拟机上网方式及相关配置详解
25
4
CFSDN坚持开源创造价值，我们致力于搭建一个资源共享平台，让每一个IT人在这里找到属于你的精彩世界.
这篇CFSDN的博客文章MySQL中使用SHOW PROFILE命令分析性能的用法整理由作者收集整理，如果你对这篇文章有兴趣，记得点赞哟.
show profile是由Jeremy Cole捐献给MySQL社区版本的。默认的是关闭的，但是会话级别可以开启这个功能。开启它可以让MySQL收集在执行语句的时候所使用的资源。为了统计报表,把profiling设为1 。
|
1
|
mysql>
SET
profiling = 1;
|
之后在运行一个查询 。
|
1
2
3
4
5
6
7
|
mysql>
SELECT
COUNT
(
DISTINCT
actor.first_name)
AS
cnt_name,
COUNT
(*)
AS
cnt
->
FROM
sakila.film_actor
->
INNER
JOIN
sakila.actor USING(actor_id)
->
GROUP
BY
sakila.film_actor.film_id
->
ORDER
BY
cnt_name
DESC
;
...
997
rows
in
set
(0.03 sec)
|
这个执行语句的剖析信息存储在这个会话中。使用SHOW PROFILES进行查看.
|
1
|
mysql> SHOW PROFILES\G
|
|
1
2
3
4
|
*************************** 1. row ***************************
Query_ID: 1
Duration: 0.02596900
Query: SELECT COUNT(DISTINCT actor.first_name) AS cnt_name,...
|
你可以使用SHOW PROFILE语句来获取已经存储的剖析数据。如果不加参数，会显示状态以及它们持续的时间.
|
1
|
mysql> SHOW PROFILE;
|
|
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
|
+------------------------+-----------+
| Status | Duration |
+------------------------+-----------+
| (initialization) | 0.000005 |
| Opening tables | 0.000033 |
| System lock | 0.000037 |
| Table lock | 0.000024 |
| init | 0.000079 |
| optimizing | 0.000024 |
| statistics | 0.000079 |
| preparing | 0.00003 |
| Creating tmp table | 0.000124 |
| executing | 0.000008 |
| Copying to tmp table | 0.010048 |
| Creating sort index | 0.004769 |
| Copying to group table | 0.0084880 |
| Sorting result | 0.001136 |
| Sending data | 0.000925 |
| end | 0.00001 |
| removing tmp table | 0.00004 |
| end | 0.000005 |
| removing tmp table | 0.00001 |
| end | 0.000011 |
| query end | 0.00001 |
| freeing items | 0.000025 |
| removing tmp table | 0.00001 |
| freeing items | 0.000016 |
| closing tables | 0.000017 |
| logging slow query | 0.000006 |
+------------------------+-----------+
|
每行都是状态变化的过程以及它们持续的时间。Status那一列和SHOW FULL PROCESSLIST的State应该是一致的。 这个值是来自于thd->proc_info变量。因此你所查看的这个值是直接来自MySQL内部的。尽管这些数值是比较直接易懂的，你也可以查看MySQL手册。 你可以给SHOW PROFILES指定一个Query_ID来查看指定的语句，还可以给输出添加新的列。如，查看用户和CPU使用。可以用如下命令。 。
|
1
|
mysql> SHOW PROFILE CPU
FOR
QUERY 1;
|
SHOW PROFILE可以深入的查看服务器执行语句的工作情况。以及也能帮助你理解执行语句消耗时间的情况。一些限制是它没有实现的功能，不能查看和剖析其他连接的语句，以及剖析时所引起的消耗.
SHOW PROFILES显示最近发给服务器的多条语句，条数根据会话变量profiling_history_size定义，默认是15，最大值为100。设为0等价于关闭分析功能.
SHOW PROFILE FOR QUERY n，这里的n就是对应SHOW PROFILES输出中的Query_ID.
例如:
|
1
|
mysql> show profiles;
|
|
1
2
3
4
5
6
7
8
9
10
11
12
13
|
+----------+-------------+---------------------------------------+
| Query_ID | Duration | Query |
+----------+-------------+---------------------------------------+
| 1 | 0.00037700 | alter table table1 drop column c3 int |
| 2 | 70.37123800 | alter table table1 drop column c3 |
| 3 | 0.00124500 | show tables |
| 4 | 0.00569800 | select * from table1 where id=2 |
| 5 | 0.00068500 | select count(1) from tables1 |
| 6 | 0.00197900 | select count(1) from table1 |
| 7 | 0.00105900 | alter table tables1 drop c1 |
| 8 | 0.00800200 | alter table table1 drop c1 |
+----------+-------------+---------------------------------------+
8 rows in set (0.00 sec)
|
。
|
1
|
mysql> SHOW PROFILE
FOR
QUERY 2; #查看
alter
table
table1
drop
column
c3的分析
|
|
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
|
+------------------------------+-----------+
| Status | Duration |
+------------------------------+-----------+
| starting | 0.000183 |
| checking permissions | 0.000057 |
| checking permissions | 0.000059 |
| init | 0.000060 |
| Opening tables | 0.000071 |
| System lock | 0.000062 |
| setup | 0.000080 |
| creating table | 0.005052 |
| After create | 0.000220 |
| copy to tmp table | 0.000244 |
| rename result table | 70.364027 |
| end | 0.000575 |
| Waiting for query cache lock | 0.000062 |
| end | 0.000075 |
| query end | 0.000057 |
| closing tables | 0.000061 |
| freeing items | 0.000080 |
| logging slow query | 0.000056 |
| logging slow query | 0.000098 |
| cleaning up | 0.000059 |
+------------------------------+-----------+
20 rows in set (0.00 sec)
|
如果没有指定FOR QUERY，那么输出最近一条语句的信息.
LIMIT部分的用法与SELECT中LIMIT子句一致，不赘述.
type是可选的，取值范围可以如下:
例:
|
1
|
mysql> show profile cpu
for
query 2;
|
|
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
|
+------------------------------+-----------+----------+------------+
| Status | Duration | CPU_user | CPU_system |
+------------------------------+-----------+----------+------------+
| starting | 0.000183 | 0.000000 | 0.000000 |
| checking permissions | 0.000057 | 0.000000 | 0.000000 |
| checking permissions | 0.000059 | 0.000000 | 0.000000 |
| init | 0.000060 | 0.000000 | 0.000000 |
| Opening tables | 0.000071 | 0.000000 | 0.000000 |
| System lock | 0.000062 | 0.000000 | 0.000000 |
| setup | 0.000080 | 0.000000 | 0.001000 |
| creating table | 0.005052 | 0.003000 | 0.001000 |
| After create | 0.000220 | 0.000000 | 0.000000 |
| copy to tmp table | 0.000244 | 0.000000 | 0.000000 |
| rename result table | 70.364027 | 7.470864 | 41.612674 |
| end | 0.000575 | 0.000000 | 0.001000 |
| Waiting for query cache lock | 0.000062 | 0.000000 | 0.000000 |
| end | 0.000075 | 0.000000 | 0.000000 |
| query end | 0.000057 | 0.000000 | 0.000000 |
| closing tables | 0.000061 | 0.000000 | 0.000000 |
| freeing items | 0.000080 | 0.000000 | 0.000000 |
| logging slow query | 0.000056 | 0.000000 | 0.000000 |
| logging slow query | 0.000098 | 0.000000 | 0.000000 |
| cleaning up | 0.000059 | 0.000000 | 0.000000 |
+------------------------------+-----------+----------+------------+
20 rows in set (0.00 sec)
|
ps: SHOW PROFILE ALL FOR QUERY 2;的信息还可以通过SELECT * FROM information_schema.profiling WHERE query_id = 2 ORDER BY seq;获取.
作用范围 这个命令只是在本会话内起作用，即无法分析本会话外的语句.
开启分析功能后，所有本会话中的语句都被分析（甚至包括执行错误的语句），除了SHOW PROFILE和SHOW PROFILES两句本身.
应用示例：使用SHOW PROFILE分析查询缓存命中的性能优势.
|
1
|
mysql>
set
profiling=1;
|
|
1
|
Query OK, 0 rows affected (0.00 sec)
|
。
|
1
|
mysql>
select
count
(1)
as
cnt
from
tran_excution;
|
|
1
2
3
4
5
6
|
+-------+
| cnt |
+-------+
| 14225 |
+-------+
1 row in set (0.00 sec)
|
由于已经启用了查询缓存，相同查询（非分区表）可以直接在查询缓存中命中.
|
1
|
mysql>
select
count
(1)
as
cnt
from
tran_excution;
|
我们仔细看下两个同样的语句的分析.
|
1
|
mysql> show profile source
for
query 1;
|
|
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
|
+--------------------------------+----------+-----------------------+---------------+-------------+
| Status | Duration | Source_function | Source_file | Source_line |
+--------------------------------+----------+-----------------------+---------------+-------------+
| starting | 0.000048 | NULL | NULL | NULL |
| Waiting for query cache lock | 0.000013 | try_lock | sql_cache.cc | 454 |
| checking query cache for query | 0.000040 | send_result_to_client | sql_cache.cc | 1561 |
| checking permissions | 0.000023 | check_access | sql_parse.cc | 4751 |
| Opening tables | 0.000040 | open_tables | sql_base.cc | 4831 |
| System lock | 0.000020 | mysql_lock_tables | lock.cc | 299 |
| Waiting for query cache lock | 0.000037 | try_lock | sql_cache.cc | 454 |
| init | 0.000020 | mysql_select | sql_select.cc | 2579 |
| optimizing | 0.000015 | optimize | sql_select.cc | 865 |
| statistics | 0.000017 | optimize | sql_select.cc | 1056 |
| preparing | 0.000016 | optimize | sql_select.cc | 1078 |
| executing | 0.000015 | exec | sql_select.cc | 1836 |
| Sending data | 0.003875 | exec | sql_select.cc | 2380 |
| end | 0.000018 | mysql_select | sql_select.cc | 2615 |
| query end | 0.000015 | mysql_execute_command | sql_parse.cc | 4440 |
| closing tables | 0.000016 | mysql_execute_command | sql_parse.cc | 4492 |
| freeing items | 0.000016 | mysql_parse | sql_parse.cc | 5640 |
| Waiting for query cache lock | 0.000012 | try_lock | sql_cache.cc | 454 |
| freeing items | 0.000032 | NULL | NULL | NULL |
| Waiting for query cache lock | 0.000017 | try_lock | sql_cache.cc | 454 |
| freeing items | 0.000016 | NULL | NULL | NULL |
| storing result in query cache | 0.000017 | end_of_result | sql_cache.cc | 1020 |
| logging slow query | 0.000018 | log_slow_statement | sql_parse.cc | 1461 |
| logging slow query | 0.000050 | log_slow_statement | sql_parse.cc | 1470 |
| cleaning up | 0.000018 | dispatch_command | sql_parse.cc | 1417 |
+--------------------------------+----------+-----------------------+---------------+-------------+
25 rows in set (0.00 sec)
|
。
|
1
|
mysql> show profile source
for
query 2;
|
|
1
2
3
4
5
6
7
8
9
10
11
12
13
|
+--------------------------------+----------+-----------------------+--------------+-------------+
| Status | Duration | Source_function | Source_file | Source_line |
+--------------------------------+----------+-----------------------+--------------+-------------+
| starting | 0.000051 | NULL | NULL | NULL |
| Waiting for query cache lock | 0.000014 | try_lock | sql_cache.cc | 454 |
| checking query cache for query | 0.000016 | send_result_to_client | sql_cache.cc | 1561 |
| checking privileges on cached | 0.000013 | send_result_to_client | sql_cache.cc | 1652 |
| checking permissions | 0.000015 | check_access | sql_parse.cc | 4751 |
| sending cached result to clien | 0.000036 | send_result_to_client | sql_cache.cc | 1749 |
| logging slow query | 0.000017 | log_slow_statement | sql_parse.cc | 1461 |
| cleaning up | 0.000018 | dispatch_command | sql_parse.cc | 1417 |
+--------------------------------+----------+-----------------------+--------------+-------------+
8 rows in set (0.00 sec)
|
可以清晰地看到缓存中命中时，大大节省了后台的开销。当然缓存的使用也需要根据各种场景（表的数据规模，更新频率等）考察使用，并不是启用缓存就一定能够提高查询效率。这里仅仅作为SHOW PROFILE的一个应用示例.
最后此篇关于MySQL中使用SHOW PROFILE命令分析性能的用法整理的文章就讲到这里了,如果你想了解更多关于MySQL中使用SHOW PROFILE命令分析性能的用法整理的内容请搜索CFSDN的文章或继续浏览相关文章，希望大家以后支持我的博客！ 。
25
4
0
在 Python 2.7 和 Python 3.6 中，我发现这有效: from cProfile import Profile; p = Profile(); p.enable() ...而这引发了
我正在尝试在 Windows 8 PRO 64 位上的 Visual Studio 2012 RTM 中为控制台程序启动性能分析 session (分析/启动性能分析)。 我收到一条弹出消息通知我 C
我在 windows 上有一个应用程序，它在单声道上运行得很好。但是，当我尝试对其进行分析时: mono --profile=log program.exe 我得到: The 'log' profil
我正在尝试使用 Django 创建注册表单。我在提交表单时收到此错误。 这就是我所做的。 models.py from django.db import models from django.cont
是否可以从 Flash Builder 导出分析结果？ 我需要它们，因为我想根据方法名称进行过滤，但 Flash Builder 的内置过滤不允许这样做。 最佳答案 它隐藏在 GUI 的一个完全隐蔽、
我真的很喜欢热图，但我需要的是热图背后的数字(又名相关矩阵)。 有没有简单的方法来提取数字？ 最佳答案 从文档开始追踪有点困难；具体来说 来自 report structure然后深入研究以下函数 g
我有一个 POM，它声明了我的项目常见的 Web 应用程序内容。我将它用作所有 Web 应用程序的父级。 是否可以仅在包装为 war 时激活配置文件？我已经尝试过属性方法，但这不起作用(因为它不是系统
在数据帧上运行 pandas-profiling 时，我看到它将索引分析为一个变量。注意:我的索引是唯一键(命名为UUID) 有没有办法排除引入索引上报？ 我知道我可以在 pandas 中删除它，但在
在数据帧上运行 pandas-profiling 时，我看到它将索引分析为一个变量。注意:我的索引是唯一键(命名为UUID) 有没有办法排除引入索引上报？ 我知道我可以在 pandas 中删除它，但在
我正在使用 Intel Vtune 来分析需要在另一台机器上进行 sudo 访问的远程应用程序。之前我已经能够在不需要 sudo 访问的那台机器上分析远程应用程序，但英特尔 Vtune 不适用于需要
我已经在我的 MVC 4 应用程序上安装了 Mini-Profiler，它运行得非常棒。我遇到的唯一问题是 UI 覆盖了我 UI 的关键部分。我可以使用 css 在页面上移动它，但理想情况下我想这样做
在使用 Chrome devtools 分析堆快照时，我似乎无法弄清楚查看分离的 DOM 树时颜色的含义。红色和黄色有什么区别？ 最佳答案 有很好的解释available here . 从文章: Re
分析器中 SQL Server 跟踪的输出包含 CPU 和持续时间列(以及其他列)。这些值的单位是什么？ 最佳答案 CPU 以毫秒为单位。在 sql server 2005 及更高版本中，保存到文件或
我有一个奇怪的问题，我正在使用 MiniProfiler，它很棒，在我的本地机器上没有任何问题，但它在我们的测试服务器上的表现似乎有所不同。它似乎会生成许多对 mini-profiler-resour
我想知道优先级 的application-{profile}.properties文件，如果有多个 spring.profiles.active添加。 例如: 比方说，我有这个 spring.prof
我有一个名为“isActive”的助手和一个名为“create”的模板.. 见下文 Template.create.isActive = function () { return Meteor.u
这是我面临的场景:我正在使用 MiniProfiler 来分析一些操作。但它缺少我必须使用的特定功能。有一个设置文件 MiniProfiler 可以让我做一些配置，比如分析什么考虑什么，什么不考虑，什
哇，这完全令人困惑，而且 dojo 1.8 文档似乎是围绕构建层的完整 clusterf**k。有人知道那里发生了什么吗？ 在构建脚本示例配置文件中，示例 amd.profile.js 有 profi
我正在为一个内部项目使用出色的 MVC Mini Profiler，但希望它能够显示时间信息，无论您是谁。理想情况下，如果用户是站点的管理员或开发人员，我希望能够显示完整的分析信息，如果用户只是标准用
打开Android Profiler编译出现如下错误: FAILURE:构建失败并出现异常。 什么地方出了错: Execution failed for task ':app:transformCla
我是一名优秀的程序员,十分优秀！