Spring boot执行时间 日志记录

方法

步骤

pom.xml

<!-- aop -->
        <dependency>
            <groupId>org.aspectj</groupId>
            <artifactId>aspectjweaver</artifactId>
            <version>1.9.2</version>
        </dependency>

LogTime.java

package com.ydfind.common.util.aop;

import java.lang.annotation.ElementType;
import java.lang.annotation.Retention;
import java.lang.annotation.RetentionPolicy;
import java.lang.annotation.Target;

/**
 * 对函数进行切面计算时间
 * @author ydfind
 * @date 2019.1.22
 */
@Target(ElementType.METHOD)
@Retention(RetentionPolicy.RUNTIME)
public @interface LogTime {
    String type();
    String name();
}

LogTimeAspect.java

package com.ydfind.common.util.aop;

import lombok.extern.slf4j.Slf4j;
import org.aspectj.lang.JoinPoint;
import org.aspectj.lang.ProceedingJoinPoint;
import org.aspectj.lang.annotation.After;
import org.aspectj.lang.annotation.Around;
import org.aspectj.lang.annotation.Aspect;
import org.aspectj.lang.annotation.Before;
import org.springframework.core.annotation.Order;
import org.springframework.stereotype.Component;

/**
 * 对函数进行切面计算时间
 * @author ydfind
 * @date 2019.1.22
 */
@Component
@Aspect
@Order(1)
@Slf4j
public class LogTimeAspect {

    @Around(value = "@annotation(logTime)")
    public Object aroundMethod(ProceedingJoinPoint pjd, LogTime logTime) throws Throwable {
        long time = System.currentTimeMillis();
        Object object = pjd.proceed();
        // 把执行函数的时间进行输出
        log.info("@TimeAop({}, {}) time = {}", logTime.type(), logTime.name(), System.currentTimeMillis() - time);
        return object;
    }

    @Before(value = "@annotation(logTime)")
    public void before(JoinPoint joinPoint, LogTime logTime) {
        System.out.println("before");
    }

    @After(value = "@annotation(logTime)")
    public void after(JoinPoint joinPoint, LogTime logTime) {
        System.out.println("after");
    }
}

入口函数

@EnableSwagger2
@SpringBootApplication
@ComponentScan({"com.ydfind.start", "com.ydfind.common"})
public class MainApplication {

    public static void main(String[] args) {
        SpringApplication.run(MainApplication.class);
    }
}

测试

package com.ydfind.start.controller.test.service;

import com.ydfind.common.util.aop.LogTime;
import org.springframework.stereotype.Component;

@Component
public class TimeService {

    @LogTime(type = "log", name = "logTime")
    public void logTime() throws InterruptedException {
        Thread.sleep(3000);
    }
}

@Autowired
    private TimeService timeService;

    @Test
    public void testTime1() throws InterruptedException {
        timeService.logTime();
    }

结果

before
[2020-01-22 18:03:38,842] [main] [INFO ] com.ydfind.common.util.aop.LogTimeAspect - @TimeAop(log, logTime) time = 3009
after

测试用例

使用@Before、@After，这是在每个@Test执行前都会执行的！