日志框架選型稿静,Logback 還是 Log4j2娇掏?

來自:掘金(作者:何甜甜在嗎)
原文鏈接
https://juejin.im/post/6844903926777511943

前段時(shí)間寫了一篇文章Logback配置文件這么寫,TPS提高10倍空执,反響還不錯(cuò)浪箭,有很多網(wǎng)友也發(fā)表了自己的意見:

image
image
image

總結(jié)一下就是:

  • logback性能測試同步和異步TPS相差不大

  • 都9102年了還在用logback

看到網(wǎng)友的意見后又繼續(xù)去做了一波測試和調(diào)研

服務(wù)器硬件

  • CPU 六核

  • 內(nèi)存 8G

測試工具

  • JMeter

  • JProfile

  • APM(New Relic)

logback日志框架同步和異步測試

之前的測試結(jié)果存在以下幾點(diǎn)問題:

  • 測試樣本數(shù)過少(即線程數(shù)和循環(huán)執(zhí)行次數(shù)過少,之前線程數(shù)為100辨绊,循環(huán)1次奶栖,樣本總數(shù)為100)

  • 測試次數(shù)過少,只進(jìn)行了一次測試门坷,結(jié)果存在偶然性

  • 兩次測試結(jié)果存在污染宣鄙,樣本數(shù)量不一樣

針對以上問題,重新測試中將線程數(shù)修改為200默蚌,每次測試中循環(huán)100次冻晤,樣本總數(shù)為2w,和原來測試的樣本數(shù)相比擴(kuò)大200倍绸吸,并且重復(fù)測試5次鼻弧。新的測試結(jié)果如下:

image

新的測試結(jié)果表明,使用logback日志框架同步和異步輸出日志方式的TPS相差不大锦茁。

把數(shù)據(jù)制作成柱形圖更直觀

image

但是總覺得異步去寫日志了攘轩,訪問api的線程將更快響應(yīng)客戶端,TPS就應(yīng)該有明顯的變化才對码俩。

想不通又去網(wǎng)上查閱了一些資料度帮,有反應(yīng)說通過APM進(jìn)行性能監(jiān)控,同步和異步的TPS將會有較大的差別稿存,TPS一定是會有明顯變化的(吶喊)够傍,于是用APM去監(jiān)控JMeter發(fā)送的請求(JMeter參數(shù)設(shè)置為線程數(shù)100,Ramp-up Period為0挠铲,循環(huán)100次):APM測試結(jié)果如下:

image

APM監(jiān)控下冕屯,在執(zhí)行的五分鐘內(nèi)異步輸出日志TPS平均為378rpm

image

APM監(jiān)控下,在執(zhí)行的五分鐘內(nèi)同步輸出日志TPS平均為333rpm 發(fā)現(xiàn)TPS同步和異步相比還是不明顯拂苹,又一次證明失敗 雖然想不明白但后來和網(wǎng)友探討了下安聘,醍醐灌頂

image

TPS變化不明顯的原因如下:TPS為每秒處理事務(wù)數(shù)痰洒,每個(gè)事務(wù)包括了如下3個(gè)過程:

  • 用戶請求服務(wù)器

  • 服務(wù)器自己的內(nèi)部處理

  • 服務(wù)器返回給用戶

服務(wù)器自己的內(nèi)部請求包括訪問數(shù)據(jù)庫、處理邏輯和打印日志浴韭,同步和異步中唯一不同的就是打印日志的方式丘喻。而從測試結(jié)果來看,打印日志耗時(shí)只占API訪問請求的5.3%念颈,所以縮短打印日志耗時(shí)不能很明顯的提高TPS泉粉,因?yàn)榇蛴r(shí)間和網(wǎng)絡(luò)請求、業(yè)務(wù)處理消耗時(shí)間可以忽略不計(jì) 但是測試結(jié)果表明榴芳,雖然使用異步輸出方式不能明顯提高TPS嗡靡,但是能夠減少打印日志的耗時(shí)。所以使用logback日志框架還是推薦使用異步輸出方式

推薦使用log4j2而不是logback

log4j2是log4j 1.x 的升級版窟感,參考了logback的一些優(yōu)秀的設(shè)計(jì)讨彼,并且修復(fù)了一些問題,帶來了一些重大的提升柿祈,在異步方面的性能得到了巨大提升哈误,其除了提供Async Append異步實(shí)現(xiàn)外還提供了Async Log異步實(shí)現(xiàn),其中Async Append異步實(shí)現(xiàn)方式和logback的異步實(shí)現(xiàn)差不多躏嚎,而Async Log基于LMAX Disruptor庫蜜自,實(shí)現(xiàn)了一個(gè)高性能的異步記錄器。

本次測試中l(wèi)og4j2異步實(shí)現(xiàn)是基于Async Log卢佣。JMeter測試參數(shù)和之前的logback測試一樣重荠,線程數(shù)200,循環(huán)次數(shù)100珠漂,重復(fù)五輪晚缩。并且logj2日志配置文件基本和logback異步配置相同尾膊,滿足:

  • 控制臺打印日志

  • 分類輸出日志

  • 按天滾動

  • 同樣的日志輸出格式

測試結(jié)果如下:

image

將TPS制作為柱形圖

image

TPS提升了6倍O蔽!!冈敛!

并且打印日志的耗時(shí)都快到統(tǒng)計(jì)不出來了 官方提供的測試報(bào)告中待笑,log4j2和logback相比性能提升更明顯。

附官方測試報(bào)告:

https://logging.apache.org/log4j/log4j-2.3/manual/async.html

結(jié)論

  • 如果使用logback框架抓谴,推薦使用異步輸出日志方式

  • 選擇日志框架暮蹂,推薦使用log4j2

?著作權(quán)歸作者所有,轉(zhuǎn)載或內(nèi)容合作請聯(lián)系作者
  • 序言:七十年代末,一起剝皮案震驚了整個(gè)濱河市癌压,隨后出現(xiàn)的幾起案子仰泻,更是在濱河造成了極大的恐慌,老刑警劉巖滩届,帶你破解...
    沈念sama閱讀 217,277評論 6 503
  • 序言:濱河連續(xù)發(fā)生了三起死亡事件集侯,死亡現(xiàn)場離奇詭異,居然都是意外死亡,警方通過查閱死者的電腦和手機(jī)棠枉,發(fā)現(xiàn)死者居然都...
    沈念sama閱讀 92,689評論 3 393
  • 文/潘曉璐 我一進(jìn)店門浓体,熙熙樓的掌柜王于貴愁眉苦臉地迎上來,“玉大人辈讶,你說我怎么就攤上這事命浴。” “怎么了贱除?”我有些...
    開封第一講書人閱讀 163,624評論 0 353
  • 文/不壞的土叔 我叫張陵生闲,是天一觀的道長。 經(jīng)常有香客問我勘伺,道長跪腹,這世上最難降的妖魔是什么? 我笑而不...
    開封第一講書人閱讀 58,356評論 1 293
  • 正文 為了忘掉前任飞醉,我火速辦了婚禮冲茸,結(jié)果婚禮上,老公的妹妹穿的比我還像新娘缅帘。我一直安慰自己轴术,他們只是感情好,可當(dāng)我...
    茶點(diǎn)故事閱讀 67,402評論 6 392
  • 文/花漫 我一把揭開白布钦无。 她就那樣靜靜地躺著逗栽,像睡著了一般。 火紅的嫁衣襯著肌膚如雪失暂。 梳的紋絲不亂的頭發(fā)上彼宠,一...
    開封第一講書人閱讀 51,292評論 1 301
  • 那天,我揣著相機(jī)與錄音弟塞,去河邊找鬼凭峡。 笑死,一個(gè)胖子當(dāng)著我的面吹牛决记,可吹牛的內(nèi)容都是我干的摧冀。 我是一名探鬼主播,決...
    沈念sama閱讀 40,135評論 3 418
  • 文/蒼蘭香墨 我猛地睜開眼系宫,長吁一口氣:“原來是場噩夢啊……” “哼索昂!你這毒婦竟也來了?” 一聲冷哼從身側(cè)響起扩借,我...
    開封第一講書人閱讀 38,992評論 0 275
  • 序言:老撾萬榮一對情侶失蹤椒惨,失蹤者是張志新(化名)和其女友劉穎,沒想到半個(gè)月后潮罪,有當(dāng)?shù)厝嗽跇淞掷锇l(fā)現(xiàn)了一具尸體康谆,經(jīng)...
    沈念sama閱讀 45,429評論 1 314
  • 正文 獨(dú)居荒郊野嶺守林人離奇死亡凄杯,尸身上長有42處帶血的膿包…… 初始之章·張勛 以下內(nèi)容為張勛視角 年9月15日...
    茶點(diǎn)故事閱讀 37,636評論 3 334
  • 正文 我和宋清朗相戀三年,在試婚紗的時(shí)候發(fā)現(xiàn)自己被綠了秉宿。 大學(xué)時(shí)的朋友給我發(fā)了我未婚夫和他白月光在一起吃飯的照片戒突。...
    茶點(diǎn)故事閱讀 39,785評論 1 348
  • 序言:一個(gè)原本活蹦亂跳的男人離奇死亡,死狀恐怖描睦,靈堂內(nèi)的尸體忽然破棺而出膊存,到底是詐尸還是另有隱情,我是刑警寧澤忱叭,帶...
    沈念sama閱讀 35,492評論 5 345
  • 正文 年R本政府宣布隔崎,位于F島的核電站,受9級特大地震影響韵丑,放射性物質(zhì)發(fā)生泄漏爵卒。R本人自食惡果不足惜,卻給世界環(huán)境...
    茶點(diǎn)故事閱讀 41,092評論 3 328
  • 文/蒙蒙 一撵彻、第九天 我趴在偏房一處隱蔽的房頂上張望钓株。 院中可真熱鬧,春花似錦陌僵、人聲如沸轴合。這莊子的主人今日做“春日...
    開封第一講書人閱讀 31,723評論 0 22
  • 文/蒼蘭香墨 我抬頭看了看天上的太陽受葛。三九已至,卻和暖如春偎谁,著一層夾襖步出監(jiān)牢的瞬間总滩,已是汗流浹背。 一陣腳步聲響...
    開封第一講書人閱讀 32,858評論 1 269
  • 我被黑心中介騙來泰國打工巡雨, 沒想到剛下飛機(jī)就差點(diǎn)兒被人妖公主榨干…… 1. 我叫王不留闰渔,地道東北人。 一個(gè)月前我還...
    沈念sama閱讀 47,891評論 2 370
  • 正文 我出身青樓鸯隅,卻偏偏與公主長得像澜建,于是被迫代替她去往敵國和親向挖。 傳聞我的和親對象是個(gè)殘疾皇子蝌以,可洞房花燭夜當(dāng)晚...
    茶點(diǎn)故事閱讀 44,713評論 2 354