我最新的慢日志去哪了?

本文涉及的产品
RDS MySQL Serverless 基础系列,0.5-2RCU 50GB
云数据库 RDS MySQL,集群系列 2核4GB
推荐场景:
搭建个人博客
云数据库 RDS MySQL,高可用系列 2核4GB
简介: 最近接到一个客户说,我在easydb中为什么看不到实时的慢sql,只能看到过气的慢日志的? 慢日志对于MySQL来说确实,是非常重要的,无论是问题排查还是性能优化,都能从其中捕获一些问题的来源,如果慢日志失去了实时性那还了得? 然后即刻登到easydb中去看,发现最新的慢日志定格在了7月5号晚22:00左右。

最近接到一个客户说,我在easydb中为什么看不到实时的慢sql,只能看到过气的慢日志的?


慢日志对于MySQL来说确实,是非常重要的,无论是问题排查还是性能优化,都能从其中捕获一些问题的来源,如果慢日志失去了实时性那还了得?


然后即刻登到easydb中去看,发现最新的慢日志定格在了7月5号晚22:00左右。难道真的是easydb捕获不到最新的慢日志了吗?


但是发现MySQL日志文件中的slowlog最后一条确实是跟easydb中的最新的一条相一致,那么easydb依旧是实时抓取slow log。那问题出现在哪里了呢?


Linux主机?还是MySQL服务器的时间?


Linux所在主机的时间


[root@msyql ]# date
Thu Jul  6 10:35:12 CST 2017


MySQL的时间


mysql> select now();
+---------------------+
| now()               |
+---------------------+
| 2017-07-06 10:36:10 |
+---------------------+
1 row in set (0.00 sec)


比较悲伤并没有看到我们想看到的时间,时间都跟我屏幕右下方的时间相一致。


那问题出在哪里了呢?到底是哪里控制了MySQL slow日志里面的时间?


看一下关于MySQL 时间的参数

mysql> show variables like '%time%';
+---------------------------------+-------------------+
| Variable_name                   | Value             |
+---------------------------------+-------------------+
| binlog_max_flush_queue_time     | 0                 |
| connect_timeout                 | 8                 |
| datetime_format                 | %Y-%m-%d %H:%i:%s |
| delayed_insert_timeout          | 300               |
| explicit_defaults_for_timestamp | ON                |
| flush_time                      | 0                 |
| innodb_flush_log_at_timeout     | 1                 |
| innodb_lock_wait_timeout        | 5                 |
| innodb_old_blocks_time          | 1000              |
| innodb_rollback_on_timeout      | OFF               |
| interactive_timeout             | 28800             |
| lc_time_names                   | en_US             |
| lock_wait_timeout               | 31536000          |
| long_query_time                 | 1.000000          |
| net_read_timeout                | 30                |
| net_write_timeout               | 60                |
| rpl_stop_slave_timeout          | 300               |
| slave_net_timeout               | 30                |
| slow_launch_time                | 2                 |

| time_format                     | %H:%i:%s          |

| timed_mutexes                   | OFF               |
| timestamp                       | 1499328877.100398 |
| wait_timeout                    | 28800             |
+---------------------------------+-------------------+
25 rows in set (0.00 sec)


然后我看了一下我自己的MySQL 关于时间的参数


mysql> show variables like '%time%';
+---------------------------------------+-------------------+
| Variable_name                         | Value             |
+---------------------------------------+-------------------+
| connect_timeout                       | 10                |
| datetime_format                       | %Y-%m-%d %H:%i:%s |
| delayed_insert_timeout                | 302               |
| flush_time                            | 0                 |
| have_response_time_distribution       | YES               |
| innodb_lock_wait_timeout              | 50                |
| innodb_old_blocks_time                | 0                 |
| innodb_rollback_on_timeout            | OFF               |
| innodb_thread_concurrency_timer_based | OFF               |
| interactive_timeout                   | 7200              |
| lc_time_names                         | en_US             |
| lock_wait_timeout                     | 31536000          |
| long_query_time                       | 1.000000          |
| net_read_timeout                      | 30                |
| net_write_timeout                     | 60                |
| query_response_time_range_base        | 10                |
| query_response_time_stats             | OFF               |
| rpl_semi_sync_master_timeout          | 1000              |
| slave_net_timeout                     | 60                |
| slow_launch_time                      | 2                 |
| slow_query_log_timestamp_always       | OFF               |
| slow_query_log_timestamp_precision    | second            |

