Mysql性能跟踪器 profiling 分析工具使用

最新推荐文章于 2023-11-30 17:30:13 发布
最新推荐文章于 2023-11-30 17:30:13 发布
阅读量182 收藏
点赞数
文章标签： 数据库

profiling功能是对mysql当前连接进行设置的，退出后恢复就木有了

开启：SET profiling=1;

关闭：SET profiling=0;

其他

SHOW profiles;

show profile for query 2;

show profile block io,cpu for query 2;


具体使用

------------

要注意两点。

1、不过版本要在5.0.37之后

手册上介绍：(SHOW PROFILES and SHOW PROFILE were added in MySQL 5.0.37. )
Important
Please note that the SHOW PROFILE and SHOW PROFILES functionality is part of the MySQL 5.0 Community Server only.
2、变量profiling是用户变量，每次都得重新启用。
以下是我做的一些实验。数据很明显，就不多解释了。
先进入某个库中然后运行：show profiling;如下：
mysql> use test
Database changed
mysql> set profiling=1;
Query OK, 0 rows affected (0.00 sec)

mysql> show tables;
+----------------+

| Tables_in_test |
+----------------+

| bag_item |
| bag_user |
| score |
| t |
+----------------+

4 rows in set (0.03 sec)

mysql> select count(*) from t;
+----------+

| count(*) |
+----------+

| 2097152 |
+----------+

1 row in set (0.74 sec)

mysql> show profiles;
+----------+------------+------------------------+
| Query_ID | Duration | Query|
+----------+------------+------------------------+
|1 | 0.02717000 | show tables|
|2 | 0.74770100 | select count(*) from t |
+----------+------------+------------------------+
2 rows in set (0.00 sec)

mysql> show profile for query 2;
+--------------------------------+----------+
| Status | Duration |
+--------------------------------+----------+
| (initialization) | 0.000004 |
| checking query cache for query | 0.000044 |
| Opening tables | 0.000012 |
| System lock| 0.000017 |
| Table lock | 0.00003|
| init | 0.000013 |
| optimizing | 0.000008 |
| statistics | 0.000013 |
| preparing| 0.000011 |
| executing| 0.000006 |
| Sending data | 0.747313 |
| end| 0.000014 |
| query end| 0.000006 |
| storing result in query cache| 0.000006 |
| freeing items| 0.000012 |
| closing tables | 0.000009 |
| logging slow query | 0.000183 |
+--------------------------------+----------+
17 rows in set (0.00 sec)

mysql> show profile block io,cpu for query 2;
+--------------------------------+----------+----------+------------+--------------+---------------+
| Status | Duration | CPU_user | CPU_system | Block_ops_in | Block_ops_out |
+--------------------------------+----------+----------+------------+--------------+---------------+
| (initialization) | 0.000004 | 0| 0|0 | 0 |
| checking query cache for query | 0.000044 | 0| 0|0 | 0 |
| Opening tables | 0.000012 | 0| 0|0 | 0 |
| System lock| 0.000017 | 0| 0|0 | 0 |
| Table lock | 0.00003| 0| 0|0 | 0 |
| init | 0.000013 | 0| 0|0 | 0 |
| optimizing | 0.000008 | 0| 0|0 | 0 |
| statistics | 0.000013 | 0| 0|0 | 0 |
| preparing| 0.000011 | 0| 0|0 | 0 |
| executing| 0.000006 | 0| 0|0 | 0 |
| Sending data | 0.747313 | 0.746887 | 0|0 | 0 |
| end| 0.000014 | 0| 0|0 | 0 |
| query end| 0.000006 | 0| 0|0 | 0 |
| storing result in query cache| 0.000006 | 0| 0|0 | 0 |
| freeing items| 0.000012 | 0| 0|0 | 0 |
| closing tables | 0.000009 | 0| 0|0 | 0 |
| logging slow query | 0.000183 | 0| 0|0 | 0 |
+--------------------------------+----------+----------+------------+--------------+---------------+
17 rows in set (0.00 sec)

mysql> insert into t(username) select username from t;
Query OK, 2097152 rows affected (34.17 sec)
Records: 2097152Duplicates: 0Warnings: 0

mysql> show profiles;
+----------+-------------+------------------------------------------------+
| Query_ID | Duration| Query|
+----------+-------------+------------------------------------------------+
|1 |0.02717000 | show tables|
|2 |0.74770100 | select count(*) from t |
|3 |0.00004200 | show prifile for query 2 |
|4 | 34.30410100 | insert into t(username) select username from t |
+----------+-------------+------------------------------------------------+
4 rows in set (0.00 sec)

