一款給日誌打標籤實現精肯定位的日誌切面利器

背景

不知道各位在生產日誌中定位問題時有沒有碰到這樣的場景：因爲coding的時候日誌輸出的比較少，出現問題時，很難經過日誌去定位到問題。又或者是，你明明coding的時候有輸出日誌。可是在龐大的日誌文件中，因爲業務線程併發比較多，你輸出的日誌又沒有加關鍵信息。你也很難定位到你所須要的日誌信息。

以前在公司裏寫業務代碼時，爲了使每一個RPC調用能被日誌記錄下來，咱們在公司基礎組件裏定義了一個切面，攔截全部的RPC調用，方法開始以前，在日誌裏輸出調用的服務和方法以及參數，方法結束時輸出方法的耗時。我相信不少人也是這麼作的。

這樣一來 ，全部的調用都能經過參數裏的關鍵信息被搜索到。也能定位到調用是什麼結束的以及耗時。

可是在有些業務方法中，也打上了不少的業務日誌。因爲核心業務的tps和qps很高，日誌是互相穿插的。若是你的日誌沒有打上關鍵的業務信息(好比訂單號，業務ID)，那就很難在日誌中被定位出來。

也許有些童鞋會說，用線程號呀。經過搜索業務ID定位到調用開始的地方，再搜索這條線程的線程號，就能夠定位整個請求的全部日誌。其實以前我也是這麼幹的，可是線程通常都是由線程池進行管理的，在tps很高的業務中，同一個線程號有可能短期會出現屢次，可是倒是不一樣的請求。並且業務方法中可能也會有異步線程，致使了線程號會變。這樣對於定位日誌就又增長了難度。固然最後能夠經過對時間戳的分析，仍是能夠定位到具體日誌。可是這樣就增長了定位的時間成本。

若是你的公司對微服務使用了分佈式追蹤，那麼定位日誌能夠經過traceId來解決。若是沒有在生產上應用分佈式追蹤，又想在併發比較高的應用的日誌上快速定位到所須要的日誌。其中一個比較有效的辦法就是：規範日誌的輸出格式。

在每行日誌輸出時儘量的加上關鍵的業務信息，而後定位起來就比較清晰了，例如：

這裏每一行日誌都加上了訂單號和請求ID，咱們把這樣的日誌頭信息稱之爲日誌標籤，有了這些標籤訂位起來就比較容易了。

那這樣是否是意味着每寫一行日誌，都必須加上這樣日誌標籤信息呢？這是否是也麻煩了？

答案是不用的。

推薦一款自動給日誌打標籤實現精肯定位的日誌切面框架，你使用了這個，你的日誌能夠實現自定義的業務標籤！

Aspect-log介紹

這是一款小巧，輕量級，對業務幾乎無侵入的日誌切面框架。特性爲：

• 使用簡單，不侵入業務代碼。只須要在方法上配置標註
• 支持log4j，logback，log4j2三種常見的日誌框架
• 配置極其簡單。提供「一鍵配置」，自動識別日誌框架
• 在方法上配置，不管調用多深或者異步調用。也能夠統一加上日誌標籤
• 無性能損耗

使用方法很簡單，若是你是springboot，aspect-log提供了自動裝配，GAV爲：

<dependency>
  <groupId>com.yomahub</groupId>
  <artifactId>aspect-log-spring-boot-starter</artifactId>
  <version>1.2</version>
</dependency>

若是你是spring，GAV爲：

<dependency>
  <groupId>com.yomahub</groupId>
  <artifactId>aspect-log-core</artifactId>
  <version>1.2</version>
</dependency>

配置方式

若是你使用spring，須要在項目裏的application.xml裏定義：

<bean class="com.yomahub.aspectlog.aop.AspectLogAop"/>

若是你使用springboot，AspectLog切面會自動裝配好。

一鍵配置方法

這種方式用javassit實現，只須要一句話就能夠實現。自動識別當前主流的日誌框架。

@SpringBootApplication
public class Runner {

    static {AspectLogEnhance.enhance();}//進行日誌加強，自動判斷日誌框架

    public static void main(String[] args) {
        try {
            SpringApplication.run(Runner.class, args);
        } catch (Throwable e) {
            e.printStackTrace();
        }
        while (true) {
            try {
                Thread.sleep(60000);
            } catch (Throwable e) {
                e.printStackTrace();
            }
        }
    }
}

