数据库时间慢了14个小时

同事反馈问题的时候,带了自己的猜想:是不是数据库字段设置为datetime导致?是不是Mybatis逆向工程生成的代码中类型不一致导致的?

同事还要把datetime改为varchar……马上被我制止了,说:先排查问题,再说解决方案,下午我也抽时间看看。

问题核查

第一步,检查数据库字段类型,是datetime的,没问题。

第二步,检查实体类中类型,是java.util.Date类型,没问题。

第三步,Bug复现。

在Bug复现这一步,用到了单元测试。话说之前还跟朋友讨论过单元测试的魅力,现在本人是越来越喜欢单元测试了。

项目基于Spring Boot的,单元测试如下(代码已脱敏):

@SpringBootTestclassDateTimeTests{@ResourceprivateUserMapper userMapper;@TestpublicvoidtestDate(){User  user=newUser();// 省略其他字段user.setCreateDate(newDate());userMapper.insertSelective(user);}}

复制

执行单元测试,查看数据库中插入的数据。Bug复现,时间的确是前一天的,与当前时间相差14个小时。

经过上面三步的排查,核实了数据库字段和代码中类型没问题。单元测试也复现了问题,同事没有欺骗我,总要眼见为实,哈哈~

于是基本确定是时区问题。

时区排查

检查服务器时间

登录测试服务器,执行date命令,检查服务器时间和时区:

[root@xxx~]# date2021年11月25日 星期四09:26:25CST[root@xxx~]# date-RThu,25Nov202109:33:34+0800

复制

显示时间是当前时间,采用CST时间,最后的+0800,即东8区,没问题。

检查数据库时区

连接数据库,执行show命令:

show variables like'%time_zone%';

+----------------------------+|Variable|Value|+----------------------------+

|system_time_zone|CST|

|time_zone|SYSTEM|

复制

system_time_zone:全局参数,系统时区,在MySQL启动时会检查当前系统的时区并根据系统时区设置全局参数system_time_zone的值。值为CST,与系统时间的时区一致。

time_zone:全局参数,设置每个连接会话的时区,默认为SYSTEM,使用全局参数system_time_zone的值。

检查代码中时区

在单元测试的方法内再添加打印时区的代码:

@TestpublicvoidtestDate(){System.out.println(System.getProperty("user.timezone"));User  user=newUser();// 省略其他字段user.setCreateDate(newDate());userMapper.insertSelective(user);}

复制

打印的时区为:

Asia/Shanghai

复制

也就是说Java中使用的是UTC时区进行业务逻辑处理的,也是东八区的时间。

那么问题到底出在哪里呢?

问题基本呈现

经过上述排查,基本上确定是时区的问题。这里,再补充一下上述相关的时区知识点。

UTC时间

UTC时间:世界协调时间(UTC)是世界上不同国家用来调节时钟和时间的主要时间标准,也就是零时区的时间。

UTC, Coordinated Universal Time是一个标准,而不是一个时区。UTC 是一个全球通用的时间标准。全球各地都同意将各自的时间进行同步协调 (coordinated),这也是UTC名字的来源:Universal Coordinated Time。

CST时间

CST时间:中央标准时间。

CST可以代表如下4个不同的时区:

Central Standard Time (USA) UT-6:00,美国

Central Standard Time (Australia) UT+9:30,澳大利亚

China Standard Time UT+8:00,中国

Cuba Standard Time UT-4:00,古巴

再次分析

很显然,这里与UTC时间无关,它只是时间标准。目前Mysql中的system_time_zone是CST,而CST可以代表4个不同的时区,那么,Mysql把它当做哪个时区进行处理了呢?

简单推算一下,中国时间是UT+8:00,美国是 UT-6:00,当传入中国时间,直接转换为美国时间(未考虑时区问题),时间便慢了14个小时。

既然知道了问题,那么解决方案也就有了。

解决方案

针对上述问题可通过数据库层面和代码层面进行解决。

方案一:修改数据库时区

既然是Mysql理解错了CST指定的时区,那么就将其设置为正确的。

连接Mysql数据库,设置正确的时区:

[root@xxxxx~]# mysql -uroot -p

mysql>set global time_zone='+8:00';

mysql>set time_zone='+8:00'

mysql>flush privileges;

复制

再次执行show命令:

show variables like'%time_zone%';

+----------------------------+|Variable|Value|+----------------------------+

|system_time_zone|CST|

|time_zone|+08:00|

复制

可以看到时区已经成为东八区的时间了。再次执行单元测试,问题得到解决。

此种方案也可以直接修改MySQL的my.cnf文件进行指定时区。

方案二:修改数据库连接参数

在代码连接数据库时,通过参数指定所使用的时区。

在配置数据库连接的URL后面添加上指定的时区serverTimezone=Asia/Shanghai:

url:jdbc:mysql://xx.xx.xx.xx:3306/db_name?useUnicode=true&characterEncoding=utf8&autoReconnect=true&serverTimezone=Asia/Shanghai

复制

再次执行单元测试,问题同样可以得到解决。

问题完了?

经过上述分析与操作,时区的问题已经解决了。问题就这么完事了吗?为什么是这样呢?

为了验证时区问题,在时区错误的数据库中,创建了一个字段,该字段类型为datetime,默认值为CURRENT_TIMESTAMP。

那么,此时插入一条记录,让Mysql自动生成该字段的时间,你猜该字段的时间是什么?中国时间。

神奇不?为什么同样是CST时区,系统自动生成的时间是正确的,而代码插入的时间就有时差问题呢?

到底是Mysql将CST时区理解为美国时间了,还是Mybatis、连接池或驱动程序将其理解为美国时间了?

重头戏开始

为了追查到底是代码中哪里出了问题,先开启Mybatis的debug日志,看看insert时是什么值:

2021-11-2511:05:28.367[|1637809527983|]DEBUG20178---[scheduling-1]c.h.s.m.H.listByCondition:==>Parameters:2021-11-2511:05:27(String),0(Integer),1(Integer),2(Integer),3(Integer),4(Integer)

复制

上面是insert时的参数,也就是说在Mybatis层面时间是没问题的。排除一个。

那是不是连接池或驱动程序的问题?连接池本身来讲跟数据库连接的具体操作关系不大,就直接来排查驱动程序。

Mybatis是xml中定义日期字段类型为TIMESTAMP,扒了一下mysql-connector-Java-8.0.x的源码,发现SqlTimestampValueFactory是用来处理TIMESTAMP类型的。

在SqlTimestampValueFactory的构造方法上打上断点,执行单元测试:

timezone

可以明确的看到,Calendar将时区设置为Locale.US,也就是美国时间,时区为CST,offset为-21600000。-21600000单位为毫秒,转化为小时,恰好是“-6:00”,这与北京时间“GMT+08:00”恰好相差14个小时。

于是一路往上最终追溯调用链路,该TimeZone来自NativeServerSession的serverTimeZone,而serverTimeZone的值是由NativeProtocol类的configureTimezone方法设置的。

publicvoidconfigureTimezone(){String configuredTimeZoneOnServer=this.serverSession.getServerVariable("time_zone");if("SYSTEM".equalsIgnoreCase(configuredTimeZoneOnServer)){configuredTimeZoneOnServer=this.serverSession.getServerVariable("system_time_zone");}String canonicalTimezone=getPropertySet().getStringProperty(PropertyKey.serverTimezone).getValue();if(configuredTimeZoneOnServer!=null){// user can override this with driver properties, so don't detect if that's the caseif(canonicalTimezone==null||StringUtils.isEmptyOrWhitespaceOnly(canonicalTimezone)){try{canonicalTimezone=TimeUtil.getCanonicalTimezone(configuredTimeZoneOnServer,getExceptionInterceptor());}catch(IllegalArgumentException iae){throwExceptionFactory.createException(WrongArgumentException.class,iae.getMessage(),getExceptionInterceptor());}}}if(canonicalTimezone!=null&&canonicalTimezone.length()>0){// 此处设置TimeZonethis.serverSession.setServerTimeZone(TimeZone.getTimeZone(canonicalTimezone));if(!canonicalTimezone.equalsIgnoreCase("GMT")&&this.serverSession.getServerTimeZone().getID().equals("GMT")){throwExceptionFactory.createException(WrongArgumentException.class,Messages.getString("Connection.9",newObject[]{canonicalTimezone}),getExceptionInterceptor());}}}

复制

debug跟踪一下上述代码,显示信息如下:

CST获得

至此,通过canonicalTimezone值的获取,可以看出URL后面配置serverTimezone=Asia/Shanghai的作用了。其中,上面第一个代码块获取time_zone的值,第二个代码块中获取system_time_zone的值。这与查询数据库获得的值一致。

因为出问题时并未在url中添加参数serverTimezone=Asia/Shanghai,所以走canonicalTimezone为null的情况。随后逻辑中调用了TimeUtil.getCanonicalTimezone方法:

publicstaticStringgetCanonicalTimezone(String timezoneStr,ExceptionInterceptor exceptionInterceptor){if(timezoneStr==null){returnnull;}timezoneStr=timezoneStr.trim();// handle '+/-hh:mm' form ...if(timezoneStr.length()>2){if((timezoneStr.charAt(0)=='+'||timezoneStr.charAt(0)=='-')&&Character.isDigit(timezoneStr.charAt(1))){return"GMT"+timezoneStr;}}synchronized(TimeUtil.class){if(timeZoneMappings==null){loadTimeZoneMappings(exceptionInterceptor);}}String canonicalTz;if((canonicalTz=timeZoneMappings.getProperty(timezoneStr))!=null){returncanonicalTz;}throwExceptionFactory.createException(InvalidConnectionAttributeException.class,Messages.getString("TimeUtil.UnrecognizedTimezoneId",newObject[]{timezoneStr}),exceptionInterceptor);}

复制

上述代码中最终走到了loadTimeZoneMappings(exceptionInterceptor);方法:

privatestaticvoidloadTimeZoneMappings(ExceptionInterceptor exceptionInterceptor){timeZoneMappings=newProperties();try{timeZoneMappings.load(TimeUtil.class.getResourceAsStream(TIME_ZONE_MAPPINGS_RESOURCE));}catch(IOException e){throwExceptionFactory.createException(Messages.getString("TimeUtil.LoadTimeZoneMappingError"),exceptionInterceptor);}// bridge all Time Zone ids known by Javafor(String tz:TimeZone.getAvailableIDs()){if(!timeZoneMappings.containsKey(tz)){timeZoneMappings.put(tz,tz);}}}

复制

该方法加载了配置文件"/com/mysql/cj/util/TimeZoneMapping.properties"里面的值,经过转换,timeZoneMappings中,对应CST的为"CST"。

最终得到canonicalTimezone为“CST”,而TimeZone获得是通过TimeZone.getTimeZone(canonicalTimezone)方法获得的。

也就是说TimeZone.getTimeZone(“CST”)的值为美国时间。写个单元测试验证一下:

publicclassTimeZoneTest{@TestpublicvoidtestTimeZone(){System.out.println(TimeZone.getTimeZone("CST"));}}

复制

打印结果:

sun.util.calendar.ZoneInfo[id="CST",offset=-21600000,dstSavings=3600000,useDaylight=true,transitions=235,lastRule=java.util.SimpleTimeZone[id=CST,offset=-21600000,dstSavings=3600000,useDaylight=true,startYear=0,startMode=3,startMonth=2,startDay=8,startDayOfWeek=1,startTime=7200000,startTimeMode=0,endMode=3,endMonth=10,endDay=1,endDayOfWeek=1,endTime=7200000,endTimeMode=0]]

复制

很显然,该方法传入CST之后,默认是美国时间。

至此,问题原因基本明朗

Mysql中设置的server_time_zone为CST,time_zone为SYSTEM

Mysql驱动查询到time_zone为SYSTEM,于是使用server_time_zone的值,为”CST“

JDK中TimeZone.getTimeZone(“CST”)获得的值为美国时区

以美国时区构造的Calendar类

SqlTimestampValueFactory使用上述Calendar来格式化系统获取的中国时间,时差问题便出现了

最终反映在数据库数据上就是错误的时间

serverVariables变量

再延伸一下,其中server_time_zone和time_zone都来自于NativeServerSession的serverVariables变量,该变量在NativeSession的loadServerVariables方法中进行初始化,关键代码:

if(versionMeetsMinimum(5,1,0)){StringBuilder queryBuf=newStringBuilder(versionComment).append("SELECT");queryBuf.append("  @@session.auto_increment_increment AS auto_increment_increment");queryBuf.append(", @@character_set_client AS character_set_client");queryBuf.append(", @@character_set_connection AS character_set_connection");queryBuf.append(", @@character_set_results AS character_set_results");queryBuf.append(", @@character_set_server AS character_set_server");queryBuf.append(", @@collation_server AS collation_server");queryBuf.append(", @@collation_connection AS collation_connection");queryBuf.append(", @@init_connect AS init_connect");queryBuf.append(", @@interactive_timeout AS interactive_timeout");if(!versionMeetsMinimum(5,5,0)){queryBuf.append(", @@language AS language");}queryBuf.append(", @@license AS license");queryBuf.append(", @@lower_case_table_names AS lower_case_table_names");queryBuf.append(", @@max_allowed_packet AS max_allowed_packet");queryBuf.append(", @@net_write_timeout AS net_write_timeout");queryBuf.append(", @@performance_schema AS performance_schema");if(!versionMeetsMinimum(8,0,3)){queryBuf.append(", @@query_cache_size AS query_cache_size");queryBuf.append(", @@query_cache_type AS query_cache_type");}queryBuf.append(", @@sql_mode AS sql_mode");queryBuf.append(", @@system_time_zone AS system_time_zone");queryBuf.append(", @@time_zone AS time_zone");if(versionMeetsMinimum(8,0,3)||(versionMeetsMinimum(5,7,20)&&!versionMeetsMinimum(8,0,0))){queryBuf.append(", @@transaction_isolation AS transaction_isolation");}else{queryBuf.append(", @@tx_isolation AS transaction_isolation");}queryBuf.append(", @@wait_timeout AS wait_timeout");NativePacketPayload resultPacket=sendCommand(this.commandBuilder.buildComQuery(null,queryBuf.toString()),false,0);Resultset rs=((NativeProtocol)this.protocol).readAllResults(-1,false,resultPacket,false,null,newResultsetFactory(Type.FORWARD_ONLY,null));Field[]f=rs.getColumnDefinition().getFields();if(f.length>0){ValueFactory<String>vf=newStringValueFactory(this.propertySet);Row r;if((r=rs.getRows().next())!=null){for(int i=0;i<f.length;i++){this.protocol.getServerSession().getServerVariables().put(f[i].getColumnLabel(),r.getValue(i,vf));}}}

复制

在上述StringBuilder的append操作中,有"@@time_zone AS time_zone"和"@@system_time_zone AS system_time_zone"两个值,然后查询数据库,从数据库获得值之后,put到serverVariables中。

再来debug一下:

system_time_zone

可以看出system_time_zone的值为CST。

time_zone

同样time_zone的值为“SYSTEM”。

根据代码中的提示,拼接与代码一样的SQL查询一下数据库:

select @@time_zone;SYSTEM

复制

值的确是“SYSTEM”。此时,我们又得出另外一个查询Mysql当前时区的方法。

至此,该问题的排查完美收官。大出一口气~~~

最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 211,265评论 6 490
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 90,078评论 2 385
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 156,852评论 0 347
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 56,408评论 1 283
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 65,445评论 5 384
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 49,772评论 1 290
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 38,921评论 3 406
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 37,688评论 0 266
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 44,130评论 1 303
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 36,467评论 2 325
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 38,617评论 1 340
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 34,276评论 4 329
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 39,882评论 3 312
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 30,740评论 0 21
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 31,967评论 1 265
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 46,315评论 2 360
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 43,486评论 2 348

推荐阅读更多精彩内容