Logback這樣配置，性能提升10倍！

今天我們講一下logback的異步日志配置，希望通過(guò)本文你可以了解以下內(nèi)容：

1. 日志輸出到文件并根據(jù)LEVEL級(jí)別將日志分類保存到不同文件

2. 通過(guò)異步輸出日志減少磁盤IO提高性能

3. 異步輸出日志的原理

01、配置文件logback-spring.xml

SpringBoot工程自帶 logback和 slf4j的依賴，所以重點(diǎn)放在編寫配置文件上，需要引入什么依賴，日志依賴沖突統(tǒng)統(tǒng)都不需要我們管了。logback框架會(huì)默認(rèn)加載 classpath下命名為 logback-spring或 logback的配置文件。

將所有日志都存儲(chǔ)在一個(gè)文件中文件大小也隨著應(yīng)用的運(yùn)行越來(lái)越大并且不好排查問(wèn)題，正確的做法應(yīng)該是將 error日志和其他日志分開(kāi)，并且不同級(jí)別的日志根據(jù)時(shí)間段進(jìn)行記錄存儲(chǔ)。

"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"?/>
????

部分標(biāo)簽說(shuō)明:

• 標(biāo)簽，必填標(biāo)簽，用來(lái)指定最基礎(chǔ)的日志輸出級(jí)別

• 標(biāo)簽，添加append

• 標(biāo)簽，通過(guò)使用該標(biāo)簽指定日志的收集策略，name屬性指定appender命名，class屬性指定輸出策略，通常有兩種，控制臺(tái)輸出和文件輸出，文件輸出就是將日志進(jìn)行一個(gè)持久化。ConsoleAppender將日志輸出到控制臺(tái)

• 標(biāo)簽，通過(guò)使用該標(biāo)簽指定過(guò)濾策略

• 標(biāo)簽指定過(guò)濾的類型

• 標(biāo)簽，使用該標(biāo)簽下的標(biāo)簽指定日志輸出格式。
• 標(biāo)簽指定收集策略，比如基于時(shí)間進(jìn)行收集
• 標(biāo)簽指定生成日志保存地址 通過(guò)這樣配置已經(jīng)實(shí)現(xiàn)了分類分天手機(jī)日志的目標(biāo)了

02、logback 高級(jí)特性異步輸出日志

之前的日志配置方式是基于同步的，每次日志輸出到文件都會(huì)進(jìn)行一次磁盤IO。采用異步寫日志的方式而不讓此次寫日志發(fā)生磁盤IO，阻塞線程從而造成不必要的性能損耗。異步輸出日志的方式很簡(jiǎn)單，添加一個(gè)基于異步寫日志的 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、異步輸出日志性能測(cè)試

既然能提高性能的話，必須進(jìn)行一次測(cè)試比對(duì)，同步和異步輸出日志性能到底能提升多少倍？

服務(wù)器硬件

• CPU 六核

• 內(nèi)存 8G

測(cè)試工具

• Apache Jmeter

同步輸出日志

• 線程數(shù)：100

• Ramp-Up Loop(可以理解為啟動(dòng)線程所用時(shí)間) ：0 可以理解為100個(gè)線程同時(shí)啟用

• 測(cè)試結(jié)果

重點(diǎn)關(guān)注指標(biāo) Throughput【TPS】 吞吐量：系統(tǒng)在單位時(shí)間內(nèi)處理請(qǐng)求的數(shù)量，在同步輸出日志中 TPS 為 44.2/sec

異步輸出日志

• 線程數(shù) 100

• Ramp-Up Loop：0

• 測(cè)試結(jié)果

TPS 為 497.5/sec ， 性能提升了10多倍！??！

異步日志輸出原理

從 logback框架下的 Logger.info方法開(kāi)始追蹤。一路的方法調(diào)用路徑如下圖所示：

異步輸出日志中最關(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);
????????}
????}

通過(guò)隊(duì)列情況判斷是否需要丟棄日志，不丟棄的話將它放到阻塞隊(duì)列中，通過(guò)查看代碼，這個(gè)阻塞隊(duì)列為 ArrayBlockingQueueu，默認(rèn)大小為256，可以通過(guò)配置文件進(jìn)行修改。Logger.info(...)到 append(...)就結(jié)束了，只做了將日志塞入到阻塞隊(duì)列的事，然后繼續(xù)執(zhí)行 Logger.info(...)下面的語(yǔ)句了。在 AsyncAppenderBase類中定義了一個(gè) Worker線程，run方法中的關(guān)鍵部分代碼如下:

E?e?=?parent.blockingQueue.take();
aai.appendLoopOnAppenders(e);

從阻塞隊(duì)列中取出一個(gè)日志，并調(diào)用 AppenderAttachableImpl類中的 appendLoopOnAppenders方法維護(hù)一個(gè) Append列表。Worker線程中調(diào)用方法過(guò)程主要如下圖：

最主要的兩個(gè)方法就是 encodewriteencode

原文鏈接：

https://juejin.cn/post/6844903909920604174

——————END——————

歡迎關(guān)注“Java引導(dǎo)者”，我們分享最有價(jià)值的Java的干貨文章，助力您成為有思想的Java開(kāi)發(fā)工程師！