分析java 中AspectJ切面执行两次的原因

 更新时间:2017年09月05日 10:56:13   投稿:lqh  
这篇文章主要介绍了分析java 中AspectJ切面执行两次的原因的相关资料,希望通过本能帮助到大家,需要的朋友可以参考下

分析java 中AspectJ切面执行两次的原因

背景

转眼之间,发现博客已经将近半年没更新了,甚是惭愧。话不多说,正如标题所言,最近在使用AspectJ的时候,发现拦截器(AOP切面)执行了两次了。我们知道,AspectJ是AOP的一种解决方案,本质上是通过代理类在目标方法执行通知(Advice),然后由代理类再去调用目标方法。所以,从这点讲,拦截器应该只会执行一次。但是在测试的时候发现拦截器执行了两次。

问题重现

既然问题已经明了,那么可以通过代码简单重现这个问题,从而更深层次分析到底是什么原因导致的。

定义一个注解:

package com.rhwayfun.aspect;

import java.lang.annotation.*;

@Target({ElementType.METHOD})
@Retention(RetentionPolicy.CLASS)
@Documented
public @interface StatsService {
}

为该注解定义切面:

package com.rhwayfun.aspect;

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;

@Aspect
public class StatsServiceInterceptor {

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

  @Around("@annotation(StatsService)")
  public Object invoke(ProceedingJoinPoint pjp) {
    try {
      log.info("before invoke target.");
      return pjp.proceed();
    } catch (Throwable e) {
      log.error("invoke occurs error:", e);
      return null;
    } finally {
      log.info("after invoke target.");
    }
  }

}

方法测试:

package com.rhwayfun;

import com.rhwayfun.aspect.StatsService;
import org.slf4j.Logger;
import org.slf4j.LoggerFactory;

import java.time.LocalDateTime;

public class AspectTest {

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

  public static void main(String[] args) {
    AspectTest.print();
  }

  @StatsService
  public static void print(){
    log.info("Now: {}", LocalDateTime.now());
  }
}

输出结果:

debug分析

由于是静态织入,所以可以通过反编译工具查看编译后的文件,如下:

public class AspectTest
{
  private static Logger log;
  private static final /* synthetic */ JoinPoint$StaticPart ajc$tjp_0;
  private static final /* synthetic */ JoinPoint$StaticPart ajc$tjp_1;

  public static void main(final String[] args) {
    StatsServiceInterceptor.aspectOf().invoke(((AroundClosure)new AspectTest$AjcClosure1(new Object[] { Factory.makeJP(AspectTest.ajc$tjp_0, (Object)null, (Object)null) })).linkClosureAndJoinPoint(0));
  }

  @StatsService
  public static void print() {
    StatsServiceInterceptor.aspectOf().invoke(((AroundClosure)new AspectTest$AjcClosure3(new Object[] { Factory.makeJP(AspectTest.ajc$tjp_1, (Object)null, (Object)null) })).linkClosureAndJoinPoint(65536));
  }

  static {
    ajc$preClinit();
    AspectTest.log = LoggerFactory.getLogger((Class)AspectTest.class);
  }

  private static /* synthetic */ void ajc$preClinit() {
    final Factory factory = new Factory("AspectTest.java", (Class)AspectTest.class);
    ajc$tjp_0 = factory.makeSJP("method-call", (Signature)factory.makeMethodSig("9", "print", "com.rhwayfun.AspectTest", "", "", "", "void"), 17);
    ajc$tjp_1 = factory.makeSJP("method-execution", (Signature)factory.makeMethodSig("9", "print", "com.rhwayfun.AspectTest", "", "", "", "void"), 22);
  }
}

请注意两个连接点:ajc$tjp_0和ajc$tjp_1,这两个连接点是产生两次调用的关键,问题注解明明是加上print()方法上的,为什么main()方法也被注入了通知呢?正因为main()方法也织入了通知,所以就形成了A call B, B call print()的调用链,有两次method-call,一次method-execution,method-execution才是我们的目标方法print(),所以我们才看到了两次输出。

method-call和method-execution都是连接点ProceedingJoinPoint的kind属性

