Java SLF4J 日志

1、SLF4J 是什么

SLF4J 是 Simple Logging Facade for Java 的缩写(for≈4),也就是简易的日志门面,以外观模式(Facade pattern,一种设计模式,为子系统中的一组接口提供一个统一的高层接口,使得子系统更容易使用)实现,支持 java.util.logging、Log4J 和 Logback。
SLF4J 的作者就是 Log4J 和 Logback 的作者。1996 年初,欧洲安全电子市场项目决定编写自己的跟踪 API,最后该 API 演变成了 Log4j,已经推出就备受宠爱。
2002 年 2 月,Sun 推出了自己的日志包 java.util.logging(可称 JUL),据说实现思想借鉴了 Log4j,毕竟此时的 Log4j 已经很成熟了。
2002 年 8 月,Apache 就推出了自己的日志包,也就是阿里巴巴开发手册上提到的 JCL(Jakarta Commons Logging)。JCL 的野心很大,它在 JUL 和 Log4j 的基础上提供了一个抽象层的接口,方便使用者在 JUL 和 Log4j 之间切换。
SLF4J作为门面日志 - 图1
但 JCL 好像并不怎么招人喜欢,Ceki Gulcu 也觉得 JCL 不好,他在 2005 年写了一个名叫 SLF4J 的新项目,SLF4J 只有接口,没有实现,他在 SLF4J 和 JUL、Log4j、JCL 之间搭了三座桥:
SLF4J作为门面日志 - 图2

2、SLF4J 解决了什么痛点

春秋战国的时候,每个国家都有自己的货币,用别国的货币也不合适,对吧?那在发生贸易的时候就比较麻烦了,货币不统一,就没法直接交易,因为货币可能不等价。
那秦始皇统一六国后,就推出了新的货币政策,全国都用一种货币,那之前的问题就解决掉了。
同样的道理,日志系统有 JUL、JCL,Ceki Gulcu 自己又写了 2 种,Log4j 和 Logback,各有各的优缺点,再加上使用者千千万,这就导致不同的应用可能会用不同的日志系统。
假设正在开发一套系统,打算用 SLF4J 作为门面,Log4j 作为日志系统,在项目中使用了 A 框架,而 A 框架的门面是 JCL,日志系统是 JUL,那就相等于要维护两套日志系统
SLF4J作为门面日志 - 图3
Ceki Gulcu 想到了这个问题,并且解决了!来看 SLF4J 官网给出的解决方案。
SLF4J作为门面日志 - 图4

  • 使用 jcl-over-slf4j.jar 替换 commons-logging.jar
  • 引入 jul-to-slf4j.jar

为了模拟这个过程,来建一个使用 JCL 的项目。
第一步,在 pom.xml 文件中引入 commons-logging.jar:

  1. <dependency>
  2. <groupId>commons-logging</groupId>
  3. <artifactId>commons-logging</artifactId>
  4. <version>1.2</version>
  5. </dependency>

第二步,新建测试类:

  1. import org.apache.commons.logging.Log;
  2. import org.apache.commons.logging.LogFactory;
  3. /**
  4. * @author Fcant
  5. */
  6. public class Demo {
  7. private static Log logger = LogFactory.getLog(Demo.class);
  8. public static void main(String[] args) {
  9. logger.info("jcl");
  10. }
  11. }

该类会通过 LogFactory 获取一个 Log 对象,并且使用 info() 方法打印一行日志。
调试这段代码的过程中可以发现,Log 的实现有四种:
SLF4J作为门面日志 - 图5
如果没有绑定 Log4j 的话,就会默认选择 Jdk14Logger——它返回的 Logger 对象,正是 java.util.logging.Logger,也就是 JUL。
因此,就可以在控制台看到以下信息:

  1. 10 21, 2020 3:13:30 下午 com.itwanger.Demo main
  2. 信息: jcl