mysql> show profile cpu,block io,memory,swaps for query 4;
+------------------------------+-----------+-----------+------------+--------------+---------------+-------+
| Status | Duration| CPU_user| CPU_system | Block_ops_in | Block_ops_out | Swaps |
+------------------------------+-----------+-----------+------------+--------------+---------------+-------+
| (initialization) | 0.000038| 0 | 0|0 | 0 | 0 |
| checking permissions | 0.000016| 0 | 0|0 | 0 | 0 |
| Opening tables | 0.000014| 0 | 0|0 | 0 | 0 |
| System lock| 0.000007| 0 | 0|0 | 0 | 0 |
| Table lock | 0.000013| 0 | 0|0 | 0 | 0 |
| init | 0.000015| 0 | 0|0 | 0 | 0 |
| optimizing | 0.000006| 0 | 0|0 | 0 | 0 |
| statistics | 0.000012| 0 | 0|0 | 0 | 0 |
| preparing| 0.000011| 0 | 0|0 | 0 | 0 |
| Creating tmp table | 0.000029| 0 | 0|0 | 0 | 0 |
| executing| 0.000005| 0 | 0|0 | 0 | 0 |
| Copying to tmp table | 1.262877| 1.24981 | 0.012998 |0 | 0 | 0 |
| converting HEAP to MyISAM| 0.384814| 0.187971| 0.19797|0 | 0 | 0 |
| Copying to tmp table on disk | 1.417069| 1.203817| 0.191971 |0 | 0 | 0 |
| Sending data | 31.104185 | 13.965877 | 0.843872 |0 | 0 | 0 |
| end| 0.000017| 0 | 0|0 | 0 | 0 |
| removing tmp table | 0.134872| 0 | 0.029995 |0 | 0 | 0 |
| end| 0.000026| 0 | 0|0 | 0 | 0 |
| query end| 0.000006| 0 | 0|0 | 0 | 0 |
| freeing items| 0.000011| 0 | 0|0 | 0 | 0 |
| closing tables | 0.000009| 0 | 0|0 | 0 | 0 |
| logging slow query | 0.000049| 0 | 0|0 | 0 | 0 |
+------------------------------+-----------+-----------+------------+--------------+---------------+-------+
22 rows in set (0.00 sec)

mysql> select count(*) from t;
+----------+
| count(*) |
+----------+
|4194304 |
+----------+
1 row in set (1.51 sec)

mysql> show profiles;
+----------+-------------+------------------------------------------------+
| Query_ID | Duration| Query|
+----------+-------------+------------------------------------------------+
|1 |0.02717000 | show tables|
|2 |0.74770100 | select count(*) from t |
|3 |0.00004200 | show prifile for query 2 |
|4 | 34.30410100 | insert into t(username) select username from t |
|5 |1.50563800 | select count(*) from t |
+----------+-------------+------------------------------------------------+
5 rows in set (0.00 sec)

