AOP与Filter拦截请求打印日志实用例子

相信各位同道在写代码的时候，肯定会写一些日志打印，因为这对往后的运维而言，至关重要的。

那么我们请求一个restfull接口的时候，哪些信息是应该被日志记录的呢？

以下做了一个基本的简单例子，这里只是示例说明基本常规实现记录的信息，根据项目的真实情况选用 :

1 . Http请求拦截器(Filter) : 从 HttpServletRequest获取基本的请求信息

package name.ealen.config;

import name.ealen.util.CommonUtil;

import org.slf4j.Logger;

import org.slf4j.LoggerFactory;

import org.springframework.beans.factory.annotation.Qualifier;

import org.springframework.boot.web.servlet.FilterRegistrationBean;

import org.springframework.context.annotation.Bean;

import org.springframework.context.annotation.Configuration;

import org.springframework.core.annotation.Order;

import javax.servlet.*;

import javax.servlet.http.HttpServletRequest;

import javax.servlet.http.HttpServletResponse;

import java.io.IOException;

import java.util.UUID;

/**

 * Created by EalenXie on 2018/9/7 15:56.

 * Http请求拦截器,日志打印请求相关信息

 */

@Configuration

public class FilterConfiguration {

    private static final Logger log = LoggerFactory.getLogger(FilterConfig.class);

    @Bean

    @Order(Integer.MIN_VALUE)

    @Qualifier("filterRegistration")

    public FilterRegistrationBean filterRegistration() {

        FilterRegistrationBean<Filter> registration = new FilterRegistrationBean<>();

        registration.setFilter(controllerFilter());

        registration.addUrlPatterns("/*");

        return registration;

    }

    private Filter controllerFilter() {

        return new Filter() {

            @Override

            public void init(FilterConfig filterConfig) {

                log.info("ControllerFilter init Success");

            }

            @Override

            public void doFilter(ServletRequest servletRequest, ServletResponse servletResponse, FilterChain chain) throws IOException, ServletException {

                HttpServletRequest request = (HttpServletRequest) servletRequest;

                HttpServletResponse response = (HttpServletResponse) servletResponse;

                String requestId = request.getHeader("Request-Id");

                if (requestId == null) requestId = request.getRequestedSessionId();

                if (requestId == null) requestId = UUID.randomUUID().toString();

                if (!"OPTIONS".equalsIgnoreCase(request.getMethod())) {

                    System.out.println();

                    log.info("Http Request Request-Id : " + requestId);

                    log.info("Http Request Information : {\"URI\":\"" + request.getRequestURL() +

                            "\",\"RequestMethod\":\"" + request.getMethod() +

                            "\",\"ClientIp\":\"" + CommonUtil.getIpAddress(request) +

                            "\",\"Content-Type\":\"" + request.getContentType() +

                            "\",\"UserAgent\":\"" + request.getHeader("user-agent") +

                            "\"}");

                }

                chain.doFilter(request, response);

            }

            @Override

            public void destroy() {

                log.info("ControllerFilter destroy");

            }

        };

    }

}

2 . Controller的拦截AOP : 获取请求的对象，请求参数，返回数据，请求返回状态，内部方法耗时。

package name.ealen.config;

import com.alibaba.fastjson.JSON;

import org.aspectj.lang.ProceedingJoinPoint;

import org.aspectj.lang.annotation.Around;

import org.aspectj.lang.annotation.Aspect;

import org.slf4j.Logger;

import org.slf4j.LoggerFactory;

import org.springframework.core.env.Environment;

import org.springframework.stereotype.Component;

import javax.annotation.Resource;

/**

 * Created by EalenXie on 2018/9/7 14:19.

 * AOP打印日志 : 请求的对象,请求参数,返回数据,请求状态,内部方法耗时

 */

@Aspect

@Component

public class ControllerInterceptor {

    private static final Logger log = LoggerFactory.getLogger(ControllerInterceptor.class);

    @Resource

    private Environment environment;

    private String getAppName() {

        try {

            return environment.getProperty("spring.application.name");

        } catch (Exception ignore) {

            return "unnamed";

        }

    }

    /**

     * 注意 : pjp.proceed()执行的异常请务必抛出，交由ControllerAdvice捕捉到并处理

     */

    @Around(value = "execution (*  name.ealen.web.*.*(..))")

    public Object processApiFacade(ProceedingJoinPoint pjp) throws Throwable {

        long startTime = System.currentTimeMillis();

        String name = pjp.getTarget().getClass().getSimpleName();

        String method = pjp.getSignature().getName();

        Object result;

        try {

            result = pjp.proceed();

            log.info("RequestTarget : " + getAppName() + "." + name + "." + method);

            Object[] requestParams = pjp.getArgs();

            if (requestParams.length > 0) {     //日志打印请求参数

                try {

                    log.info("RequestParam : {}", JSON.toJSON(requestParams));

                } catch (Exception e) {

                    for (Object param : requestParams) {

                        try {

                            log.info("RequestParam : {}", JSON.toJSON(param));

                        } catch (Exception ig) {

                            log.info("RequestParam : {}", param.toString());

                        }

                    }

                }

            }

        } finally {

            log.info("Internal Method Cost Time: {}ms", System.currentTimeMillis() - startTime);

        }

        return result;

    }

}

