通過閱讀本篇文章將了解到:
1.日志輸出到文件并根據(jù)LEVEL級別將日志分類保存到不同文件
2.通過異步輸出日志減少磁盤IO提高性能
3.異步輸出日志的原理
1、配置文件logback-spring.xml
Spring Boot工程自帶logback和slf4j的依賴,所以重點(diǎn)放在編寫配置文件上,需要引入什么依賴,日志依賴沖突統(tǒng)統(tǒng)都不需要我們管了。
logback框架會默認(rèn)加載classpath下命名為logback-spring或logback的配置文件。
將所有日志都存儲在一個文件中文件大小也隨著應(yīng)用的運(yùn)行越來越大并且不好排查問題,正確的做法應(yīng)該是將error日志和其他日志分開,并且不同級別的日志根據(jù)時間段進(jìn)行記錄存儲。
<?xml version="1.0" encoding="utf-8"?>
<configuration>
<property resource="logback.properties"/>
<Appender name="CONSOLE-LOG" class="ch.qos.logback.core.ConsoleAppender">
<layout class="ch.qos.logback.classic.PatternLayout">
<pattern>[%d{yyyy-MM-dd' 'HH:mm:ss.sss}] [%C] [%t] [%L] [%-5p] %m%n</pattern>
</layout>
</appender>
<!--獲取比info級別高(包括info級別)但除error級別的日志-->
<appender name="INFO-LOG" class="ch.qos.logback.core.rolling.RollingFileAppender">
<filter class="ch.qos.logback.classic.filter.LevelFilter">
<level>ERROR</level>
<onMatch>DENY</onMatch>
<onMismatch>ACCEPT</onMismatch>
</filter>
<encoder>
<pattern>[%d{yyyy-MM-dd' 'HH:mm:ss.sss}] [%C] [%t] [%L] [%-5p] %m%n</pattern>
</encoder>
<!--滾動策略-->
<rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
<!--路徑-->
<fileNamePattern>${LOG_INFO_HOME}//%d.log</fileNamePattern>
<maxHistory>30</maxHistory>
</rollingPolicy>
</appender>
<appender name="ERROR-LOG" class="ch.qos.logback.core.rolling.RollingFileAppender">
<filter class="ch.qos.logback.classic.filter.ThresholdFilter">
<level>ERROR</level>
</filter>
<encoder>
<pattern>[%d{yyyy-MM-dd' 'HH:mm:ss.sss}] [%C] [%t] [%L] [%-5p] %m%n</pattern>
</encoder>
<!--滾動策略-->
<rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
<!--路徑-->
<fileNamePattern>${LOG_ERROR_HOME}//%d.log</fileNamePattern>
<maxHistory>30</maxHistory>
</rollingPolicy>
</appender>
<root level="info">
<appender-ref ref="CONSOLE-LOG" />
<appender-ref ref="INFO-LOG" />
<appender-ref ref="ERROR-LOG" />
</root>
</configuration>
部分標(biāo)簽說明
- <root>標(biāo)簽,必填標(biāo)簽,用來指定最基礎(chǔ)的日志輸出級別
- <appender-ref>標(biāo)簽,添加append
- <append>標(biāo)簽,通過使用該標(biāo)簽指定日志的收集策略
- name屬性指定appender命名
- class屬性指定輸出策略,通常有兩種,控制臺輸出和文件輸出,文件輸出就是將日志進(jìn)行一個持久化。ConsoleAppender將日志輸出到控制臺
- <filter>標(biāo)簽,通過使用該標(biāo)簽指定過濾策略
- <level>標(biāo)簽指定過濾的類型
- <encoder>標(biāo)簽,使用該標(biāo)簽下的<pattern>標(biāo)簽指定日志輸出格式
- <rollingPolicy>標(biāo)簽指定收集策略,比如基于時間進(jìn)行收集
- <fileNamePattern>標(biāo)簽指定生成日志保存地址 通過這樣配置已經(jīng)實(shí)現(xiàn)了分類分天手機(jī)日志的目標(biāo)了

