*Shell Command Output*.txt

17:08:33.032 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) BaseTransaction.begin
17:08:33.038 TRACE [org.hornetq.core.server] (Thread-0 (HornetQ-client-global-threads-424743902)) InVMConnection [serverID=0, id=1f35cafb-406d-11e5-b852-e340d91e4477]::Sending inVM packet
17:08:33.038 TRACE [org.hornetq.core.client] (Thread-0 (HornetQ-client-global-threads-424743902)) handling packet PACKET(NullResponseMessage)[type=21, channelID=10, packetObject=NullResponseMessage]
17:08:33.038 TRACE [org.hornetq.core.server] (Thread-0 (HornetQ-client-global-threads-424743902)) InVMConnection [serverID=0, id=1f35cafb-406d-11e5-b852-e340d91e4477]::packet sent done
17:08:33.039 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) Loading class com.arjuna.ats.arjuna.recovery.ActionStatusService
17:08:33.171 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) Loading class com.arjuna.ats.internal.arjuna.objectstore.ShadowNoFileLockStore
17:08:33.308 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) FileSystemStore.createHierarchy(PutObjectStoreDirHere/ShadowNoFileLockStore/defaultStore/)
17:08:33.312 INFO  [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ARJUNA012163: Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 57362
17:08:38.401 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) Loading class com.arjuna.ats.internal.arjuna.utils.SocketProcessId
17:08:38.402 INFO  [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ARJUNA012337: TransactionStatusManagerItem host: 127.0.0.1 port: 57362
17:08:38.403 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) OutputObjectState::OutputObjectState()
17:08:38.404 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) Loading class com.arjuna.ats.internal.arjuna.objectstore.ShadowNoFileLockStore
17:08:38.404 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) FileSystemStore.createHierarchy(/home/tcrawley/work/clojure/immutant/transactions/ObjectStore/ShadowNoFileLockStore/defaultStore/)
17:08:38.404 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) FileSystemStore.write_committed(0:ffff0a00000a:ec16:55ca6456:0, /Recovery/TransactionStatusManager)
17:08:38.404 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ShadowingStore.write_state(0:ffff0a00000a:ec16:55ca6456:0, /Recovery/TransactionStatusManager, StateType.OS_ORIGINAL)
17:08:38.404 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ShadowingStore.genPathName(0:ffff0a00000a:ec16:55ca6456:0, /Recovery/TransactionStatusManager, StateType.OS_ORIGINAL)
17:08:38.404 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) FileSystemStore.genPathName(0:ffff0a00000a:ec16:55ca6456:0, /Recovery/TransactionStatusManager, 11)
17:08:38.405 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) FileSystemStore.openAndLock(/home/tcrawley/work/clojure/immutant/transactions/ObjectStore/ShadowNoFileLockStore/defaultStore/Recovery/TransactionStatusManager/0_ffff0a00000a_ec16_55ca6456_0, FileLock.F_WRLCK, true)
17:08:38.412 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) FileSystemStore.closeAndUnlock(/home/tcrawley/work/clojure/immutant/transactions/ObjectStore/ShadowNoFileLockStore/defaultStore/Recovery/TransactionStatusManager/0_ffff0a00000a_ec16_55ca6456_0, null, java.io.FileOutputStream@62c4ce9a)
17:08:38.412 INFO  [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ARJUNA012170: TransactionStatusManager started on port 57362 and host 127.0.0.1 with service com.arjuna.ats.arjuna.recovery.ActionStatusService
17:08:38.413 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) StateManager::StateManager( 2, 0 )
17:08:38.413 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::BasicAction()
17:08:38.414 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::Begin() for action-id 0:ffff0a00000a:ec16:55ca6456:2
17:08:38.414 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::actionInitialise() for action-id 0:ffff0a00000a:ec16:55ca6456:2
17:08:38.415 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ActionHierarchy::ActionHierarchy(1)
17:08:38.415 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ActionHierarchy::add(0:ffff0a00000a:ec16:55ca6456:2, 1)
17:08:38.416 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::addChildThread () action 0:ffff0a00000a:ec16:55ca6456:2 adding Thread[Thread-1 (HornetQ-client-global-threads-424743902),5,HornetQ-client-global-threads-424743902]
17:08:38.416 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::addChildThread () action 0:ffff0a00000a:ec16:55ca6456:2 adding Thread[Thread-1 (HornetQ-client-global-threads-424743902),5,HornetQ-client-global-threads-424743902] result = true
17:08:38.417 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionReaper::instantiate()
17:08:38.417 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionReaper::TransactionReaper ( 9223372036854775807 )
17:08:38.418 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,HornetQ-client-global-threads-424743902] sleeping for 9223370597527457389
17:08:38.419 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) Thread Thread[Transaction Reaper Worker 0,5,HornetQ-client-global-threads-424743902] waiting for cancelled TXs
17:08:38.419 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionReaper::insert ( BasicAction: 0:ffff0a00000a:ec16:55ca6456:2 status: ActionStatus.RUNNING, 60 )
17:08:38.419 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) ReaperElement::ReaperElement ( BasicAction: 0:ffff0a00000a:ec16:55ca6456:2 status: ActionStatus.RUNNING, 60 )
17:08:38.419 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,HornetQ-client-global-threads-424743902] sleeping for 60000
17:08:38.419 TRACE [org.infinispan.cache.impl.CacheImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Implicit transaction started! Transaction: TransactionImple < ac, BasicAction: 0:ffff0a00000a:ec16:55ca6456:2 status: ActionStatus.RUNNING >
17:08:38.420 TRACE [org.infinispan.transaction.impl.TransactionTable] (Thread-1 (HornetQ-client-global-threads-424743902)) Created a new local transaction: LocalXaTransaction{xid=null} LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=null, backupKeyLocks=null, topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.xa.LocalXaTransaction@1
17:08:38.422 TRACE [org.infinispan.interceptors.InvocationContextInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Invoked with command PutKeyValueCommand{key=:a, value=1, flags=null, putIfAbsent=true, valueMatcher=MATCH_EXPECTED, metadata=EmbeddedMetadata{version=null}, successful=true} and InvocationContext [org.infinispan.context.impl.LocalTxInvocationContext@73c1dafe]
17:08:38.423 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
17:08:38.424 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionImple.enlistResource ( TransactionXaAdapter{localTransaction=LocalXaTransaction{xid=null} LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=null, backupKeyLocks=null, topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.xa.LocalXaTransaction@1} )
17:08:38.424 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
17:08:38.427 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) StateManager::StateManager( 1, 0 )
17:08:38.427 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) AbstractRecord::AbstractRecord (0:ffff0a00000a:ec16:55ca6456:4, 1)
17:08:38.427 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) XAResourceRecord.XAResourceRecord ( < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a00000a:ec16:55ca6456:2, node_name=1, branch_uid=0:ffff0a00000a:ec16:55ca6456:3, subordinatenodename=null, eis_name=0 >, TransactionXaAdapter{localTransaction=LocalXaTransaction{xid=null} LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=null, backupKeyLocks=null, topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.xa.LocalXaTransaction@1} ), record id=0:ffff0a00000a:ec16:55ca6456:4
17:08:38.427 TRACE [org.infinispan.transaction.xa.TransactionXaAdapter] (Thread-1 (HornetQ-client-global-threads-424743902)) start called on tx GlobalTransaction:<null>:1:local
17:08:38.428 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/XAResourceRecord for 0:ffff0a00000a:ec16:55ca6456:4
17:08:38.429 TRACE [org.infinispan.interceptors.EntryWrappingInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Wrapping entry ':a'? true
17:08:38.429 TRACE [org.infinispan.container.EntryFactoryImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Exists in context? null 
17:08:38.429 TRACE [org.infinispan.container.EntryFactoryImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Retrieved from container null (forceFetch=false, isLocal=true)
17:08:38.429 TRACE [org.infinispan.container.EntryFactoryImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Creating new entry.
17:08:38.430 TRACE [org.infinispan.container.EntryFactoryImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Wrap :a for put. Entry=RepeatableReadEntry(fa3a437){key=:a, value=null, oldValue=null, isCreated=true, isChanged=false, isRemoved=false, isValid=true, skipRemoteGet=false, metadata=EmbeddedMetadata{version=null}}
17:08:38.430 TRACE [org.infinispan.interceptors.CallInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Executing command: PutKeyValueCommand{key=:a, value=1, flags=null, putIfAbsent=true, valueMatcher=MATCH_EXPECTED, metadata=EmbeddedMetadata{version=null}, successful=true}.
17:08:38.430 TRACE [org.infinispan.interceptors.EntryWrappingInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) The return value is null
17:08:38.430 TRACE [org.infinispan.transaction.impl.LocalTransaction] (Thread-1 (HornetQ-client-global-threads-424743902)) Adding modification PutKeyValueCommand{key=:a, value=1, flags=null, putIfAbsent=true, valueMatcher=MATCH_EXPECTED, metadata=EmbeddedMetadata{version=null}, successful=true}. Mod list is null
17:08:38.430 TRACE [org.infinispan.cache.impl.CacheImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Committing transaction as it was implicit: TransactionImple < ac, BasicAction: 0:ffff0a00000a:ec16:55ca6456:2 status: ActionStatus.RUNNING >
17:08:38.430 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) BaseTransaction.commit
17:08:38.430 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionImple.commitAndDisassociate
17:08:38.430 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::End() for action-id 0:ffff0a00000a:ec16:55ca6456:2
17:08:38.430 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::onePhaseCommit() for action-id 0:ffff0a00000a:ec16:55ca6456:2
17:08:38.430 TRACE [com.arjuna.ats.jta] (Thread-1 (HornetQ-client-global-threads-424743902)) XAResourceRecord.topLevelOnePhaseCommit for XAResourceRecord < resource:TransactionXaAdapter{localTransaction=LocalXaTransaction{xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a00000a:ec16:55ca6456:2, node_name=1, branch_uid=0:ffff0a00000a:ec16:55ca6456:3, subordinatenodename=null, eis_name=0 >} LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=null, backupKeyLocks=null, topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.xa.LocalXaTransaction@1}, txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a00000a:ec16:55ca6456:2, node_name=1, branch_uid=0:ffff0a00000a:ec16:55ca6456:3, subordinatenodename=null, eis_name=0 >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@14692f09 >, record id=0:ffff0a00000a:ec16:55ca6456:4
17:08:38.430 TRACE [org.infinispan.transaction.xa.TransactionXaAdapter] (Thread-1 (HornetQ-client-global-threads-424743902)) end called on tx GlobalTransaction:<null>:1:local(tx-test)
17:08:38.432 TRACE [org.infinispan.transaction.impl.TransactionCoordinator] (Thread-1 (HornetQ-client-global-threads-424743902)) Sending prepare command through the chain: VersionedPrepareCommand {modifications=[PutKeyValueCommand{key=:a, value=1, flags=null, putIfAbsent=true, valueMatcher=MATCH_EXPECTED, metadata=EmbeddedMetadata{version=null}, successful=true}], onePhaseCommit=false, versionsSeen=null, gtx=GlobalTransaction:<null>:1:local, cacheName='tx-test'}
17:08:38.432 TRACE [org.infinispan.interceptors.InvocationContextInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Invoked with command VersionedPrepareCommand {modifications=[PutKeyValueCommand{key=:a, value=1, flags=null, putIfAbsent=true, valueMatcher=MATCH_EXPECTED, metadata=EmbeddedMetadata{version=null}, successful=true}], onePhaseCommit=false, versionsSeen=null, gtx=GlobalTransaction:<null>:1:local, cacheName='tx-test'} and InvocationContext [org.infinispan.context.impl.LocalTxInvocationContext@d4cce3a]
17:08:38.432 TRACE [org.infinispan.interceptors.locking.OptimisticLockingInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Not using lock reordering as we have a single key.
17:08:38.432 TRACE [org.infinispan.interceptors.locking.OptimisticLockingInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Locking key :a, no need to check for pending locks.
17:08:38.432 TRACE [org.infinispan.util.concurrent.locks.LockManagerImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Attempting to lock :a with acquisition timeout of 10000 millis
17:08:38.433 TRACE [org.infinispan.util.concurrent.locks.containers.OwnableReentrantPerEntryLockContainer] (Thread-1 (HornetQ-client-global-threads-424743902)) Creating and acquiring new lock instance for key :a
17:08:38.433 TRACE [org.infinispan.util.concurrent.locks.LockManagerImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Successfully acquired lock :a!
17:08:38.434 TRACE [org.infinispan.transaction.impl.AbstractCacheTransaction] (Thread-1 (HornetQ-client-global-threads-424743902)) Registering locked key: :a
17:08:38.434 TRACE [org.infinispan.interceptors.locking.OptimisticLockingInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Performing local write skew check for key :a
17:08:38.434 TRACE [org.infinispan.container.entries.RepeatableReadEntry] (Thread-1 (HornetQ-client-global-threads-424743902)) Performing local write skew check. actualValue=null, transactionValue=null
17:08:38.434 TRACE [org.infinispan.interceptors.CallInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Suppressing invocation of method handlePrepareCommand.
17:08:38.434 TRACE [org.infinispan.transaction.impl.TransactionCoordinator] (Thread-1 (HornetQ-client-global-threads-424743902)) Committing transaction GlobalTransaction:<null>:1:local
17:08:38.435 TRACE [org.infinispan.interceptors.InvocationContextInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Invoked with command VersionedCommitCommand{gtx=GlobalTransaction:<null>:1:local, cacheName='tx-test', topologyId=-1, updatedVersions=null} and InvocationContext [org.infinispan.context.impl.LocalTxInvocationContext@679f7f58]
17:08:38.435 TRACE [org.infinispan.interceptors.CallInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) Suppressing invocation of method handleCommitCommand.
17:08:38.437 TRACE [org.infinispan.interceptors.EntryWrappingInterceptor] (Thread-1 (HornetQ-client-global-threads-424743902)) About to commit entry RepeatableReadEntry(fa3a437){key=:a, value=1, oldValue=null, isCreated=true, isChanged=true, isRemoved=false, isValid=true, skipRemoteGet=true, metadata=EmbeddedMetadata{version=null}}
17:08:38.437 TRACE [org.infinispan.statetransfer.CommitManager] (Thread-1 (HornetQ-client-global-threads-424743902)) Trying to commit. Key=:a. Operation Flag=null, L1 invalidation=false
17:08:38.437 TRACE [org.infinispan.statetransfer.CommitManager] (Thread-1 (HornetQ-client-global-threads-424743902)) Committing key=:a. It is a L1 invalidation or a normal put and no tracking is enabled!
17:08:38.438 TRACE [org.infinispan.container.entries.ReadCommittedEntry] (Thread-1 (HornetQ-client-global-threads-424743902)) Updating entry (key=:a removed=false valid=true changed=true created=true loaded=false value=1 metadata=EmbeddedMetadata{version=null}, providedMetadata=null)
17:08:38.438 TRACE [org.infinispan.container.DefaultDataContainer] (Thread-1 (HornetQ-client-global-threads-424743902)) Creating new ICE for writing. Existing=null, metadata=EmbeddedMetadata{version=null}, new value=1
17:08:38.439 TRACE [org.infinispan.container.DefaultDataContainer] (Thread-1 (HornetQ-client-global-threads-424743902)) Store ImmortalCacheEntry{key=:a, value=1} in container
17:08:38.440 TRACE [org.infinispan.util.concurrent.locks.LockManagerImpl] (Thread-1 (HornetQ-client-global-threads-424743902)) Attempting to unlock :a
17:08:38.440 TRACE [org.infinispan.util.concurrent.locks.containers.OwnableReentrantPerEntryLockContainer] (Thread-1 (HornetQ-client-global-threads-424743902)) Unlocking lock instance for key :a
17:08:38.441 TRACE [org.infinispan.transaction.impl.AbstractCacheTransaction] (Thread-1 (HornetQ-client-global-threads-424743902)) Clearing locked keys: [:a]
17:08:38.441 TRACE [org.infinispan.transaction.impl.TransactionTable] (Thread-1 (HornetQ-client-global-threads-424743902)) Removed LocalXaTransaction{xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a00000a:ec16:55ca6456:2, node_name=1, branch_uid=0:ffff0a00000a:ec16:55ca6456:3, subordinatenodename=null, eis_name=0 >} LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=null, backupKeyLocks=null, topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.xa.LocalXaTransaction@1 from transaction table.
17:08:38.441 TRACE [org.infinispan.transaction.impl.AbstractCacheTransaction] (Thread-1 (HornetQ-client-global-threads-424743902)) Transaction GlobalTransaction:<null>:1:local has completed, notifying listening threads.
17:08:38.441 TRACE [org.infinispan.transaction.impl.AbstractEnlistmentAdapter] (Thread-1 (HornetQ-client-global-threads-424743902)) Committed in onePhase? false isOptimistic? true
17:08:38.441 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::End() result for action-id (0:ffff0a00000a:ec16:55ca6456:2) is (TwoPhaseOutcome.PREPARE_OK) node id: (1)
17:08:38.441 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::removeChildThread () action 0:ffff0a00000a:ec16:55ca6456:2 removing TSThread:1
17:08:38.441 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) BasicAction::removeChildThread () action 0:ffff0a00000a:ec16:55ca6456:2 removing TSThread:1 result = true
17:08:38.441 TRACE [com.arjuna.ats.arjuna] (Thread-1 (HornetQ-client-global-threads-424743902)) TransactionReaper::remove ( BasicAction: 0:ffff0a00000a:ec16:55ca6456:2 status: ActionStatus.COMMITTED )