当我们打开或关闭某些日志位置时，log4j 实际上在做什么？

Question

我们知道我们可以通过其属性/配置文件配置 log4j 以关闭特定位置（Java 中的类或包）的日志。我的问题如下：

1. log4j 实际上对这些标志做了什么？
2. log4j 中的日志语句是否仍然被调用，但只是因为该标志而没有被写入文件或控制台？那么仍然有性能影响吗？
3. 是否像C++中的#ifdef一样在编译时生效然后可以限制性能影响？

谢谢，

Answer 1

是的，日志语句仍然会被执行。这就是为什么首先检查日志级别是一个很好的模式：类似这样，

if (log.isInfoEnabled()) {
    log.info("My big long info string: " + someMessage);
}

当日志级别不支持 INFO 语句时，这是为了防止为信息 String 重新分配空间。

它与 #ifdef 不同 - #ifdef 是编译器指令，而 Log4J 配置是在运行时处理的。

编辑：我讨厌由于无知而被降级，所以这里有一篇文章支持我的答案。

来自http://surguy.net/articles/removing-log-messages.xml：

在 Log4J 中，如果您在以下位置记录消息
DEBUG级别，以及当前的Appender
设置为仅记录 INFO 消息
级别及以上，则该消息将
不显示。性能
调用 log 方法的惩罚
本身是最小的——几纳秒。
然而，可能需要更长的时间
评估日志的参数
方法。例如：

logger.debug("大对象是
“+largeObject.toString());

评估largeObject.toString()可能会
慢一点，之前就评估过了
对记录器的调用，因此记录器
无法阻止对其进行评估，
即使它不会被使用。

编辑 2：来自 log4j 手册本身 (http://logging .apache.org/log4j/1.2/manual.html）：

用户应该注意以下性能问题。

1. 关闭日志记录时的日志记录性能。
   当完全关闭日志记录或仅关闭一组级别的日志记录时，日志请求的成本包括方法调用加上整数比较。在 233 MHz Pentium II 机器上，此成本通常在 5 到 50 纳秒范围内。

   但是，方法调用涉及参数构造的“隐藏”成本。

   例如，对于一些记录猫，写作，

    logger.debug("条目编号：" + i + " is " + String.valueOf(entry[i]));
   

   会产生构造消息参数的成本，即将整数i和entry[i]转换为字符串，并连接中间字符串，无论消息是否会被记录。参数构造的成本可能相当高，并且取决于所涉及参数的大小。

   为了避免参数构造成本，请编写：

   if(logger.isDebugEnabled() {
     logger.debug("条目编号：" + i + " is " + String.valueOf(entry[i]));
   }
   

   如果禁用调试，这不会产生参数构造的成本。另一方面，如果记录器启用了调试，则评估记录器是否启用的成本将增加两倍：一次在 debugEnabled 中，一次在调试中。这是一个微不足道的开销，因为评估记录器大约需要实际记录时间的 1%。

Answer 2

我运行了一个简单的基准测试来测试。

    for (int j = 0; j < 5; j++) {
        long t1 = System.nanoTime() / 1000000;
        int iterations = 1000000;
        for (int i = 0; i < iterations; i++) {
            int test = i % 10;
            log.debug("Test " + i + " has value " + test);
        }
        long t2 = System.nanoTime() / 1000000;
        log.info("elapsed time: " + (t2 - t1));

        long t3 = System.nanoTime() / 1000000;
        for (int i = 0; i < iterations; i++) {
            int test = i % 10;
            if (log.isDebugEnabled()) {
                log.debug("Test " + i + " has value " + test);
            }
        }
        long t4 = System.nanoTime() / 1000000;
        log.info("elapsed time 2: " + (t4 - t3));
    }

elapsed time: 539
elapsed time 2: 17
elapsed time: 450
elapsed time 2: 18
elapsed time: 454
elapsed time 2: 19
elapsed time: 454
elapsed time 2: 17
elapsed time: 450
elapsed time 2: 19

对于 1.6.0_18，这让我感到惊讶，因为我本以为内联会阻止这种情况。也许具有转义分析功能的 Java 7 会。

然而，我仍然不会将调试语句包装在 if 子句中，除非半微秒数量级的时间改进变得很重要！

Answer 3

1. log4j 将处理日志语句，并检查是否在特定日志记录级别启用了特定记录器。如果不是，则不会记录该语句。

2. 这些检查比实际写入磁盘（或控制台）要便宜得多，但它们仍然有影响。

3. 不，java没有#ifdef这样的概念（无论如何，开箱即用，有java预编译器）