AspectJ切面执行两次原因分析
来源:互联网 发布:elasticsearch mysql 编辑:程序博客网 时间:2024/06/10 20:16
背景
转眼之间,发现博客已经将近半年没更新了,甚是惭愧。话不多说,正如标题所言,最近在使用AspectJ的时候,发现拦截器(AOP切面)执行了两次了。我们知道,AspectJ是AOP的一种解决方案,本质上是通过代理类在目标方法执行通知(Advice),然后由代理类再去调用目标方法。所以,从这点讲,拦截器应该只会执行一次。但是在测试的时候发现拦截器执行了两次。
问题重现
既然问题已经明了,那么可以通过代码简单重现这个问题,从而更深层次分析到底是什么原因导致的。
定义一个注解:
package com.rhwayfun.aspect;import java.lang.annotation.*;@Target({ElementType.METHOD})@Retention(RetentionPolicy.CLASS)@Documentedpublic @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;@Aspectpublic 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)")
。
重新运行上面的测试类,结果如下:
- AspectJ切面执行两次原因分析
- java 切面执行两次
- page_load执行两次的原因
- Java Web开发中,自定义过滤器被执行两次的原因分析及解决办法
- struts2 action执行两次的原因
- 任务调度---执行两次 问题原因总结
- Activity OnCreate执行两次的原因!
- spring定时任务执行两次的原因
- AspectJ Aop 面向切面
- 4.5 注入AspectJ切面
- aspectJ切面使用
- AspectJ(面向切面)
- 注入AspectJ切面
- tomcat--项目启动两次的原因分析
- Android onTouch 点击事件执行两次分析
- EditText的触摸事件 执行两次分析
- Android onTouch 点击事件执行两次分析
- Spring aspectJ切面使用步骤
- Maven手动添加依赖的jar文件到本地Maven仓库
- 深入PHP面向对象、模式与实践——高级特性(4)
- Spring整合Hibernate的方法
- 2. Add Two Numbers
- 学习日记-集合容器
- AspectJ切面执行两次原因分析
- android的UI和常用控件
- 第七十篇:从ADAS到自动驾驶(三):车道检测
- UVa 10976
- 数据结构-线性表
- JS中Float类型加减乘除
- 神经网络:多层网络与C++实现
- C# 控制台简单日历
- 软件测试——电话账单收费