来一手 AOP 注解方式进行日志记录
系统日志对于定位/排查问题的重要性不言而喻,相信许多开发和运维都深有体会。
通过日志追踪代码运行状况,模拟系统执行情况,并迅速定位代码/部署环境问题。
系统日志同样也是数据统计/建模的重要依据,通过分析系统日志能窥探出许多隐晦的内容。
如系统的健壮性(服务并发访问/数据库交互/整体响应时间...)
某位用户的喜好(分析用户操作习惯,推送对口内容...)
当然系统开发者还不满足于日志组件打印出来的日志,毕竟冗余且篇幅巨长。
so,对于关键的系统操作设计日志表,并在代码中进行操作的记录,配合 SQL 统计和搜索数据是件很愉快的事情。
本篇旨在总结在 Spring 下使用 AOP 注解方式进行日志记录的过程,如果能对你有所启发阁下不甚感激。
1. 依赖类库
<dependency> <groupId>org.aspectj</groupId> <artifactId>aspectjweaver</artifactId> <version>${aspectjweaver.version}</version> </dependency>
AspectJ 中的很多语法结构基本上已成为 AOP 领域的标准。
Spring 也有自己的 Spring-AOP,采用运行时生成代理类,底层可以选用 JDK 或者 CGLIB 动态代理。
通俗点,AspectJ 在编译时增强要切入的类,而 Spring-AOP 是在运行时通过代理类增强切入的类,效率和性能可想而知。
Spring 在 2.0 的时候就已经开始支持 AspectJ ,现在到 4.X 的时代已经很完美的和 AspectJ 拥抱到了一起。
开启扫描 AspectJ 注解的支持:
<!-- proxy-target-class等于true是强制使用cglib代理,proxy-target-class默认false,如果你的类实现了接口 就走JDK代理,如果没有,走cglib代理 --> <!-- 注:对于单利模式建议使用cglib代理,虽然JDK动态代理比cglib代理速度快,但性能不如cglib --> <aop:aspectj-autoproxy proxy-target-class="true"/>
2. 定义切入点日志注解
目标操作日志表,其中设计了一些必要的字段,具体字段请拿捏具体项目场景,根据表结构设计注解如下。
@Inherited @Target(ElementType.METHOD) @Retention(RetentionPolicy.RUNTIME) public @interface OperationLog { String operationModular() default ""; String operationContent() default ""; }
上述我只做了两个必要的参数,一个为操作的模块,一个为具体的操作内容。
其实根据项目场景这里参数的设计可以非常丰富,不被其他程序员吐槽在此一举。
3. 编写处理日志切点类
@Pointcut("@annotation(com.rambo.spm.common.aop.OperationLog)") public void operationLogAspect() { }
类的构造函数上描述了该类要拦截的为 OperationLog 的注解方法, 同样你也可以配置 XML 进行拦截。
切入点的姿势有很多,不仅是正则同样也支持组合表达式,强大的表达式能让你精准的切入到任何你想要的地方。
更多详情:http://blog.csdn.net/zhengchao1991/article/details/53391244
看到这里如果你对 Spring AOP 数据库事务控制熟悉,其实 Spring AOP 记录日志是相似的机制。
@Before("operationLogAspect()") public void doBefore(JoinPoint joinPoint) { logger.info("before aop:{}", joinPoint); //do something } @Around("operationLogAspect()") public Object doAround(ProceedingJoinPoint point) { logger.info("Around:{}", point); Object proceed = null; try { proceed = point.proceed(); //do somthing } catch (Throwable throwable) { throwable.printStackTrace(); logger.error("日志 aop 异常信息:{}", throwable.getMessage()); } return proceed; } @AfterThrowing("operationLogAspect()") public void doAfterThrowing(JoinPoint pjp) { logger.info("@After:{}", pjp); //do somthing } @After("operationLogAspect()") public void doAfter(JoinPoint pjp) { logger.info("@After:{}", pjp); } @AfterReturning("operationLogAspect()") public void doAfterReturning(JoinPoint point) { logger.info("@AfterReturning:{}", point); }
AspectJ 提供了几种通知方法,通过在方法上注解这几种通知,解析对应的方法入参,你就能洞悉切点的一切运行情况。
前置通知(@Before):在某连接点(join point)之前执行的通知,但这个通知不能阻止连接点前的执行(除非它抛出一个异常);
返回后通知(@AfterReturning):在某连接点(join point)正常完成后执行的通知:例如,一个方法没有抛出任何异常,正常返回;
抛出异常后通知(@AfterThrowing):方法抛出异常退出时执行的通知;
后通知(@After):当某连接点退出的时候执行的通知(不论是正常返回还是异常退出);
环绕通知(@Around):包围一个连接点(joinpoint)的通知,如方法调用;
通知方法中的值与构造函数一致,指定该通知对哪个切点有效,
上述 @Around 为最强大的一种通知类型,可以在方法调用前后完成自定义的行为,它可选择是否继续执行切点、直接返回、抛出异常来结束执行。
@Around 之所以如此强大是和它的入参有关,别的注解注解入参只容许 JoinPoint ,而 @Around 注解容许入参 ProceedingJoinPoint。
package org.aspectj.lang; import org.aspectj.runtime.internal.AroundClosure; public interface ProceedingJoinPoint extends JoinPoint { void set$AroundClosure(AroundClosure var1); Object proceed() throws Throwable; Object proceed(Object[] var1) throws Throwable; }
反编译 ProceedingJoinPoint 你会恍然大悟,Proceedingjoinpoint 继承了 JoinPoint 。
在 JoinPoint 的基础上暴露出 proceed 这个方法。proceed 方法很重要,这是 aop 代理链执行的方法。
暴露出这个方法,就能支持 aop:around 这种切面(而其他的几种切面只需要用到 JoinPoint,这跟切面类型有关), 能决定是否走代理链还是走自己拦截的其他逻辑。
如果项目没有特定的需求,妥善使用 @Around 注解就能帮你解决一切问题。
@Around("operationLogAspect()") public Object doAround(ProceedingJoinPoint point) { logger.info("Around:{}", point); Object proceed = null; try { proceed = point.proceed(); Object pointTarget = point.getTarget(); Signature pointSignature = point.getSignature(); String targetName = pointTarget.getClass().getName(); String methodName = pointSignature.getName(); Method method = pointTarget.getClass().getMethod(pointSignature.getName(), ((MethodSignature) pointSignature).getParameterTypes()); OperationLog methodAnnotation = method.getAnnotation(OperationLog.class); String operationModular = methodAnnotation.operationModular(); String operationContent = methodAnnotation.operationContent(); OperationLogPO log = new OperationLogPO(); log.setOperUserid(SecureUtil.simpleUUID()); log.setOperUserip(HttpUtil.getClientIP(getHttpReq())); log.setOperModular(operationModular); log.setOperContent(operationContent); log.setOperClass(targetName); log.setOperMethod(methodName); log.setOperTime(new Date()); log.setOperResult("Y"); operationLogService.insert(log); } catch (Throwable throwable) { throwable.printStackTrace(); logger.error("日志 aop 异常信息:{}", throwable.getMessage()); } return proceed; }
别忘记将上面切点处理类/和要切入的类托管给 Spring,Aop 日志是不是很简单,复杂的应该是 aspectj 内部实现机制,有机会要看看源码哦。
处理切点类完整代码:
@Aspect @Component public class OperationLogAspect { private static final Logger logger = LoggerFactory.getLogger(OperationLogAspect.class); //ProceedingJoinPoint 与 JoinPoint //注入Service用于把日志保存数据库 //这里我用resource注解,一般用的是@Autowired,他们的区别如有时间我会在后面的博客中来写 @Resource private OperationLogService operationLogService; //@Pointcut("execution (* com.rambo.spm.*.controller..*.*(..))") @Pointcut("@annotation(com.rambo.spm.common.aop.OperationLog)") public void operationLogAspect() { } @Before("operationLogAspect()") public void doBefore(JoinPoint joinPoint) { logger.info("before aop:{}", joinPoint); gePointMsg(joinPoint); } @Around("operationLogAspect()") public Object doAround(ProceedingJoinPoint point) { logger.info("Around:{}", point); Object proceed = null; try { proceed = point.proceed(); Object pointTarget = point.getTarget(); Signature pointSignature = point.getSignature(); String targetName = pointTarget.getClass().getName(); String methodName = pointSignature.getName(); Method method = pointTarget.getClass().getMethod(pointSignature.getName(), ((MethodSignature) pointSignature).getParameterTypes()); OperationLog methodAnnotation = method.getAnnotation(OperationLog.class); String operationModular = methodAnnotation.operationModular(); String operationContent = methodAnnotation.operationContent(); OperationLogPO log = new OperationLogPO(); log.setOperUserid(SecureUtil.simpleUUID()); log.setOperUserip(HttpUtil.getClientIP(getHttpReq())); log.setOperModular(operationModular); log.setOperContent(operationContent); log.setOperClass(targetName); log.setOperMethod(methodName); log.setOperTime(new Date()); log.setOperResult("Y"); operationLogService.insert(log); } catch (Throwable throwable) { throwable.printStackTrace(); logger.error("日志 aop 异常信息:{}", throwable.getMessage()); } return proceed; } @AfterThrowing("operationLogAspect()") public void doAfterThrowing(JoinPoint pjp) { logger.info("@AfterThrowing:{}", pjp); } @After("operationLogAspect()") public void doAfter(JoinPoint pjp) { logger.info("@After:{}", pjp); } @AfterReturning("operationLogAspect()") public void doAfterReturning(JoinPoint point) { logger.info("@AfterReturning:{}", point); } private void gePointMsg(JoinPoint joinPoint) { logger.info("切点所在位置:{}", joinPoint.toString()); logger.info("切点所在位置的简短信息:{}", joinPoint.toShortString()); logger.info("切点所在位置的全部信息:{}", joinPoint.toLongString()); logger.info("切点AOP代理对象:{}", joinPoint.getThis()); logger.info("切点目标对象:{}", joinPoint.getTarget()); logger.info("切点被通知方法参数列表:{}", joinPoint.getArgs()); logger.info("切点签名:{}", joinPoint.getSignature()); logger.info("切点方法所在类文件中位置:{}", joinPoint.getSourceLocation()); logger.info("切点类型:{}", joinPoint.getKind()); logger.info("切点静态部分:{}", joinPoint.getStaticPart()); } private HttpServletRequest getHttpReq() { RequestAttributes requestAttributes = RequestContextHolder.getRequestAttributes(); ServletRequestAttributes servletRequestAttributes = (ServletRequestAttributes) requestAttributes; return servletRequestAttributes.getRequest(); } }
上述三步骤之后,你就可以在想记录日志的方法上面添加注解来进行记录操作日志,像下面这样。
源码托管地址:https://git.oschina.net/LanboEx/spmvc-mybatis.git 有这方面需求和兴趣的可以检出到本地跑一跑。
作者:Orson
出处:http://www.cnblogs.com/java-class/
如果,您认为阅读这篇博客让您有些收获,不妨点击一下右下角的【推荐】
如果,您希望更容易地发现我的新博客,不妨点击一下左下角的【关注我】
如果,您对我的博客内容感兴趣,请继续关注我的后续博客,我是【Orson】
本文版权归作者和博客园共有,欢迎转载,但未经作者同意必须保留此段
声明,且在文章页面明显位置给出原文连接,否则保留追究法律责任的权利。