其实,这属于Ajc编译器的一个Bug,详见Ajc-bug

所以,到这一步,问题就很清晰了,因为Ajc编辑器的bug,导致了在main方法中也织入了通知,所以在执行的时候,输出了两次日志。

解决方法

方案一

因为两次调用的kind属性不一样,所以可以通过kind属性来判断时候调用切面。这样显得不优雅,而且如果切面有更多的逻辑的话,需要加各种if-else的判断,所以不推荐。

方法二

更优雅的方案是修改@Around("@annotation(StatsService)")的逻辑,改为@Around("execution(* *(..)) && @annotation(StatsService)")。

重新运行上面的测试类,结果如下:

如有疑问请留言或者到本站社区交流讨论,感谢阅读,希望能帮助到大家,谢谢大家对本站的支持!

相关文章

  • Java线程状态及切换、关闭线程的正确姿势分享

    Java线程状态及切换、关闭线程的正确姿势分享

    这篇文章主要给大家介绍了关于Java线程状态及切换、关闭线程的正确姿势,文中通过示例代码介绍的非常详细,对大家的学习或者使用Java具有一定的参考学习价值,需要的朋友们下面来一起学习学习吧
    2019-10-10
  • Map获取键值,Map的几种遍历方法总结(推荐)

    Map获取键值,Map的几种遍历方法总结(推荐)

    下面小编就为大家带来一篇Map获取键值,Map的几种遍历方法总结(推荐)。小编觉得挺不错的,现在就分享给大家,也给大家做个参考。一起跟随小编过来看看吧
    2017-04-04
  • 开发工具EesyCode使用方法解析

    开发工具EesyCode使用方法解析

    这篇文章主要介绍了开发工具EesyCode使用方法解析,文中通过示例代码介绍的非常详细,对大家的学习或者工作具有一定的参考学习价值,需要的朋友可以参考下
    2020-04-04
  • Spring Boot实战之模板引擎

    Spring Boot实战之模板引擎

    这篇文章主要介绍了Spring Boot实战之模板引擎,小编觉得挺不错的,现在分享给大家,也给大家做个参考。一起跟随小编过来看看吧
    2018-05-05
  • Elasticsearch mapping 概念及自动创建示例

    Elasticsearch mapping 概念及自动创建示例

    这篇文章主要为大家介绍了Elasticsearch mapping 概念及自动创建示例详解,有需要的朋友可以借鉴参考下,希望能够有所帮助,祝大家多多进步,早日升职加薪
    2022-11-11
  • Java中println输出汉字乱码问题一招解决方案

    Java中println输出汉字乱码问题一招解决方案

    这篇文章主要介绍了Java中println输出汉字乱码问题一招解决方案,具有很好的参考价值,希望对大家有所帮助。如有错误或未考虑完全的地方,望不吝赐教
    2022-12-12
  • SpringBoot集成Kafka的步骤

    SpringBoot集成Kafka的步骤

    这篇文章主要介绍了SpringBoot集成Kafka的步骤,帮助大家更好的理解和使用SpringBoot,感兴趣的朋友可以了解下
    2021-01-01
  • 基于java的opencv开发过程详解

    基于java的opencv开发过程详解

    这篇文章主要介绍了基于java的opencv开发过程详解,本文给大家介绍的非常详细,对大家的学习或工作具有一定的参考借鉴价值,需要的朋友可以参考下
    2021-04-04
  • 为何修改equals方法时还要重写hashcode方法的原因分析

    为何修改equals方法时还要重写hashcode方法的原因分析

    这篇文章主要介绍了为何修改equals方法时还要重写hashcode方法的原因分析,具有很好的参考价值,希望对大家有所帮助。如有错误或未考虑完全的地方,望不吝赐教
    2021-06-06
  • SpringBoot项目启动后再请求远程接口的解决方式

    SpringBoot项目启动后再请求远程接口的解决方式

    Spring Boot是由Pivotal团队提供的全新框架,其设计目的是用来简化Spring应用的创建、运行、调试、部署等,这篇文章主要介绍了SpringBoot项目启动后再请求远程接口的实现方式 ,需要的朋友可以参考下
    2023-02-02

最新评论