終于能把線(xiàn)上日志打印好了，分享15個(gè)小建議

前言

大家好，我是程序員田螺。日志是快速定位問(wèn)題的好幫手，是撕逼和甩鍋的利器！打印好日志非常重要。今天我們來(lái)聊聊日志打印的15個(gè)好建議~

1. 選擇恰當(dāng)?shù)娜罩炯?jí)別

常見(jiàn)的日志級(jí)別有5種，分別是error、warn、info、debug、trace。日常開(kāi)發(fā)中，我們需要選擇恰當(dāng)?shù)娜罩炯?jí)別，不要反手就是打印info哈~

• error：錯(cuò)誤日志，指比較嚴(yán)重的錯(cuò)誤，對(duì)正常業(yè)務(wù)有影響，需要運(yùn)維配置監(jiān)控的；
• warn：警告日志，一般的錯(cuò)誤，對(duì)業(yè)務(wù)影響不大，但是需要開(kāi)發(fā)關(guān)注；
• info：信息日志，記錄排查問(wèn)題的關(guān)鍵信息，如調(diào)用時(shí)間、出參入?yún)⒌鹊龋?/section>
• debug：用于開(kāi)發(fā)DEBUG的，關(guān)鍵邏輯里面的運(yùn)行時(shí)數(shù)據(jù)；
• trace：最詳細(xì)的信息，一般這些信息只記錄到日志文件中。

2. 日志要打印出方法的入?yún)?、出?/span>

我們并不需要打印很多很多日志，只需要打印可以快速定位問(wèn)題的有效日志。有效的日志，是甩鍋的利器！

哪些算得的上有效關(guān)鍵的日志呢？比如說(shuō)，方法進(jìn)來(lái)的時(shí)候，打印入?yún)?/strong>。再然后呢，在方法返回的時(shí)候，就是打印出參，返回值。入?yún)⒌脑?huà)，一般就是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;
}

3. 選擇合適的日志格式

理想的日志格式，應(yīng)當(dāng)包括這些最基本的信息：如當(dāng)前時(shí)間戳（一般毫秒精確度）、日志級(jí)別，線(xiàn)程名字等等。在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> 

如果我們的日志格式，連當(dāng)前時(shí)間都沒(méi)有記錄，那連請(qǐng)求的時(shí)間點(diǎn)都不知道了？

4. 遇到if...else...等條件時(shí)，每個(gè)分支首行都盡量打印日志

當(dāng)你碰到if...else...或者switch這樣的條件時(shí)，可以在分支的首行就打印日志，這樣排查問(wèn)題時(shí)，就可以通過(guò)日志，確定進(jìn)入了哪個(gè)分支，代碼邏輯更清晰，也更方便排查問(wèn)題了。

正例：

if(user.isVip()){
  log.info("該用戶(hù)是會(huì)員,Id:{},開(kāi)始處理會(huì)員邏輯",user,getUserId());
  //會(huì)員邏輯
}else{
  log.info("該用戶(hù)是非會(huì)員,Id:{},開(kāi)始處理非會(huì)員邏輯",user,getUserId())
  //非會(huì)員邏輯
}

5.日志級(jí)別比較低時(shí)，進(jìn)行日志開(kāi)關(guān)判斷

對(duì)于trace/debug這些比較低的日志級(jí)別，必須進(jìn)行日志級(jí)別的開(kāi)關(guān)判斷。

正例：

User user = new User(666L, "公眾號(hào)", "撿田螺的小男孩");
if (log.isDebugEnabled()) {
    log.debug("userId is: {}", user.getId());
}

因?yàn)楫?dāng)前有如下的日志代碼：

logger.debug("Processing trade with id: " + id + " and symbol: " + symbol);

如果配置的日志級(jí)別是warn的話(huà)，上述日志不會(huì)打印，但是會(huì)執(zhí)行字符串拼接操作，如果symbol是對(duì)象， 還會(huì)執(zhí)行toString()方法，浪費(fèi)了系統(tǒng)資源，執(zhí)行了上述操作，最終日志卻沒(méi)有打印，因此建議加日志開(kāi)關(guān)判斷。

6. 不能直接使用日志系統(tǒng)（Log4j、Logback）中的 API，而是使用日志框架SLF4J中的API。

SLF4J 是門(mén)面模式的日志框架，有利于維護(hù)和各個(gè)類(lèi)的日志處理方式統(tǒng)一，并且可以在保證不修改代碼的情況下，很方便的實(shí)現(xiàn)底層日志框架的更換。

正例：

import org.slf4j.Logger; 
import org.slf4j.LoggerFactory;

private static final Logger logger = LoggerFactory.getLogger(TianLuoBoy.class);

7. 建議使用參數(shù)占位{}，而不是用+拼接。

反例：

logger.info("Processing trade with id: " + id + " and symbol: " + symbol);

上面的例子中，使用+操作符進(jìn)行字符串的拼接，有一定的性能損耗。

正例如下：

logger.info("Processing trade with id: {} and symbol : {} ", id, symbol); 

