[jboss-jira] [JBoss JIRA] (WFLY-3135) ARJUNA016087 warning message in batch jobs when committing transactions
James Perkins (JIRA)
issues at jboss.org
Tue Apr 15 11:40:33 EDT 2014
[ https://issues.jboss.org/browse/WFLY-3135?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
James Perkins resolved WFLY-3135.
---------------------------------
Fix Version/s: 8.1.0.CR1
Resolution: Done
I'm going to consider this fixed for now. The batch spec says each open, read , write and close needs to be in it's own transaction. With JCA that won't work for an {{XAResource}} that have been enlisted.
The current resolution until JSR 352 states otherwise is to add a {{jberet.local-tx}} property with the value of {{true}}} to either your job.xml file or passed in via the {{JobOperator.start()}}. *Note:* If you use this and require transactions you *must* handle the begin and commit of the transaction within your implementing code.
> ARJUNA016087 warning message in batch jobs when committing transactions
> -----------------------------------------------------------------------
>
> Key: WFLY-3135
> URL: https://issues.jboss.org/browse/WFLY-3135
> Project: WildFly
> Issue Type: Bug
> Security Level: Public(Everyone can see)
> Components: Batch
> Affects Versions: 8.0.0.Final
> Reporter: James Perkins
> Assignee: James Perkins
> Fix For: 8.1.0.CR1
>
>
> Batch jobs are getting a warning message printing after a commit is attempted.
> {code}
> 13:52:15,270 WARN [com.arjuna.ats.jta] (batch-batch - 3) ARJUNA016087: TransactionImple.delistResource - unknown resource
> {code}
> Turning on trace logging gives the following log messages
> {code}
> 13:52:15,252 INFO [com.test.cms.batch.partition.mapper.File0591Mapper] (batch-batch - 1) Generation the partitions.....
> 13:52:15,255 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.begin
> 13:52:15,255 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 2) Reader: OPEN
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 1) BaseTransaction.begin
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.begin
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.putResource
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,256 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 3) Reader: OPEN
> 13:52:15,256 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,257 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,259 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,259 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,259 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,259 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,259 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.putResource
> 13:52:15,260 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,260 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,260 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,260 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,260 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,260 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,262 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,262 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,262 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.putResource
> 13:52:15,262 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,262 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getTransactionKey
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.enlistResource ( LocalXAResourceImpl at 61568070[connectionListener=35219b06 connectionManager=760a6afc warned=false currentXid=null productName=H2 productVersion=1.3.173 (2013-07-28) jndiName=java:/ISSUER_DS] )
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.getResource
> 13:52:15,263 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.putResource
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.commit
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.commitAndDisassociate
> 13:52:15,264 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 2) SynchronizationImple.beforeCompletion
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.enlistResource ( LocalXAResourceImpl at 4d090bc5[connectionListener=5a6417cc connectionManager=760a6afc warned=false currentXid=null productName=H2 productVersion=1.3.173 (2013-07-28) jndiName=java:/ISSUER_DS] )
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.delistResource ( LocalXAResourceImpl at 61568070[connectionListener=35219b06 connectionManager=760a6afc warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:5c4cbb49:5328b03a:42, node_name=1, branch_uid=0:ffff7f000001:5c4cbb49:5328b03a:46, subordinatenodename=null, eis_name=java:/ISSUER_DS > productName=H2 productVersion=1.3.173 (2013-07-28) jndiName=java:/ISSUER_DS], 67108864 )
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,265 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,266 TRACE [com.arjuna.ats.jta] (batch-batch - 2) SynchronizationImple.afterCompletion
> 13:52:15,266 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.commit
> 13:52:15,266 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.begin
> 13:52:15,266 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.commitAndDisassociate
> 13:52:15,266 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 2) Reader: READ ITEMS
> 13:52:15,266 TRACE [com.arjuna.ats.jta] (batch-batch - 3) SynchronizationImple.beforeCompletion
> 13:52:15,266 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.delistResource ( LocalXAResourceImpl at 4d090bc5[connectionListener=5a6417cc connectionManager=760a6afc warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:5c4cbb49:5328b03a:44, node_name=1, branch_uid=0:ffff7f000001:5c4cbb49:5328b03a:48, subordinatenodename=null, eis_name=java:/ISSUER_DS > productName=H2 productVersion=1.3.173 (2013-07-28) jndiName=java:/ISSUER_DS], 67108864 )
> 13:52:15,267 INFO [com.test.cms.batch.writer.FileWriterTest] (batch-batch - 2) Write item:VALUE_PART_1
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.commit
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.commitAndDisassociate
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 3) SynchronizationImple.afterCompletion
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.begin
> 13:52:15,267 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.begin
> 13:52:15,268 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 2) Reader: READ ITEMS
> 13:52:15,268 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 3) Reader: READ ITEMS
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.commit
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.commitAndDisassociate
> 13:52:15,268 INFO [com.test.cms.batch.writer.FileWriterTest] (batch-batch - 3) Write item:VALUE_PART_2
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.begin
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.commit
> 13:52:15,268 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 2) Reader: close
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.commitAndDisassociate
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.begin
> 13:52:15,268 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,269 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.delistResource ( LocalXAResourceImpl at 61568070[connectionListener=35219b06 connectionManager=760a6afc warned=false currentXid=null productName=H2 productVersion=1.3.173 (2013-07-28) jndiName=java:/ISSUER_DS], 67108864 )
> 13:52:15,269 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 3) Reader: READ ITEMS
> 13:52:15,269 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,269 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.commit
> 13:52:15,269 WARN [com.arjuna.ats.jta] (batch-batch - 2) ARJUNA016087: TransactionImple.delistResource - unknown resource
> 13:52:15,269 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.commitAndDisassociate
> 13:52:15,269 TRACE [com.arjuna.ats.jta] (batch-batch - 2) BaseTransaction.commit
> 13:52:15,269 TRACE [com.arjuna.ats.jta] (batch-batch - 2) TransactionImple.commitAndDisassociate
> 13:52:15,269 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.begin
> 13:52:15,269 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 2) Reader: close
> 13:52:15,269 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 3) Reader: close
> 13:52:15,270 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,270 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,270 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.delistResource ( LocalXAResourceImpl at 4d090bc5[connectionListener=5a6417cc connectionManager=760a6afc warned=false currentXid=null productName=H2 productVersion=1.3.173 (2013-07-28) jndiName=java:/ISSUER_DS], 67108864 )
> 13:52:15,270 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
> 13:52:15,270 WARN [com.arjuna.ats.jta] (batch-batch - 3) ARJUNA016087: TransactionImple.delistResource - unknown resource
> 13:52:15,270 TRACE [com.arjuna.ats.jta] (batch-batch - 3) BaseTransaction.commit
> 13:52:15,270 TRACE [com.arjuna.ats.jta] (batch-batch - 3) TransactionImple.commitAndDisassociate
> 13:52:15,270 INFO [com.test.cms.batch.reader.File0591Reader] (batch-batch - 3) Reader: close
> 13:52:15,270 TRACE [com.arjuna.ats.jta] (batch-batch - 1) BaseTransaction.commit
> 13:52:15,271 TRACE [com.arjuna.ats.jta] (batch-batch - 1) TransactionImple.commitAndDisassociate
> {code}
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
More information about the jboss-jira
mailing list