项目中日志输出例子

package com.qlead.pams;

import com.alibaba.fastjson.JSONObject;
import lombok.extern.slf4j.Slf4j;
import org.aspectj.lang.JoinPoint;
import org.aspectj.lang.annotation.AfterReturning;
import org.aspectj.lang.annotation.Aspect;
import org.aspectj.lang.annotation.Before;
import org.aspectj.lang.annotation.Pointcut;
import org.springframework.stereotype.Component;
import org.springframework.web.context.request.RequestContextHolder;
import org.springframework.web.context.request.ServletRequestAttributes;

import javax.servlet.http.HttpServletRequest;
import java.util.Map;
import java.util.Objects;

@Slf4j
@Aspect
@Component
public class PamsRequestLogger {
    @Pointcut("execution(public * com.qlead.pams.controller.*Controller.*(..))")
    public void log() {
    }

    @Before("log()")
    public void beforeLog(JoinPoint point) {
        ServletRequestAttributes attributes = (ServletRequestAttributes) RequestContextHolder.getRequestAttributes();
        HttpServletRequest request = Objects.requireNonNull(attributes).getRequest();

        log.debug("[REQUEST USER] {} :{}", request.getRemoteAddr(), request.getHeader("User-Agent"));
        log.debug("[REQUEST TARGET] {} :{}:{}()", request.getRequestURL(), point.getSignature().getDeclaringTypeName(), point.getSignature().getName());

        Map<String, String[]> parameterMap = request.getParameterMap();
        if (parameterMap != null) {
            log.debug("[REQUEST PARAM]: {}", JSONObject.toJSONString(parameterMap));
        }

        request.setAttribute("START_TIME", System.currentTimeMillis());
    }

    @AfterReturning("log()")
    public void afterReturning() {
        ServletRequestAttributes attributes = (ServletRequestAttributes) RequestContextHolder.getRequestAttributes();
        HttpServletRequest request = Objects.requireNonNull(attributes).getRequest();

        long start = (Long) request.getAttribute("START_TIME");
        log.debug("[DURATION] {}ms", System.currentTimeMillis() - start);
    }
}

部署到服务器中，可以在控制台中看到日志：

2020-02-27 15:46:50.149 DEBUG 16910 --- [nio-8088-exec-8] com.qlead.pams.PamsRequestLogger         : [REQUEST USER] 172.205.9.124 :Mozilla/5.0 (Windows NT 6.3; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/33.0.1750.146 Safari/537.36
2020-02-27 15:46:50.150 DEBUG 16910 --- [nio-8088-exec-8] com.qlead.pams.PamsRequestLogger         : [REQUEST TARGET] http://172.205.9.124:8088/unit/all :com.qlead.pams.controller.UnitController.retrieveAll.()
2020-02-27 15:46:50.150 DEBUG 16910 --- [nio-8088-exec-8] com.qlead.pams.PamsRequestLogger         : [REQUEST PARAM]: no param
2020-02-27 15:46:50.151 DEBUG 16910 --- [nio-8088-exec-8] c.q.pams.mapper.UnitMapper.selectList    : ==>  Preparing: SELECT u_id AS id,u_account_id AS accountId,u_name AS name,u_manager AS manager,u_cellphone AS cellphone,u_type AS type,u_status AS status,u_create_time AS createTime,u_modified_time AS modifiedTime FROM tb_unit 
2020-02-27 15:46:50.151 DEBUG 16910 --- [nio-8088-exec-8] c.q.pams.mapper.UnitMapper.selectList    : ==> Parameters: 
2020-02-27 15:46:50.154 DEBUG 16910 --- [nio-8088-exec-8] c.q.pams.mapper.UnitMapper.selectList    : <==      Total: 32
2020-02-27 15:46:50.154 DEBUG 16910 --- [nio-8088-exec-8] com.qlead.pams.PamsRequestLogger         : [DURATION] 4ms