现象

相同的日志打印了两次，且因为日志的配置不同，导致脱敏的情况不一致。

代码与配置

代码

  [java]
1
2
3
4
5
6
7
8
9
10
11
12
13
14
package com.ryo.log4j2.cfg.additivity;

import org.apache.logging.log4j.LogManager;
import org.apache.logging.log4j.Logger;

public class SimpleDemo {

  private static final Logger logger = LogManager.getLogger(SimpleDemo.class.getName());

  public static void main(String[] args) {
    logger.info("do it");
  }

}

log4j2.xml 配置文件

简化如下

  [xml]
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
<?xml version="1.0" encoding="UTF-8"?>
<!--monitorInterval属性指定的配置元素和设置为非零值那么下次将要检查文件日志事件评估和/或记录和monitorInterval运行自上次检查。
下面的例子展示了如何配置配置文件属性,以便将检查变化只有在至少60秒后运行。最小间隔5秒。-->
<Configuration status="WARN" monitorInterval="60">

    <properties>
        <property name="LOG_HOME">${sys:user.home}/logs/log4j2/log4j2/</property>
    </properties>

    <Appenders>
        <Console name="Console" target="SYSTEM_OUT">
            <PatternLayout pattern="CURRENT %d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n"/>
        </Console>
    </Appenders>


    <Loggers>
        <Root level="DEBUG">
            <AppenderRef ref="Console"/>
        </Root>
    </Loggers>
</Configuration>

这里没有额外的 appender，就算有，所有的匹配的 additivity="false"，应该也不会重复才对。

问题分析

发现项目配置比较奇怪，在启动的时候 jvm 指定了配置。

  [plaintext]
1
-Dlog4j.configurationFile=otherLog4j2.xml,log4j2.xml

相当于指定了两个配置文件。

otherLog4j2.xml

  [xml]
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
<?xml version="1.0" encoding="UTF-8"?>
<!--monitorInterval属性指定的配置元素和设置为非零值那么下次将要检查文件日志事件评估和/或记录和monitorInterval运行自上次检查。
下面的例子展示了如何配置配置文件属性,以便将检查变化只有在至少60秒后运行。最小间隔5秒。-->
<Configuration status="WARN" monitorInterval="60">

    <properties>
        <property name="LOG_HOME">${sys:user.home}/logs/log4j2/log4j2/</property>
    </properties>

    <Appenders>
        <Console name="COMMON-Console" target="SYSTEM_OUT">
            <PatternLayout pattern="OTHER %d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n"/>
        </Console>

        <File name="FileAll" fileName="all.log" bufferedIO="false" advertise="true">
            <!--控制台只输出level及以上级别的信息（onMatch），其他的直接拒绝（onMismatch）-->
            <ThresholdFilter level="INFO" onMatch="ACCEPT" onMismatch="DENY"/>
        </File>
    </Appenders>


    <Loggers>
        <Root level="DEBUG">
            <AppenderRef ref="COMMON-Console"/>
        </Root>
    </Loggers>
</Configuration>

日志效果

2 个都会打印。

  [plaintext]
1
2
3
4
2023-07-20 14:07:46,005 main WARN The bufferSize is set to 8192 but bufferedIo is false: false

OTHER 14:07:46.057 [main] INFO  com.ryo.log4j2.cfg.additivity.SimpleDemo - do it
CURRENT 14:07:46.057 [main] INFO  com.ryo.log4j2.cfg.additivity.SimpleDemo - do it

解决方式

直接把 jvm 启动时只保留一个 log4j2.xml 文件即可。

如果确实需要另外一个配置文件，可以考虑在 log4j2.xml 针对 logger 进行拦截重写日志隔离级别，避免重复打印。

精准匹配之后，就不会再往兜底执行了。两个都是兜底，就会执行多次。

参考资料

https://blog.csdn.net/blue_driver/article/details/125007794

现象
参考资料

Log4j2-24-log4j2 相同的日志打印 2 次

现象