mysql> show profile cpu,block io,memory,swaps,context switches,source for query 5;
+--------------------------------+----------+----------+------------+-------------------+---------------------+--------------+---------------+-------+---------------------------+---------------+-------------+
| Status | Duration | CPU_user | CPU_system | Context_voluntary | Context_involuntary | Block_ops_in | Block_ops_out | Swaps | Source_function | Source_file | Source_line |
+--------------------------------+----------+----------+------------+-------------------+---------------------+--------------+---------------+-------+---------------------------+---------------+-------------+
| (initialization) | 0.000004 | 0| 0| 0 | 0 |0 | 0 | 0 | send_result_to_client | sql_cache.cc|1099 |
| checking query cache for query | 0.000048 | 0| 0| 0 | 0 |0 | 0 | 0 | open_tables | sql_base.cc |2629 |
| Opening tables | 0.000011 | 0| 0| 0 | 0 |0 | 0 | 0 | mysql_lock_tables | lock.cc | 153 |
| System lock| 0.000007 | 0| 0| 0 | 0 |0 | 0 | 0 | mysql_lock_tables | lock.cc | 162 |
| Table lock | 0.000032 | 0| 0| 0 | 0 |0 | 0 | 0 | mysql_select| sql_select.cc |2242 |
| init | 0.000013 | 0| 0| 0 | 0 |0 | 0 | 0 | optimize| sql_select.cc | 745 |
| optimizing | 0.000008 | 0| 0| 0 | 0 |0 | 0 | 0 | optimize| sql_select.cc | 904 |
| statistics | 0.000013 | 0| 0| 0 | 0 |0 | 0 | 0 | optimize| sql_select.cc | 914 |
| preparing| 0.000011 | 0| 0| 0 | 0 |0 | 0 | 0 | exec| sql_select.cc |1565 |
| executing| 0.000006 | 0| 0| 0 | 0 |0 | 0 | 0 | exec| sql_select.cc |2083 |
| Sending data | 1.505401 | 1.504771 | 0| 3 | 5 |0 | 0 | 0 | mysql_select| sql_select.cc |2287 |
| end| 0.000015 | 0| 0| 0 | 0 |0 | 0 | 0 | mysql_execute_command | sql_parse.cc|5122 |
| query end| 0.000005 | 0| 0| 0 | 0 |0 | 0 | 0 | query_cache_end_of_result | sql_cache.cc| 729 |
| storing result in query cache| 0.000007 | 0| 0| 0 | 0 |0 | 0 | 0 | mysql_parse | sql_parse.cc|6116 |
| freeing items| 0.00001| 0| 0| 0 | 0 |0 | 0 | 0 | dispatch_command| sql_parse.cc|2146 |
| closing tables | 0.000009 | 0| 0| 0 | 0 |0 | 0 | 0 | log_slow_statement| sql_parse.cc|2204 |
| logging slow query | 0.000038 | 0| 0| 0 | 0 |0 | 0 | 0 | dispatch_command| sql_parse.cc|2169 |
+--------------------------------+----------+----------+------------+-------------------+---------------------+--------------+---------------+-------+---------------------------+---------------+-------------+
17 rows in set (0.00 sec)
mysql> update t set username = 'waill';
Query OK, 4194304 rows affected (44.82 sec)
Rows matched: 4194304Changed: 4194304Warnings: 0

mysql> show profiles;
+----------+-------------+------------------------------------------------+
| Query_ID | Duration| Query|
+----------+-------------+------------------------------------------------+
|1 |0.02717000 | show tables|
|2 |0.74770100 | select count(*) from t |
|3 |0.00004200 | show prifile for query 2 |
|4 | 34.30410100 | insert into t(username) select username from t |
|5 |1.50563800 | select count(*) from t |
|6 | 44.82054700 | update t set username = 'waill'|
+----------+-------------+------------------------------------------------+
6 rows in set (0.00 sec)

mysql> show profile cpu,block io,memory,swaps,context switches,source for query 6;
+----------------------+-----------+-----------+------------+-------------------+---------------------+--------------+---------------+-------+-----------------------+---------------+-------------+
| Status | Duration| CPU_user| CPU_system | Context_voluntary | Context_involuntary | Block_ops_in | Block_ops_out | Swaps | Source_function | Source_file | Source_line |
+----------------------+-----------+-----------+------------+-------------------+---------------------+--------------+---------------+-------+-----------------------+---------------+-------------+
| (initialization) | 0.000035| 0 | 0| 0 | 0 |0 | 0 | 0 | check_access| sql_parse.cc|5306 |
| checking permissions | 0.000011| 0 | 0| 0 | 0 |0 | 0 | 0 | open_tables | sql_base.cc |2629 |
| Opening tables | 0.000011| 0 | 0| 0 | 0 |0 | 0 | 0 | mysql_lock_tables | lock.cc | 153 |
| System lock| 0.000007| 0 | 0| 0 | 0 |0 | 0 | 0 | mysql_lock_tables | lock.cc | 162 |
| Table lock | 0.000008| 0 | 0| 0 | 0 |0 | 0 | 0 | mysql_update| sql_update.cc | 167 |
| init | 0.000021| 0 | 0| 0 | 0 |0 | 0 | 0 | mysql_update| sql_update.cc | 429 |
| Updating | 44.514679 | 27.976747 | 2.818571 |9949 |1584 |0 | 0 | 0 | mysql_update| sql_update.cc | 560 |
| end| 0.305671| 0.028996| 0.023997 |43 |18 |0 | 0 | 0 | mysql_execute_command | sql_parse.cc|5122 |
| query end| 0.000026| 0 | 0| 0 | 0 |0 | 0 | 0 | mysql_parse | sql_parse.cc|6116 |
| freeing items| 0.000016| 0 | 0| 0 | 0 |0 | 0 | 0 | dispatch_command| sql_parse.cc|2146 |
| closing tables | 0.000012| 0 | 0| 0 | 0 |0 | 0 | 0 | log_slow_statement| sql_parse.cc|2204 |
| logging slow query | 0.00005 | 0 | 0| 0 | 0 |0 | 0 | 0 | dispatch_command| sql_parse.cc|2169 |
+----------------------+-----------+-----------+------------+-------------------+---------------------+--------------+---------------+-------+-----------------------+---------------+-------------+

