logback日志異步打印的南里

小編給大家分享一下logback日志異步打印的南里，相信大部分人都還不怎么了解，因此分享這篇文章給大家參考一下，希望大家閱讀完這篇文章后大有收獲，下面讓我們一起去了解一下吧！

最近碰到一個問題：客戶的服務器程序偶爾出現請求響應過慢的情況，通過查看日志發現RSA驗證簽名的代碼執行超過20秒，而正常情況下只需要16毫秒。

RSA證書是服務器啟動就加載好的，不存在讀文件慢的問題。看了一下那幾行代碼，最有嫌疑的就是logback日志打印的代碼了。

查看了生產日志配置，logback.xml里配置為，每月生成一個文件夾，當月的日志文件都在同一文件夾下，比如201901文件夾下是2019年1月的所有日志文件。每個文件配置為*.log.zip后綴，大小為10MB，也就是說10MB為大小分隔文件。日志打印類配置的是RollingFileAppender。

2019-1-30那一天，2019年1月文件夾下已經產生了5000多個日志文件，平均每天167個日志文件。

所以懷疑是日志文件太多，索引文件的時間太長導致代碼執行變慢，也就是說，要這樣懷疑的話，那就有一個前提：日志打印是同步的，調用打印日志的那行代碼執行完了寫入文件后，才會繼續向下執行業務代碼。

我問了好幾個同事，都給我說日志打印是異步執行的，只有一個朋友說是同步的。我們都會以為打印日志寫文件比較消耗時間，日志框架應該不會同步去寫文件吧。然而事實卻是如果不配置異步打印方式的話，就是以同步方式進行的日志打印的。

斷點跟進代碼發現是同步執行日志寫入文件的，只有當配置的appender是AsyncAppender時，日志打印才是按照異步方式打印的。

下面是同步打印日志的情況：

logback.xml配置：

<appender name="CONSOLE" class="ch.qos.logback.core.ConsoleAppender">
    ......
</appender>

<root level="DEBUG">
    <appender-ref ref="CONSOLE"/>
</root>

執行代碼：

LogWork.debug("111111111111");
System.out.println("2222222222222");

運行結果：

可以看到，執行結果是先把日志框架的日志打印執行完，再執行后面的業務代碼的。所以是同步的。

下面是配置異步打印日志類的方法：

異步打印日志類AsyncAppender需要引用一個其他的日志打印類，ASYNC只需要把需要打印的日志寫入定義好的緩存隊列里，然后開啟一個守護線程去隊列里取得日志調用CONSOLE日志打印器去寫入文件。這樣日志打印就是異步進行的了。

logback.xml配置：

<appender name="CONSOLE" class="ch.qos.logback.core.ConsoleAppender">
    ......
</appender>

<appender name="ASYNC" class="ch.qos.logback.classic.AsyncAppender">
    <discardingThreshold>0</discardingThreshold>
    <queueSize>100</queueSize>
    <neverBlock>true</neverBlock>
    <appender-ref ref="CONSOLE"/>
</appender>

<root level="INFO">
    <appender-ref ref="ASYNC"/>
</root>

執行代碼：

LogWork.debug("111111111111");
System.out.println("2222222222222");

運行結果：

運行結果說明，調用日志框架打印的代碼執行后只是把要打印的日志放入緩存隊列，然后就繼續向下執行后面的代碼，所以才先打印了后面的222222，之后才打印111111。說明是現在經過配置，日志打印異步工作的。

因文章開頭的問題引出了對日志打印同步性的問題，所以研究了一番，不過最終也沒有和遇到的問題聯系起來，因為就算知道了日志是同步打印的，也沒法解釋為什么偶爾出現幾分鐘代碼執行特別慢。證據不充分，所以沒法給出結論。

以上是logback日志異步打印的南里的所有內容，感謝各位的閱讀！相信大家都有了一定的了解，希望分享的內容對大家有所幫助，如果還想學習更多知識，歡迎關注億速云行業資訊頻道！