您好,登錄后才能下訂單哦!
小編給大家分享一下logback日志異步打印的南里,相信大部分人都還不怎么了解,因此分享這篇文章給大家參考一下,希望大家閱讀完這篇文章后大有收獲,下面讓我們一起去了解一下吧!
最近碰到一個(gè)問(wèn)題:客戶的服務(wù)器程序偶爾出現(xiàn)請(qǐng)求響應(yīng)過(guò)慢的情況,通過(guò)查看日志發(fā)現(xiàn)RSA驗(yàn)證簽名的代碼執(zhí)行超過(guò)20秒,而正常情況下只需要16毫秒。
RSA證書(shū)是服務(wù)器啟動(dòng)就加載好的,不存在讀文件慢的問(wèn)題??戳艘幌履菐仔写a,最有嫌疑的就是logback日志打印的代碼了。
查看了生產(chǎn)日志配置,logback.xml里配置為,每月生成一個(gè)文件夾,當(dāng)月的日志文件都在同一文件夾下,比如201901文件夾下是2019年1月的所有日志文件。每個(gè)文件配置為*.log.zip后綴,大小為10MB,也就是說(shuō)10MB為大小分隔文件。日志打印類(lèi)配置的是RollingFileAppender。
2019-1-30那一天,2019年1月文件夾下已經(jīng)產(chǎn)生了5000多個(gè)日志文件,平均每天167個(gè)日志文件。
所以懷疑是日志文件太多,索引文件的時(shí)間太長(zhǎng)導(dǎo)致代碼執(zhí)行變慢,也就是說(shuō),要這樣懷疑的話,那就有一個(gè)前提:日志打印是同步的,調(diào)用打印日志的那行代碼執(zhí)行完了寫(xiě)入文件后,才會(huì)繼續(xù)向下執(zhí)行業(yè)務(wù)代碼。
我問(wèn)了好幾個(gè)同事,都給我說(shuō)日志打印是異步執(zhí)行的,只有一個(gè)朋友說(shuō)是同步的。我們都會(huì)以為打印日志寫(xiě)文件比較消耗時(shí)間,日志框架應(yīng)該不會(huì)同步去寫(xiě)文件吧。然而事實(shí)卻是如果不配置異步打印方式的話,就是以同步方式進(jìn)行的日志打印的。
斷點(diǎn)跟進(jìn)代碼發(fā)現(xiàn)是同步執(zhí)行日志寫(xiě)入文件的,只有當(dāng)配置的appender是AsyncAppender時(shí),日志打印才是按照異步方式打印的。
下面是同步打印日志的情況:
logback.xml配置:
<appender name="CONSOLE" class="ch.qos.logback.core.ConsoleAppender"> ...... </appender> <root level="DEBUG"> <appender-ref ref="CONSOLE"/> </root>
執(zhí)行代碼:
LogWork.debug("111111111111"); System.out.println("2222222222222");
運(yùn)行結(jié)果:
可以看到,執(zhí)行結(jié)果是先把日志框架的日志打印執(zhí)行完,再執(zhí)行后面的業(yè)務(wù)代碼的。所以是同步的。
下面是配置異步打印日志類(lèi)的方法:
異步打印日志類(lèi)AsyncAppender需要引用一個(gè)其他的日志打印類(lèi),ASYNC只需要把需要打印的日志寫(xiě)入定義好的緩存隊(duì)列里,然后開(kāi)啟一個(gè)守護(hù)線程去隊(duì)列里取得日志調(diào)用CONSOLE日志打印器去寫(xiě)入文件。這樣日志打印就是異步進(jìn)行的了。
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>
執(zhí)行代碼:
LogWork.debug("111111111111"); System.out.println("2222222222222");
運(yùn)行結(jié)果:
運(yùn)行結(jié)果說(shuō)明,調(diào)用日志框架打印的代碼執(zhí)行后只是把要打印的日志放入緩存隊(duì)列,然后就繼續(xù)向下執(zhí)行后面的代碼,所以才先打印了后面的222222,之后才打印111111。說(shuō)明是現(xiàn)在經(jīng)過(guò)配置,日志打印異步工作的。
因文章開(kāi)頭的問(wèn)題引出了對(duì)日志打印同步性的問(wèn)題,所以研究了一番,不過(guò)最終也沒(méi)有和遇到的問(wèn)題聯(lián)系起來(lái),因?yàn)榫退阒懒巳罩臼峭酱蛴〉?,也沒(méi)法解釋為什么偶爾出現(xiàn)幾分鐘代碼執(zhí)行特別慢。證據(jù)不充分,所以沒(méi)法給出結(jié)論。
以上是logback日志異步打印的南里的所有內(nèi)容,感謝各位的閱讀!相信大家都有了一定的了解,希望分享的內(nèi)容對(duì)大家有所幫助,如果還想學(xué)習(xí)更多知識(shí),歡迎關(guān)注億速云行業(yè)資訊頻道!
免責(zé)聲明:本站發(fā)布的內(nèi)容(圖片、視頻和文字)以原創(chuàng)、轉(zhuǎn)載和分享為主,文章觀點(diǎn)不代表本網(wǎng)站立場(chǎng),如果涉及侵權(quán)請(qǐng)聯(lián)系站長(zhǎng)郵箱:is@yisu.com進(jìn)行舉報(bào),并提供相關(guān)證據(jù),一經(jīng)查實(shí),將立刻刪除涉嫌侵權(quán)內(nèi)容。