12 rows in set (0.00 sec)

转：http://www.justwinit.cn/post/1275/

sho_ko
关注
Mysql自带profiling性能分析工具
qq_38852289的博客
08-31 1203
MySQL5.0.37版本及其以上支持了Profiling – 官方手册。此工具可用来查询 SQL 会执行多少时间，System lock和Table lock 花多少时间等等，对定位一条语句的 I/O消耗和CPU消耗 非常重要。 从启动profile之后的所有查询包括错误的语句都会记录。 关闭或者set profiling=0 就关闭了。接下来，我们就粗略的来看看profilin
Mysql进阶优化篇01——四万字详解数据库性能分析工具(深入、全面、详细，收藏备用)
m0_67394360的博客
07-28 238
在定位了慢sql后，可以使用Describe或者Explain进行针对性的分析。如果想知道SQL的执行计划，比如是全表扫描，还是索引扫描，可以通过EXPLAIN去完成。EXPLAIN命令是查看优化器如何决定执行查询的主要方法。可以帮助我们深入了解MySQL的基于开销的优化器，还可以获得很多可能被优化器考虑到的访问策略的细节，以及当运行SQL语句时哪种策略预计会被优化器采用。5.7版本mysql8.0版本mysql和。...
Mysql 远程跟踪执行工具
08-18
用了Mysql 之后,发现就没有远程跟踪的工具了..所以本人利用C#开发一个工具类似于mssqlprofiler的跟踪工具.可以远程跟踪执行的语句. 在程序中屏蔽了一些系统语句.所以很清爽,不会错过一个有用的语句,比较稳定.支持"换肤"功能.数十种不同的皮肤,给你不一样的体验.
MySQL 性能跟踪方法
weixin_33946605的博客
12-19 137
https://www.cnblogs.com/zhoujinyi/p/5236705.html https://dev.mysql.com/doc/refman/5.7/en/slow-query-log.html https://dev.mysql.com/doc/refman/5.7/en/dba-dtrace-mysqld-ref.html https://dev.mysql.com...
MySQL的性能监视工具有哪些？底层原理是什么？
长风破浪会有时的博客
04-24 349
通过捕获MySQL服务器的统计信息（如查询次数、响应时间、连接数等）来分析性能瓶颈和调整服务器配置，比如MySQL自带的Performance Schema和Slow Query Log。MySQL Enterprise Monitor：MySQL官方提供的监视工具，可以监控多个MySQL服务器的性能、健康状况和安全状态。MySQL自带的Performance Schema：通过捕获MySQL服务器上运行的操作的统计信息来提供性能分析和调试工具。
mysql-trace分析优化器执行计划
lujiangui的专栏
10-01 248
MySQL5.6提供了对SQL的跟踪trace, 通过trace文件能够进一步了解为什么优化器选择A计划, 而不是选择B计划。 打开trace ， 设置格式为 JSON，并设置trace最大能够使用的内存大小，避免解析过程中因为默认内存过小而不能够完整展示。 SET optimizer_trace="enabled=on",end_markers_in_json=on; set optimizer_trace_max_mem_size=1000000; 执行SQL语句 select * from
mysql profiling的使用与sql分析
程序员
12-21 1358
mysql在5.0.3版本之后添加了profiling来进行性能分析 首先进入数据库 show profiles； mysql默认是关闭的，也就是OFF 需要设置成1开启，也就是ON，注意设置以后退出窗口之后会还原，要永久生效貌似需要重启mysql才行 查看是否开启: 开启:set profiling=1 在这之后每次执行的sql语句都会被记录。 然后可以查看具体每个sql...
mysql性能工具的使用.txt
11-05
本文将详细介绍几种常用的MySQL性能分析工具及其使用方法，帮助读者更好地理解和掌握MySQL的性能优化技巧。 #### MySQL性能分析概述 MySQL提供了多种工具来帮助用户分析和优化其性能，主要包括慢查询日志、`...
使用Explain工具分析查询性能瓶颈
Explain工具是MySQL中一个强大的工具，用于分析和优化SQL查询的执行计划。它通过提供有关查询执行过程的详细统计信息，帮助数据库管理员和开发人员识别和解决性能问题。 Explain工具的工作原理是模拟查询的执行过程...
mysql语句性能分析工具——profiling
最新发布
zyjzyjjyzjyz的博客
11-30 1935
sql查询慢的情况很常见，对于慢sql的优化有三个步骤，又称优化三板斧。板斧一：查看执行计划explain板斧二：建立合适索引板斧三：使用合适的连接关系和过滤条件来实现sql语句的优化如果执行计划正确sql语句的性能还很慢，可以通过mysql的profiling工具进行定位分析。为了更精准的定位一条sql语句的性能问题，需要清楚的直到这条sql语句运行时消耗多少系统资源。
Mysql之Explain
jannal专栏
03-06 285
文章目录执行计划列信息idselect_typetablepartitionstypepossible_keyskeykey_lenrefrowsfilteredExtra 执行计划 所谓的执行计划就是Mysql如何执行一条Sql语句,包括Sql查询的顺序、是否使用索引、以及使用的索引信息等内容。不同版本的Mysql和不同的存储引擎执行计划不完全相同 语法 1. 基本 explain ...
MySQL准确定位性能瓶颈
去武帝城，战王仙芝
03-31 550
MySQL自带的准确定位性能瓶颈的 profiling 可以用来准确定位一条sql的性能瓶颈 Query Profiler是MYSQL5.1之后提供的一个很方便的用于诊断Query执行的工具，能够准确的获取一条查询执行过程中的CPU，IO等情况； 1，开启profiling：set profiling=1; 2，执行QUERY，在profiling过程中所有的query都可以记录下来； 3，查...
MySQL高性能实战——part3——分析SQL,定位慢SQL(性能优化的前提)
weixin_45929082的博客
03-02 712
MySQL高性能实战——part3.分析SQL,定位慢SQL(性能优化的前提)
mysql checking permissions 优化_mysql 查询优化 ~ 善用profie利器
weixin_42527178的博客
01-27 1352
一 简介:利用profile分析慢语句的过程有助于我们进行语句的优化二 执行过程set profiling=1;set profiling=0;2 执行sql3 查看过程消耗三 结果1 查看执行过程耗时SHOW profile FOR query 1checking permissions：检查权限Opening tables：打开表init ： 初始化System lock ：系统锁optim...
Mysql分析-profile详解（转http://blog.csdn.net/ty_hf/article/details/54895026）
冰恋冬的专栏
02-27 535
一。前言当我们要对某一条sql的性能进行分析时，可以使用它。Profiling是从 mysql5.0.3版本以后才开放的。启动profile之后，所有查询包括错误的语句都会记录在内。关闭会话或者set profiling=0 就关闭了。（如果将profiling_history_size参数设置为0，同样具有关闭MySQL的profiling效果。）此工具可用来查询SQL执行状态，System l...
mysql trace工具_mysql--trace工具的使用
weixin_35755188的博客
01-19 543
MySQL5.6版本后提供了对SQL的跟踪工具trace，通过使用trace可以让我们明白optimizer如何选择执行计划的。使用过程：打开trace，设置格式为JSON，设置trace的缓存大小，避免因为容量大小而不能显示完整的跟踪过程。mysql> set optimizer_trace="enabled=on",end_markers_in_JSON=on;Query OK, 0 r...
Mysql性能瓶颈深度定位分析
热门推荐
smooth的博客
06-10 1万+
我们在性能测试过程中，经常会遇到Mysql出现性能瓶颈的情况，对于数据库来说，所谓的性能瓶颈无非是慢SQL、CPU高、IO高（有人会说内存高也算，说的对，比如发生比较严重的swap，由于我没遇到过，没有案例来说明），这次我就举这三方面的例子来进行性能分析： 首先我们要保证没有数据库配置方面的性能问题，毕竟在性能测试前，需要对基本配置撸一遍，避免犯低级错误。 一、慢SQL分析 首先业务系统慢，肯定是体现在响应时间上，所以在性能测试中，如果发现慢我们就从响应时间上进行拆分，如果拆到mysql，那就是分析慢
block IO层框架分析3
鹤冲云霄的专栏
06-17 1750
1. 在block IO层框架分析2中，我们看到request请求会被提交到IO调度系统中，elv_merge判断bio是否可以被合并到request。 在准备好request后，linux块层调用add_request添加request，该函数直接以ELEVATOR_INSERT_SORT为参数直接调用__elv_add_request。 在blk_peek_request函数中，调用__e
MySQL性能分析：profile工具使用详解及优化策略
"MySQL性能分析工具profile使用教程" 在MySQL数据库优化过程中，SQL性能分析是一项关键任务，它有助于识别和解决查询效率低下的问题。本文主要讲解如何利用MySQL内置的性能分析工具——profile来进行SQL执行的深入...
评论
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

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

抵扣说明：

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

余额充值