(Stack Overflowとのマルチポストになります)
Spring Boot の ログ出力情報としてリクエスト単位で固有の値を共通的に付与したいと考えています。
この目的のため、Logback MDCを利用しリファレンスの例7.5を参考にして後述のように実装しました。
この実装では、Spring MVCの通常のcontrollerなどでは想定通り所望の情報(今回の実装ではrequestId
の値)が付与されてログ出力されるのですが、 ErrorContoller
実装クラスのログ出力では付与されませんでした。
ErrorController
に実装した処理が実行されるのはMDCFilter
を抜けてからだ(MDC.remove()
が実行された後だからだ)、という原因は理解できるのですが、ではどのように実装すれば良いのか、というのがわかりません。
(DispatcherServlet
よりも先にMDCFilter
が適用されるようにすれば良いように思われたのですが、その実現方法がわかりませんでした。)
@Controller
@RequestMapping("/error")
@RequiredArgsConstructor
@Slf4j
public class MyErrorController implements ErrorController {
private final ErrorAttributes errorAttributes;
@Override
public String getErrorPath() {
return null;
}
@RequestMapping
@ResponseBody
public Map<String, String> error(final HttpServletRequest request) {
final ServletWebRequest webRequest = new ServletWebRequest(request);
final Throwable th = errorAttributes.getError(webRequest);
if (th != null) {
// ここで出力したログにMDCの値(requestId)が付与されない
log.error("MyErrorController", th);
}
return Map.of("result", "error");
}
}
// http://logback.qos.ch/manual/mdc.html#autoMDC
public class MDCFilter extends OncePerRequestFilter {
@Override
protected void doFilterInternal(final HttpServletRequest request, final HttpServletResponse response,
final FilterChain filterChain)
throws ServletException, IOException {
final String requestId = UUID.randomUUID().toString();
MDC.put("requestId", requestId);
try {
filterChain.doFilter(request, response);
} finally {
MDC.remove("requestId");
}
}
}
@Configuration
public class MyConfig {
@Bean
public FilterRegistrationBean<MDCFilter> mdcFilter() {
final FilterRegistrationBean<MDCFilter> bean = new FilterRegistrationBean<>();
bean.setFilter(new MDCFilter());
bean.addUrlPatterns("/*");
bean.setOrder(Ordered.HIGHEST_PRECEDENCE);
return bean;
}
}
logback-spring.xml
:
<configuration>
<appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender">
<encoder>
<pattern>%d{HH:mm:ss.SSS} [%thread] requestId:%X{requestId} %-5level %logger{36} - %msg%n</pattern>
</encoder>
</appender>
<root level="debug">
<appender-ref ref="STDOUT" />
</root>
</configuration>
実行結果("requestId:
" の後ろにUUIDが出力されて欲しいが、そうなっていない):
18:15:13.705 [http-nio-8080-exec-1] requestId: ERROR c.e.l.MyErrorController - MyErrorController
java.lang.RuntimeException: Error occured.
...
類似の質問として次のようなものを見つけました(が、明確な回答は寄せられていないようでした):