我們使用了大括號(hào){}來(lái)作為日志中的占位符，比于使用+操作符，更加優(yōu)雅簡(jiǎn)潔。并且，相對(duì)于反例，使用占位符僅是替換動(dòng)作，可以有效提升性能。

8. 建議使用異步的方式來(lái)輸出日志。

• 日志最終會(huì)輸出到文件或者其它輸出流中的，IO性能會(huì)有要求的。如果異步，就可以顯著提升IO性能。
• 除非有特殊要求，要不然建議使用異步的方式來(lái)輸出日志。以logback為例吧，要配置異步很簡(jiǎn)單，使用AsyncAppender就行

<appender name="FILE_ASYNC" class="ch.qos.logback.classic.AsyncAppender">
    <appender-ref ref="ASYNC"/>
</appender>

9. 不要使用e.printStackTrace()

反例：

try{
  // 業(yè)務(wù)代碼處理
}catch(Exception e){
  e.printStackTrace();
}

正例：

try{
  // 業(yè)務(wù)代碼處理
}catch(Exception e){
  log.error("你的程序有異常啦",e);
}

理由：

• e.printStackTrace()打印出的堆棧日志跟業(yè)務(wù)代碼日志是交錯(cuò)混合在一起的，通常排查異常日志不太方便。
• e.printStackTrace()語(yǔ)句產(chǎn)生的字符串記錄的是堆棧信息，如果信息太長(zhǎng)太多，字符串常量池所在的內(nèi)存塊沒(méi)有空間了,即內(nèi)存滿(mǎn)了，那么，用戶(hù)的請(qǐng)求就卡住啦~

10. 異常日志不要只打一半，要輸出全部錯(cuò)誤信息

反例1：

try {
    //業(yè)務(wù)代碼處理
} catch (Exception e) {
    // 錯(cuò)誤
    LOG.error('你的程序有異常啦');
} 

• 異常e都沒(méi)有打印出來(lái)，所以壓根不知道出了什么類(lèi)型的異常。

反例2：

try {
    //業(yè)務(wù)代碼處理
} catch (Exception e) {
    // 錯(cuò)誤
    LOG.error('你的程序有異常啦', e.getMessage());
} 

• e.getMessage()不會(huì)記錄詳細(xì)的堆棧異常信息，只會(huì)記錄錯(cuò)誤基本描述信息，不利于排查問(wèn)題。

正例：

try {
    //業(yè)務(wù)代碼處理
} catch (Exception e) {
    // 錯(cuò)誤
    LOG.error('你的程序有異常啦', e);
} 

11. 禁止在線(xiàn)上環(huán)境開(kāi)啟 debug

禁止在線(xiàn)上環(huán)境開(kāi)啟debug，這一點(diǎn)非常重要。

因?yàn)橐话阆到y(tǒng)的debug日志會(huì)很多，并且各種框架中也大量使用 debug的日志，線(xiàn)上開(kāi)啟debug不久可能會(huì)打滿(mǎn)磁盤(pán)，影響業(yè)務(wù)系統(tǒng)的正常運(yùn)行。

12.不要記錄了異常，又拋出異常

反例如下：

log.error("IO exception", e);
throw new MyException(e);

• 這樣實(shí)現(xiàn)的話(huà)，通常會(huì)把棧信息打印兩次。這是因?yàn)椴东@了MyException異常的地方，還會(huì)再打印一次。
• 這樣的日志記錄，或者包裝后再拋出去，不要同時(shí)使用！否則你的日志看起來(lái)會(huì)讓人很迷惑。

13.避免重復(fù)打印日志

避免重復(fù)打印日志，醬紫會(huì)浪費(fèi)磁盤(pán)空間。如果你已經(jīng)有一行日志清楚表達(dá)了意思，避免再冗余打印，反例如下：

if(user.isVip()){
  log.info("該用戶(hù)是會(huì)員,Id:{}",user,getUserId());
  //冗余，可以跟前面的日志合并一起
  log.info("開(kāi)始處理會(huì)員邏輯,id:{}",user,getUserId());
  //會(huì)員邏輯
}else{
  //非會(huì)員邏輯
}

如果你是使用log4j日志框架，務(wù)必在log4j.xml中設(shè)置 additivity=false，因?yàn)榭梢员苊庵貜?fù)打印日志

正例：

<logger name="com.taobao.dubbo.config" additivity="false"> 

14.日志文件分離

• 我們可以把不同類(lèi)型的日志分離出去，比如access.log，或者error級(jí)別error.log，都可以單獨(dú)打印到一個(gè)文件里面。
• 當(dāng)然，也可以根據(jù)不同的業(yè)務(wù)模塊，打印到不同的日志文件里，這樣我們排查問(wèn)題和做數(shù)據(jù)統(tǒng)計(jì)的時(shí)候，都會(huì)比較方便啦。

15. 核心功能模塊，建議打印較完整的日志

• 我們?nèi)粘ｉ_(kāi)發(fā)中，如果核心或者邏輯復(fù)雜的代碼，建議添加詳細(xì)的注釋?zhuān)约拜^詳細(xì)的日志。
• 日志要多詳細(xì)呢？腦洞一下，如果你的核心程序哪一步出錯(cuò)了，通過(guò)日志可以定位到，那就可以啦。