Lee's Space Station

修改 logging 时间

2019/01/06 Share

问题

在记录一些必要信息时,我通常会使用 logging 模块,在输出信息时同时可以输出时间和日志等级,例如使用 basicConfig 来先设定日志格式:

1
2
3
logging.basicConfig(format='%(asctime)s %(levelname)s: %(message)s', 
level=logging.INFO,
datefmt='%Y-%m-%d %H:%M:%S')

假如说我们在中国,当前北京时间为 2019-01-06 17:30:11,那么正常情况下日志时间应该是也是这个,但是有些时候(比如在某些 docker 容器内)却是不正确的,例如会是下面的结果:

1
2
>>> logging.info("Hello")
2019-01-06 09:30:11 INFO: Hello

也就是说输出的日志时间比正确时间慢 8 个小时。

这是由于系统时间是错误的,或者说时区不正确,logging 模块中 asctime 的时间使用的是 time.localtime() 返回的时间,而 time.localtime() 又是使用的 time.time() 返回的时间,而 time.time() 返回的是 UTC 时间,即从 1970-1-1 00:00:00 到现在的秒数,由于时区不对,所以没有转成中国所在的 UTC+8 时区。

解决

那么问题找到了,解决方法也很直接:为返回的时间加上 UTC 偏移

本文解决方法的前提是不能(不方便)修改系统时间。

吐槽一下,寻找解决办法过程中各种时间标准简直把我绕的不行,参见 GMT,UTC,DST,CST 各种时间标准

经过 SO 上这篇回答的提醒,可以设定 logging.Formatter.converter 来转换时间,但是回答里是转换成 GMT 时间,我们需要自己重写一个函数来加上 UTC 偏移,返回正确的时间,传给 logging.Formatter.converter

1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
import logging
import datetime


def beijing(sec, what):
beijing_time = datetime.datetime.now() + datetime.timedelta(hours=8)
return beijing_time.timetuple()


logging.Formatter.converter = beijing

logging.basicConfig(
format="%(asctime)s %(levelname)s: %(message)s",
level=logging.INFO,
datefmt="%Y-%m-%d %H:%M:%S",
)

这样就可以将时间修正为正确的时间了。

END

CATALOG
  1. 1. 问题
  2. 2. 解决
  3. 3. END