怎么把使用 JCL 的项目改造成使用 SLF4J 的呢?
第三步,使用 jcl-over-slf4j.jar 替换 commons-logging.jar,并加入 jul-to-slf4j.jar、slf4j-log4j12.jar(会自动引入 slf4j-api.jar 和 log4j.jar):

  1. <dependency>
  2. <groupId>org.slf4j</groupId>
  3. <artifactId>jcl-over-slf4j</artifactId>
  4. <version>1.7.25</version>
  5. </dependency>
  6. <dependency>
  7. <groupId>org.slf4j</groupId>
  8. <artifactId>jul-to-slf4j</artifactId>
  9. <version>1.7.29</version>
  10. </dependency>
  11. <dependency>
  12. <groupId>org.slf4j</groupId>
  13. <artifactId>slf4j-log4j12</artifactId>
  14. <version>1.7.25</version>
  15. </dependency>

第四步,在 resources 目录下创建 log4j.properties 文件,内容如下所示:

  1. ### 设置###
  2. log4j.rootLogger = debug,stdout,D
  3. ### 输出信息到控制台 ###
  4. log4j.appender.stdout = org.apache.log4j.ConsoleAppender
  5. log4j.appender.stdout.Target = System.out
  6. log4j.appender.stdout.layout = org.apache.log4j.PatternLayout
  7. log4j.appender.stdout.layout.ConversionPattern = [%-5p] %d{yyyy-MM-dd HH:mm:ss,SSS} method:%l%n%m%n
  8. ### 输出DEBUG 级别以上的日志到=debug.log ###
  9. log4j.appender.D = org.apache.log4j.DailyRollingFileAppender
  10. log4j.appender.D.File = debug.log
  11. log4j.appender.D.Append = true
  12. log4j.appender.D.Threshold = DEBUG
  13. log4j.appender.D.layout = org.apache.log4j.PatternLayout
  14. log4j.appender.D.layout.ConversionPattern = %d{yyyy-MM-dd HH:mm:ss} [ %t:%r ] - [ %p ] %m%n

再次运行 Demo 类,可以发现 target 目录下会生成一个名叫 debug.log 的文件,内容如下所示:

  1. 2020-10-21 15:32:06 [ main:0 ] - [ INFO ] jcl

并且可以在控制台看到以下信息:

  1. [INFO ] 2020-10-21 15:32:06,192 method:com.itwanger.Demo.main(Demo.java:12)
  2. jcl

仔细对比一下,就可以发现,这次输出的格式和之前不一样,这就是因为 Log4j 和 JUL 的日志格式不同导致的。
另外有没有发现?并没有改动测试类 Demo,它里面使用的仍然是 JCL 获取 Log 的方式:

  1. private static Log logger = LogFactory.getLog(Demo.class);

但输出的格式已经切换到 Log4j 了!
SLF4J 除了提供这种解决方案,绑定 Log4j 替换 JUL 和 JCL;还提供了绑定 Logback 替换 JUL、JCL、Log4j 的方案:
SLF4J作为门面日志 - 图6
还有绑定 JUL 替换 JCL 和 Log4j 的方案:
SLF4J作为门面日志 - 图7

3、SLF4J 比 Log4J 的优势

SLF4J 除了解决掉以上的痛点,帮助应用程序独立于任何特定的日志系统,还有一个非常厉害的功能,那就是 SLF4J 在打印日志的时候使用了占位符 {},它有点类似于 String 类的 format() 方法(使用 %s 等填充参数),但更加便捷,这在很大程度上提高了程序的性能。
众所周知,字符串是不可变的,字符串拼接会创建很多不必要的字符串对象,极大的消耗了内存空间。但 Log4J 在打印带参数的日志时,只能使用字符串拼接的方式:

  1. String name = "Fcant";
  2. int age = 18;
  3. logger.debug(name + ",年纪:" + age + ",Hello World");

非常笨重,但加入了 SLF4J 后,这个问题迎刃而解。来看一下在 Log4j 项目中加入 SLF4J 的详细的步骤。
第一步,把 log4j 的依赖替换为 slf4j-log4j12(Maven 会自动引入 slf4j-api.jar 和 log4j.jar):

  1. <dependency>
  2. <groupId>org.slf4j</groupId>
  3. <artifactId>slf4j-log4j12</artifactId>
  4. <version>1.7.25</version>
  5. </dependency>

