My Oracle Support Banner

Understanding and Troubleshooting Causes of Transaction Rollbacks and Timeouts (Doc ID 1929520.1)

Last updated on JULY 10, 2018

Applies to:

Oracle Commerce Platform - Version 2006.3 and later
Information in this document applies to any platform.

Symptoms

The following errors are known to occur when a transaction is timed out:

WebLogic

/atg/deployment/DeploymentManager Exception was thrown while deploying item. Transaction batch will be rolled back java.lang.RuntimeException: CONTAINER:atg.repository.RepositoryException; SOURCE:java.sql.SQLException: Unexpected exception while enlisting XAConnection java.sql.SQLException: Transaction rolled back: Transaction timed out after 1203 seconds
/atg/deployment/DeploymentManager BEA1-389C0D9B3312D32325D3
/atg/deployment/DeploymentManager at weblogic.jdbc.jta.DataSource.enlist(DataSource.java:1616)
/atg/deployment/DeploymentManager at weblogic.jdbc.jta.DataSource.refreshXAConnAndEnlist(DataSource.java:1503)
/atg/deployment/DeploymentManager at weblogic.jdbc.jta.DataSource.getConnection(DataSource.java:446)
/atg/deployment/DeploymentManager at weblogic.jdbc.jta.DataSource.connect(DataSource.java:403)
/atg/deployment/DeploymentManager at weblogic.jdbc.common.internal.RmiDataSource.getConnection(RmiDataSource.java:364)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSATransaction.getConnection(GSATransaction.java:732)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItemDescriptor.getConnection(GSAItemDescriptor.java:2364)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItemDescriptor.loadProperties(GSAItemDescriptor.java:5344)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItemDescriptor.loadProperty(GSAItemDescriptor.java:5470)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItem.getPersistentPropertyValue(GSAItem.java:1078)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItem.getPropertyValue(GSAItem.java:971)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItem.getItemTransactionState(GSAItem.java:2101)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItem.getItemTransactionState(GSAItem.java:1981)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItem.getItemTransactionStateUnchecked(GSAItem.java:2173)
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItem.getPropertyValue(GSAItem.java:1247)
/atg/deployment/DeploymentManager at atg.repository.RepositoryItemImpl.getPropertyValue(RepositoryItemImpl.java:128)
/atg/deployment/DeploymentManager at atg.deployment.DeploymentWorkerThread.processMarkerPhase(DeploymentWorkerThread.java:609)
/atg/deployment/DeploymentManager at atg.deployment.DeploymentWorkerThread.run(DeploymentWorkerThread.java:300)
/atg/deployment/DeploymentManager Caused by: weblogic.transaction.TimedOutException: Transaction timed out after 1203 seconds
/atg/deployment/DeploymentManager BEA1-389C0D9B3312D32325D3
/atg/deployment/DeploymentManager at weblogic.jdbc.jta.DataSource.enlist(DataSource.java:1614)
/atg/deployment/DeploymentManager ... 17 more
/atg/deployment/DeploymentManager
/atg/deployment/DeploymentManager at atg.adapter.gsa.GSAItemDescriptor.loadProperty(GSAItemDescriptor.java:5478)

JBoss

