Logback这样配置，性能提升10倍！

往期热门文章：
1、牛客网：为什么不能将实数作为 HashMap 的 key？
2、RedisJson发布官方性能报告，性能碾压ES和Mongo
3、分布式数据一致性思考-B端系统一致性
4、Java字符串拼接的五种方法，哪种性能最好？
5、一次线上JVM调优实践，FullGC40次/天到10天一次的优化过程

今天我们讲一下logback的异步日志配置，希望通过本文你可以了解以下内容：

1. 日志输出到文件并根据LEVEL级别将日志分类保存到不同文件

2. 通过异步输出日志减少磁盘IO提高性能

3. 异步输出日志的原理

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 高级特性异步输出日志

"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线程中调用方法过程主要如下图：

最主要的两个方法就是 encodewriteencode

最近热文阅读：
1、牛客网：为什么不能将实数作为 HashMap 的 key？
2、RedisJson发布官方性能报告，性能碾压ES和Mongo
3、分布式数据一致性思考-B端系统一致性
4、Java字符串拼接的五种方法，哪种性能最好？
5、一次线上JVM调优实践，FullGC40次/天到10天一次的优化过程
6、Chrome 浏览器全球大翻车？让 20 多亿用户无网可上
7、while(1) 和 for(;;)有什么区别？
8、使用MySQL，请用好 JSON 这张牌！
9、延时任务实现方案
10、服务端如何防止重复支付
关注公众号，你想要的Java都在这里