[esb-issues] [JBoss JIRA] Assigned: (JBESB-1712) Database already closed - jbpm persistence error on shutdown

Database already closed - jbpm persistence error on shutdown ------------------------------------------------------------ Key: JBESB-1712 URL: http://jira.jboss.com/jira/browse/JBESB-1712 Project: JBoss ESB Issue Type: Bug Security Level: Public(Everyone can see) Components: Process flow Affects Versions: 4.2.1 CP2 Reporter: Tom Cunningham Assigned To: Kevin Conner Fix For: 4.2.1 CP3 Using SOA Software as my registry, I'm seeing this upon shutdown on JBESB_4_2_1_GA_CP branch. It's reproducible 100% of the time, but I don't see it 13:03:37,969 INFO [AjpProtocol] Starting Coyote AJP/1.3 on ajp-127.0.0.1-8009 13:03:38,011 INFO [Server] JBoss (MX MicroKernel) [4.2.1.GA (build: SVNTag=JBoss_4_2_1_GA date=200707131605)] Started in 1m:12s:983ms ^C13:03:55,819 INFO [Server] Runtime shutdown hook called, forceHalt: true 13:03:55,819 INFO [Server] JBoss SHUTDOWN: Undeploying all packages 13:03:55,846 INFO [TopicService] Topic[/topic/org.jboss.soa.esb.transformation.Update] stopped 13:03:56,453 WARN [AbstractBatcher] exception clearing maxRows/queryTimeout org.h2.jdbc.JdbcSQLException: Database is already closed (to disable automatic closing at VM shutdown, add ";DB_CLOSE_ON_EXIT=FALSE" to the db URL) [90121-68] at org.h2.message.Message.getSQLException(Message.java:91) at org.h2.message.Message.getSQLException(Message.java:95) at org.h2.message.Message.getSQLException(Message.java:73) at org.h2.message.Message.getSQLException(Message.java:116) at org.h2.jdbc.JdbcConnection.checkClosed(JdbcConnection.java:1226) at org.h2.jdbc.JdbcStatement.checkClosed(JdbcStatement.java:826) at org.h2.jdbc.JdbcStatement.getMaxRows(JdbcStatement.java:342) at org.jboss.resource.adapter.jdbc.CachedPreparedStatement.getMaxRows(CachedPreparedStatement.java:369) at org.jboss.resource.adapter.jdbc.WrappedStatement.getMaxRows(WrappedStatement.java:267) at org.hibernate.jdbc.AbstractBatcher.closeQueryStatement(AbstractBatcher.java:276) at org.hibernate.jdbc.AbstractBatcher.closeQueryStatement(AbstractBatcher.java:212) at org.hibernate.loader.Loader.prepareQueryStatement(Loader.java:1583) at org.hibernate.loader.Loader.doQuery(Loader.java:673) at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:236) at org.hibernate.loader.Loader.doList(Loader.java:2220) at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2104) at org.hibernate.loader.Loader.list(Loader.java:2099) at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:378) at org.hibernate.hql.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:338) at org.hibernate.engine.query.HQLQueryPlan.performList(HQLQueryPlan.java:172) at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1121) at org.hibernate.impl.QueryImpl.list(QueryImpl.java:79) at org.hibernate.impl.AbstractQueryImpl.uniqueResult(AbstractQueryImpl.java:811) at org.jbpm.db.JobSession.getFirstAcquirableJob(JobSession.java:40) at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:114) at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:58) 13:03:56,463 WARN [JDBCExceptionReporter] SQL Error: 90121, SQLState: 90121 13:03:56,463 ERROR [JDBCExceptionReporter] Database is already closed (to disable automatic closing at VM shutdown, add ";DB_CLOSE_ON_EXIT=FALSE" to the db URL) [90121-68] 13:03:56,463 ERROR [JobSession] org.hibernate.exception.GenericJDBCException: could not execute query 13:03:56,486 ERROR [Services] problem closing service 'persistence' org.jbpm.JbpmException: couldn't commit JTA transaction at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:78) at org.jbpm.persistence.jta.JtaDbPersistenceService.close(JtaDbPersistenceService.java:37) at org.jbpm.svc.Services.close(Services.java:225) at org.jbpm.JbpmContext.close(JbpmContext.java:139) at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:144) at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:58) Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Can't commit because the transaction is in aborted state at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1394) at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:135) at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:87) at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.commit(ServerVMClientUserTransaction.java:140) at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:76) ... 5 more 13:03:56,489 ERROR [JobExecutorThread] exception in job executor thread. waiting 5000 milliseconds org.jbpm.JbpmException: problem closing services {persistence=org.jbpm.JbpmException: couldn't commit JTA transaction} at org.jbpm.svc.Services.close(Services.java:245) at org.jbpm.JbpmContext.close(JbpmContext.java:139) at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:144) at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:58) Caused by: org.jbpm.JbpmException: couldn't commit JTA transaction at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:78) at org.jbpm.persistence.jta.JtaDbPersistenceService.close(JtaDbPersistenceService.java:37) at org.jbpm.svc.Services.close(Services.java:225) ... 3 more Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Can't commit because the transaction is in aborted state at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1394) at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:135) at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:87) at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.commit(ServerVMClientUserTransaction.java:140) at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:76) ... 5 more 13:04:01,498 WARN [JDBCExceptionReporter] SQL Error: 90121, SQLState: 90121 13:04:01,498 ERROR [JDBCExceptionReporter] Database is already closed (to disable automatic closing at VM shutdown, add ";DB_CLOSE_ON_EXIT=FALSE" to the db URL) [90121-68] 13:04:01,498 ERROR [JobSession] org.hibernate.exception.GenericJDBCException: could not execute query 13:04:01,505 ERROR [Services] problem closing service 'persistence' org.jbpm.JbpmException: couldn't commit JTA transaction at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:78) at org.jbpm.persistence.jta.JtaDbPersistenceService.close(JtaDbPersistenceService.java:37) at org.jbpm.svc.Services.close(Services.java:225) at org.jbpm.JbpmContext.close(JbpmContext.java:139) at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:144) at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:58) Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Can't commit because the transaction is in aborted state at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1394) at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:135) at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:87) at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.commit(ServerVMClientUserTransaction.java:140) at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:76) ... 5 more 13:04:01,505 ERROR [JobExecutorThread] exception in job executor thread. waiting 10000 milliseconds org.jbpm.JbpmException: problem closing services {persistence=org.jbpm.JbpmException: couldn't commit JTA transaction} at org.jbpm.svc.Services.close(Services.java:245) at org.jbpm.JbpmContext.close(JbpmContext.java:139) at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:144) at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:58) Caused by: org.jbpm.JbpmException: couldn't commit JTA transaction at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:78) at org.jbpm.persistence.jta.JtaDbPersistenceService.close(JtaDbPersistenceService.java:37) at org.jbpm.svc.Services.close(Services.java:225) ... 3 more Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Can't commit because the transaction is in aborted state at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1394) at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:135) at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:87) at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.commit(ServerVMClientUserTransaction.java:140) at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:76) ... 5 more 13:04:11,511 WARN [JDBCExceptionReporter] SQL Error: 90121, SQLState: 90121 13:04:11,511 ERROR [JDBCExceptionReporter] Database is already closed (to disable automatic closing at VM shutdown, add ";DB_CLOSE_ON_EXIT=FALSE" to the db URL) [90121-68] 13:04:11,511 ERROR [JobSession] org.hibernate.exception.GenericJDBCException: could not execute query 13:04:11,515 ERROR [Services] problem closing service 'persistence' org.jbpm.JbpmException: couldn't commit JTA transaction at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:78) at org.jbpm.persistence.jta.JtaDbPersistenceService.close(JtaDbPersistenceService.java:37) at org.jbpm.svc.Services.close(Services.java:225) at org.jbpm.JbpmContext.close(JbpmContext.java:139) at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:144) at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:58) Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Can't commit because the transaction is in aborted state at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1394) at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:135) at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:87) at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.commit(ServerVMClientUserTransaction.java:140) at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:76) ... 5 more 13:04:11,516 ERROR [JobExecutorThread] exception in job executor thread. waiting 20000 milliseconds org.jbpm.JbpmException: problem closing services {persistence=org.jbpm.JbpmException: couldn't commit JTA transaction} at org.jbpm.svc.Services.close(Services.java:245) at org.jbpm.JbpmContext.close(JbpmContext.java:139) at org.jbpm.job.executor.JobExecutorThread.acquireJobs(JobExecutorThread.java:144) at org.jbpm.job.executor.JobExecutorThread.run(JobExecutorThread.java:58) Caused by: org.jbpm.JbpmException: couldn't commit JTA transaction at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:78) at org.jbpm.persistence.jta.JtaDbPersistenceService.close(JtaDbPersistenceService.java:37) at org.jbpm.svc.Services.close(Services.java:225) ... 3 more Caused by: javax.transaction.RollbackException: [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] [com.arjuna.ats.internal.jta.transaction.arjunacore.commitwhenaborted] Can't commit because the transaction is in aborted state at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1394) at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:135) at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:87) at org.jboss.tm.usertx.client.ServerVMClientUserTransaction.commit(ServerVMClientUserTransaction.java:140) at org.jbpm.persistence.jta.JtaDbPersistenceService.endJtaTransaction(JtaDbPersistenceService.java:76) ... 5 more 13:04:21,292 INFO [QueueService] Queue[/queue/quickstart_scripting_groovy_esb] stopped 13:04:21,293 INFO [QueueService] Queue[/queue/quickstart_scripting_groovy_gw] stopped 13:04:21,301 INFO [TomcatDeployer] undeploy, ctxPath=/jbossesb, warUrl=.../tmp/deploy/tmp3840management.esb-contents/jbossesb-exp.war/