MyBatis的设计思想很简单,可以看做是对JDBC的一次封装,并提供强大的动态SQL映射功能。但是由于它本身也有一些缓存、事务管理等功能,所以实际使用中还是会碰到一些问题——另外,最近接触了JFinal,其思想和Hibernate类似,但要更简洁,和MyBatis的设计思想不同,但有一点相同:都是想通过简洁的设计最大限度地简化开发和提升性能——说到性能,前段时间碰到两个问题:
在一个上层方法(DAO方法的上层)内删除一条记录,然后再插入一条相同主键的记录时,会报主键冲突的错误。
某些项目中的DAO方法平均执行时间会是其他一些项目中的 2倍 。
第一个问题是偶尔会出现,在实验环境无论如何也重现不了,经过分析MyBatis的逻辑,估计是两个DAO分别拿到了两个不同的Connection,第二个语句比第一个更早的被提交,导致了主键冲突,有待进一步的分析和验证。对于第二个问题,本文将尝试通过分析源代码和实验找到它的root cause,主要涉及到以下内容:
问题描述与分析
MyBatis在Spring环境下的载入过程
MyBatis在Spring环境下事务的管理
实验验证
项目环境整个系统是微服务架构,这里讨论的「项目」是指一个单独的服务。单个项目的框架基本是Spring+MyBatis,具体版本如下:
Spring 3.2.9/4.3.5 + Mybatis 3.2.6 + mybatis-spring 1.2.2 + mysql connector 5.1.20 + commons-dbcp 1.4
与MyBatis和事务相关的配置如下:
//代码1 <!-- bean#1--> <bean destroy-method="close"> <!-- 一些数据库信息配置--> <!-- 一些DBCP连接池配置 --> //在这里设置是否自动提交 <property value="${dbcp.defaultAutoCommit}" /> </bean> <!-- bean#2--> <bean> <property ref="dataSource" /> <property value="classpath*:path/to/mapper/**/*.xml" /> </bean> <!-- bean#3 --> <bean> <property ref="dataSource" /> </bean> <!-- bean#4--> <bean> <property value=".path.to.mapper" /> <property value="sqlSessionFactory"/> </bean> <!-- bean5 --> <tx:annotation-driven transaction-manager="transactionManager" /> 问题描述与分析一倍的时间差挺严重的,平均到每次调用,正常的大约在6到10几 ms,慢的要近20 ms,由于调用次数很多,导致整体性能会有很大的差别。经过仔细比对这几个项目,发现DAO执行慢的项目的数据源配置(bean#1)中 defaultAutoCommit的配置都是 false。而且将此配置改为 true之后就恢复了正常。
由此推断是在MyBatis在执行「非自动提交」语句时,进行等待,或者多提交了一次,导致实际调用数据库API次数增多。但是这个推断也有个问题,由于整个项目是在Spring环境中运行的,而且也开启了Spring的事务管理,所以还是需要详细的看一下MyBatis到底是如何装配DAO方法与管理事务的,才能彻底解开谜团。
问题重现首先写一个Service,其中调用了同一个mapper类的两个方法分别2次, insertModelList()会在数据库中插入两条记录, delModels()方法会删除这两条记录,代码如下:
//代码2 //@Transactional public void testIS(){ List<Model> models= new ArrayList<>(); //省略一些数据工作。。。 modelMapper.insertModelList(50001l, models); modelMapper.delModels(50001); if (CollectionUtils.isNotEmpty(models)) modelMapper.insertModelList(50001, models); modelMapper.delModels(50001); } public void testOther(){ System.out.println("加载类:"); System.out.println(modelMapper.getClass().getClassLoader()); modelMapper.delModels(50001); }实际项目中使用cat来进行执行时间的统计,这里也仿照cat,使用一个单独的AOP类实现时间的计算:
//代码3 public class DaoTimeAdvice { private long time = 0; private long num = 0; public Object calcTime(ProceedingJoinPoint joinPoint) throws Throwable { long then = System.nanoTime(); Object object = joinPoint.proceed(); long now = System.nanoTime(); setTime(getTime() + (now-then)); setNum(getNum() + 1); return object; } //省略getter & setter。。。 public void printInfo() { System.out.println("总共次数:" + num); System.out.println("总共时间:" + time); System.out.println("平均时间:" + time / num); } }测试代码:
//代码4 public static void test(){ System.out.println(new SimpleDateFormat("[yyyy-MM-dd HH:mm:ss]").format(new Date()) + " 开始测试!"); for (int i = 0; i < TEST_NUM; i++) { ItemStrategyServiceTest ist = (ItemStrategyServiceTest) context.getBean("isTS"); ist.testIS(); if (i % 1000 == 0) { System.out.println("1000次"); } } DaoTimeAdvice ad = (DaoTimeAdvice) context.getBean("daoTimeAdvice"); ad.printInfo(); ItemStrategyServiceTest ist = (ItemStrategyServiceTest) context.getBean("isTS"); ist.testOther(); System.exit(1); }