第二步,在 resources 目录下创建 log4j.properties 文件:

  1. ### 设置###
  2. log4j.rootLogger = debug,stdout,D,E
  3. ### 输出信息到控制台 ###
  4. log4j.appender.stdout = org.apache.log4j.ConsoleAppender
  5. log4j.appender.stdout.Target = System.out
  6. log4j.appender.stdout.layout = org.apache.log4j.PatternLayout
  7. log4j.appender.stdout.layout.ConversionPattern = [%-5p] %d{yyyy-MM-dd HH:mm:ss,SSS} method:%l%n%m%n
  8. ### 输出DEBUG 级别以上的日志到=debug.log ###
  9. log4j.appender.D = org.apache.log4j.DailyRollingFileAppender
  10. log4j.appender.D.File = debug.log
  11. log4j.appender.D.Append = true
  12. log4j.appender.D.Threshold = DEBUG
  13. log4j.appender.D.layout = org.apache.log4j.PatternLayout
  14. log4j.appender.D.layout.ConversionPattern = %d{yyyy-MM-dd HH:mm:ss} [ %t:%r ] - [ %p ] %m%n
  15. ### 输出ERROR 级别以上的日志到=error.log ###
  16. log4j.appender.E = org.apache.log4j.DailyRollingFileAppender
  17. log4j.appender.E.File =error.log
  18. log4j.appender.E.Append = true
  19. log4j.appender.E.Threshold = ERROR
  20. log4j.appender.E.layout = org.apache.log4j.PatternLayout
  21. log4j.appender.E.layout.ConversionPattern = %d{yyyy-MM-dd HH:mm:ss} [ %t:%r ] - [ %p ] %m%n

第三步,新建测试类:

  1. import org.slf4j.Logger;
  2. import org.slf4j.LoggerFactory;
  3. /**
  4. * @author Fcant
  5. */
  6. public class Log4jSLF4JDemo {
  7. private static final Logger logger = LoggerFactory.getLogger(Log4jSLF4JDemo.class);
  8. public static void main(String[] args) {
  9. logger.debug("{},Hello","Fcant");
  10. }
  11. }

使用占位符要比“+”操作符方便的多。并且此时不再需要 isDebugEnabled() 先进行判断,debug() 方法会在字符串拼接之前执行。
如果只是 Log4J 的话,会先进行字符串拼接,再执行 debug() 方法,来看示例代码:

  1. String name = "Fcant";
  2. int age = 18;
  3. logger.debug(name + ",年纪:" + age + ",Hi");

在调试这段代码的时候,可以发现如果日志系统的级别不是 DEBUG,就会多执行了字符串拼接的操作,白白浪费了性能。 :::tips 注意:阿里巴巴开发手册上还有一条「强制」级别的规约:
SLF4J作为门面日志 - 图8 ::: 这是因为如果参数是基本数据类型的话,会先进行自动装箱(Integer.valueOf())。测试代码如下所示:

  1. logger.debug("Fcant,{}岁", 18);

通过反编译工具就可以看得到:

  1. logger.debug("\u6C89\u9ED8\u738B\u4E8C\uFF0C{}\u5C81", Integer.valueOf(18));

如果参数需要调用其他方法的话,debug() 方法会随后调用。
也就是说,如果不 isDebugEnabled() 的话,在不是 DEBUG 级别的情况下,会多执行自动装箱和调用其他方法的操作——程序的性能就下降了!

4、总结

1)在使用日志系统的时候,一定要使用 SLF4J 作为门面担当。
2)SLF4J 可以统一日志系统,作为上层的抽象接口,不需要关注底层的日志实现,可以是 Log4j,也可以是 Logback,或者 JUL、JCL。
3)SLF4J 在打印日志的时候可以使用占位符,既提高了程序性能(临时字符串少了,垃圾回收的工作量就小),又让代码变得美观统一。