2、logback 高級特性異步輸出日志
之前的日志配置方式是基于同步的,每次日志輸出到文件都會進(jìn)行一次磁盤IO。采用異步寫日志的方式而不讓此次寫日志發(fā)生磁盤IO,阻塞線程從而造成不必要的性能損耗。關(guān)注微信公眾號互聯(lián)網(wǎng)架構(gòu)師在后臺回復(fù)2T可以獲取我整理的架構(gòu)師全套教程。
異步輸出日志的方式很簡單,添加一個基于異步寫日志的appender,并指向原先配置的appender即可。
<!-- 異步輸出 -->
<appender name="ASYNC-INFO" class="ch.qos.logback.classic.AsyncAppender">
<!-- 不丟失日志.默認(rèn)的,如果隊(duì)列的80%已滿,則會丟棄TRACT、DEBUG、INFO級別的日志 -->
<discardingThreshold>0</discardingThreshold>
<!-- 更改默認(rèn)的隊(duì)列的深度,該值會影響性能.默認(rèn)值為256 -->
<queueSize>256</queueSize>
<!-- 添加附加的appender,最多只能添加一個 -->
<appender-ref ref="INFO-LOG"/>
</appender>
<appender name="ASYNC-ERROR" class="ch.qos.logback.classic.AsyncAppender">
<!-- 不丟失日志.默認(rèn)的,如果隊(duì)列的80%已滿,則會丟棄TRACT、DEBUG、INFO級別的日志 -->
<discardingThreshold>0</discardingThreshold>
<!-- 更改默認(rèn)的隊(duì)列的深度,該值會影響性能.默認(rèn)值為256 -->
<queueSize>256</queueSize>
<!-- 添加附加的appender,最多只能添加一個 -->
<appender-ref ref="ERROR-LOG"/>
</appender>
3、異步輸出日志性能測試
既然能提高性能的話,必須進(jìn)行一次測試比對,同步和異步輸出日志性能到底能提升多少倍?
服務(wù)器硬件
- CPU 六核
- 內(nèi)存 8G
測試工具
Apache Jmeter
同步輸出日志
- 線程數(shù):100
- Ramp-Up Loop(可以理解為啟動線程所用時間) :0 可以理解為100個線程同時啟用
- 測試結(jié)果

重點(diǎn)關(guān)注指標(biāo)Throughput【TPS】吞吐量:系統(tǒng)在單位時間內(nèi)處理請求的數(shù)量,在同步輸出日志中TPS為44.2/sec。坑爹的日志無法按天切割問題,這個大家也得注意下。
異步輸出日志
- 線程數(shù) 100
- Ramp-Up Loop:0
- 測試結(jié)果

TPS為497.5/sec,性能提升了10多倍!!!
4、異步日志輸出原理
從logback框架下的Logger.info方法開始追蹤。一路的方法調(diào)用路徑2T架構(gòu)師學(xué)習(xí)資料干貨分享如下圖所示:

異步輸出日志中最關(guān)鍵的就是配置文件中ch.qos.logback.classic包下AsyncAppenderBase類中的append方法,查看該方法的源碼:
protected void append(E eventObject) {
if(!this.isQueueBelowDiscardingThreshold() || !this.isDiscardable(eventObject)) {
this.preprocess(eventObject);
this.put(eventObject);
}
}
通過隊(duì)列情況判斷是否需要丟棄日志,不丟棄的話將它放到阻塞隊(duì)列中,通過查看代碼,這個阻塞隊(duì)列為ArrayBlockingQueueu,默認(rèn)大小為256,可以通過配置文件進(jìn)行修改。
Logger.info(...)到append(...)就結(jié)束了,只做了將日志塞入到阻塞隊(duì)列的事,然后繼續(xù)執(zhí)行Logger.info(...)下面的語句了。在AsyncAppenderBase類中定義了一個Worker線程,run方法中的關(guān)鍵部分代碼如下:
E e = parent.blockingQueue.take();aai.appendLoopOnAppenders(e);
從阻塞隊(duì)列中取出一個日志,并調(diào)用AppenderAttachableImpl類中的appendLoopOnAppenders方法維護(hù)一個Append列表。Worker線程中調(diào)用方法過程主要如下圖:

最主要的兩個方法就是encode和write方法,前一個法方會根據(jù)配置文件中encode指定的方式轉(zhuǎn)化為字節(jié)碼,后一個方法將轉(zhuǎn)化成的字節(jié)碼寫入到文件中去。
所以寫文件是通過新起一個線程去完成的,主線程將日志扔到阻塞隊(duì)列中,然后又去做其他事情了。
作者:何甜甜在嗎
鏈接:juejin.im/post/5d4d61326fb9a06aff5e5ff5