<<<<<<< HEAD Log4j自定义Converter ======= Log4j自定义Converter >>>>>>> 24b6b53 ( 【init】) <<<<<<< HEAD
UP | HOME
<<<<<<< HEAD
=======
>>>>>>> 24b6b53 ( 【init】)

Log4j自定义Converter
发布于 6 12, 2020 by ThinkCat.

<<<<<<< HEAD

Table of Contents

  • 缘由
  • 实现
    • 依赖项
    • 自定义Converter
    • 配置log4j2.xml
    • 测试验证
    • =======

      Table of Contents

      <<<<<<< HEAD

      缘由

      =======

      缘由

      >>>>>>> 24b6b53 ( 【init】)

      新公司的基础框架不是太好用,和前公司比,还是差了不少。由于接手的都是老系统,所有东西,都是用的很久之前的,好多很原生的东西,包括JDBC、reids操作,都是裸操作连接。

      前两天还出了一个任务中,使用了JDBC连接,没有归还连接池,导致的bug… ORZ…

      其中基础的日志链路追踪,和前公司比,真是差了不少。加上老系统代码比较杂,追踪问题特别麻烦,日志中也很难看到连续的东西。于是打算把TraceId这种东西,加到每个日志里面,方便按每个请求查链路日志。

      <<<<<<< HEAD

      实现

      =======

      实现

      >>>>>>> 24b6b53 ( 【init】)

      拿SpringBoot做了一个小demo来验证了一下,代码很简单。

      <<<<<<< HEAD

      依赖项

      =======

      依赖项

      >>>>>>> 24b6b53 ( 【init】)

      首先引入日志依赖:

      <dependency>
        <groupId>org.springframework.boot</groupId>
        <artifactId>spring-boot-starter</artifactId>
        <exclusions>
          <!--排除logback-->
          <exclusion>
            <groupId>org.springframework.boot</groupId>
            <artifactId>spring-boot-starter-logging</artifactId>
          </exclusion>
        </exclusions>
      </dependency>
      <dependency>
        <groupId>org.springframework.boot</groupId>
        <artifactId>spring-boot-starter-log4j2</artifactId>
      </dependency>
      <dependency>
        <groupId>org.projectlombok</groupId>
        <artifactId>lombok</artifactId>
        <version>1.18.8</version>
        <scope>provided</scope>
      </dependency>
      

      由于公司现有用的都是 log4j2 的组件,所以依赖中要排除掉 spring boot 默认用的logback组件,然后引入 log4j2 依赖。另外平时使用 lombok 习惯了,也引入一下。

      <<<<<<< HEAD

      自定义Converter

      =======

      自定义Converter

      >>>>>>> 24b6b53 ( 【init】)

      代码如下:

      package org.example;
      
      import org.apache.logging.log4j.core.LogEvent;
      import org.apache.logging.log4j.core.config.plugins.Plugin;
      import org.apache.logging.log4j.core.pattern.ConverterKeys;
      import org.apache.logging.log4j.core.pattern.LogEventPatternConverter;
      
      @Plugin(name = "TradeConverter",category = "Converter")
      @ConverterKeys({"traceId"})
      public class TraceConverter extends LogEventPatternConverter {
      <<<<<<< HEAD
          protected TraceConverter(String name, String style) {
      =======
          protected TraceConverter(String name, String style) {
      >>>>>>> 24b6b53 ( 【init】)
              super(name, style);
          }
      
          public static TraceConverter newInstance(final String[] options) {
              return new TraceConverter("traceId", "traceId");
          }
      
      
          @Override
          public void format(LogEvent logEvent, StringBuilder stringBuilder) {
              stringBuilder.append(getTraceId());
          }
      
          private String getTraceId(){
              //这里实际从基础框架获取请求的TraceId
              return "TestTraceId";
          }
      }
      
      

      实际还是很简单的,继承 LogEventPatternConverter 就可以了。

      <<<<<<< HEAD

      配置log4j2.xml

      =======

      配置log4j2.xml

      >>>>>>> 24b6b53 ( 【init】)

      找了一份比较齐全的配置文件:

      <<<<<<< HEAD
      <?xml version="1.0" encoding="UTF-8"?>
      <!--日志级别以及优先级排序: OFF > FATAL > ERROR > WARN > INFO > DEBUG > TRACE > ALL -->
      =======
      
      <?xml version="1.0" encoding="UTF-8"?>
      <!--日志级别以及优先级排序: OFF > FATAL > ERROR > WARN > INFO > DEBUG > TRACE > ALL -->
      >>>>>>> 24b6b53 ( 【init】)
      <!--Configuration后面的status,这个用于设置log4j2自身内部的信息输出,可以不设置,当设置成trace时,你会看到log4j2内部各种详细输出-->
      <!--monitorInterval:Log4j能够自动检测修改配置 文件和重新配置本身,设置间隔秒数-->
      <configuration status="WARN" monitorInterval="30">
          <!--先定义所有的appender-->
          <appenders>
              <!--这个输出控制台的配置-->
              <console name="Console" target="SYSTEM_OUT">
                  <!--输出日志的格式-->
                  <PatternLayout pattern="[%d{HH:mm:ss:SSS}] [%p] ----[%traceId]  ------ %l - %m%n"/>
              </console>
              <!--文件会打印出所有信息,这个log每次运行程序会自动清空,由append属性决定,这个也挺有用的,适合临时测试用-->
              <File name="log" fileName="log/test.log" append="false">
                  <PatternLayout pattern="%d{HH:mm:ss.SSS} %-5level %class{36} %L %M - %msg%xEx%n"/>
              </File>
              <!-- 这个会打印出所有的info及以下级别的信息,每次大小超过size,则这size大小的日志会自动存入按年份-月份建立的文件夹下面并进行压缩,作为存档-->
              <RollingFile name="RollingFileInfo" fileName="${sys:user.home}/logs/info.log"
                           filePattern="${sys:user.home}/logs/$${date:yyyy-MM}/info-%d{yyyy-MM-dd}-%i.log">
                  <!--控制台只输出level及以上级别的信息(onMatch),其他的直接拒绝(onMismatch)-->
                  <ThresholdFilter level="info" onMatch="ACCEPT" onMismatch="DENY"/>
                  <PatternLayout pattern="[%d{HH:mm:ss:SSS}] [%p] - %l - %m%n"/>
                  <Policies>
                      <TimeBasedTriggeringPolicy/>
                      <SizeBasedTriggeringPolicy size="100 MB"/>
                  </Policies>
              </RollingFile>
              <RollingFile name="RollingFileWarn" fileName="${sys:user.home}/logs/warn.log"
                           filePattern="${sys:user.home}/logs/$${date:yyyy-MM}/warn-%d{yyyy-MM-dd}-%i.log">
                  <ThresholdFilter level="warn" onMatch="ACCEPT" onMismatch="DENY"/>
                  <PatternLayout pattern="[%d{HH:mm:ss:SSS}] [%p] - %l - %m%n"/>
                  <Policies>
                      <TimeBasedTriggeringPolicy/>
                      <SizeBasedTriggeringPolicy size="100 MB"/>
                  </Policies>
      <<<<<<< HEAD
                  <!-- DefaultRolloverStrategy属性如不设置,则默认为最多同一文件夹下7个文件,这里设置了20 -->
      =======
                  <!-- DefaultRolloverStrategy属性如不设置,则默认为最多同一文件夹下7个文件,这里设置了20 -->
      >>>>>>> 24b6b53 ( 【init】)
                  <DefaultRolloverStrategy max="20"/>
              </RollingFile>
              <RollingFile name="RollingFileError" fileName="${sys:user.home}/logs/error.log"
                           filePattern="${sys:user.home}/logs/$${date:yyyy-MM}/error-%d{yyyy-MM-dd}-%i.log">
                  <ThresholdFilter level="error" onMatch="ACCEPT" onMismatch="DENY"/>
                  <PatternLayout pattern="[%d{HH:mm:ss:SSS}] [%p] - %l - %m%n"/>
                  <Policies>
                      <TimeBasedTriggeringPolicy/>
                      <SizeBasedTriggeringPolicy size="100 MB"/>
                  </Policies>
              </RollingFile>
          </appenders>
          <!--然后定义logger,只有定义了logger并引入的appender,appender才会生效-->
          <loggers>
              <!--过滤掉spring和mybatis的一些无用的DEBUG信息-->
              <logger name="org.springframework" level="INFO"/>
              <logger name="org.mybatis" level="INFO"/>
              <root level="all">
                  <appender-ref ref="Console"/>
                  <appender-ref ref="RollingFileInfo"/>
                  <appender-ref ref="RollingFileWarn"/>
                  <appender-ref ref="RollingFileError"/>
              </root>
          </loggers>
      </configuration>
      
      

      重点在于配置的 PatternLayout :

      <PatternLayout pattern="[%d{HH:mm:ss:SSS}] [%p] ----[%traceId]  ------ %l - %m%n"/>
      
      <<<<<<< HEAD

      测试验证

      =======

      测试验证

      >>>>>>> 24b6b53 ( 【init】)

      启动一下,打个日志看看。

      package org.example;
      
      import lombok.extern.log4j.Log4j2;
      import lombok.extern.slf4j.Slf4j;
      import org.springframework.boot.SpringApplication;
      import org.springframework.boot.autoconfigure.SpringBootApplication;
      
      /**
       * Hello world!
       */
      @Log4j2
      @SpringBootApplication
      public class App {
      
          public static void main(String[] args) {
      
              SpringApplication.run(App.class, args);
              log.error("Hello World");
              log.info("Hello World");
          }
      }
      
      

      Console 的输出格式如下:

        .   ____          _            __ _ _
       /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
      ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
       \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
        '  |____| .__|_| |_|_| |_\__, | / / / /
       =========|_|==============|___/=/_/_/_/
       :: Spring Boot ::        (v2.2.7.RELEASE)
      
      [22:04:19:068] [INFO] ----[TestTraceId]  ------ org.springframework.boot.StartupInfoLogger.logStarting(StartupInfoLogger.java:55) - Starting App on HeHe-2.local with PID 25792 ..
      [22:04:19:071] [DEBUG] ----[TestTraceId]  ------ org.springframework.boot.StartupInfoLogger.logStarting(StartupInfoLogger.java:56) - Running with Spring Boot v2.2.7.RELEASE, Spring v5.2.6.RELEASE
      [22:04:19:072] [INFO] ----[TestTraceId]  ------ org.springframework.boot.SpringApplication.logStartupProfileInfo(SpringApplication.java:651) - No active profile set, falling back to default profiles: default
      [22:04:19:423] [INFO] ----[TestTraceId]  ------ org.springframework.boot.StartupInfoLogger.logStarted(StartupInfoLogger.java:61) - Started App in 0.557 seconds (JVM running for 1.165)
      [22:04:19:424] [ERROR] ----[TestTraceId]  ------ org.example.App.main(App.java:18) - Hello World
      [22:04:19:424] [INFO] ----[TestTraceId]  ------ org.example.App.main(App.java:19) - Hello World
      
      Process finished with exit code 0
      

      已经可以正常输出拿到的 TestTraceId 信息了。至此,完成。