3 . 全局的异常处理返回Advice :

package name.ealen.config;

import com.alibaba.fastjson.JSON;

import name.ealen.util.CommonUtil;

import org.slf4j.Logger;

import org.slf4j.LoggerFactory;

import org.springframework.http.HttpStatus;

import org.springframework.http.ResponseEntity;

import org.springframework.web.bind.annotation.ControllerAdvice;

import org.springframework.web.bind.annotation.ExceptionHandler;

import org.springframework.web.client.HttpClientErrorException;

import org.springframework.web.client.HttpServerErrorException;

import javax.servlet.http.HttpServletRequest;

import java.util.HashMap;

import java.util.Map;

/**

 * Created by EalenXie on 2018/11/8 16:25.

 * 全局异常、错误返回处理

 */

@ControllerAdvice

public class ControllerExceptionListener {

    private final Logger log = LoggerFactory.getLogger(ControllerExceptionListener.class);

    @ExceptionHandler(value = Throwable.class)

    public ResponseEntity Throwable(Throwable throwable, HttpServletRequest request) {

        Map<String, String> resultMap = getThrowable(throwable);

        if (request != null) {

            Integer statusCode = (Integer) request.getAttribute("javax.servlet.error.status_code");

            resultMap.put("Requester-Ip", CommonUtil.getIpAddress(request));

            resultMap.put("Requester-Agent", request.getHeader("user-agent"));

            if (statusCode != null) {

                new ResponseEntity<>(JSON.toJSON(resultMap).toString(), HttpStatus.valueOf(statusCode));

            }

        }

        return new ResponseEntity<>(JSON.toJSON(resultMap).toString(), HttpStatus.INTERNAL_SERVER_ERROR);

    }

    @ExceptionHandler(value = HttpServerErrorException.class)

    public ResponseEntity HttpServerErrorException(HttpServerErrorException serverError) {

        Map<String, String> resultMap = getThrowable(serverError);

        HttpStatus status = serverError.getStatusCode();

        resultMap.put("responseBody", "" + serverError.getResponseBodyAsString());

        resultMap.put("statusCode", "" + status.toString());

        resultMap.put("statusText", "" + serverError.getStatusText());

        resultMap.put("statusReasonPhrase", "" + status.getReasonPhrase());

        return new ResponseEntity<>(JSON.toJSON(resultMap).toString(), status);

    }

    @ExceptionHandler(value = HttpClientErrorException.class)

    public ResponseEntity HttpClientErrorException(HttpClientErrorException clientError) {

        Map<String, String> resultMap = getThrowable(clientError);

        HttpStatus status = clientError.getStatusCode();

        resultMap.put("responseBody", "" + clientError.getResponseBodyAsString());

        resultMap.put("statusCode", "" + clientError.getStatusCode().toString());

        resultMap.put("statusText", "" + clientError.getStatusText());

        resultMap.put("statusReasonPhrase", "" + status.getReasonPhrase());

        return new ResponseEntity<>(JSON.toJSON(resultMap).toString(), status);

    }

    /**

     * 公共异常信息

     */

    private Map<String, String> getThrowable(Throwable throwable) {

        Map<String, String> resultMap = new HashMap<>();

        resultMap.put("throwable", "" + throwable);

        resultMap.put("throwableTime", "" + CommonUtil.getCurrentDateTime());

        resultMap.put("message", "" + throwable.getMessage());

        resultMap.put("localizedMessage", "" + throwable.getLocalizedMessage());

        log.error("Exception : {}", JSON.toJSON(resultMap));

        throwable.printStackTrace();

        return resultMap;

    }

}

4 . 提供一个简单的restfull接口 :

package name.ealen.web;

import org.springframework.http.HttpStatus;

import org.springframework.http.ResponseEntity;

import org.springframework.web.bind.annotation.RequestBody;

import org.springframework.web.bind.annotation.RequestMapping;

import org.springframework.web.bind.annotation.RestController;

/**

 * Created by EalenXie on 2018/9/7 14:24.

 */

@RestController

public class SayHelloController {

    @RequestMapping("/sayHello")

    public String sayHello() {

        return "hello world";

    }

    @RequestMapping("/say")

    public ResponseEntity<?> say(@RequestBody Object o) {

        return new ResponseEntity<>(o, HttpStatus.OK);

    }

}

4 . 使用Postman进行基本测试 :

AOP与Filter拦截请求打印日志实用例子