以下代码会产生一些意外行为logging.handlers.TimedRotatingFileHandler：
logger1 = logging.getLogger("One")
logger2 = logging.getLogger("Two")
handler1 = TimedRotatingFileHandler(
"service.log",
when="M",
backupCount=2,
utc=True
)
handler2 = TimedRotatingFileHandler(
"service.log",
when="M",
backupCount=2,
utc=True
)
formatter = logging.Formatter("%(asctime)s - %(name)s - %(message)s")
handler1.setFormatter(formatter)
handler2.setFormatter(formatter)
logger1.addHandler(handler1)
logger2.addHandler(handler2)
while True:
sleep(0.5)
logger1.error("ONE")
logger2.error("TWO")
我让它运行几分钟，第一次运行结束时一切正常。我知道存在某种非一的行为，即文件不会在预期的那一刻翻转，但我对此表示满意：
-rw-rw-r-- 1 dave dave 3.8K Sep 24 16:11 service.log
-rw-rw-r-- 1 dave dave 3.8K Sep 24 16:11 service.log.2019-09-24_09-09
-rw-rw-r-- 1 dave dave 4.3K Sep 24 16:10 service.log.2019-09-24_09-08
但是，如果我在不删除现有 service.log* 文件的情况下再次在同一目录中启动脚本之前等待几分钟，现在logger1开始按预期写入service.log.2019-09-24_09-11和logger2写入。注意nextservice.log的最后修改时间。16:17service.log.2019-09-24_09-11
-rw-rw-r-- 1 dave dave 540 Sep 24 16:17 service.log
-rw-rw-r-- 1 dave dave 540 Sep 24 16:17 service.log.2019-09-24_09-11
-rw-rw-r-- 1 dave dave 3.8K Sep 24 16:11 service.log.2019-09-24_09-09
这对我来说似乎是一个错误，因为logger1从几分钟前开始通过不同的调用继续写入文件logger.error- 这不仅仅是重新启动脚本时的第一个条目。
dave:~$ cat service.log.2019-09-24_09-11
2019-09-24 16:16:55,390 - One - ONE
2019-09-24 16:16:55,892 - One - ONE
2019-09-24 16:16:56,393 - One - ONE
2019-09-24 16:16:56,894 - One - ONE
2019-09-24 16:16:57,395 - One - ONE
2019-09-24 16:16:57,896 - One - ONE
2019-09-24 16:16:58,397 - One - ONE
2019-09-24 16:16:58,898 - One - ONE
...
我正在使用 Python 3.7.4。
编辑：可以在不停止和启动脚本的情况下重现警报行为，只要几分钟没有写入日志即可。
sleep(180)如果我们更换，观察日志文件的状态（每个都被清除到 36 字节大小！）
while True:
sleep(0.5)
logger1.error("ONE")
logger2.error("TWO")
...和...
for _ in range(60):
sleep(1)
logger1.error("ONE")
logger2.error("TWO")
sleep(180) # blocking, no logs written but log files are in eroded state
for _ in range(60):
sleep(1)
logger1.error("ONE")
logger2.error("TWO")