| time_format                           | %H:%i:%s          |

| timed_mutexes                         | OFF               |
| timestamp                             | 1499329112        |
| trx_changes_idle_timeout              | 0                 |
| trx_idle_timeout                      | 0                 |
| trx_readonly_idle_timeout             | 0                 |
| wait_timeout                          | 86400             |
+---------------------------------------+-------------------+

注:北美东部夏令时间英文名: Eastern Daylight Time 也就是上面的EDT

       北美中部夏令时间英文名: Central Daylight Time 也就是上面的CST

这个system_time_zone跟time_zone有什么区别呢?这两个参数是干什么的呢?

看一下官方文档的解释:


 
也就是说这个参数才是真正的控制服务器的区时,且在MySQL一旦运行,这个区时就是写死了的,不会变。那么也就是很有可能是由于这两个参数的问题导致我的慢日志的时间变慢了。来测试一下 



 
启动MySQL的时候指定timezone为CST 

发现这个时候的MySQL系统时间慢了8个小时,而且慢日志的时间跟这个时间是一致的,也是慢了8个小时。


 

./bin/mysqld_safe --defaults-file=/home/my3301/my.cnf --timezone=CST-8 &


启动MySQL的时候指定timezone为CST-8

发现这个时候的MySQL系统时间跟我左下角的时间就正好吻合了,并且慢日志的时间跟我左下角的时间一致了。

这其实就验证了timezone这个参数影响了慢日志写入到日志里面的时间了。


那system_time_zone根time_zone又有什么区别呢?

The system_time_zone variable differs from time_zone. Although they might have the same value, the latter variable is used to initialize the time zone for each client that connects.

也就是一个是服务器系统时区,一个是连接的客户端的时区。

如果这两个参数设置不当,就会导致Linux主机系统的时间、MySQL select now()的时间,还有慢日志等日志产生的时间不一致。

这也就是为什么客户的慢日志为啥只能收集到12小时“前”的原因了~

相关实践学习
日志服务之使用Nginx模式采集日志
本文介绍如何通过日志服务控制台创建Nginx模式的Logtail配置快速采集Nginx日志并进行多维度分析。
目录
相关文章
|
1月前
|
监控 关系型数据库 MySQL
分析慢查询日志
【10月更文挑战第29天】分析慢查询日志
42 3
|
1月前
|
监控 关系型数据库 数据库
怎样分析慢查询日志?
【10月更文挑战第29天】怎样分析慢查询日志?
42 2
|
7月前
|
SQL 关系型数据库 分布式数据库
在PolarDB中,慢日志明细中记录的耗时包括这个等待时间吗?
在PolarDB中,慢日志明细中记录的耗时包括这个等待时间吗?
59 0
|
SQL Arthas druid
MyBtais 批量插入慢排查及分析(后续)
MyBtais 批量插入慢排查及分析(后续)
190 0
|
开发工具 开发者
慢与快
我经常跟我的学员这样说:对于学习做游戏这件事情,大家要放平心态,不用着急,我给大家提供一年时间的服务,所以你大可以慢慢来。如果你的时间充足的话,就制定一个为期较短的计划。如果你工作比较忙,时间不够的话,那么就制定一个比较宽松的计划。最重要的是不要停,不要半途而废,不要就此放下。想一下,如果你能够在一年的时间里学会并掌握一门新技能,那也是相当不错的了。
81 0
|
关系型数据库 MySQL 索引
慢查询日志开启一
慢查询日志开启一
88 0
|
SQL 关系型数据库 MySQL
慢查询日志开启二
慢查询日志开启二
56 0
|
SQL 监控 关系型数据库
慢查询日志|学习笔记
快速学习慢查询日志
126 0
慢查询日志|学习笔记