針對於主流的Log日誌框架做了適配（推薦用這種）

針對於每種Log日誌框架提供了適配，具體使用方法請參照文末提供的項目主頁裏的文檔。

如何使用

接下來在你的方法上加上@AspectLog標註，簡單的例子以下：

@AspectLog({"id"})
public void demo1(String id,String name){
  log.info("這是第一條日誌");
  log.info("這是第二條日誌");
  log.info("這是第三條日誌");
  new Thread(() -> log.info("這是異步日誌")).start();
}

假設id的值爲'NO1234'，日誌打出來的樣子以下：

2020-02-08 20:22:33.945 [main] INFO  Demo - [NO1234] 這是第一條日誌
2020-02-08 20:22:33.945 [main] INFO  Demo - [NO1234] 這是第二條日誌
2020-02-08 20:22:33.945 [main] INFO  Demo - [NO1234] 這是第三條日誌
2020-02-08 20:22:33.948 [Thread-3] INFO  Demo - [NO1234] 這是異步日誌

@AspectLog支持多個標籤:

@AspectLog({"id","name"})
public void demo1(String id,String name){
  log.info("這是第一條日誌");
  log.info("這是第二條日誌");
  log.info("這是第三條日誌");
  new Thread(() -> log.info("這是異步日誌")).start();
}

假設傳入id的值爲'NO1234'，name爲'jenny'，日誌打出來的樣子以下：

2020-02-08 22:09:40.101 [main] INFO  Demo - [NO1234-jenny] 這是第一條日誌
2020-02-08 22:09:40.101 [main] INFO  Demo - [NO1234-jenny] 這是第二條日誌
2020-02-08 22:09:40.102 [main] INFO  Demo - [NO1234-jenny] 這是第三條日誌
2020-02-08 22:09:40.103 [Thread-3] INFO  Demo - [NO1234-jenny] 這是異步日誌

@AspectLog支持自定pattern和多個參數的鏈接符

@AspectLog(value = {"id","name"},pattern = "<-{}->",joint = "_")
public void demo(String id,String name){
  log.info("加了patter和joint的示例");
}

日誌打出來的樣子以下：

2020-02-08 22:09:40.103 [main] INFO  Demo - <-NO1234_jenny-> 加了patter和joint的示例

@AspectLog支持點操做符，適用於對象的取值，支持類型爲業務對象和Map

@AspectLog({"person.id","person.age","person.company.department.dptId"})
public void demo(Person person){
  log.info("多參數加多層級示例");
}

日誌打出來的樣子以下：

2020-02-08 22:09:40.110 [main] INFO  Demo - [31-25-80013] 多參數加多層級示例

@AspectLog支持自定義Convert，適用於更復雜的業務場景

@AspectLog(convert = CustomAspectLogConvert.class)
public void demo(Person person){
  log.info("自定義Convert示例");
}

public class CustomAspectLogConvert implements AspectLogConvert {
    @Override
    public String convert(Object[] args) {
        Person person = (Person)args[0];
        return "PERSON(" + person.getId() + ")";
    }
}

日誌打印出來的樣子以下：

2020-02-20 17:05:12.414 [main] INFO  Demo - [PERSON(31] 自定義Convert示例

@AspectLog支持編程式設值

public void demo(){
  AspectLogContext.putLogValue("[SO1001]");
  log.info("代碼控制示例");
}

日誌打出來的樣子：

2020-02-08 22:09:40.110 [main] INFO  Demo - [SO1001] 代碼控制示例

結尾

日誌輸出對於一個服務來講，便是執行軌跡的記錄，也是尋錯找源的根本。日誌打的全面而工整，對於排查問題來講，是事半功倍的，相信小夥伴都有看過亂糟糟的日誌吧，在一堆這樣的日誌文件中取尋根溯源，那是極其痛苦的。

我相信不少開發者都有着代碼潔癖，工程的架構，代碼的結構，變量的定義，都有着苛刻的要求。這是種好習慣，這種好習慣會使代碼的閱讀和理解事半功倍。

因此Aspect-log就是這樣一款能讓你的日誌也變得工整和賞心悅目的工具。

最後附上Aspect-log的工程主頁，歡迎關注和提出issue

https://bryan31.gitee.io/aspe...

但願這款小工具能讓你的日誌變得工整，能讓你的排查變得簡單而快捷。

聯繫做者