Loading...

XML

Word

Printable

Details

Type: Bug
Resolution: Done
Priority: Major
Fix Version/s: 8.1.0.CR1, 8.1.0.Final
Affects Version/s: 8.0.0.Final
Component/s: Batch
Labels:
None

Forum Reference:
https://community.jboss.org/message/863072, https://community.jboss.org/thread/238152

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

Attachments

Activity

People

Assignee:: James Perkins

Reporter:: James Perkins

Votes:: 1 Vote for this issue

Watchers:: 4 Start watching this issue

Dates

Created:: 2014/03/18 6:13 PM

Updated:: 2017/12/06 11:29 AM

Resolved:: 2014/04/15 11:38 AM