Connection pool is shutdown periodically
didier2011 Dec 13, 2011 3:46 PMHi,
I've got some serious problems with the connection pooling, we've got a five hour test suite for user acceptance testing, every couple of minutes, it's not possible to get a managed connection. The pool is shutdown. Some minutes after the exception the pool seems to recover, till the next exception (sometimes after five minutes, sometimes after an hour)!
What's the problem? Is this a known problem on 7.1 Beta? What could cause this?
11:49:54,528 DEBUG [org.hibernate.hql.internal.ast.QueryTranslatorImpl] (ajp--127.0.0.1-21437-2) org.hibernate.internal.CoreMessageLogger_$logger
11:49:54,528 DEBUG [org.hibernate.hql.internal.ast.ErrorCounter] (ajp--127.0.0.1-21437-2) org.hibernate.internal.CoreMessageLogger_$logger
11:49:54,529 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (ajp--127.0.0.1-21437-2) org.hibernate.internal.CoreMessageLogger_$logger
11:49:54,529 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (JBossConnectionValidator) run: ConnectionValidator notifying pools, interval: 2
11:49:54,529 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (JBossConnectionValidator) my.ds.core-pool: emptyManagedConnectionPool(org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool@bcbf5c)
11:49:54,529 DEBUG [org.jboss.jca.core.connectionmanager.pool.validator.ConnectionValidator] (JBossConnectionValidator) internalUnregisterPool: setting interval to Long.MAX_VALUE
11:49:54,530 DEBUG [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (ajp--127.0.0.1-21437-2) my.ds.core-pool: getConnection(null, null) [1/75]
11:49:54,531 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (JBossConnectionValidator) run: ConnectionValidator notifying pools, interval: 2
11:49:54,533 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (JBossConnectionValidator) run: ConnectionValidator notifying pools, interval: 2
11:49:54,535 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (JBossConnectionValidator) run: ConnectionValidator notifying pools, interval: 2
11:49:54,537 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (JBossConnectionValidator) run: ConnectionValidator notifying pools, interval: 2
11:49:54,539 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (JBossConnectionValidator) run: ConnectionValidator notifying pools, interval: 2
11:49:54,541 DEBUG [org.jboss.jca.core.connectionmanager.pool.idle.IdleRemover] (JBossConnectionValidator) run: ConnectionValidator notifying pools, interval: 2
11:49:54,531 DEBUG [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (ajp--127.0.0.1-21437-2) Could not open connection [n/a]: java.sql.SQLException: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/my.ds.core
at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:137)
at org.hibernate.service.jdbc.connections.internal.DatasourceConnectionProviderImpl.getConnection(DatasourceConnectionProviderImpl.java:141) [hibernate-core-4.0.0.CR6.ja
r:4.0.0.CR6]
at org.hibernate.internal.AbstractSessionImpl$NonContextualJdbcConnectionAccess.obtainConnection(AbstractSessionImpl.java:276) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.obtainConnection(LogicalConnectionImpl.java:299) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.getConnection(LogicalConnectionImpl.java:169) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler.extractPhysicalConnection(ConnectionProxyHandler.java:82) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler.continueInvocation(ConnectionProxyHandler.java:138) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.engine.jdbc.internal.proxy.AbstractProxyHandler.invoke(AbstractProxyHandler.java:81) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at $Proxy81.prepareStatement(Unknown Source) at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$5.doPrepare(StatementPreparerImpl.java:149) [hibernate-core-4
.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$StatementPreparationTemplate.prepareStatement(StatementPreparerImpl.java:178) [hibernate-core-4.0.0.CR6.jar:4
.0.0.CR6]
at org.hibernate.engine.jdbc.internal.StatementPreparerImpl.prepareQueryStatement(StatementPreparerImpl.java:147) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.loader.Loader.prepareQueryStatement(Loader.java:1739) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.loader.Loader.doQuery(Loader.java:828) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:289) [hibernate-core-4.0.0.CR6.jar:4.0.0.CR6]
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
...
at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:151) [jboss-as-web-7.1.0.Alpha2-SNAPSHOT.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:362) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:505) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.coyote.ajp.AjpProtocol$AjpConnectionHandler.process(AjpProtocol.java:445) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930) [jbossweb-7.0.3.Final.jar:7.1.0.Alpha2-SNAPSHOT]
at java.lang.Thread.run(Thread.java:662) [:1.6.0_26]
Caused by: javax.resource.ResourceException: IJ000453: Unable to get managed connection for java:/my.ds.core
at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:375)
at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:367)
at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:448)
at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:129)
... 111 more
Caused by: javax.resource.spi.RetryableUnavailableException: The pool has been shutdown
at org.jboss.jca.core.connectionmanager.pool.mcp.SemaphoreArrayListManagedConnectionPool.getConnection(SemaphoreArrayListManagedConnectionPool.java:264)
at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getTransactionNewConnection(AbstractPool.java:492)
at org.jboss.jca.core.connectionmanager.pool.AbstractPool.getConnection(AbstractPool.java:373)
at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.getManagedConnection(AbstractConnectionManager.java:332)
... 114 more