Python logging定时生成日志文件后内容互换问题原因解析

核心原因：delay=True参数与轮转时机的冲突 #

你的代码中使用了TimedRotatingFileHandler的delay=True参数，这个参数会延迟日志文件的打开操作，直到第一次有日志需要写入时才创建并打开文件，这是导致内容互换的关键因素，具体逻辑如下：

1. 轮转触发与文件未初始化的矛盾
   当Handler初始化后，若程序等待时间（比如你的time.sleep()）超过了设定的轮转间隔（代码中interval=80秒），第一次调用logger.debug()输出日志时，Handler会先执行轮转检查：

   • 发现当前时间已超过下一次轮转节点，尝试将基础日志文件（如app.log）重命名为备份文件（如app.log.20240520_100000）。
   • 但此时基础日志文件还不存在（delay=True还未触发文件创建），轮转操作无法完成预期的重命名，只能直接创建新的基础日志文件并写入第一条日志。

2. 文件句柄维护异常
   当后续轮转触发时，Handler会尝试将当前存在的基础日志文件重命名为备份文件，再创建新的基础文件。但由于第一次轮转时的异常（原文件不存在），Handler内部的文件句柄没有正确更新，导致后续日志写入被指向了刚重命名的备份文件，新创建的基础文件反而没有日志内容，最终出现基础文件与备份文件内容“互换”的假象。

其他辅助影响因素 #

• 日志器传播属性未关闭：代码中未设置logger.propagate = False，若根日志器也配置了文件输出，可能导致日志被重复写入不同文件，加重内容混乱。
• 轮转时间计算偏差：TimedRotatingFileHandler的轮转时间基于Handler初始化时间计算，若初始化后长时间无日志输出，第一次触发轮转时的时间计算可能出现偏差，导致轮转逻辑执行异常。

内容的提问来源于stack exchange，提问作者AtHuuu