项目环境:
Java Web项目框架为Spring MVC+JPA,使用c3p0连接池,发布环境为Tomcat 7
错误描述:
项目运行一段时间(大概几个小时)之后访问时会出现第一次访问报错,再次访问正常的现象,且多次出现此问题。
报错日志:
- org.springframework.transaction.CannotCreateTransactionException: Could not open JPA EntityManager for transaction; nested exception is javax.persistence.PersistenceException: org.hibernate.TransactionException: JDBC begin transaction failed:
- at org.springframework.orm.jpa.JpaTransactionManager.doBegin(JpaTransactionManager.java:428)
- at org.springframework.transaction.support.AbstractPlatformTransactionManager.getTransaction(AbstractPlatformTransactionManager.java:372)
- at org.springframework.transaction.interceptor.TransactionAspectSupport.createTransactionIfNecessary(TransactionAspectSupport.java:417)
- at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:255)
- at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:94)
- at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
- at org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:631)
- at com.appcarcare.cube.service.UserServicea4429cba.getUserDao(<generated>)
-
- at com.appcarcare.cube.servlet.DataCenterServlet$SqlTimer.connectSql(DataCenterServlet.java:76)
- at com.appcarcare.cube.servlet.DataCenterServlet$SqlTimer.run(DataCenterServlet.java:70)
- at java.util.TimerThread.mainLoop(Timer.java:555)
- at java.util.TimerThread.run(Timer.java:505)
- Caused by: javax.persistence.PersistenceException: org.hibernate.TransactionException: JDBC begin transaction failed:
- at org.hibernate.ejb.AbstractEntityManagerImpl.convert(AbstractEntityManagerImpl.java:1387)
- at org.hibernate.ejb.AbstractEntityManagerImpl.convert(AbstractEntityManagerImpl.java:1310)
-
- at org.hibernate.ejb.AbstractEntityManagerImpl.throwPersistenceException(AbstractEntityManagerImpl.java:1397)
- at org.hibernate.ejb.TransactionImpl.begin(TransactionImpl.java:62)
- at org.springframework.orm.jpa.DefaultJpaDialect.beginTransaction(DefaultJpaDialect.java:71)
- at org.springframework.orm.jpa.vendor.HibernateJpaDialect.beginTransaction(HibernateJpaDialect.java:60)
- at org.springframework.orm.jpa.JpaTransactionManager.doBegin(JpaTransactionManager.java:378)
- ... 11 more
- Caused by: org.hibernate.TransactionException: JDBC begin transaction failed:
- at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doBegin(JdbcTransaction.java:76)
- at org.hibernate.engine.transaction.spi.AbstractTransactionImpl.begin(AbstractTransactionImpl.java:160)
-
- at org.hibernate.internal.SessionImpl.beginTransaction(SessionImpl.java:1426)
- at org.hibernate.ejb.TransactionImpl.begin(TransactionImpl.java:59)
- ... 14 more
- Caused by: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure
-
- The last packet successfully received from the server was 1,836,166 milliseconds ago. The last packet sent successfully to the server was 29,134 milliseconds ago.
- at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
- at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
- at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
- at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
- at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)
- at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1117)
- at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3567)
- at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3456)
-
- at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3997)
- at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2468)
- at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2629)
- at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2713)
- at com.mysql.jdbc.ConnectionImpl.setAutoCommit(ConnectionImpl.java:5060)
- at com.mchange.v2.c3p0.impl.NewProxyConnection.setAutoCommit(NewProxyConnection.java:881)
- at org.hibernate.engine.transaction.internal.jdbc.JdbcTransaction.doBegin(JdbcTransaction.java:72)
-
- ... 17 more
- Caused by: java.net.SocketException: Software caused connection abort: recv failed
- at java.net.SocketInputStream.socketRead0(Native Method)
- at java.net.SocketInputStream.read(SocketInputStream.java:150)
- at java.net.SocketInputStream.read(SocketInputStream.java:121)
- at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:114)
- at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:161)
- at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:189)
- at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:3014)
- at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3467)
- ... 25 more
原因分析:
MySQL服务器默认的“wait_timeout”是28800秒即8小时,意味着如果一个连接的空闲时间超过8个小时,MySQL将自动断开该连接,而连接池却认为该连接还是有效的(因为并未校验连接的有效性),当应用申请使用该连接时,就会导致上面的报错。
解决方案(解决这个问题的办法有三种,推荐第二种):
1. 增加 MySQL 的 wait_timeout 属性的值
修改mysql安装目录下的配置文件 my.ini文件(如果没有此文件,复制“my-default.ini”文件,生成“复件 my-default.ini”文件。将“复件 my-default.ini”文件重命名成“my.ini” ),在文件中设置:
- wait_timeout=31536000
- interactive_timeout=31536000
这两个参数的默认值是8小时(60*60*8=28800)。
注意: 1.wait_timeout的最大值只允许2147483 (24天左右)
2.修改配置文件为网上大部分文章所提供的方式,也可以使用mysql命令对这两个属性进行修改
2. 减少连接池内连接的生存周期
减少连接池内连接的生存周期,使之小于上一项中所设置的wait_timeout 的值。
修改 c3p0 的配置文件,在 Spring 的配置文件中设置:
- <bean id="dataSource" class="com.mchange.v2.c3p0.ComboPooledDataSource">
- <property name="maxIdleTime"value="1800"/>
- <!--other properties -->
- </bean>
3. 定期使用连接池内的连接
定期使用连接池内的连接,使得它们不会因为闲置超时而被 MySQL 断开。
修改 c3p0 的配置文件,在 Spring 的配置文件中设置:
- <bean id="dataSource" class="com.mchange.v2.c3p0.ComboPooledDataSource">
- <property name="preferredTestQuery" value="SELECT 1"/>
- <property name="idleConnectionTestPeriod" value="18000"/>
- <property name="testConnectionOnCheckout" value="true"/>
- </bean>
知识扩展C3P0
C3P0是一个开放源代码的JDBC连接池,它在lib目录中与Hibernate一起发布,包括了实现jdbc3和jdbc2扩展规范说明的Connection 和Statement 池的DataSources 对象。 c3p0配置文件
使用DBCP连接池时出现MySql 8小时断开连接的解决方法
修改l配置文件:
修改如下:
- <data-sources>
- <data-source key="org.apache.struts.action.DATA_SOURCE" type="org.apache.commons.dbcp.BasicDataSource">
- <set-property property="driverClassName" value="com.mysql.jdbc.Driver" />
- <set-property property="description" value="wjjg" />
- <set-property property="url" value="jdbc:mysql://localhost/wjjg?useUnicode=true&characterEncoding=GB2312" />
- <set-property property="password" value="12345678" />
- <set-property property="username" value="wjjg" />
- <set-property property="maxActive" value="10" />
- <set-property property="maxIdle" value="60000" />
- <set-property property="maxWait" value="60000" />
- <set-property property="defaultAutoCommit" value="true" />
- <set-property property="defaultReadOnly" value="false" />
- <set-property property="testOnBorrow" value="true"/>
- <set-property property="validationQuery" value="select 1"/>
- </data-source>
其中testOnBorrow 和 validationQuery 很重要。
testOnBorrow的意思是从数据库连接池中取得连接时,对其的有效性进行检查。
validationQuery 是用来检查的SQL语句,“select 1”执行较快,是一个不错的检测语句。
回顾
当测试人员反映到这个问题的时候,很快就锁定了Mysql八小时的问题,但是解决方案却费了我不小功夫,先是考虑到修改mysql配置文件肯定是不太合理的,弃之。
然后想了一下在一个servlet中写了个定时器,让它每两小时查一下数据库,运行了几天发现问题仍然存在,将定时器间隔时间修改为30分钟、3分钟仍然无济于事,异常照常出现,弃之。
在网上搜了一下解决方案也挺多,把
- <set-property property="testOnBorrow" value="true"/>
- <set-property property="validationQuery" value="select 1"/>
写入到配置文件中,运行报错,说bean中没有这两个属性,查看了一下原来是我用的c3p0连接池,而这个解决方案是针对DBCP连接池的。
最后在配置文件中,添加了
- <property name="maxIdleTime"value="1800"/>
成功解决了问题。
感悟,问题很简单,就是改个配置文件,但是遇到问题需要快速解决问题的能力,更需要认真的态度。