Logback这样配置,性能提升10倍!
大家好,我是二哥呀。
今天我们讲一下logback的异步日志配置,希望通过本文你可以了解以下内容:
日志输出到文件并根据LEVEL级别将日志分类保存到不同文件 通过异步输出日志减少磁盘IO提高性能 异步输出日志的原理
01、配置文件logback-spring.xml
SpringBoot工程自带 logback和 slf4j的依赖,所以重点放在编写配置文件上,需要引入什么依赖,日志依赖冲突统统都不需要我们管了。logback框架会默认加载 classpath下命名为 logback-spring或 logback的配置文件。
将所有日志都存储在一个文件中文件大小也随着应用的运行越来越大并且不好排查问题,正确的做法应该是将 error日志和其他日志分开,并且不同级别的日志根据时间段进行记录存储。
"1.0" encoding="utf-8"?>
"logback.properties"/>
"CONSOLE-LOG" class="ch.qos.logback.core.ConsoleAppender">
"ch.qos.logback.classic.PatternLayout">
[%d{yyyy-MM-dd' 'HH:mm:ss.sss}] [%C] [%t] [%L] [%-5p] %m%n
"INFO-LOG" class="ch.qos.logback.core.rolling.RollingFileAppender">
"ch.qos.logback.classic.filter.LevelFilter">
ERROR
DENY
ACCEPT
[%d{yyyy-MM-dd' 'HH:mm:ss.sss}] [%C] [%t] [%L] [%-5p] %m%n
"ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
${LOG_INFO_HOME}//%d.log
30
"ERROR-LOG" class="ch.qos.logback.core.rolling.RollingFileAppender">
"ch.qos.logback.classic.filter.ThresholdFilter">
ERROR
[%d{yyyy-MM-dd' 'HH:mm:ss.sss}] [%C] [%t] [%L] [%-5p] %m%n
"ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
${LOG_ERROR_HOME}//%d.log
30
"info">
"CONSOLE-LOG" />
"INFO-LOG" />
"ERROR-LOG" />
部分标签说明:
标签,必填标签,用来指定最基础的日志输出级别 标签,添加append 标签,通过使用该标签指定日志的收集策略,name属性指定appender命名,class属性指定输出策略,通常有两种,控制台输出和文件输出,文件输出就是将日志进行一个持久化。ConsoleAppender将日志输出到控制台 标签,通过使用该标签指定过滤策略 标签指定过滤的类型 标签,使用该标签下的 标签指定日志输出格式。 标签指定收集策略,比如基于时间进行收集 标签指定生成日志保存地址 通过这样配置已经实现了分类分天手机日志的目标了
02、logback 高级特性异步输出日志
之前的日志配置方式是基于同步的,每次日志输出到文件都会进行一次磁盘IO。采用异步写日志的方式而不让此次写日志发生磁盘IO,阻塞线程从而造成不必要的性能损耗。异步输出日志的方式很简单,添加一个基于异步写日志的 appender,并指向原先配置的 appender即可
"ASYNC-INFO" class="ch.qos.logback.classic.AsyncAppender">
0
256
"INFO-LOG"/>
"ASYNC-ERROR" class="ch.qos.logback.classic.AsyncAppender">
0
256
"ERROR-LOG"/>
03、异步输出日志性能测试
既然能提高性能的话,必须进行一次测试比对,同步和异步输出日志性能到底能提升多少倍?
服务器硬件
CPU 六核 内存 8G
测试工具
Apache Jmeter
同步输出日志
线程数:100 Ramp-Up Loop(可以理解为启动线程所用时间) :0 可以理解为100个线程同时启用 测试结果
重点关注指标 Throughput【TPS】 吞吐量:系统在单位时间内处理请求的数量,在同步输出日志中 TPS 为 44.2/sec
异步输出日志
线程数 100 Ramp-Up Loop:0 测试结果
TPS 为 497.5/sec , 性能提升了10多倍!!!
异步日志输出原理
从 logback框架下的 Logger.info方法开始追踪。一路的方法调用路径如下图所示:
异步输出日志中最关键的就是配置文件中 ch.qos.logback.classic.AsyncAppenderBase.append
protected void append(E eventObject) {
if(!this.isQueueBelowDiscardingThreshold() || !this.isDiscardable(eventObject)) {
this.preprocess(eventObject);
this.put(eventObject);
}
}
通过队列情况判断是否需要丢弃日志,不丢弃的话将它放到阻塞队列中,通过查看代码,这个阻塞队列为 ArrayBlockingQueueu,默认大小为256,可以通过配置文件进行修改。Logger.info(...)到 append(...)就结束了,只做了将日志塞入到阻塞队列的事,然后继续执行 Logger.info(...)下面的语句了。在 AsyncAppenderBase类中定义了一个 Worker线程,run方法中的关键部分代码如下:
E e = parent.blockingQueue.take();
aai.appendLoopOnAppenders(e);
从阻塞队列中取出一个日志,并调用 AppenderAttachableImpl类中的 appendLoopOnAppenders方法维护一个 Append列表。Worker线程中调用方法过程主要如下图:
最主要的两个方法就是 encodewrite
encode
原文链接:https://juejin.cn/post/6844903909920604174
本篇已收录至 GitHub 上星标 1.2k+ star 的开源专栏《Java 程序员进阶之路》,该专栏风趣幽默、通俗易懂,对 Java 爱好者极度友好和舒适😄,内容包括但不限于 Java 基础、Java 集合框架、Java IO、Java 并发编程、Java 虚拟机、Java 企业级开发(Git、SSM、Spring Boot)等核心知识点。
star 了这个仓库就等于成为了一名更优秀的 Java 程序员。
https://github.com/itwanger/toBeBetterJavaer
点击「阅读原文」可以跳转到《Java 程序员进阶之路》在线阅读网站。
没有什么使我停留——除了目的,纵然岸旁有玫瑰、有绿荫、有宁静的港湾,我是不系之舟。
推荐阅读: