« Return to Thread: OracleXAException - XID is not valid

OracleXAException - XID is not valid

by greenbean :: Rate this Message:

| View in Thread

Some parts of this message have been removed. Learn more about Nabble's security policy.
We are getting an OracleXAException when writing to Oracle 10g from a web service.  It only seems to occur when we execute the web service multiple times in a row (the client iterates a list of values, executing the web service for each).
 
We added in some additional logging following the example here:  http://jira.codehaus.org/browse/BTM-98
 
We get Oracle error 24756 (the xid is not valid).  Does anyone know why this would occurr?   Attached are logs entries from BitronixTransaction during the problem.  In other cases, the same code is executed, and does not have problem with the commit.
 
2011-10-06 15:10:51,950 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The XA Error is :-4
2011-10-06 15:10:51,958 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The XA Error message is :The XID is not valid.
2011-10-06 15:10:51,969 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The Oracle Error code is :24756
2011-10-06 15:10:51,974 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The Oracle SQL Error code is :0
2011-10-06 15:10:51,977 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) OracleXAException cause:
2011-10-06 15:10:52,002 (737072696E672D62746D0000000471270B2F00000691) ERROR [bitronix.tm.twopc.AbstractPhaseEngine] (http-0.0.0.0-8080-27) resource oracle failed on a Bitronix XID [737072696E672D62746D0000000471270B2F00000691 : 737072696E672D62746D0000000471270B7600000693]: bitronix.tm.internal.BitronixXAException: unknown heuristic termination, global state of this transaction is unknown - guilty: an XAResourceHolderState with uniqueName=oracle XAResource=oracle.jdbc.driver.T4CXAResource@3c332d5b (ended) with XID a Bitronix XID 737072696E672D62746D0000000471270B2F00000691 : 737072696E672D62746D0000000471270B7600000693]
 
Caused by: oracle.jdbc.xa.OracleXAException
        at oracle.jdbc.xa.OracleXAResource.checkError(OracleXAResource.java:1040)
        at oracle.jdbc.xa.client.OracleXAResource.commit(OracleXAResource.java:573)
        at bitronix.tm.twopc.Committer$CommitJob.commitResource(Committer.java:194)
        ... 60 more
 
 

