[jboss-jira] [JBoss JIRA] (WFLY-3135) ARJUNA016087 warning message in batch jobs when committing transactions

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 Components: Batch Affects Versions: 8.0.0.Final Reporter: James Perkins Assignee: James Perkins Fix For: 8.1.0.CR1, 8.1.0.Final 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@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@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@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@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@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@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}