主页 / coding / 问题 / 78943238
Accepted
SamPaul Isaac
Asked: 2024-09-03 15:57:35 +0800 CST

为什么@Around 建议的后方法部分似乎在@AfterReturning 和@After 建议之后执行？

  • 772

我有一个 AspectJ 配置，其中包含一个用于测量目标方法执行时间的 @Around 建议。但是，执行时间是在 @AfterReturning 建议之后记录的，这不是所需的顺序。

@Aspect
@Component
public class LoggingAspectUsingPointcut {

    @Pointcut("execution(* com.sam.aspect.pointcut.controller.UsingPointcutController.*(..))")
    public void controllerMethods() {}

    @Before("controllerMethods()")
    public void logBefore() {
        System.out.println("Method is about to be called");
    }

    @Around("controllerMethods()")
    public Object logExecutionTime(ProceedingJoinPoint joinPoint) throws Throwable {
        System.out.println("Around advice start");
        long start = System.currentTimeMillis();
        Object proceed = joinPoint.proceed(); // Proceed with the method execution
        long executionTime = System.currentTimeMillis() - start;
        System.out.println(joinPoint.getSignature() + " executed in " + executionTime + "ms");
        System.out.println("Around Advice End");
        return proceed;
    }

    @After("controllerMethods()")
    public void logAfter() {
        System.out.println("Method has been called");
    }

    @AfterReturning(pointcut = "execution(* com.sam.aspect.pointcut.controller.UsingPointcutController  .*(..))", returning = "result")
    public void logAfterReturning(Object result) {
        System.out.println("Method executed successfully." + result);
    }
}

我预期的顺序是：

  1. @Around pre-method（在执行proceed()之前）
  2. @前
  3. 目标方法执行(proceed())
  4. @Around post-method（执行时响应）
  5. @AfterReturning
  6. @后

相反，我得到的是：

  1. @Around-已执行
  2. @Before-已执行
  3. 目标方法-已执行
  4. @AfterReturning-已执行
  5. @After-执行
  6. @Around post-method -已执行但未按所需顺序执行

输出：

Around advice start
Method is about to be called
Method executed successfully.[Record 1, Record 2, Record 3, Record 4, Record 5, Record 6, Record 7, Record 8, Record 9, Record 10]
Method has been called
List com.sam.aspect.pointcut.controller.UsingPointcutController.getData() executed in 13ms
Around Advice End

我在这里错过了什么？如何确保在 @AfterReturning 和 @After 建议之前记录执行时间？

Spring Boot 3.3.3。类已使用 @Aspect 和 @Component 正确注释。此外，确保在应用程序中配置了 @EnableAspectJAutoProxy。

spring-boot

1 个回答

  1. Best Answer

    您描述的行为记录在 Spring 手册的“Advice Ordering”一章中。请特别注意以下部分：

    如果多个建议都想在同一个连接点运行，会发生什么情况？Spring AOP 遵循与 AspectJ 相同的优先级规则来确定建议执行的顺序。优先级最高的建议在“进入”时首先运行（因此，给定两个前置建议，优先级最高的建议将首先运行）。在从连接点“退出”时，优先级最高的建议最后运行（因此，给定两个后置建议，优先级最高的建议将第二个运行）。

    (...)

    从 Spring Framework 5.2.7 开始，@Aspect需要在同一个连接点运行的同一个类中定义的通知方法将根据其通知类型按以下顺序分配优先级，从最高优先级到最低优先级：@Around，@Before，@After，@AfterReturning，@AfterThrowing。但请注意，通知方法将在同一方面中的任何或通知方法@After之后有效地调用，遵循 AspectJ 的“最终通知之后”语义。@AfterReturning@AfterThrowing@After

    即，@Around在进入时首先运行，在离开时最后运行。因此，您的方面逻辑在概念上是错误的。我建议将之前/之后的建议折叠到周围建议中，添加一些 try-catch-finally 以获得您想要的行为。或者，您可以将方面拆分为两个方面，并确保使用@Order正确的方式使计时方面的运行优先级低于日志记录方面。

    • 1

相关问题