Uploaded image for project: 'WildFly'
  1. WildFly
  2. WFLY-3135

ARJUNA016087 warning message in batch jobs when committing transactions

    Details

      Description

      Batch jobs are getting a warning message printing after a commit is attempted.

      13:52:15,270 WARN  [com.arjuna.ats.jta] (batch-batch - 3) ARJUNA016087: TransactionImple.delistResource - unknown resource
      

      Turning on trace logging gives the following log messages

      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
      

        Gliffy Diagrams

          Attachments

            Activity

              People

              • Assignee:
                jamezp James Perkins
                Reporter:
                jamezp James Perkins
              • Votes:
                1 Vote for this issue
                Watchers:
                4 Start watching this issue

                Dates

                • Created:
                  Updated:
                  Resolved: