MySQL中的日志包括:错误日志、二进制日志、通用查询日志、慢查询日志等等。这里主要介绍下比较常用的两个功能:通用查询日志和慢查询日志。
1)通用查询日志:记录建立的客户端连接和执行的语句。
2)慢查询日志:记录所有执行时间超过long_query_time秒的所有查询或者不使用索引的查询
(1)通用查询日志
在学习通用日志查询时,需要知道两个数据库中的常用命令:1) show variables like ‘%version%’;
mysql>showvariableslike’%version%’; ————————- —————————— |Variable_name|Value| ————————- —————————— |innodb_version|5.6.37||protocol_version|10||slave_type_conversions|||version|5.6.37-log||version_comment|MySQLCommunityServer(GPL)||version_compile_machine|x86_64||version_compile_os|Linux| ————————- —————————— 7rowsinset(0.00sec)#上述命令,显示当前数据库中与版本号相关的东西。
2) show variables like ‘%general%’;
mysql>showvariableslike’%general%’; —————— ——————————- |Variable_name|Value| —————— ——————————- |general_log|OFF||general_log_file|/var/lib/mysql/nginx-test.log| —————— ——————————- 2rowsinset(0.00sec)#可以查看,当前的通用日志查询是否开启,如果general_log的值为ON则为开启,为OFF则为关闭(默认情况下是关闭的)。
3) show variables like ‘%log_output%’;
mysql>showvariableslike’%log_output%’; ————— ——- |Variable_name|Value| ————— ——- |log_output|FILE| ————— ——- 1rowinset(0.00sec)
查看当前慢查询日志输出的格式,可以是FILE(存储在数数据库的数据文件中的hostname.log),也可以是TABLE(存储在数据库中的mysql.general_log)
问题:如何开启MySQL通用查询日志,以及如何设置要输出的通用日志输出格式呢?
开启通用日志查询:setglobalgeneral_log=on;关闭通用日志查询:setglobalgeneral_log=off;设置通用日志输出为表方式:setgloballog_output=’TABLE’;设置通用日志输出为文件方式:setgloballog_output=’FILE’;设置通用日志输出为表和文件方式:setgloballog_output=’FILE,TABLE’;#注意:上述命令只对当前生效,当MySQL重启失效,如果要永久生效,需要配置my.cnf
日志输出的效果图如下:记录到mysql.general_log表结构如下:
mysql>descgeneral_log; ————– ——————— —— —– ——————- —————————– |Field|Type|Null|Key|Default|Extra| ————– ——————— —— —– ——————- —————————– |event_time|timestamp|NO||CURRENT_TIMESTAMP|onupdateCURRENT_TIMESTAMP||user_host|mediumtext|NO||NULL|||thread_id|bigint(21)unsigned|NO||NULL|||server_id|int(10)unsigned|NO||NULL|||command_type|varchar(64)|NO||NULL|||argument|mediumtext|NO||NULL|| ————– ——————— —— —– ——————- —————————– 6rowsinset(0.00sec)
my.cnf文件的配置如下:
general_log=1#为1表示开启通用日志查询,值为0表示关闭通用日志查询log_output=FILE,TABLE#设置通用日志的输出格式为文件和表(2)慢查询日志
MySQL的慢查询日志是MySQL提供的一种日志记录,用来记录在MySQL中响应时间超过阈值的语句,具体指运行时间超过long_query_time值的SQL,则会被记录到慢查询日志中(日志可以写入文件或者数据库表,如果对性能要求高的话,建议写文件)。默认情况下,MySQL数据库是不开启慢查询日志的,long_query_time的默认值为10(即10秒,通常设置为1秒),即运行10秒以上的语句是慢查询语句。一般来说,慢查询发生在大表(比如:一个表的数据量有几百万),且查询条件的字段没有建立索引,此时,要匹配查询条件的字段会进行全表扫描,耗时查过long_query_time,则为慢查询语句。
问题:如何查看当前慢查询日志的开启情况?
在MySQL中输入命令:show variables like ‘%quer%’;
mysql>showvariableslike’%quer%’; —————————————- ——————————- |Variable_name|Value| —————————————- ——————————- |binlog_rows_query_log_events|OFF||ft_query_expansion_limit|20||have_query_cache|YES||log_queries_not_using_indexes|ON||log_throttle_queries_not_using_indexes|0||long_query_time|10.000000||query_alloc_block_size|8192||query_cache_limit|1048576||query_cache_min_res_unit|4096||query_cache_size|1048576||query_cache_type|OFF||query_cache_wlock_invalidate|OFF||query_prealloc_size|8192||slow_query_log|ON||slow_query_log_file|/var/log/mysql/mysql_slow.log| —————————————- ——————————- 15rowsinset(0.00sec)#主要掌握以下的几个参数:(1)slow_query_log的值为ON为开启慢查询日志,OFF则为关闭慢查询日志。(2)slow_query_log_file的值是记录的慢查询日志到文件中(注意:默认名为主机名.log,慢查询日志是否写入指定文件中,需要指定慢查询的输出日志格式为文件,相关命令为:showvariableslike‘%log_output%’;去查看输出的格式)。(3)long_query_time指定了慢查询的阈值,即如果执行语句的时间超过该阈值则为慢查询语句,默认值为10秒。(4)log_queries_not_using_indexes如果值设置为ON,则会记录所有没有利用索引的查询(注意:如果只是将log_queries_not_using_indexes设置为ON,而将slow_query_log设置为OFF,此时该设置也不会生效,即该设置生效的前提是slow_query_log的值设置为ON),一般在性能调优的时候会暂时开启。问题:设置MySQL慢查询的输出日志格式为文件还是表,或者两者都有?
通过命令:show variables like ‘%log_output%’;
mysql>showvariableslike’%log_output%’; ————— ———— |Variable_name|Value| ————— ———— |log_output|FILE,TABLE| ————— ———— 1rowinset(0.00sec)
通过log_output的值可以查看到输出的格式,上面的值为FILE,TABLE。当然,我们也可以设置输出的格式为文本,或者同时记录文本和数据库表中,设置的命令如下:
#慢查询日志输出到表中(即mysql.slow_log)setgloballog_output=’TABLE’;#慢查询日志仅输出到文本中(即:slow_query_log_file指定的文件)setgloballog_output=’FILE’;#慢查询日志同时输出到文本和表中setgloballog_output=’FILE,TABLE’;关于慢查询日志的表中的数据个文本中的数据格式分析:
慢查询的日志记录myql.slow_log表中,格式如下:
mysql>mysql>select*frommysql.slow_loglimit1; ——————— ——————————– ———— ———– ———– ————— ———— —————- ———– ———– —————————————————————————————- ———– |start_time|user_host|query_time|lock_time|rows_sent|rows_examined|db|last_insert_id|insert_id|server_id|sql_text|thread_id| ——————— ——————————– ———— ———– ———– ————— ———— —————- ———– ———– —————————————————————————————- ———– |2018-02-0711:16:55|root[root]@[121.196.203.51]|00:00:00|00:00:00|13|40|jp_core_db|0|0|0|selectpd.lastAuctionfromProductpdwherepd.status=’O’andpd.auctionStatus=’A’|1621| ——————— ——————————– ———— ———– ———– ————— ———— —————- ———– ———– —————————————————————————————- ———– 1rowinset(0.00sec)慢查询的日志记录到mysql_slow.log文件中,格式如下:#Time:18011814:58:37#User@Host:root[root]@localhost[]Id:150#Query_time:0.000270Lock_time:0.000109Rows_sent:0Rows_examined:6SETtimestamp=1516258717;deletefromuserwhereUser=’app’;#可以看到,不管是表还是文件,都具体记录了:是那条语句导致慢查询(sql_text),该慢查询语句的查询时间(query_time),锁表时间(Lock_time),以及扫描过的行数(rows_examined)等信息。问题:如何查询当前慢查询的语句的个数?
在MySQL中有一个变量专门记录当前慢查询语句的个数:输入命令:show global status like ‘%slow%’;
mysql>showglobalstatuslike’%slow%’; ——————— ——- |Variable_name|Value| ——————— ——- |Slow_launch_threads|132||Slow_queries|1772| ——————— ——- 2rowsinset(0.00sec)(注意:上述所有命令,如果都是通过MySQL的shell将参数设置进去,如果重启MySQL,所有设置好的参数将失效,如果想要永久的生效,需要将配置参数写入my.cnf文件中)。补充知识点:如何利用MySQL自带的慢查询日志分析工具mysqldumpslow分析日志?mysqldumpslow–sc–t10slow-query.log
具体参数设置如下:
-s 表示按何种方式排序,c、t、l、r分别是按照记录次数、时间、查询时间、返回的记录数来排序,ac、at、al、ar,表示相应的倒叙;
-t 表示top的意思,后面跟着的数据表示返回前面多少条;
-g 后面可以写正则表达式匹配,大小写不敏感。
[root@nginx-test/var/log/mysql]#mysqldumpslow-sc-t2/var/log/mysql/mysql_slow.logReadingmysqlslowquerylogfrom/var/log/mysql/mysql_slow.logCount:125448Time=0.00s(131s)Lock=0.00s(3s)Rows=2.2(272835),2users@2hostsselectproductauc0_.productAuctionIdasproductA1_12_,productauc0_.auctionIndexasauctionI2_12_,productauc0_.bidCoinsasbidCoins3_12_,productauc0_.bidPriceasbidPrice4_12_,productauc0_.bidStepasbidStep5_12_,productauc0_.bidTimeasbidTime6_12_,productauc0_.bidderasbidder7_12_,productauc0_.buyFlagasbuyFlag8_12_,productauc0_.categoryCodeascategory9_12_,productauc0_.createTimeascreateT10_12_,productauc0_.currentAuctionDetailIdascurrent11_12_,productauc0_.currentBidPriceascurrent12_12_,productauc0_.currentBidTimeascurrent13_12_,productauc0_.currentBidderascurrent14_12_,productauc0_.effectCoinaseffectC15_12_,productauc0_.effetcPointaseffetcP16_12_,productauc0_.endTimeasendTime17_12_,productauc0_.newUserFlagasnewUser18_12_,productauc0_.productCodeasproduct19_12_,productauc0_.productCostasproduct20_12_,productauc0_.productNameasproduct21_12_,productauc0_.productPriceasproduct22_12_,productauc0_.refundRateasrefundR23_12_,productauc0_.startPriceasstartPr24_12_,productauc0_.startTimeasstartTi25_12_,productauc0_.statusasstatus26_12_,productauc0_.updateTimeasupdateT27_12_fromProductAuctionproductauc0_whereproductauc0_.status=’S’Count:66216Time=0.00s(127s)Lock=0.00s(2s)Rows=1.7(115074),root[root]@[121.196.203.51]selectproductauc0_.productAuctionIdasproductA1_12_,productauc0_.auctionIndexasauctionI2_12_,productauc0_.bidCoinsasbidCoins3_12_,productauc0_.bidPriceasbidPrice4_12_,productauc0_.bidStepasbidStep5_12_,productauc0_.bidTimeasbidTime6_12_,productauc0_.bidderasbidder7_12_,productauc0_.buyFlagasbuyFlag8_12_,productauc0_.categoryCodeascategory9_12_,productauc0_.createTimeascreateT10_12_,productauc0_.currentAuctionDetailIdascurrent11_12_,productauc0_.currentBidPriceascurrent12_12_,productauc0_.currentBidTimeascurrent13_12_,productauc0_.currentBidderascurrent14_12_,productauc0_.effectCoinaseffectC15_12_,productauc0_.effetcPointaseffetcP16_12_,productauc0_.endTimeasendTime17_12_,productauc0_.firstBidTimeasfirstBi18_12_,productauc0_.newUserFlagasnewUser19_12_,productauc0_.noviceReturnFlagasnoviceR20_12_,productauc0_.productCodeasproduct21_12_,productauc0_.productCostasproduct22_12_,productauc0_.productNameasproduct23_12_,productauc0_.productPriceasproduct24_12_,productauc0_.refundRateasrefundR25_12_,productauc0_.startPriceasstartPr26_12_,productauc0_.startTimeasstartTi27_12_,productauc0_.statusasstatus28_12_,productauc0_.updateTimeasupdateT29_12_fromProductAuctionproductauc0_whereproductauc0_.status=’S’上述中的参数含义如下:Count:125448#语句出现了125448次;Time=0.00s(131s)#执行最长时间为0.00s,累计总耗费时间131s;Lock=0.0s(3s)#等待锁最长时间为0s,累计等待锁耗费时间为3s;Rows=2.2(272835)#发送给客户端最多的行数为2.2,累计发送给客户端的函数为272835#注意:mysqldumpslow脚本是用perl语言写的,具体mysqldumpslow的用法后期再讲)问题:实际在学习过程中,如何得知设置的慢查询是有效的?
很简单,我们可以手动产生一条慢查询语句,比如,如果我们的慢查询log_query_time的值设置为1,则我们可以执行如下语句:select sleep(1);该条语句即是慢查询语句,之后,便可以在相应的日志输出文件或表中去查看是否有该条语句。
– MORE | 往期精彩文章 –
2019 再出发,撸起袖子加油干,我们一直在路上!
如何用 10 句话激怒程序猿?
2018最喜感照片,笑出声(zhu)了(jiao
几百万人信息数据泄漏?尽快改密码吧…..
数据库分库分表,何时分?怎样分?
天天要“干货”,变成大神的少,变成“伸手党”的越来越多!
如果你喜欢本文