理解Python日志处理流程
python的logging模块是一个功能强大且灵活的日志记录框架。其核心组件包括：
- 日志器（Logger）：应用程序代码直接与之交互的接口，负责接收日志消息并将其传递给相应的处理器。
- 处理器（Handler）：负责将日志消息发送到指定的目标，例如文件、控制台、网络等。
- 格式化器（Formatter）：定义日志消息的输出格式。
- 过滤器（Filter）：提供更细粒度的控制，决定哪些日志消息可以被处理。
日志消息的生命周期遵循一个严格的流程：
- 消息生成：应用程序调用logger.debug()、logger.info()等方法生成日志消息。
- 日志器级别检查：日志器会首先检查自身设置的级别。如果消息的级别低于日志器的级别，该消息将被直接丢弃，不会传递给任何处理器。这是日志流的第一道“关卡”。
- 处理器分发：如果消息通过了日志器的级别检查，它将被分发给所有附加到该日志器的处理器。
- 处理器级别检查：每个处理器也会检查自身设置的级别。如果消息的级别低于处理器的级别，该处理器将不会处理此消息。
- 格式化与输出：通过了处理器级别检查的消息，会由处理器关联的格式化器进行格式化，然后输出到指定目标。
INFO级别日志不输出的根本原因
在实际应用中，开发者常遇到INFO级别日志无法输出的问题，即使他们已经将文件处理器（FileHandler）或控制台处理器（StreamHandler）的级别设置为logging.INFO。这通常是因为忽略了日志器本身的级别设置。
Python logging模块中，当通过logging.getLogger(name)获取一个日志器实例时，如果该日志器是首次创建，其默认的级别是logging.WARNING。这意味着，任何低于WARNING级别（例如INFO、DEBUG）的消息，在到达日志器时就会被其自身的级别检查所过滤掉，根本不会有机会传递给后续的处理器。
因此，即使您的文件处理器被设置为logging.INFO，如果日志器本身的级别仍保持默认的WARNING，那么logger.info()产生的消息将永远无法到达该文件处理器。
立即学习“Python免费学习笔记（深入）”；
解决方案：显式设置日志器级别
解决此问题的关键在于，除了设置各个处理器的级别外，还必须显式地将日志器（Logger）的级别设置为您希望捕获的最低级别。例如，如果您想看到INFO级别及以上的所有日志，就应该将日志器级别设置为logging.INFO。
以下是修正后的setup_logger函数示例，其中增加了对日志器级别logger.setLevel()的设置：
import logging
import os
def setup_logger(dtc_name, log_dir, is_debug=False, is_interactive=False):
"""
配置并返回一个日志器实例。
Args:
dtc_name (str): 日志器的名称，通常与应用或模块名称一致。
log_dir (str): 日志文件存放的目录。
is_debug (bool): 是否启用调试模式，影响控制台输出级别。
is_interactive (bool): 是否启用交互模式，影响控制台输出级别。
Returns:
logging.Logger: 配置好的日志器实例。
"""
# 确保日志目录存在
os.makedirs(log_dir, exist_ok=True)
# 定义日志格式
formatter = logging.Formatter("%(asctime)s %(process)s %(levelname)s - %(message)s")
# 获取或创建日志器实例
logger = logging.getLogger(dtc_name)
# 关键步骤：设置日志器的总级别
# 只有级别高于或等于此设定的消息才会被日志器处理
if is_debug:
logger.setLevel(logging.DEBUG) # 调试模式下，日志器捕获所有消息
elif is_interactive:
logger.setLevel(logging.INFO) # 交互模式下，日志器捕获INFO及以上消息
else:
logger.setLevel(logging.INFO) # 默认情况下，捕获INFO及以上消息
# 避免重复添加处理器，这在多次调用setup_logger时很重要
if logger.handlers:
for handler in logger.handlers[:]:
logger.removeHandler(handler)
# 配置信息日志文件处理器
info_log_path = os.path.join(log_dir, f"{dtc_name}.log")
info_log = logging.FileHandler(info_log_path)
info_log.setFormatter(formatter)
info_log.setLevel(logging.INFO) # 文件记录所有INFO及以上消息
logger.addHandler(info_log)
# 配置错误日志文件处理器
error_log_path = os.path.join(log_dir, f"{dtc_name}_error.log")
error_log = logging.FileHandler(error_log_path)
error_log.setFormatter(formatter)
error_log.setLevel(logging.ERROR) # 文件只记录ERROR及以上消息
logger.addHandler(error_log)
# 配置控制台输出处理器
console_log = logging.StreamHandler()
console_log.setFormatter(formatter)
# 根据is_debug和is_interactive设置控制台输出级别
if is_debug:
console_log.setLevel(logging.DEBUG) # 调试模式下，控制台输出DEBUG及以上
elif is_interactive:
console_log.setLevel(logging.INFO) # 交互模式下，控制台输出INFO及以上
else:
console_log.setLevel(logging.ERROR) # 默认只在控制台输出ERROR及以上
logger.addHandler(console_log)
return logger
# 示例用法
def main():
# 创建一个名为 'logs' 的目录用于存放日志文件
log_directory = 'logs'
# 示例1: 默认模式 (日志器级别为INFO, 控制台ERROR)
print("\n--- 示例1: 默认模式 ---")
logger1 = setup_logger('DefaultLogger', log_directory, is_debug=False, is_interactive=False)
for handler in logger1.handlers:
print(f"Logger '{logger1.name}' Handler: {handler} | Level: {logging.getLevelName(handler.level)}")
logger1.debug('DefaultLogger: This is a debug message')
logger1.info('DefaultLogger: This is an info message')
logger1.warning('DefaultLogger: This is a warning message')
logger1.error('DefaultLogger: This is an error message')
logger1.critical('DefaultLogger: This is a critical message')
# 示例2: 调试模式 (日志器级别为DEBUG, 控制台DEBUG)
print("\n--- 示例2: 调试模式 ---")
logger2 = setup_logger('DebugLogger', log_directory, is_debug=True, is_interactive=False)
for handler in logger2.handlers:
print(f"Logger '{logger2.name}' Handler: {handler} | Level: {logging.getLevelName(handler.level)}")
logger2.debug('DebugLogger: This is a debug message')
logger2.info('DebugLogger: This is an info message')
logger2.warning('DebugLogger: This is a warning message')
logger2.error('DebugLogger: This is an error message')
logger2.critical('DebugLogger: This is a critical message')
# 示例3: 交互模式 (日志器级别为INFO, 控制台INFO)
print("\n--- 示例3: 交互模式 ---")
logger3 = setup_logger('InteractiveLogger', log_directory, is_debug=False, is_interactive=True)
for handler in logger3.handlers:
print(f"Logger '{logger3.name}' Handler: {handler} | Level: {logging.getLevelName(handler.level)}")
logger3.debug('InteractiveLogger: This is a debug message')
logger3.info('InteractiveLogger: This is an info message')
logger3.warning('InteractiveLogger: This is a warning message')
logger3.error('InteractiveLogger: This is an error message')
logger3.critical('InteractiveLogger: This is a critical message')
if __name__ == "__main__":
main()运行上述代码，您会观察到：
- 在默认模式下，控制台将只显示WARNING、ERROR、CRITICAL级别的消息，而DefaultLogger.log文件将包含INFO及以上的所有消息。
- 在调试模式下，控制台和DebugLogger.log文件都将显示所有级别的消息，包括DEBUG。
- 在交互模式下，控制台和InteractiveLogger.log文件都将显示INFO及以上的所有消息。
这清晰地展示了日志器级别和处理器级别协同工作的方式。
注意事项与最佳实践
-
日志器与处理器级别的协同：
- 日志器级别是日志流的总开关。它决定了哪些最低级别的消息会被日志器接受并传递给其附加的处理器。
- 处理器级别是分流开关。它决定了每个特定的输出目标（如文件、控制台）会处理哪些级别的消息。
- 重要原则：消息的级别必须大于或等于日志器的级别，并且大于或等于相应处理器的级别，才能最终被该处理器输出。
-
避免重复日志输出：
- 在多次调用setup_logger函数或在应用程序生命周期中重新配置日志时，务必检查并移除日志器已有的处理器，以防止重复添加处理器导致日志重复输出。示例代码中的if logger.handlers: for handler in logger.handlers[:]: logger.removeHandler(handler)就是为了解决这个问题。
-
根日志器（Root Logger）：
- logging.basicConfig()函数会配置根日志器。如果您在代码中没有显式获取具名日志器，而是直接使用logging.info()、logging.error()等，那么您实际上是在使用根日志器。根日志器的默认级别也是WARNING。
- 在大型应用中，推荐使用具名日志器（logging.getLogger('my_app')），这样可以更灵活地控制不同模块的日志行为，避免与第三方库或根日志器的配置冲突。
-
级别顺序：
- NOTSET
- 级别数字越小，表示消息越不重要。
总结
Python logging模块是一个强大的工具，但其配置的细微之处有时会令人困惑。解决INFO级别日志不输出问题的核心在于理解并正确设置日志器（Logger）自身的级别。日志器级别是日志流的第一道关卡，它会过滤掉所有低于其设定级别的消息，无论其附加的处理器级别如何。通过显式地将日志器级别设置为所需捕获的最低级别（例如logging.INFO或logging.DEBUG），并结合各个处理器特定的输出需求，可以确保您的应用程序日志系统按预期工作，为调试和监控提供准确、完整的记录。
