Python 记录器忽略类中的 FileHandler 和 StreamHandler 级别

Question

我正在尝试为我的日志文件和流设置不同的记录器级别，并且我(似乎)遵循了演示(https://docs.python.org/3/howto/logging-cookbook.html)到点。但是，在我的实际代码中它不起作用。在测试脚本中运行这个演示是有效的:

import logging

logger = logging.getLogger('simple_example')
logger.setLevel(logging.DEBUG)
# create file handler which logs even debug messages
fh = logging.FileHandler('spam.log')
fh.setLevel(logging.DEBUG)
# create console handler with a higher log level
ch = logging.StreamHandler()
ch.setLevel(logging.ERROR)
# create formatter and add it to the handlers
formatter = logging.Formatter('%(asctime)s - %(name)s - %(levelname)s - %(message)s')
ch.setFormatter(formatter)
fh.setFormatter(formatter)
# add the handlers to logger
logger.addHandler(ch)
logger.addHandler(fh)

# 'application' code
logger.debug('debug message')
logger.info('info message')
logger.warning('warn message')
logger.error('error message')
logger.critical('critical message')

print(logger.handlers)
print(logger)

结果是

alex@alexpc:~/Projects/claritydb$ python test.py ; cat spam.log
2019-09-16 11:53:44,293 - simple_example - ERROR - error message
2019-09-16 11:53:44,293 - simple_example - CRITICAL - critical message
[<StreamHandler <stderr> (ERROR)>, <FileHandler /home/Projects/claritydb/spam.log (DEBUG)>]
<Logger simple_example (DEBUG)>
2019-09-16 11:53:44,293 - simple_example - DEBUG - debug message
2019-09-16 11:53:44,293 - simple_example - INFO - info message
2019-09-16 11:53:44,293 - simple_example - WARNING - warn message
2019-09-16 11:53:44,293 - simple_example - ERROR - error message
2019-09-16 11:53:44,293 - simple_example - CRITICAL - critical message

但是由于某种原因，这将无法在实际代码中正确执行:

class MyClass(object):

    def __init__(self,
                 verbosity_stream="WARNING", 
                 verbosity_log="INFO"):

        self.logger = self.setup_logger(verbosity_stream=verbosity_stream, verbosity_log=verbosity_log)

    def setup_logger(self, verbosity_stream="ERROR", verbosity_log="WARNING"):

        # Create a custom logger

        logger = logging.getLogger(f"do_stuff")
        logger.setLevel(logging.DEBUG)

        fh = logging.FileHandler(f"do_stuff.log", mode='w')
        fh.setLevel(getattr(logging, verbosity_log))

        ch = logging.StreamHandler()
        ch.setLevel(getattr(logging, verbosity_stream))

        # create formatter and add it to the handlers
        formatter = logging.Formatter('%(asctime)s - %(levelname)s - %(message)s')
        ch.setFormatter(formatter)
        fh.setFormatter(formatter)

        # add the handlers to the self.logger
        logger.addHandler(ch)
        logger.addHandler(fh)

        print(logger.handlers)

        return logger

对 self.logger 的任何调用都会在 logging.DEBUG 级别输出到控制台和日志文件——这显然不是我想要的。我很困惑我错过了什么，因为当我问主要方法它的文件处理程序是什么时

print(self.logger.handlers)
print(self.logger)

[<StreamHandler <stderr> (ERROR)>, <FileHandler /home/Projects/claritydb/do_stuff.log (DEBUG)>]
<Logger do_stuff (DEBUG)>

这与测试用例相同，但我的控制台和日志文件中都有所有信息级别的输出。更改我的 handler.setLevel 命令对输出没有影响，唯一改变它的是将 setLevel 命令更改为定义最顶部的初始记录器调用(即 ch.setLevel 和 fh.setLevel 无效，但 logger.setLevel 确实[而且我认为它应该只是初始过滤器)。

这让我发疯，有人知道吗？

EDIT1:更改

logger = logging.getLogger(f"do_stuff")

线到

logger = logging.getLogger()

似乎解决了这个问题，但我不知道为什么......

Answer 1

当您调用 logger = logging.getLogger()您将获得根记录器，然后附加您的处理程序并设置其级别等等。当你这样做 logger = logging.getLogger(f"do_stuff")您将获得一个名为 do_stuff 的记录器但是根记录器仍然存在，并且您新创建的记录器将其日志传播到根记录器处理程序。由于在这种情况下，根记录器没有添加任何处理程序，因此它使用默认的 logging.lastResort使用它的默认级别。您可以通过多种方式解决此问题。一种简单的解决方案是设置 logger.propagate = False .

你也不需要这个fh.setLevel(getattr(logging, verbosity_log)) . Python 处理直接传入的字符串就好了。