使用Spring AOP来统计方法的执行时间

一、解决方案

1、传统方法

最简单、粗暴的方法是给各个需要统计的方法开始和结尾处加的时间戳，然后差值计算结果即可，代码如下：

[html] view plain copy

1. long startTime = System.currentTimeMillis();  
2.   
3. // 业务代码  
4.   
5. long endTime = System.currentTimeMillis();    
6. System.out.println("程序运行时间：" + (endTime - startTime) + "ms");    //输出程序运行时间    

这样的方式需要给很多统计方法都加上耗时时间的代码，这些代码与核心业务无关却大量重复、分散在各处，维护起来也困难。

2、面向切面编程的方法

所以，不推荐使用上面的代码。利用 spring AOP的思想来完成这个功能，代码和相关的解释如下：

[html] view plain copy

1. import org.apache.commons.logging.Log;    
2. import org.apache.commons.logging.LogFactory;    
3. import org.aspectj.lang.ProceedingJoinPoint;    
4. import org.aspectj.lang.annotation.Around;    
5. import org.aspectj.lang.annotation.Aspect;    
6. import org.aspectj.lang.reflect.MethodSignature;    
7. import org.springframework.stereotype.Component;  
8.   
9. /**  
10.  * 检测方法执行耗时的spring切面类  
11.  * 使用@Aspect注解的类，Spring将会把它当作一个特殊的Bean（一个切面），也就是不对这个类本身进行动态代理  
12.  * @author blinkfox  
13.  * @date 2016-07-04  
14.  */  
15. @Aspect  
16. @Component  
17. public class TimeInterceptor {  
18.   
19.     private static Log logger = LogFactory.getLog(TimeInterceptor.class);  
20.   
21.     // 一分钟，即60000ms  
22.     private static final long ONE_MINUTE = 60000;  
23.   
24.     // service层的统计耗时切面，类型必须为final String类型的,注解里要使用的变量只能是静态常量类型的  
25.     public static final String POINT = "execution (* com.blinkfox.test.service.impl.*.*(..))";  
26.   
27.     /**  
28.      * 统计方法执行耗时Around环绕通知  
29.      * @param joinPoint  
30.      * @return  
31.      */  
32.     @Around(POINT)  
33.     public Object timeAround(ProceedingJoinPoint joinPoint) {  
34.         // 定义返回对象、得到方法需要的参数  
35.         Object obj = null;  
36.         Object[] args = joinPoint.getArgs();  
37.         long startTime = System.currentTimeMillis();  
38.   
39.         try {  
40.             obj = joinPoint.proceed(args);  
41.         } catch (Throwable e) {  
42.             logger.error("统计某方法执行耗时环绕通知出错", e);  
43.         }  
44.   
45.         // 获取执行的方法名  
46.         long endTime = System.currentTimeMillis();  
47.         MethodSignature signature = (MethodSignature) joinPoint.getSignature();  
48.         String methodName = signature.getDeclaringTypeName() + "." + signature.getName();  
49.   
50.         // 打印耗时的信息  
51.         this.printExecTime(methodName, startTime, endTime);  
52.   
53.         return obj;  
54.     }  
55.   
56.     /**  
57.      * 打印方法执行耗时的信息，如果超过了一定的时间，才打印  
58.      * @param methodName  
59.      * @param startTime  
60.      * @param endTime  
61.      */  
62.     private void printExecTime(String methodName, long startTime, long endTime) {  
63.         long diffTime = endTime - startTime;  
64.         if (diffTime > ONE_MINUTE) {  
65.             logger.warn("-----" + methodName + " 方法执行耗时：" + diffTime + " ms");  
66.         }  
67.     }  
68.   
69. }  

<aop:aspectj-autoproxy proxy-target-class="true" />
    <bean id="logServiceMethodTimeAspectBean"
        class="com.dtn.system.framework.monitor.TimeInterceptor">
    </bean>
注意：最后还需要在 applicationContext.xml文件中加上AOP需要的配置 <aop:aspectj-autoproxy/>，这样Spring才能识别到它。