2011-10-06 15:10:51,318 () DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) creating new transaction with GTRID [737072696E672D62746D0000000471270B2F00000691]\par
2011-10-06 15:10:51,323 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) changing transaction status to ACTIVE\par
2011-10-06 15:10:51,329 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) transaction status is changing from NO_TRANSACTION to ACTIVE - executing 0 listener(s)\par
2011-10-06 15:10:51,335 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) registering synchronization org.hibernate.transaction.synchronization.HibernateSynchronizationImpl@6df13522\par
2011-10-06 15:10:51,357 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) registering synchronization org.hibernate.transaction.synchronization.HibernateSynchronizationImpl@4f898cfc\par
2011-10-06 15:10:51,662 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) before completion, 6 synchronization(s) to execute\par
2011-10-06 15:10:51,667 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) executing synchronization a DeferredReleaseSynchronization of a JdbcPooledConnection from datasource postgres in state NOT_ACCESSIBLE with usage count 0 wrapping org.postgresql.xa.PGXAConnection@1a6f0570\par
2011-10-06 15:10:51,669 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) executing synchronization a DeferredReleaseSynchronization of a JdbcPooledConnection from datasource oracle in state NOT_ACCESSIBLE with usage count 0 wrapping oracle.jdbc.driver.T4CXAConnection@1d38c0f8\par
2011-10-06 15:10:51,671 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) executing synchronization a DeferredReleaseSynchronization of a JdbcPooledConnection from datasource postgres in state NOT_ACCESSIBLE with usage count 0 wrapping org.postgresql.xa.PGXAConnection@22a960f4\par
2011-10-06 15:10:51,674 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) executing synchronization a ClearContextSynchronization for a Bitronix Transaction with GTRID [737072696E672D62746D0000000471270B2F00000691], status=ACTIVE, 3 resource(s) enlisted (started Sun Aug 09 19:30:53 GMT 1970)\par
2011-10-06 15:10:51,676 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) executing synchronization org.hibernate.transaction.synchronization.HibernateSynchronizationImpl@6df13522\par
2011-10-06 15:10:51,679 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) executing synchronization org.hibernate.transaction.synchronization.HibernateSynchronizationImpl@4f898cfc\par
2011-10-06 15:10:51,682 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) found unclosed resource to delist: an XAResourceHolderState with uniqueName=postgres XAResource=org.postgresql.xa.PGXAConnection@22a960f4 (started) with XID a Bitronix XID [737072696E672D62746D0000000471270B2F00000691 : 737072696E672D62746D0000000471270B4A00000692]\par
2011-10-06 15:10:51,684 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) found unclosed resource to delist: an XAResourceHolderState with uniqueName=oracle XAResource=oracle.jdbc.driver.T4CXAResource@3c332d5b (started) with XID a Bitronix XID [737072696E672D62746D0000000471270B2F00000691 : 737072696E672D62746D0000000471270B7600000693]\par
2011-10-06 15:10:51,691 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) found unclosed resource to delist: an XAResourceHolderState with uniqueName=postgres XAResource=org.postgresql.xa.PGXAConnection@1a6f0570 (started) with XID a Bitronix XID [737072696E672D62746D0000000471270B2F00000691 : 737072696E672D62746D0000000471270C0100000694]\par
2011-10-06 15:10:51,695 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) committing, 3 enlisted resource(s)\par
2011-10-06 15:10:51,697 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) changing transaction status to PREPARING\par
2011-10-06 15:10:51,701 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) transaction status is changing from ACTIVE to PREPARING - executing 0 listener(s)\par
2011-10-06 15:10:51,840 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) changing transaction status to PREPARED\par
2011-10-06 15:10:51,840 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) transaction status is changing from PREPARING to PREPARED - executing 0 listener(s)\par
2011-10-06 15:10:51,840 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) 3 interested resource(s)\par
2011-10-06 15:10:51,846 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) changing transactionstatus to COMMITTING (forced)\par
2011-10-06 15:10:51,917 (737072696E672D62746D0000000471270B2F00000691) DEBUG [bitronix.tm.BitronixTransaction] (http-0.0.0.0-8080-27) transaction status is changing from PREPARED to COMMITTING - executing 0 listener(s)\par
2011-10-06 15:10:51,950 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The XA Error is :-4\par
2011-10-06 15:10:51,958 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The XA Error message is :The XID is not valid.\par
2011-10-06 15:10:51,969 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The Oracle Error code is :24756\par
2011-10-06 15:10:51,974 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) The Oracle SQL Error code is :0\par
2011-10-06 15:10:51,977 (737072696E672D62746D0000000471270B2F00000691) INFO  [bitronix.tm.twopc.Committer] (http-0.0.0.0-8080-27) OracleXAException cause:\par
2011-10-06 15:10:52,002 (737072696E672D62746D0000000471270B2F00000691) ERROR [bitronix.tm.twopc.AbstractPhaseEngine] (http-0.0.0.0-8080-27) resource oracle\par
 failed on a Bitronix XID [737072696E672D62746D0000000471270B2F00000691 : 737072696E672D62746D0000000471270B7600000693]: bitronix.tm.internal.BitronixXAException: unknown heuristic termination, global state of this transaction is unknown - guilty: an XAResourceHolderState with uniqueName=oracle XAResource=oracle.jdbc.driver.T4CXAResource@3c332d5b (ended) with XID a Bitronix XID [737072696E672D62746D0000000471270B2F00000691 : 737072696E672D62746D0000000471270B7600000693]\par
        at bitronix.tm.twopc.Committer$CommitJob.handleXAException(Committer.java:226)\par
        at bitronix.tm.twopc.Committer$CommitJob.commitResource(Committer.java:215)\par
        at bitronix.tm.twopc.Committer$CommitJob.execute(Committer.java:183)\par
        at bitronix.tm.twopc.executor.Job.run(Job.java:72)\par
        at bitronix.tm.twopc.executor.SyncExecutor.submit(SyncExecutor.java:31)\par
        at bitronix.tm.twopc.AbstractPhaseEngine.runJobsForPosition(AbstractPhaseEngine.java:127)\par
        at bitronix.tm.twopc.AbstractPhaseEngine.executePhase(AbstractPhaseEngine.java:88)\par
        at bitronix.tm.twopc.Committer.commit(Committer.java:82)\par
        at bitronix.tm.BitronixTransaction.commit(BitronixTransaction.java:254)\par
        at bitronix.tm.BitronixTransactionManager.commit(BitronixTransactionManager.java:121)\par
        at org.springframework.transaction.jta.JtaTransactionManager.doCommit(JtaTransactionManager.java:1009)\par
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:754)\par
        at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:723)\par
        at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:393)\par
        at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:120)\par
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)\par
        at org.springframework.aop.framework.Cglib2AopProxy$DynamicAdvisedInterceptor.intercept(Cglib2AopProxy.java:621)\par
...\par
Caused by: oracle.jdbc.xa.OracleXAException\par
        at oracle.jdbc.xa.OracleXAResource.checkError(OracleXAResource.java:1040)\par
        at oracle.jdbc.xa.client.OracleXAResource.commit(OracleXAResource.java:573)\par
        at bitronix.tm.twopc.Committer$CommitJob.commitResource(Committer.java:194)\par
        ... 60 more\par
\par
}
 
---------------------------------------------------------------------
To unsubscribe from this list, please visit:

    http://xircles.codehaus.org/manage_email

 « Return to Thread: OracleXAException - XID is not valid