Python 日志重复打印的常见原因

1次阅读

根本原因是日志器被重复添加处理器,如basicconfig()后又手动addhandler、模块多次导入导致getlogger()重复配置,或框架预配置与用户配置冲突。

Python 日志重复打印的常见原因

为什么 Logging.info() 会打印两遍?

根本原因通常是日志器被重复添加了处理器(Handler),尤其是调用 basicConfig() 后又手动添加 Handler,或模块被多次导入导致 logging.getLogger() 多次配置。

常见错误现象:INFO:root:hello 出现两次,甚至四次;不同模块的日志混在一起重复;单元测试里日志爆炸式输出。

  • 检查是否在循环、装饰器、或 __init__.py 中反复调用 logger.addHandler()
  • 确认没在多个地方调用 logging.basicConfig() —— 它只生效一次,但若先调用过,后续再手动 addHandler 就会叠加
  • logger.handlers 打印当前绑定的处理器数量:print(len(logger.handlers)),非零且大于 1 就是问题所在

模块级日志器被导入多次怎么办?

pythonimport 是幂等的,但如果你在模块顶层写了 logging.getLogger().addHandler(...),而该模块被多个路径 import(比如被 main.pytests/test_x.py 同时导入),就会重复绑定。

使用场景:工具脚本、CLI 工具、django/flask 的扩展模块中容易踩这个坑。

立即学习Python免费学习笔记(深入)”;

  • 把日志配置逻辑移到函数内(如 setup_logging()),只在程序入口显式调用一次
  • 改用命名 logger:logger = logging.getLogger(__name__),避免污染 root logger
  • 添加守卫判断:if not logger.handlers: 再 addHandler,比靠运气更可靠

basicConfig() 和手动配置 Handler 冲突了吗?

是的。一旦 basicConfig() 被调用(哪怕没显式写,某些库如 pytestflask 会悄悄调),它会给 root logger 加一个 StreamHandler;此时你再 getLogger().addHandler(),就变成双输出。

性能影响不大,但日志格式、级别、目标完全失控,尤其上线后发现日志文件翻倍增长。

  • 要么全程不用 basicConfig(),全手动配置 Formatter + Handler + Logger
  • 要么彻底禁用它:logging.basicConfig(handlers=[])(空列表可清掉默认 handler)
  • 注意:basicConfig() 只在 root logger 没 handler 时生效,所以它和你的代码谁先执行,决定了结果

Django/Flask 中日志重复的特殊表现

框架启动时会预配置 root logger,而你在 settings.pyapp.py 里又调 logging.getLogger().addHandler(),结果就是每条日志打两份:一份来自框架的 handler,一份来自你的。

典型错误信息:WARNING:django.request:Not Found: /xxx 在终端和文件里各出现一次,时间戳还差几毫秒。

  • Django 用户应改用 LOGGING 配置字典,在 settings.py 里统一声明,别碰 addHandler
  • Flask 用户避免在创建 app 前调用任何 logging.* 配置,优先用 app.loggerapp.logger.addHandler()
  • 所有框架下,别对 logging.getLogger('werkzeug')logging.getLogger('django') 手动加 handler —— 它们自有管理逻辑

最麻烦的是跨进程或 reload 场景(比如 Flask debug 模式、gunicorn worker 重启),handler 实际没销毁,只是被新实例叠加了。这时候光看代码找不到问题,得靠 logger.handlers 实时查。

text=ZqhQzanResources