求你了,別亂打印日志了!
圖片來自 包圖網(wǎng)
選擇恰當?shù)娜罩炯墑e
常見的日志級別有 5 種,分別是 error、warn、info、debug、trace:
- error:錯誤日志,指比較嚴重的錯誤,對正常業(yè)務(wù)有影響,需要運維配置監(jiān)控的。
- warn:警告日志,一般的錯誤,對業(yè)務(wù)影響不大,但是需要開發(fā)關(guān)注。
- info:信息日志,記錄排查問題的關(guān)鍵信息,如調(diào)用時間、出參入?yún)⒌鹊取?/li>
- debug:用于開發(fā) DEBUG 的,關(guān)鍵邏輯里面的運行時數(shù)據(jù)。
- trace:最詳細的信息,一般這些信息只記錄到日志文件中。
日常開發(fā)中,我們需要選擇恰當?shù)娜罩炯墑e,不要反手就是打印 info 哈。
日志要打印出方法的入?yún)ⅰ⒊鰠?/h3>
我們并不需要打印很多很多日志,只需要打印可以快速定位問題的有效日志。有效的日志,是甩鍋的利器!
哪些算得上有效關(guān)鍵的日志呢?比如說,方法進來的時候,打印入?yún)ⅰT偃缓竽兀诜椒ǚ祷氐臅r候,就是打印出參,返回值。入?yún)⒌脑挘话憔褪?userId 或者 bizSeq 這些關(guān)鍵信息。
正例如下:
- public String testLogMethod(Document doc, Mode mode){
- log.debug(“method enter param:{}”,userId);
- String id = "666";
- log.debug(“method exit param:{}”,id);
- return id;
- }
選擇合適的日志格式
理想的日志格式,應(yīng)當包括這些最基本的信息:如當前時間戳(一般毫秒精確度)、日志級別,線程名字等等。
在 logback 日志里可以這么配置:
- <appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender">
- <encoder>
- <pattern>%d{HH:mm:ss.SSS} %-5level [%thread][%logger{0}] %m%n</pattern>
- </encoder>
- </appender>
如果我們的日志格式,連當前時間都沒有記錄,那連請求的時間點都不知道了?
遇到 if...else... 等條件時,每個分支首行都盡量打印日志
當你碰到 if...else... 或者 switch 這樣的條件時,可以在分支的首行就打印日志,這樣排查問題時,就可以通過日志,確定進入了哪個分支,代碼邏輯更清晰,也更方便排查問題了。
正例:
- if(user.isVip()){
- log.info("該用戶是會員,Id:{},開始處理會員邏輯",user,getUserId());
- //會員邏輯
- }else{
- log.info("該用戶是非會員,Id:{},開始處理非會員邏輯",user,getUserId())
- //非會員邏輯
- }
日志級別比較低時,進行日志開關(guān)判斷
對于 trace/debug 這些比較低的日志級別,必須進行日志級別的開關(guān)判斷。
正例:
- User user = new User(666L, "公眾號", "撿田螺的小男孩");
- if (log.isDebugEnabled()) {
- log.debug("userId is: {}", user.getId());
- }
因為當前有如下的日志代碼:
- logger.debug("Processing trade with id: " + id + " and symbol: " + symbol);
如果配置的日志級別是 warn 的話,上述日志不會打印,但是會執(zhí)行字符串拼接操作,如果 symbol 是對象,還會執(zhí)行 toString() 方法,浪費了系統(tǒng)資源,執(zhí)行了上述操作,最終日志卻沒有打印,因此建議加日志開關(guān)判斷。
不能直接使用日志系統(tǒng)中的 API
不能直接使用日志系統(tǒng)(Log4j、Logback)中的 API,而是使用日志框架 SLF4J 中的 API。
SLF4J 是門面模式的日志框架,有利于維護和各個類的日志處理方式統(tǒng)一,并且可以在保證不修改代碼的情況下,很方便的實現(xiàn)底層日志框架的更換。
正例:
- import org.slf4j.Logger;
- import org.slf4j.LoggerFactory;
- private static final Logger logger = LoggerFactory.getLogger(TianLuoBoy.class);
建議使用參數(shù)占位 {},而不是用 + 拼接。
反例:
- logger.info("Processing trade with id: " + id + " and symbol: " + symbol);
上面的例子中,使用+操作符進行字符串的拼接,有一定的性能損耗。
正例如下:
- logger.info("Processing trade with id: {} and symbol : {} ", id, symbol);
我們使用了大括號{}來作為日志中的占位符,比于使用+操作符,更加優(yōu)雅簡潔。并且,相對于反例,使用占位符僅是替換動作,可以有效提升性能。
建議使用異步的方式來輸出日志
日志最終會輸出到文件或者其它輸出流中的,IO 性能會有要求的。如果異步,就可以顯著提升 IO 性能。
除非有特殊要求,要不然建議使用異步的方式來輸出日志。以 logback 為例吧,要配置異步很簡單,使用 AsyncAppender 就行。
- <appender name="FILE_ASYNC" class="ch.qos.logback.classic.AsyncAppender">
- <appender-ref ref="ASYNC"/>
- </appender>
不要使用 e.printStackTrace()
反例:
- try{
- // 業(yè)務(wù)代碼處理
- }catch(Exception e){
- e.printStackTrace();
- }
正例:
- try{
- // 業(yè)務(wù)代碼處理
- }catch(Exception e){
- log.error("你的程序有異常啦",e);
- }
理由:
- e.printStackTrace() 打印出的堆棧日志跟業(yè)務(wù)代碼日志是交錯混合在一起的,通常排查異常日志不太方便。
- e.printStackTrace() 語句產(chǎn)生的字符串記錄的是堆棧信息,如果信息太長太多,字符串常量池所在的內(nèi)存塊沒有空間了,即內(nèi)存滿了,那么,用戶的請求就卡住啦!
異常日志不要只打一半,要輸出全部錯誤信息
反例 1:
- try {
- //業(yè)務(wù)代碼處理
- } catch (Exception e) {
- // 錯誤
- LOG.error('你的程序有異常啦');
- }
異常 e 都沒有打印出來,所以壓根不知道出了什么類型的異常。
反例 2:
- try {
- //業(yè)務(wù)代碼處理
- } catch (Exception e) {
- // 錯誤
- LOG.error('你的程序有異常啦', e.getMessage());
- }
e.getMessage() 不會記錄詳細的堆棧異常信息,只會記錄錯誤基本描述信息,不利于排查問題。
正例:
- try {
- //業(yè)務(wù)代碼處理
- } catch (Exception e) {
- // 錯誤
- LOG.error('你的程序有異常啦', e);
- }
禁止在線上環(huán)境開啟 debug
禁止在線上環(huán)境開啟 debug,這一點非常重要。
因為一般系統(tǒng)的 debug 日志會很多,并且各種框架中也大量使用 debug 的日志,線上開啟 debug 不久可能會打滿磁盤,影響業(yè)務(wù)系統(tǒng)的正常運行。
不要記錄了異常,又拋出異常
反例如下:
- log.error("IO exception", e);
- throw new MyException(e);
這樣實現(xiàn)的話,通常會把棧信息打印兩次。這是因為捕獲了 MyException 異常的地方,還會再打印一次。
這樣的日志記錄,或者包裝后再拋出去,不要同時使用!否則你的日志看起來會讓人很迷惑。
避免重復(fù)打印日志
避免重復(fù)打印日志,醬紫會浪費磁盤空間。如果你已經(jīng)有一行日志清楚表達了意思,避免再冗余打印。
反例如下:
- if(user.isVip()){
- log.info("該用戶是會員,Id:{}",user,getUserId());
- //冗余,可以跟前面的日志合并一起
- log.info("開始處理會員邏輯,id:{}",user,getUserId());
- //會員邏輯
- }else{
- //非會員邏輯
- }
如果你是使用 log4j 日志框架,務(wù)必在 log4j.xml 中設(shè)置 additivity=false,因為可以避免重復(fù)打印日志。
正例:
- <logger name="com.taobao.dubbo.config" additivity="false">
日志文件分離
我們可以把不同類型的日志分離出去,比如 access.log,或者 error 級別 error.log,都可以單獨打印到一個文件里面。
當然,也可以根據(jù)不同的業(yè)務(wù)模塊,打印到不同的日志文件里,這樣我們排查問題和做數(shù)據(jù)統(tǒng)計的時候,都會比較方便啦。
核心功能模塊,建議打印較完整的日志
我們?nèi)粘i_發(fā)中,如果核心或者邏輯復(fù)雜的代碼,建議添加詳細的注釋,以及較詳細的日志。
日志要多詳細呢?腦洞一下,如果你的核心程序哪一步出錯了,通過日志可以定位到,那就可以啦。
作者:撿田螺的小男孩
編輯:陶家龍
出處:轉(zhuǎn)載自公眾號撿田螺的小男孩