转载

分布式中采用Logback的MDC机制与AOP切面结合串联日志

前言

在实际开发中，打印日志是十分重要的。在生产环境中，如果日志打得好可以快速地排查问题，而在分布式的场景下，一个请求会跨越多个节点，既一个业务可能需要多个节点协调配合处理。那么日志将会分散，而为了更好的查看日志，我们需要将它们串联起来，这样便会使排查问题变得更佳轻松。

串联ID在分布式日志打印的好处

举例一个简单分布式场景下使用串联日志的例子。场景如下：一笔支付请求从产品系统发起，期间经历了核心系统和网关系统最后调用银行系统实现资金划转，并逐步响应结果直到回到产品系统。这里暂且把整个支付流程看作是同步的，当这笔交易在生产环境中因其中某一环境出现异常时，我们需查看日志进行排查，而这笔交易因为流经多个系统，所以日志是分散的。这时候如果有一个唯一标识且能把所有日志串联起来那么将会方便和提高问题排查的效率。

分布式中采用Logback的MDC机制与AOP切面结合串联日志

串连ID的实现方式

串联的核心要点是把ID做为一个请求必传参数。常见如采用手动打印日志，既在各个接口服务内多处加上logger打印，打印内容里加上串联ID，如：

但是，还有另一种更简便的打印方式，既是MDC ( Mapped Diagnostic Contexts ) + AOP切面结合。MDC它是一个线程安全的存放诊断日志的容器。在处理请求前将请求的唯一标示放到MDC容器中，这个唯一标示会随着日志一起输出，以此来区分该条日志是属于那个请求的。并在请求处理完成之后清除MDC容器。

MDC对外提供的方法

分布式中采用Logback的MDC机制与AOP切面结合串联日志

Logback配置

使用Logback的MDC机制，需要在logback.xml日志模板中进行一些设置，在logback.xml中，通过使用 %X{ }来占位，替换到对应的MDC中key的值。MDC容器的key可以多次赋值，每一次赋值会覆盖上一次的值。

分布式中采用Logback的MDC机制与AOP切面结合串联日志

MDC简单应用例子

分布式中采用Logback的MDC机制与AOP切面结合串联日志

往MDC容器中put入键值对，在日志打印时，日志会按照我们预先在logback.xml中的格式输出，而其中占位符会替换上MDC中对应key的value值。打印结果如下：

MDC与AOP切面的结合

如编写一个切面，被调用的服务在执行操作前，切面会将关键信息输出日志并同时将串联ID put到容器中，使其能在接下来同一线程内输出的日志中都包含该串联ID信息，在将日志串联起来的同时也方便了日志的打印。

分布式中采用Logback的MDC机制与AOP切面结合串联日志

除了自定义切面外，Logback也提供了一个过滤器 MDCInsertingServletFilter ，感兴趣的朋友可以去详细了解下。

管理每个线程的MDC容器

这里要特别要注意一点的是在主线程上，新起一个子线程，并由 java.util.concurrent.Executors来执行它时，在早期的版本中子线程可以直接自动继承父线程的MDC容器中的内容，因为MDC在早期版本中使用的是InheritableThreadLocal来作为底层实现。但是由于性能问题被取消了，最后还是使用的是ThreadLocal来作为底层实现。这样子线程就不能直接继承父线程的MDC容器。

举个例子：

分布式中采用Logback的MDC机制与AOP切面结合串联日志