轻量级分布式日志追踪-Tlog快速入门

一、前言

公司目前还没有上 SkyWalking、Pinpoint等分布式追踪系统，所以先用个轻量级的吧
Tlog：只生成TraceId写入日志文件，没有收集、存储、查询，所以轻量
以 spring-boot 2.3.12(非native) + log4j2 为例

二、快速入门

1. 选择接入方式

日志框架适配器方式最稳定，优先选择
另有 Javaagent方式、字节码注入方式

2. 安装

建议把 tlog的依赖放到 log4j2前面，可以少修改 log4j2配置文件
原项目依赖 hutool 时，版本不一致可能包冲突

      <dependency>
          <groupId>com.yomahub</groupId>
          <artifactId>tlog-all-spring-boot-starter</artifactId>
          <version>1.5.0</version>
      </dependency>

按需依赖

      <dependency>
          <groupId>com.yomahub</groupId>
          <artifactId>tlog-feign-spring-boot-starter</artifactId>
          <version>1.5.0</version>
      </dependency>
      <dependency>
          <groupId>com.yomahub</groupId>
          <artifactId>tlog-web-spring-boot-starter</artifactId>
          <version>1.5.0</version>
      </dependency>

3. Log4j2框架适配器

如果 tlog的依赖没有放到 log4j2前面，需把pattern中的 m/msg/message 改成 tm/tmsg/tmessage
日志pattern 没有包含 %X 变量(MDC变量)时，可以不用增加 %TX{tl}，会自动记录
日志pattern 包含 %X 变量时，需修改 pattern，增加 %TX{tl}，记录TraceId。否则不会记录TraceId

4. 效果

启动日志：Converter key 'message' is already mapped to 'class com.yomahub.tlog.core.enhance.log4j2.AspectLogLog4j2Converter'

2020-09-16 18:12:56,748 [WARN] [TLOG]重新生成traceId[7161457983341056]  >> com.yomahub.tlog.web.TLogWebInterceptor:39
2020-09-16 18:12:56,763 [INFO] <0><7161457983341056> logback-dubbox-consumer:invoke method sayHello,name=jack  >> com.yomahub.tlog.example.dubbox.controller.DemoController:22
2020-09-16 18:12:56,763 [INFO] <0><7161457983341056> 测试日志aaaa  >> com.yomahub.tlog.example.dubbox.controller.DemoController:23
2020-09-16 18:12:56,763 [INFO] <0><7161457983341056> 测试日志bbbb  >> com.yomahub.tlog.example.dubbox.controller.DemoController:24

三、配置

1. 日志标签模板自定义

TLog默认只打出spanId和traceId，可修改 spring-boot 配置文件调整：

tlog.pattern=[$preApp][$preIp][$spanId][$traceId]

2. 自动打印调用参数和时间

默认不打印参数和时间，可修改 spring-boot 配置文件调整：

tlog.enable-invoke-time-print=true

效果如下，方法开头增加一行记录参数日志，末尾增加耗时日志：

2020-12-01 19:20:07.768 [DubboServerHandler-127.0.0.1:30900-thread-2] INFO  c.y.tlog.dubbo.filter.TLogDubboInvokeTimeFilter - <0.1><7592057736843136> [TLOG]开始调用接口[DemoService]的方法[sayHello],参数为:["jack"]
2020-12-01 19:20:07.787 [DubboServerHandler-127.0.0.1:30900-thread-2] INFO  c.y.t.example.dubbo.service.impl.DemoServiceImpl - <0.1><7592057736843136> logback-dubbox-provider:invoke method sayHello,name=jack
2020-12-01 19:20:07.788 [Thread-14] INFO  c.y.tlog.example.dubbo.service.impl.AsynDomain - <0.1><7592057736843136> 这是异步方法哦
2020-12-01 19:20:07.788 [Thread-14] INFO  c.y.tlog.example.dubbo.service.impl.AsynDomain - <0.1><7592057736843136> 异步方法开始
2020-12-01 19:20:07.789 [Thread-14] INFO  c.y.tlog.example.dubbo.service.impl.AsynDomain - <0.1><7592057736843136> 异步方法结束
2020-12-01 19:20:07.795 [DubboServerHandler-127.0.0.1:30900-thread-2] INFO  c.y.tlog.dubbo.filter.TLogDubboInvokeTimeFilter - <0.1><7592057736843136> [TLOG]结束接口[DemoService]中方法[sayHello]的调用,耗时为:90毫秒