2014-08-13 15:50:49,058 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX ac87d43:a1bf:53ea4ef2:852f81 in state  RUN
2014-08-13 15:50:49,058 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-10) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_18] - TransactionReaper::check timeout for TX ac87d43:a1bf:53ea4ef2:852f83 in state  RUN
2014-08-13 15:50:49,061 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-11) [com.arjuna.ats.arjuna.coordinator.BasicAction_58] - Abort of action id ac87d43:a1bf:53ea4ef2:852f81 invoked while multiple threads active within it.
2014-08-13 15:50:49,063 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-11) [com.arjuna.ats.arjuna.coordinator.CheckedAction_2] - CheckedAction::check - atomic action ac87d43:a1bf:53ea4ef2:852f81 aborting with 1 threads active!
2014-08-13 15:50:49,156 ERROR [/atg/dynamo/messaging/SqlJmsProvider] (InputDestinationConsumer-sqldms:/Fulfillment/ModifyOrderNotification)
org.jboss.util.NestedSQLException: Transaction TransactionImple < ac, BasicAction: ac87d43:a1bf:53ea4ef2:852f81 status: ActionStatus.ABORTING > cannot proceed STATUS_ROLLING_BACK; - nested throwable: (javax.transaction.RollbackException: Transaction TransactionImple < ac, BasicAction: ac87d43:a1bf:53ea4ef2:852f81 status: ActionStatus.ABORTING > cannot proceed STATUS_ROLLING_BACK)
at org.jboss.resource.adapter.jdbc.WrapperDataSource.checkTransactionActive(WrapperDataSource.java:165)
at org.jboss.resource.adapter.jdbc.WrappedConnection.checkTransactionActive(WrappedConnection.java:834)
at org.jboss.resource.adapter.jdbc.WrappedConnection.checkStatus(WrappedConnection.java:849)
at org.jboss.resource.adapter.jdbc.WrappedConnection.checkTransaction(WrappedConnection.java:826)
at org.jboss.resource.adapter.jdbc.WrappedConnection.prepareStatement(WrappedConnection.java:241)
at atg.dms.sql.SqlTopicManager.deleteEntry(SqlTopicManager.java:1514)
at atg.dms.sql.Postman.beforeCompletion(Postman.java:157)
at com.arjuna.ats.internal.jta.resources.arjunacore.SynchronizationImple.beforeCompletion(SynchronizationImple.java:101)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.beforeCompletion(TwoPhaseCoordinator.java:269)
at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:89)
at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:160)
at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1431)
at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:137)
at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75)
at atg.dtm.TransactionManagerWrapper.commit(TransactionManagerWrapper.java:416)
at atg.dtm.TransactionDemarcation.end(TransactionDemarcation.java:529)
at atg.dtm.TransactionDemarcation.end(TransactionDemarcation.java:618)
at atg.dms.patchbay.InputDestinationConsumer.runXATransactions(InputDestinationConsumer.java:438)
at atg.dms.patchbay.InputDestinationConsumer.run(InputDestinationConsumer.java:223)
at java.lang.Thread.run(Thread.java:662)
Caused by: javax.transaction.RollbackException: Transaction TransactionImple < ac, BasicAction: ac87d43:a1bf:53ea4ef2:852f81 status: ActionStatus.ABORTING > cannot proceed STATUS_ROLLING_BACK
at org.jboss.resource.connectionmanager.TxConnectionManager.checkTransactionActive(TxConnectionManager.java:374)
at org.jboss.resource.connectionmanager.BaseConnectionManager2$ConnectionManagerProxy.checkTransactionActive(BaseConnectionManager2.java:972)
at org.jboss.resource.adapter.jdbc.WrapperDataSource.checkTransactionActive(WrapperDataSource.java:161)
... 19 more
2014-08-13 15:50:49,160 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (InputDestinationConsumer-sqldms:/Fulfillment/ModifyOrderNotification) [com.arjuna.ats.arjuna.coordinator.BasicAction_35] - End called illegally on atomic action ac87d43:a1bf:53ea4ef2:852f81
2014-08-13 15:50:49,171 WARN  [com.arjuna.ats.arjuna.logging.arjLoggerI18N] (Thread-11) [com.arjuna.ats.arjuna.coordinator.TransactionReaper_7] - TransactionReaper::doCancellations worker Thread[Thread-11,5,jboss] successfully canceled TX ac87d43:a1bf:53ea4ef2:852f81

WebSphere

[6/19/14 16:09:33:149 CDT] 00000007 TimeoutManage I   WTRN0006W: Transaction 00000146B5DB181A000000010007009222C3C3B5358310156CE0A2C11D5871A03BDF472800000146B5DB181A000000010007009222C3C3B5358310156CE0A2C11D5871A03BDF472800000001 has timed out after 1800 seconds.
[6/19/14 16:09:33:152 CDT] 00000007 TimeoutManage I   WTRN0124I: When the timeout occurred the thread with which the transaction is, or was most recently, associated was Thread[RepositoryWorkerThread-5(Reference Update Phase),5,DeploymentWorkerThreadGroup - Reference Update Phase]. The stack trace of this thread when the timeout occurred was:
       atg.adapter.gsa.ItemCache.getItem(ItemCache.java:641)
       atg.adapter.gsa.ItemCache.getItem(ItemCache.java:541)
       atg.adapter.gsa.GSAItemDescriptor.getCachedItem(GSAItemDescriptor.java:6498)
       atg.adapter.gsa.GSAItemDescriptor.getCachedItem(GSAItemDescriptor.java:6465)
       atg.adapter.gsa.GSAItemDescriptor.getItem(GSAItemDescriptor.java:3149)
       atg.adapter.gsa.GSARepository.getItem(GSARepository.java:353)
       atg.deployment.repository.RepositoryWorkerThread.deployItem(RepositoryWorkerThread.java:1096)
       atg.deployment.repository.RepositoryWorkerThread.processMarkerForReferenceUpdatePhase(RepositoryWorkerThread.java:362)
       atg.deployment.DeploymentWorkerThread.processMarkerPhase(DeploymentWorkerThread.java:568)
       atg.deployment.DeploymentWorkerThread.run(DeploymentWorkerThread.java:335)

If the same transaction ID is reported as being timed out over the course of several different errors, it is possible that you are running into a problem as described in <Document 1930429.1> - Transaction Is Not Active Errors Reported From Same Request Handling Thread.

Cause

To view full details, sign in with your My Oracle Support account.

Don't have a My Oracle Support account? Click to get started!


In this Document
Symptoms
 WebLogic
 JBoss
 WebSphere
Cause
Solution
 1. Gather Thread Dumps
 2. Analyze Thread Dumps
 3. Review Transaction Handling Code
 4. Review Verbose Garbage Collection (GC) Output
References


My Oracle Support provides customers with access to over a million knowledge articles and a vibrant support community of peers and Oracle experts.