文章内容
线上排查问题时候，发现请求太多导致日志错综复杂，没办法把用户在一次或多次请求的日志关联在一起，所以就利用SpringBoot+Logback手写了一个简单的链路追踪。
一、实现原理
Spring Boot默认使用LogBack日志系统，并且已经引入了相关的jar包，所以我们无需任何配置便可以使用LogBack打印日志。
MDC（Mapped Diagnostic Context，映射调试上下文）是log4j和logback提供的一种方便在多线程条件下记录日志的功能。
实现思路是：在一个请求开始时，将请求相关的上下文信息（例如客户ID、客户的IP地址、sessionId、请求参数等）添加到MDC，然后配置好logback-spring.xml，则Logback组件将会在每条日志中打印出存放到MDC的信息，从而实现一个ID贯穿用户的所有操作。
二、代码实现
新建一个spring boot项目spring-boot-log，按照下面步骤操作：
1、新建日志拦截器
日志拦截器在请求开始获取用户的sessionId，当然也可以生成一个UUID，生成后存放到MDC中。SessionInterceptor代码如下：
01 02 03 04 05 06 07 08 09 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 | /** * 日志拦截器 * */public class SessionInterceptor extends HandlerInterceptorAdapter { /** * 会话ID */ private final static String SESSION_KEY = "sessionId"; @Override public void postHandle(HttpServletRequest arg0, HttpServletResponse arg1, Object arg2, ModelAndView arg3) throws Exception { } @Override public boolean preHandle(HttpServletRequest request, HttpServletResponse response, Object handler) throws Exception {// String token = UUID.randomUUID().toString().replaceAll("-",""); //本例测试使用sessionId，也可以使用UUID等 String token = request.getSession().getId(); MDC.put(SESSION_KEY, token); return true; } @Override public void afterCompletion(HttpServletRequest arg0, HttpServletResponse arg1, Object arg2, Exception arg3) throws Exception { // 删除 MDC.remove(SESSION_KEY); }} |
2、新建配置类
新建InterceptorConfig，注册刚才的日志拦截器。InterceptorConfig代码如下：
01 02 03 04 05 06 07 08 09 10 11 12 13 | @Configurationpublic class InterceptorConfig implements WebMvcConfigurer { @Bean public SessionInterceptor getSessionInterceptor() { return new SessionInterceptor(); } @Override public void addInterceptors(InterceptorRegistry registry) { registry.addInterceptor(getSessionInterceptor()).addPathPatterns("/*"); }} |
3、修改logback-spring.xml
配置logback-spring.xml，获取日志拦截器添加的sessionId并打印到日志中，配置文件中获取方式如下：
1 | %X{sessionId} |
本例中打印sessionId到控制台和文件，完整配置如下：
01 02 03 04 05 06 07 08 09 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 | <?xml version="1.0" encoding="UTF-8"?><configuration> <property name="log.base" value="./log/logback"/> <appender name="stdout" class="ch.qos.logback.core.ConsoleAppender"> <encoder> <pattern> %date [%thread] [%X{sessionId}] %-5level %logger{80} - %msg%n </pattern> </encoder> </appender> <appender name="logfile" class="ch.qos.logback.core.rolling.RollingFileAppender"> <File>${log.base}.log</File> <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy"> <FileNamePattern>${log.base}.%d{yyyy -MM-dd}.log.zip</FileNamePattern> </rollingPolicy> <encoder> <pattern> %date [%thread] [%X{sessionId}] %-5level %logger{80} - %msg%n </pattern> </encoder> </appender> <logger name="com.sample" level="TRACE"/> <root> <level value="INFO"/> <appender-ref ref="stdout"/> <appender-ref ref="logfile"/> </root></configuration> |
4、添加controller
新建TestLogController，打印日志。代码如下：
01 02 03 04 05 06 07 08 09 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 | @RestControllerpublic class TestLogController { Logger log = LoggerFactory.getLogger(getClass()); /** * 测试登录 */ @RequestMapping(value = "/testLogin") public String testLogin() { log.info("用户登录成功！"); return "ok"; } /** * 测试下单 */ @RequestMapping(value = "/testNewOrder") public String testNewOrder() { log.info("用户创建了订单！"); log.info("请求完成，返回ok！"); return "ok"; } /** * 测试购买 */ @RequestMapping(value = "/testPay") public String testPay() { log.info("用户付款！"); return "ok"; }} |
5、测试
打开浏览器连续访问接口testLogin、testNewOrder和testPay，模拟用户登录、下单、付款操作，控制台和文件中打印的日志中已经包含了sessonId信息，打印的结果如下：
1 2 3 4 | [http-nio-8888-exec-1] [CB8E7DB250A31F2BE6C05B30633B9A95] INFO com.example.springbootlog.controller.TestLogController - 用户登录成功！[http-nio-8888-exec-2] [CB8E7DB250A31F2BE6C05B30633B9A95] INFO com.example.springbootlog.controller.TestLogController - 用户创建了订单！[http-nio-8888-exec-2] [CB8E7DB250A31F2BE6C05B30633B9A95] INFO com.example.springbootlog.controller.TestLogController - 请求完成，返回ok！[http-nio-8888-exec-3] [CB8E7DB250A31F2BE6C05B30633B9A95] INFO com.example.springbootlog.controller.TestLogController - 用户付款！ |
到此SpringBoot+Logback手写一个简单的链路追踪功能已经全部实现。