Jboss 4.2.3 and org.jboss.util.NestedSQLException: Transaction TransactionImple < ac, BasicAction: status: ActionStatus.COMMITTED > cannot proceed STATUS_COMMITTED
zenzei2k Jun 6, 2011 4:48 PMHi! I'm using jboss 4.2.3 with Hibernate 3.2.4.sp1 and spring 2.5.6, and sometimes in my hibernate.log I see this kind of trace. This trace is not logged as ERROR but it doesn't look good to me so I don't know if it's an error that should be discarded, or there is something I could do to trace it better, cause it didn't generate any other stacktrace in server.log or my application.log.
Sorry I don't give any more info but I don't know what else can I tell about it.
2011-06-01 12:13:49,406 2185125 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Timer-2:) about to open PreparedStatement (open PreparedStatements: 0, globally: 1) 2011-06-01 12:13:49,406 2185125 DEBUG [org.hibernate.SQL] (Timer-2:) update Jugada set estado=?, idTipoSerie=?, identificador=?, idTipoJuego=?, idCartonHasta=?, desde=?, hasta=?, datos=?, fechaComienzo=?, fechaFin=?, idCartonDesde=?, recaudacion=?, pagos=? where idJugada=? 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding '1' to parameter: 1 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding '1' to parameter: 2 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding '3' to parameter: 3 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding '27' to parameter: 4 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding '114955' to parameter: 5 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding null to parameter: 6 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding null to parameter: 7 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.StringType] (Timer-2:) binding '' to parameter: 8 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.TimestampType] (Timer-2:) binding '2011-06-01 12:14:03' to parameter: 9 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.TimestampType] (Timer-2:) binding null to parameter: 10 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.IntegerType] (Timer-2:) binding '114955' to parameter: 11 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.DoubleType] (Timer-2:) binding '0.0' to parameter: 12 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.DoubleType] (Timer-2:) binding '0.0' to parameter: 13 2011-06-01 12:13:49,406 2185125 TRACE [org.hibernate.type.LongType] (Timer-2:) binding '18444' to parameter: 14 2011-06-01 12:13:49,406 2185125 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Timer-2:) Executing batch size: 1 2011-06-01 12:13:49,421 2185140 DEBUG [org.hibernate.jdbc.AbstractBatcher] (Timer-2:) about to close PreparedStatement (open PreparedStatements: 1, globally: 3) 2011-06-01 12:13:49,421 2185140 DEBUG [org.hibernate.jdbc.ConnectionManager] (Timer-2:) skipping aggressive-release due to flush cycle 2011-06-01 12:13:49,421 2185140 DEBUG [org.hibernate.jdbc.ConnectionManager] (Timer-2:) skipping aggresive-release due to borrowed connection 2011-06-01 12:13:49,437 2185156 DEBUG [org.hibernate.jdbc.ConnectionManager] (Timer-2:) releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)] 2011-06-01 12:13:49,437 2185156 DEBUG [org.hibernate.util.JDBCExceptionReporter] (Timer-2:) could not log warnings org.jboss.util.NestedSQLException: Transaction TransactionImple < ac, BasicAction: -3f57ebd8:905:4de64ea6:4b08 status: ActionStatus.COMMITTED > cannot proceed STATUS_COMMITTED; - nested throwable: (javax.transaction.RollbackException: Transaction TransactionImple < ac, BasicAction: -3f57ebd8:905:4de64ea6:4b08 status: ActionStatus.COMMITTED > cannot proceed STATUS_COMMITTED) at org.jboss.resource.adapter.jdbc.WrapperDataSource.checkTransactionActive(WrapperDataSource.java:165) at org.jboss.resource.adapter.jdbc.WrappedConnection.checkTransactionActive(WrappedConnection.java:833) at org.jboss.resource.adapter.jdbc.WrappedConnection.checkStatus(WrappedConnection.java:848) at org.jboss.resource.adapter.jdbc.WrappedConnection.checkTransaction(WrappedConnection.java:825) at org.jboss.resource.adapter.jdbc.WrappedConnection.getWarnings(WrappedConnection.java:618) at org.hibernate.util.JDBCExceptionReporter.logAndClearWarnings(JDBCExceptionReporter.java:22) at org.hibernate.jdbc.ConnectionManager.closeConnection(ConnectionManager.java:449) at org.hibernate.jdbc.ConnectionManager.cleanup(ConnectionManager.java:385) at org.hibernate.jdbc.ConnectionManager.close(ConnectionManager.java:324) at org.hibernate.impl.SessionImpl.close(SessionImpl.java:298) at org.hibernate.impl.SessionImpl.managedClose(SessionImpl.java:354) at org.hibernate.transaction.CacheSynchronization.afterCompletion(CacheSynchronization.java:90) at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.afterCompletion(SynchronizationImple.java:136) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.afterCompletion(TwoPhaseCoordinator.java:340) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:93) at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:177) at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1389) 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.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:175) at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:87) at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:191) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:95) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:62) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77) at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:110) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.stateless.StatelessContainer.dynamicInvoke(StatelessContainer.java:304) at org.jboss.ejb3.remoting.IsLocalInterceptor.invokeLocal(IsLocalInterceptor.java:81) at org.jboss.ejb3.remoting.IsLocalInterceptor.invoke(IsLocalInterceptor.java:72) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.stateless.StatelessRemoteProxy.invoke(StatelessRemoteProxy.java:107) at $Proxy131.perform(Unknown Source) at sun.reflect.GeneratedMethodAccessor102.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.springframework.remoting.rmi.RmiClientInterceptorUtils.invokeRemoteMethod(RmiClientInterceptorUtils.java:108) at org.springframework.ejb.access.SimpleRemoteSlsbInvokerInterceptor.doInvoke(SimpleRemoteSlsbInvokerInterceptor.java:98) at org.springframework.ejb.access.AbstractRemoteSlsbInvokerInterceptor.invokeInContext(AbstractRemoteSlsbInvokerInterceptor.java:137) at org.springframework.ejb.access.AbstractSlsbInvokerInterceptor.invoke(AbstractSlsbInvokerInterceptor.java:188) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at $Proxy162.perform(Unknown Source) at com.server.scheduler.TestScheduler.perform(TestScheduler.java:42) at org.jboss.varia.scheduler.Scheduler$Listener.handleNotification(Scheduler.java:1251) at sun.reflect.GeneratedMethodAccessor5.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.jboss.mx.notification.NotificationListenerProxy.invoke(NotificationListenerProxy.java:153) at $Proxy11.handleNotification(Unknown Source) at javax.management.NotificationBroadcasterSupport.handleNotification(NotificationBroadcasterSupport.java:257) at javax.management.NotificationBroadcasterSupport$SendNotifJob.run(NotificationBroadcasterSupport.java:322) at javax.management.NotificationBroadcasterSupport$1.execute(NotificationBroadcasterSupport.java:307) at javax.management.NotificationBroadcasterSupport.sendNotification(NotificationBroadcasterSupport.java:229) at javax.management.timer.Timer.sendNotification(Timer.java:1237) at javax.management.timer.Timer.notifyAlarmClock(Timer.java:1206) at javax.management.timer.TimerAlarmClock.run(Timer.java:1289) at java.util.TimerThread.mainLoop(Timer.java:512) at java.util.TimerThread.run(Timer.java:462) Caused by: javax.transaction.RollbackException: Transaction TransactionImple < ac, BasicAction: -3f57ebd8:905:4de64ea6:4b08 status: ActionStatus.COMMITTED > cannot proceed STATUS_COMMITTED at org.jboss.resource.connectionmanager.TxConnectionManager.checkTransactionActive(TxConnectionManager.java:282) at org.jboss.resource.connectionmanager.BaseConnectionManager2$ConnectionManagerProxy.checkTransactionActive(BaseConnectionManager2.java:880) at org.jboss.resource.adapter.jdbc.WrapperDataSource.checkTransactionActive(WrapperDataSource.java:161) ... 65 more 2011-06-01 12:13:49,453 2185172 DEBUG [org.hibernate.util.JDBCExceptionReporter] (Timer-2:) could not clear warnings org.jboss.util.NestedSQLException: Transaction TransactionImple < ac, BasicAction: -3f57ebd8:905:4de64ea6:4b08 status: ActionStatus.COMMITTED > cannot proceed STATUS_COMMITTED; - nested throwable: (javax.transaction.RollbackException: Transaction TransactionImple < ac, BasicAction: -3f57ebd8:905:4de64ea6:4b08 status: ActionStatus.COMMITTED > cannot proceed STATUS_COMMITTED) at org.jboss.resource.adapter.jdbc.WrapperDataSource.checkTransactionActive(WrapperDataSource.java:165) at org.jboss.resource.adapter.jdbc.WrappedConnection.checkTransactionActive(WrappedConnection.java:833) at org.jboss.resource.adapter.jdbc.WrappedConnection.checkStatus(WrappedConnection.java:848) at org.jboss.resource.adapter.jdbc.WrappedConnection.checkTransaction(WrappedConnection.java:825) at org.jboss.resource.adapter.jdbc.WrappedConnection.clearWarnings(WrappedConnection.java:639) at org.hibernate.util.JDBCExceptionReporter.logAndClearWarnings(JDBCExceptionReporter.java:31) at org.hibernate.jdbc.ConnectionManager.closeConnection(ConnectionManager.java:449) at org.hibernate.jdbc.ConnectionManager.cleanup(ConnectionManager.java:385) at org.hibernate.jdbc.ConnectionManager.close(ConnectionManager.java:324) at org.hibernate.impl.SessionImpl.close(SessionImpl.java:298) at org.hibernate.impl.SessionImpl.managedClose(SessionImpl.java:354) at org.hibernate.transaction.CacheSynchronization.afterCompletion(CacheSynchronization.java:90) at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.afterCompletion(SynchronizationImple.java:136) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.afterCompletion(TwoPhaseCoordinator.java:340) at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:93) at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:177) at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1389) 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.aspects.tx.TxPolicy.endTransaction(TxPolicy.java:175) at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:87) at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:191) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:95) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:62) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.aspects.security.AuthenticationInterceptor.invoke(AuthenticationInterceptor.java:77) at org.jboss.ejb3.security.Ejb3AuthenticationInterceptor.invoke(Ejb3AuthenticationInterceptor.java:110) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.stateless.StatelessContainer.dynamicInvoke(StatelessContainer.java:304) at org.jboss.ejb3.remoting.IsLocalInterceptor.invokeLocal(IsLocalInterceptor.java:81) at org.jboss.ejb3.remoting.IsLocalInterceptor.invoke(IsLocalInterceptor.java:72) at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101) at org.jboss.ejb3.stateless.StatelessRemoteProxy.invoke(StatelessRemoteProxy.java:107) at $Proxy131.perform(Unknown Source) at sun.reflect.GeneratedMethodAccessor102.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.springframework.remoting.rmi.RmiClientInterceptorUtils.invokeRemoteMethod(RmiClientInterceptorUtils.java:108) at org.springframework.ejb.access.SimpleRemoteSlsbInvokerInterceptor.doInvoke(SimpleRemoteSlsbInvokerInterceptor.java:98) at org.springframework.ejb.access.AbstractRemoteSlsbInvokerInterceptor.invokeInContext(AbstractRemoteSlsbInvokerInterceptor.java:137) at org.springframework.ejb.access.AbstractSlsbInvokerInterceptor.invoke(AbstractSlsbInvokerInterceptor.java:188) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at $Proxy162.perform(Unknown Source) at server.scheduler.TestScheduler.perform(TestScheduler.java:42) at org.jboss.varia.scheduler.Scheduler$Listener.handleNotification(Scheduler.java:1251) at sun.reflect.GeneratedMethodAccessor5.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.jboss.mx.notification.NotificationListenerProxy.invoke(NotificationListenerProxy.java:153) at $Proxy11.handleNotification(Unknown Source) at javax.management.NotificationBroadcasterSupport.handleNotification(NotificationBroadcasterSupport.java:257) at javax.management.NotificationBroadcasterSupport$SendNotifJob.run(NotificationBroadcasterSupport.java:322) at javax.management.NotificationBroadcasterSupport$1.execute(NotificationBroadcasterSupport.java:307) at javax.management.NotificationBroadcasterSupport.sendNotification(NotificationBroadcasterSupport.java:229) at javax.management.timer.Timer.sendNotification(Timer.java:1237) at javax.management.timer.Timer.notifyAlarmClock(Timer.java:1206) at javax.management.timer.TimerAlarmClock.run(Timer.java:1289) at java.util.TimerThread.mainLoop(Timer.java:512) at java.util.TimerThread.run(Timer.java:462) Caused by: javax.transaction.RollbackException: Transaction TransactionImple < ac, BasicAction: -3f57ebd8:905:4de64ea6:4b08 status: ActionStatus.COMMITTED > cannot proceed STATUS_COMMITTED at org.jboss.resource.connectionmanager.TxConnectionManager.checkTransactionActive(TxConnectionManager.java:282) at org.jboss.resource.connectionmanager.BaseConnectionManager2$ConnectionManagerProxy.checkTransactionActive(BaseConnectionManager2.java:880) at org.jboss.resource.adapter.jdbc.WrapperDataSource.checkTransactionActive(WrapperDataSource.java:161) ... 65 more
Thanks in advance!