Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 880787 Details for
Bug 1082652
Transaction started in MDB can't be committed when previous transaction was started on client with jboss ejb remoting
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
jta required server.log
org.jboss.as.test.jbossts.crashrec.test.JMSMdbCrashRecoveryTestCase_none_jta_server.required.log (text/x-log), 148.09 KB, created by
Ondrej Chaloupka
on 2014-03-31 14:47:18 UTC
(
hide
)
Description:
jta required server.log
Filename:
MIME Type:
Creator:
Ondrej Chaloupka
Created:
2014-03-31 14:47:18 UTC
Size:
148.09 KB
patch
obsolete
>10:32:13,892 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 36) JBAS010280: Activating Infinispan subsystem. >10:32:13,899 INFO [org.jboss.as.jacorb] (ServerService Thread Pool -- 37) JBAS016300: Activating JacORB Subsystem >10:32:13,946 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 44) JBAS012605: Activated the following JSF Implementations: [main, 1.2] >10:32:13,970 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 48) JBAS011800: Activating Naming Subsystem >10:32:13,972 INFO [org.jboss.as.security] (ServerService Thread Pool -- 54) JBAS013171: Activating Security Subsystem >10:32:13,975 INFO [org.jboss.as.connector.logging] (MSC service thread 1-5) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.24.Final) >10:32:13,979 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 56) JBAS010153: Node identifier property is set to the default value. Please make sure it is unique. >10:32:13,979 INFO [org.jboss.as.security] (MSC service thread 1-3) JBAS013170: Current PicketBox version=4.0.19.SP5 >10:32:13,986 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 58) JBAS015537: Activating WebServices Extension >10:32:14,013 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 32) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) >10:32:14,051 INFO [org.jboss.as.naming] (MSC service thread 1-4) JBAS011802: Starting Naming Service >10:32:14,052 INFO [org.jboss.as.mail.extension] (MSC service thread 1-3) JBAS015400: Bound mail session [java:jboss/mail/Default] >10:32:14,058 INFO [org.jboss.jaxr] (MSC service thread 1-5) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory >10:32:14,501 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-4) ARJUNA032010: JBossTS Recovery Service (tag: 430b320d1e2bb4b97bd2226c29c36953d5939c4c) - JBoss Inc. >10:32:14,516 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost.localdomain/127.0.0.1:8080 >10:32:14,531 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost.localdomain/127.0.0.1:8080 >10:32:14,541 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-4) ARJUNA012324: Start RecoveryActivators >10:32:14,600 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-4) Expiry scan interval set to 43200 seconds >10:32:14,601 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.arjuna.recovery.ExpiredTransactionStatusManagerScanner >10:32:14,601 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-4) Expiry scan interval set to 43200 seconds >10:32:14,601 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-4) ExpiredTransactionStatusManagerScanner created, with expiry time of 43200 seconds >10:32:14,602 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.arjuna.objectstore.ShadowNoFileLockStore >10:32:14,605 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) FileSystemStore.createHierarchy(/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/ShadowNoFileLockStore/defaultStore/) >10:32:14,616 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-4) ExpiredEntryMonitor - constructed >10:32:14,626 INFO [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ARJUNA012296: ExpiredEntryMonitor running at Mon, 31 Mar 2014 10:32:14 >10:32:14,627 DEBUG [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ExpiredTransactionStatusManagerScanner - scanning to remove items from before Sun, 30 Mar 2014 22:32:14 >10:32:14,627 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) InputObjectState::InputObjectState() >10:32:14,630 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.CommitMarkableResourceRecordRecoveryModule >10:32:14,630 INFO [org.jboss.as.jacorb] (MSC service thread 1-7) JBAS016330: CORBA ORB Service started >10:32:14,632 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) FileSystemStore.allObjUids(/Recovery/TransactionStatusManager, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:14,633 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) OutputObjectState::OutputObjectState() >10:32:14,635 DEBUG [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) >10:32:14,642 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.arjuna.coordinator.CheckedActionFactoryImple >10:32:14,644 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) StateManager::StateManager( 2, 0 ) >10:32:14,670 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) BasicAction::BasicAction() >10:32:14,673 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) InputObjectState::InputObjectState() >10:32:14,673 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) FileSystemStore.allObjUids(/Recovery/TransactionStatusManager, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:14,673 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) OutputObjectState::OutputObjectState() >10:32:14,673 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.arjuna.recovery.AtomicActionRecoveryModule >10:32:14,674 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) StateManager::StateManager( 2, 0 ) >10:32:14,674 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) BasicAction::BasicAction() >10:32:14,674 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-4) AtomicActionRecoveryModule created >10:32:14,674 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) InputObjectState::InputObjectState() >10:32:14,674 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) FileSystemStore.allObjUids(/Recovery/TransactionStatusManager, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:14,674 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) OutputObjectState::OutputObjectState() >10:32:14,674 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.txoj.recovery.TORecoveryModule >10:32:14,675 INFO [org.jboss.ws.common.management] (MSC service thread 1-6) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.3.0.Final >10:32:14,677 DEBUG [com.arjuna.ats.txoj] (MSC service thread 1-4) TORecoveryModule created >10:32:14,677 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.arjuna.objectstore.ShadowNoFileLockStore >10:32:14,677 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) FileSystemStore.createHierarchy(/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/ShadowNoFileLockStore/defaultStore/) >10:32:14,677 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule >10:32:14,679 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) InputObjectState::InputObjectState() >10:32:14,683 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.JTATransactionLogXAResourceOrphanFilter >10:32:14,684 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.JTANodeNameXAResourceOrphanFilter >10:32:14,684 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.SubordinateJTAXAResourceOrphanFilter >10:32:14,686 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-4) ARJUNA012343: RecoveryManagerImple is ready. Socket listener is turned off. >10:32:14,686 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-4) ARJUNA032013: Starting transaction recovery manager >10:32:14,700 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) ObjectStoreDir: /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store >10:32:14,700 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) StateManager::StateManager( 2, 0 ) >10:32:14,700 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) BasicAction::BasicAction() >10:32:14,707 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingjournal,bindingsDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingbindings,largeMessagesDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messaginglargemessages,pagingDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingpaging) >10:32:14,715 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== SCANNING >10:32:14,715 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221006: Waiting to obtain live lock >10:32:14,716 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) StateManager::StateManager( 0:0:0:0:0 ) >10:32:14,716 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) BasicAction::BasicAction(0:0:0:0:0) >10:32:14,716 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread scanning >10:32:14,724 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery first pass at Mon, 31 Mar 2014 10:32:14 >10:32:14,725 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) CommitMarkableResourceRecordRecoveryModule::periodicWorkFirstPass >10:32:14,725 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:14,725 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicActionConnectable, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:14,725 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:14,725 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:32:14,726 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:14,726 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:14,726 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:14,726 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:14,726 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:14,726 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule first pass >10:32:14,726 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:14,726 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:14,726 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:32:14,727 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:14,727 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - first pass >10:32:14,727 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:14,727 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allTypes(InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: ) >10:32:14,727 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:14,727 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:14,737 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - first pass >10:32:14,737 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:14,739 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/AbstractRecord/XAResourceRecord, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:14,740 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:14,746 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@5469814a, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@1d93f216}, transactionOriginNodeIdentifier='1'} >10:32:14,749 TRACE [com.arjuna.ats.jts] (MSC service thread 1-4) ArjunaTransactionImple::ArjunaTransactionImple ( 0:0:0:0:0 ) >10:32:14,750 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:32:14,751 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids new recoveryXids RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@5469814a, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@1d93f216}, transactionOriginNodeIdentifier='1'} 1396254734751 >10:32:14,752 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:14,772 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221013: Using NIO Journal >10:32:14,773 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) Loading class com.arjuna.ats.jbossatx.jta.TransactionManagerDelegate >10:32:14,777 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-4) ARJUNA032017: JBossTS Transaction Service (JTA version - tag: 430b320d1e2bb4b97bd2226c29c36953d5939c4c) - JBoss Inc. >10:32:14,783 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) TransactionReaper::instantiate() >10:32:14,790 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-4) TransactionReaper::TransactionReaper ( 9223372036854775807 ) >10:32:14,791 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) ReaperThread.run () >10:32:14,792 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,main] sleeping for 9223370640600041015 >10:32:14,798 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-4) registering bean jboss.jta:type=ObjectStore >10:32:14,808 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ReaperWorkerThread.run () >10:32:14,808 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) Thread Thread[Transaction Reaper Worker 0,5,main] waiting for cancelled TXs >10:32:14,828 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015876: Starting deployment of "dsdriver.jar" (runtime-name: "dsdriver.jar") >10:32:14,843 INFO [org.jboss.as.jacorb] (MSC service thread 1-3) JBAS016328: CORBA Naming Service started >10:32:14,845 INFO [org.jboss.as.remoting] (MSC service thread 1-8) JBAS017100: Listening on 127.0.0.1:4447 >10:32:14,847 INFO [org.jboss.as.remoting] (MSC service thread 1-6) JBAS017100: Listening on 127.0.0.1:9999 >10:32:14,850 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-4) JBAS015012: Started FileSystemDeploymentService for directory /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/deployments >10:32:14,900 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS] >10:32:14,980 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221034: Waiting to obtain live lock >10:32:14,981 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221035: Live Server Obtained live lock >10:32:15,222 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-1) JBAS010404: Deploying non-JDBC-compliant driver class org.postgresql.Driver (version 9.3) >10:32:16,065 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5445 for CORE protocol >10:32:16,068 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5455 for CORE protocol >10:32:16,070 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221007: Server is now live >10:32:16,070 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221001: HornetQ Server version 2.3.18.Final (2.3.18, 123) [f63f71f6-b8ae-11e3-b4a8-9b998317edfe] >10:32:16,073 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221003: trying to deploy queue jms.queue.DLQ >10:32:16,149 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-2) JBAS010406: Registered connection factory java:/JmsXA >10:32:16,160 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 60) JBAS011601: Bound messaging object to jndi name java:/jms/queue/DLQ >10:32:16,161 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 62) HQ221003: trying to deploy queue jms.queue.ExpiryQueue >10:32:16,171 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/jms/queue/ExpiryQueue >10:32:16,175 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory >10:32:16,176 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 61) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory >10:32:16,191 INFO [org.hornetq.ra] (MSC service thread 1-2) HornetQ resource adaptor started >10:32:16,191 INFO [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-2) IJ020002: Deployed: file://RaActivatorhornetq-ra >10:32:16,193 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-2) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA] >10:32:16,235 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) JBAS018559: Deployed "dsdriver.jar" (runtime-name : "dsdriver.jar") >10:32:16,408 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management >10:32:16,410 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990 >10:32:16,411 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-6) started in 4446ms - Started 231 of 268 services (71 services are lazy, passive or on-demand) >10:32:18,742 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-3) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA] >10:32:18,743 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS] >10:32:18,744 INFO [org.apache.catalina.core] (MSC service thread 1-6) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started >10:32:18,745 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 16) JBAS011605: Unbound messaging object to jndi name java:/jms/queue/DLQ >10:32:18,746 INFO [org.jboss.jaxr] (MSC service thread 1-3) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory >10:32:18,746 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 13) JBAS011605: Unbound messaging object to jndi name java:/ConnectionFactory >10:32:18,753 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 4) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory >10:32:18,757 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 10) JBAS011605: Unbound messaging object to jndi name java:/jms/queue/ExpiryQueue >10:32:18,768 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-7) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost.localdomain/127.0.0.1:8080 >10:32:18,769 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-7) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost.localdomain/127.0.0.1:8080 >10:32:18,775 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) JBAS015877: Stopped deployment dsdriver.jar (runtime-name: dsdriver.jar) in 42ms >10:32:18,792 INFO [org.hornetq.ra] (ServerService Thread Pool -- 13) HQ151003: HornetQ resource adaptor stopped >10:32:18,793 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-6) ARJUNA032018: Destroying TransactionManagerService >10:32:18,794 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-6) ARJUNA032014: Stopping transaction recovery manager >10:32:18,795 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) PeriodicRecovery: Mode <== TERMINATED >10:32:18,795 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) PeriodicRecovery: shutdown waiting for scan to end >10:32:18,795 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: scan TERMINATED at phase 1 >10:32:18,795 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== INACTIVE >10:32:18,795 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread exiting >10:32:18,795 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) PeriodicRecovery: shutdown scan wait complete >10:32:18,892 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 13) HQ221002: HornetQ Server version 2.3.18.Final (2.3.18, 123) [f63f71f6-b8ae-11e3-b4a8-9b998317edfe] stopped >10:32:18,900 INFO [org.jboss.as.mail.extension] (MSC service thread 1-2) JBAS015401: Unbound mail session [java:jboss/mail/Default] >10:32:18,906 INFO [org.jboss.as] (MSC service thread 1-2) JBAS015950: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-6) stopped in 175ms >10:32:18,909 INFO [org.jboss.as] (MSC service thread 1-6) JBAS015899: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-6) starting >10:32:18,910 DEBUG [org.jboss.as.config] (MSC service thread 1-6) Configured system properties: > awt.toolkit = sun.awt.X11.XToolkit > catalina.home = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/tmp > file.encoding = UTF-8 > file.encoding.pkg = sun.io > file.separator = / > java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment > java.awt.printerjob = sun.print.PSPrinterJob > java.class.path = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/jboss-modules.jar:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/byteman/byteman.jar > java.class.version = 51.0 > java.endorsed.dirs = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/endorsed > java.ext.dirs = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/ext:/usr/java/packages/lib/ext > java.home = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre > java.io.tmpdir = /tmp > java.library.path = /usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib > java.naming.factory.url.pkgs = org.jboss.as.naming.interfaces:org.jboss.ejb.client.naming > java.net.preferIPv4Stack = true > java.net.preferIPv6Addresses = false > java.protocol.handler.pkgs = org.jboss.net.protocol|org.jboss.vfs.protocol > java.runtime.name = OpenJDK Runtime Environment > java.runtime.version = 1.7.0_51-mockbuild_2014_01_31_16_21-b00 > java.specification.name = Java Platform API Specification > java.specification.vendor = Oracle Corporation > java.specification.version = 1.7 > java.util.logging.manager = org.jboss.logmanager.LogManager > java.vendor = Oracle Corporation > java.vendor.url = http://java.oracle.com/ > java.vendor.url.bug = http://bugreport.sun.com/bugreport/ > java.version = 1.7.0_51 > java.vm.info = mixed mode > java.vm.name = OpenJDK 64-Bit Server VM > java.vm.specification.name = Java Virtual Machine Specification > java.vm.specification.vendor = Oracle Corporation > java.vm.specification.version = 1.7 > java.vm.vendor = Oracle Corporation > java.vm.version = 24.51-b03 > javax.management.builder.initial = org.jboss.as.jmx.PluggableMBeanServerBuilder > javax.xml.datatype.DatatypeFactory = __redirected.__DatatypeFactory > javax.xml.parsers.DocumentBuilderFactory = __redirected.__DocumentBuilderFactory > javax.xml.parsers.SAXParserFactory = __redirected.__SAXParserFactory > javax.xml.stream.XMLEventFactory = __redirected.__XMLEventFactory > javax.xml.stream.XMLInputFactory = __redirected.__XMLInputFactory > javax.xml.stream.XMLOutputFactory = __redirected.__XMLOutputFactory > javax.xml.transform.TransformerFactory = __redirected.__TransformerFactory > javax.xml.validation.SchemaFactory:http://www.w3.org/2001/XMLSchema = __redirected.__SchemaFactory > javax.xml.xpath.XPathFactory:http://java.sun.com/jaxp/xpath/dom = __redirected.__XPathFactory > jboss.bundles.dir = /home/ochaloup/jboss/jboss-eap-6.3.0.DR6/bundles > jboss.dist = /home/ochaloup/jboss/jboss-eap-6.3.0.DR6 > jboss.home.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts > jboss.host.name = localhost > jboss.modules.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/modules > jboss.modules.system.pkgs = org.jboss.byteman > jboss.node.name = jbossts > jboss.qualified.host.name = localhost.localdomain > jboss.server.base.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone > jboss.server.config.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/configuration > jboss.server.data.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data > jboss.server.deploy.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/content > jboss.server.log.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/log > jboss.server.name = localhost > jboss.server.persist.config = true > jboss.server.temp.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/tmp > jbossas.project.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../../.. > jbossas.ts.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../.. > jbossas.ts.integ.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/.. > jbossas.ts.submodule.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts > line.separator = > > logging.configuration = file:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/configuration/logging.properties > mcast = 230.0.0.4 > module.path = /home/ochaloup/jboss/jboss-eap-6.3.0.DR6/modules:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/modules > node0 = 127.0.0.1 > node1 = 127.0.0.1 > node2 = 127.0.0.1 > org.apache.xml.security.ignoreLineBreaks = true > org.jboss.boot.log.file = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/log/boot.log > org.jboss.byteman.contrib.dtest.rmiregistry.port = 1199 > org.jboss.byteman.debug = true > org.jboss.byteman.verbose = true > org.jboss.com.sun.CORBA.ORBUseDynamicStub = true > org.jboss.logmanager.nocolor = true > org.jboss.resolver.warning = true > org.jboss.security.context.ThreadLocal = true > org.omg.CORBA.ORBClass = org.jacorb.orb.ORB > org.omg.CORBA.ORBSingletonClass = org.jacorb.orb.ORBSingleton > org.xml.sax.driver = __redirected.__XMLReaderFactory > os.arch = amd64 > os.name = Linux > os.version = 3.13.6-200.fc20.x86_64 > path.separator = : > sun.arch.data.model = 64 > sun.boot.class.path = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/resources.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/rt.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/sunrsasign.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/jsse.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/jce.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/charsets.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/netx.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/plugin.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/rhino.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/jfr.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/classes > sun.boot.library.path = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/amd64 > sun.cpu.endian = little > sun.cpu.isalist = > sun.io.unicode.encoding = UnicodeLittle > sun.java.command = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/jboss-modules.jar -mp /home/ochaloup/jboss/jboss-eap-6.3.0.DR6/modules:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/modules -jaxpmodule javax.xml.jaxp-provider org.jboss.as.standalone -server-config standalone-full.xml > sun.java.launcher = SUN_STANDARD > sun.jnu.encoding = UTF-8 > sun.management.compiler = HotSpot 64-Bit Tiered Compilers > sun.nio.ch.bugLevel = > sun.os.patch.level = unknown > ts.timeout.factor = 100 > user.country = US > user.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/workdir > user.home = /home/ochaloup > user.language = en > user.name = ochaloup > user.timezone = Europe/Prague >10:32:18,910 DEBUG [org.jboss.as.config] (MSC service thread 1-6) VM Arguments: -Xmx1303m -XX:MaxPermSize=256m -Djboss.dist=/home/ochaloup/jboss/jboss-eap-6.3.0.DR6 -Djava.net.preferIPv4Stack=true -Djava.net.preferIPv6Addresses=false -Dts.timeout.factor=100 -Dnode0=127.0.0.1 -Dnode1=127.0.0.1 -Dnode2=127.0.0.1 -Dmcast=230.0.0.4 -Djbossas.ts.submodule.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts -Djbossas.ts.integ.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/.. -Djbossas.ts.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../.. -Djbossas.project.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../../.. -Djboss.dist=/home/ochaloup/jboss/jboss-eap-6.3.0.DR6 -Dorg.jboss.logmanager.nocolor=true -Dorg.jboss.byteman.debug=true -Dorg.jboss.byteman.verbose=true -Dorg.jboss.byteman.contrib.dtest.rmiregistry.port=1199 -Djboss.modules.system.pkgs=org.jboss.byteman -javaagent:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/byteman/byteman.jar=address:127.0.0.1,port:9091,listener:true,sys:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/byteman/byteman-dtest.jar -Djboss.node.name=jbossts -ea -Djboss.home.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts -Dorg.jboss.boot.log.file=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/log/boot.log -Dlogging.configuration=file:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/configuration/logging.properties -Djboss.bundles.dir=/home/ochaloup/jboss/jboss-eap-6.3.0.DR6/bundles >10:32:19,017 TRACE [com.arjuna.ats.jts] (Finalizer) ArjunaTransactionImple.finalize - called for < 0:0:0:0:0 > >10:32:19,018 TRACE [com.arjuna.ats.arjuna] (Finalizer) BasicAction::finalize() >10:32:19,089 INFO [org.jboss.as.server] (Controller Boot Thread) JBAS015888: Creating http management service using socket-binding (management-http) >10:32:19,098 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 36) JBAS010280: Activating Infinispan subsystem. >10:32:19,098 INFO [org.jboss.as.jacorb] (ServerService Thread Pool -- 37) JBAS016300: Activating JacORB Subsystem >10:32:19,106 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 48) JBAS011800: Activating Naming Subsystem >10:32:19,107 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 32) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) >10:32:19,109 INFO [org.jboss.as.jacorb] (MSC service thread 1-4) JBAS016330: CORBA ORB Service started >10:32:19,110 INFO [org.jboss.as.connector.logging] (MSC service thread 1-4) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.24.Final) >10:32:19,115 INFO [org.jboss.jaxr] (MSC service thread 1-5) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory >10:32:19,115 INFO [org.jboss.as.naming] (MSC service thread 1-3) JBAS011802: Starting Naming Service >10:32:19,116 INFO [org.jboss.as.mail.extension] (MSC service thread 1-7) JBAS015400: Bound mail session [java:jboss/mail/Default] >10:32:19,125 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 56) JBAS010153: Node identifier property is set to the default value. Please make sure it is unique. >10:32:19,125 INFO [org.jboss.as.security] (ServerService Thread Pool -- 54) JBAS013171: Activating Security Subsystem >10:32:19,131 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 58) JBAS015537: Activating WebServices Extension >10:32:19,132 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-7) ARJUNA032010: JBossTS Recovery Service (tag: 430b320d1e2bb4b97bd2226c29c36953d5939c4c) - JBoss Inc. >10:32:19,132 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-7) ARJUNA032013: Starting transaction recovery manager >10:32:19,133 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-7) ARJUNA012324: Start RecoveryActivators >10:32:19,136 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-7) ExpiredEntryMonitor - constructed >10:32:19,138 INFO [org.jboss.as.security] (MSC service thread 1-6) JBAS013170: Current PicketBox version=4.0.19.SP5 >10:32:19,151 INFO [org.jboss.ws.common.management] (MSC service thread 1-3) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.3.0.Final >10:32:19,154 INFO [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ARJUNA012296: ExpiredEntryMonitor running at Mon, 31 Mar 2014 10:32:19 >10:32:19,158 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-7) ARJUNA012310: Recovery manager listening on endpoint 127.0.0.1:4712 >10:32:19,158 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-6) JBWEB003001: Coyote HTTP/1.1 initializing on : http-/127.0.0.1:8080 >10:32:19,159 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-7) PeriodicRecovery: starting listener worker thread >10:32:19,169 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-6) JBWEB003000: Coyote HTTP/1.1 starting on: http-/127.0.0.1:8080 >10:32:19,170 DEBUG [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ExpiredTransactionStatusManagerScanner - scanning to remove items from before Sun, 30 Mar 2014 22:32:19 >10:32:19,170 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) InputObjectState::InputObjectState() >10:32:19,170 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) FileSystemStore.allObjUids(/Recovery/TransactionStatusManager, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:19,170 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) OutputObjectState::OutputObjectState() >10:32:19,171 DEBUG [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) >10:32:19,171 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-7) ARJUNA012344: RecoveryManagerImple is ready on port 4712 >10:32:19,174 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) JBAS015876: Starting deployment of "dsdriver.jar" (runtime-name: "dsdriver.jar") >10:32:19,178 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-7) ObjectStoreDir: /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store >10:32:19,178 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-7) StateManager::StateManager( 2, 0 ) >10:32:19,178 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-7) BasicAction::BasicAction() >10:32:19,178 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-7) StateManager::StateManager( 0:0:0:0:0 ) >10:32:19,178 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-7) BasicAction::BasicAction(0:0:0:0:0) >10:32:19,178 TRACE [com.arjuna.ats.jts] (MSC service thread 1-7) ArjunaTransactionImple::ArjunaTransactionImple ( 0:0:0:0:0 ) >10:32:19,179 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-7) ARJUNA032017: JBossTS Transaction Service (JTA version - tag: 430b320d1e2bb4b97bd2226c29c36953d5939c4c) - JBoss Inc. >10:32:19,179 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-7) registering bean jboss.jta:type=ObjectStore >10:32:19,185 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-2) JBAS015012: Started FileSystemDeploymentService for directory /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/deployments >10:32:19,186 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== SCANNING >10:32:19,186 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread scanning >10:32:19,187 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery first pass at Mon, 31 Mar 2014 10:32:19 >10:32:19,187 INFO [org.jboss.as.jacorb] (MSC service thread 1-8) JBAS016328: CORBA Naming Service started >10:32:19,193 INFO [org.jboss.as.remoting] (MSC service thread 1-5) JBAS017100: Listening on 127.0.0.1:9999 >10:32:19,194 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-8) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS] >10:32:19,195 INFO [org.jboss.as.remoting] (MSC service thread 1-3) JBAS017100: Listening on 127.0.0.1:4447 >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) CommitMarkableResourceRecordRecoveryModule::periodicWorkFirstPass >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicActionConnectable, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:19,196 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:19,196 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:19,196 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule first pass >10:32:19,196 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:19,197 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:19,197 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:32:19,197 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:19,197 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - first pass >10:32:19,197 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:19,197 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allTypes(InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: ) >10:32:19,197 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:19,197 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:19,197 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:19,211 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingjournal,bindingsDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingbindings,largeMessagesDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messaginglargemessages,pagingDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingpaging) >10:32:19,212 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221006: Waiting to obtain live lock >10:32:19,212 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221013: Using NIO Journal >10:32:19,252 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-4) JBAS010404: Deploying non-JDBC-compliant driver class org.postgresql.Driver (version 9.3) >10:32:19,297 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221034: Waiting to obtain live lock >10:32:19,298 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221035: Live Server Obtained live lock >10:32:19,516 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5445 for CORE protocol >10:32:19,517 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5455 for CORE protocol >10:32:19,518 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221007: Server is now live >10:32:19,518 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221001: HornetQ Server version 2.3.18.Final (2.3.18, 123) [f63f71f6-b8ae-11e3-b4a8-9b998317edfe] >10:32:19,520 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 60) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory >10:32:19,520 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 62) HQ221003: trying to deploy queue jms.queue.ExpiryQueue >10:32:19,522 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-5) JBAS010406: Registered connection factory java:/JmsXA >10:32:19,527 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/jms/queue/ExpiryQueue >10:32:19,527 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 63) HQ221003: trying to deploy queue jms.queue.DLQ >10:32:19,528 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:/jms/queue/DLQ >10:32:19,530 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 61) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory >10:32:19,531 INFO [org.hornetq.ra] (MSC service thread 1-5) HornetQ resource adaptor started >10:32:19,531 INFO [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-5) IJ020002: Deployed: file://RaActivatorhornetq-ra >10:32:19,532 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-8) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA] >10:32:19,559 INFO [org.jboss.as.server] (ServerService Thread Pool -- 33) JBAS018559: Deployed "dsdriver.jar" (runtime-name : "dsdriver.jar") >10:32:19,564 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management >10:32:19,565 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990 >10:32:19,565 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-6) started in 654ms - Started 231 of 268 services (71 services are lazy, passive or on-demand) >10:32:20,806 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 29) HQ221003: trying to deploy queue jms.queue.queue/crashRecoveryQueue >10:32:20,860 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 29) JBAS011601: Bound messaging object to jndi name java:jboss/queue/crashRecoveryQueue >10:32:21,024 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 29) HQ221003: trying to deploy queue jms.queue.queue/MDBTriggerQueue >10:32:21,041 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 29) JBAS011601: Bound messaging object to jndi name java:jboss/queue/MDBTriggerQueue >10:32:21,374 INFO [org.jboss.as.repository] (management-handler-thread - 2) JBAS014900: Content added at location /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/content/59/eb3602cadad01b61bfb668e5f99a8843a5cf09/content >10:32:21,382 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) JBAS015876: Starting deployment of "crashrecovery-jms.jar" (runtime-name: "crashrecovery-jms.jar") >10:32:21,436 WARN [org.jboss.as.dependency.private] (MSC service thread 1-4) JBAS018567: Deployment "deployment.crashrecovery-jms.jar" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. >10:32:21,471 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-8) JNDI bindings for session bean named MessageHelper in deployment unit deployment "crashrecovery-jms.jar" are as follows: > > java:global/crashrecovery-jms/MessageHelper!org.jboss.as.test.jbossts.crashrec.jms.MessageHelperRemote > java:app/crashrecovery-jms/MessageHelper!org.jboss.as.test.jbossts.crashrec.jms.MessageHelperRemote > java:module/MessageHelper!org.jboss.as.test.jbossts.crashrec.jms.MessageHelperRemote > java:jboss/exported/crashrecovery-jms/MessageHelper!org.jboss.as.test.jbossts.crashrec.jms.MessageHelperRemote > java:global/crashrecovery-jms/MessageHelper > java:app/crashrecovery-jms/MessageHelper > java:module/MessageHelper > >10:32:21,472 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-8) JNDI bindings for session bean named CrashHelperTestXAResource in deployment unit deployment "crashrecovery-jms.jar" are as follows: > > java:global/crashrecovery-jms/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:app/crashrecovery-jms/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:module/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:jboss/exported/crashrecovery-jms/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:global/crashrecovery-jms/CrashHelperTestXAResource > java:app/crashrecovery-jms/CrashHelperTestXAResource > java:module/CrashHelperTestXAResource > >10:32:21,473 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-8) JNDI bindings for session bean named TestXAResourceRecoveryHelper in deployment unit deployment "crashrecovery-jms.jar" are as follows: > > java:global/crashrecovery-jms/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper > java:app/crashrecovery-jms/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper > java:module/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper > java:global/crashrecovery-jms/TestXAResourceRecoveryHelper > java:app/crashrecovery-jms/TestXAResourceRecoveryHelper > java:module/TestXAResourceRecoveryHelper > >10:32:21,473 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-8) JNDI bindings for session bean named TransactionStoreBrowserService in deployment unit deployment "crashrecovery-jms.jar" are as follows: > > java:global/crashrecovery-jms/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService > java:app/crashrecovery-jms/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService > java:module/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService > java:global/crashrecovery-jms/TransactionStoreBrowserService > java:app/crashrecovery-jms/TransactionStoreBrowserService > java:module/TransactionStoreBrowserService > >10:32:21,473 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-8) JNDI bindings for session bean named JMSCrashHelper in deployment unit deployment "crashrecovery-jms.jar" are as follows: > > java:global/crashrecovery-jms/JMSCrashHelper!org.jboss.as.test.jbossts.crashrec.jms.JMSCrashHelperRemote > java:app/crashrecovery-jms/JMSCrashHelper!org.jboss.as.test.jbossts.crashrec.jms.JMSCrashHelperRemote > java:module/JMSCrashHelper!org.jboss.as.test.jbossts.crashrec.jms.JMSCrashHelperRemote > java:jboss/exported/crashrecovery-jms/JMSCrashHelper!org.jboss.as.test.jbossts.crashrec.jms.JMSCrashHelperRemote > java:global/crashrecovery-jms/JMSCrashHelper > java:app/crashrecovery-jms/JMSCrashHelper > java:module/JMSCrashHelper > >10:32:21,621 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty hostName will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,621 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty MaxDeliveryCnt will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,621 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty useJndi will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,621 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty port will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,621 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty queueManager will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,622 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty username will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,622 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty connectionFactory will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,622 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty channel will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,622 WARN [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014105: ActivationConfigProperty transportType will be ignored since it is not allowed by resource adapter: hornetq-ra >10:32:21,655 INFO [org.jboss.as.ejb3] (MSC service thread 1-2) JBAS014142: Started message driven bean 'mdb' with 'hornetq-ra' resource adapter >10:32:21,670 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 29) BaseTransaction.begin >10:32:21,670 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 30) BaseTransaction.begin >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) StateManager::StateManager( 2, 0 ) >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) StateManager::StateManager( 2, 0 ) >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::BasicAction() >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::BasicAction() >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:12 >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:13 >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:12 >10:32:21,671 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:13 >10:32:21,672 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) ActionHierarchy::ActionHierarchy(5) >10:32:21,672 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) ActionHierarchy::ActionHierarchy(5) >10:32:21,672 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:13, 1) >10:32:21,672 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:12, 1) >10:32:21,674 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:12 adding Thread[ServerService Thread Pool -- 30,5,ServerService ThreadGroup] >10:32:21,674 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:13 adding Thread[ServerService Thread Pool -- 29,5,ServerService ThreadGroup] >10:32:21,675 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:12 adding Thread[ServerService Thread Pool -- 30,5,ServerService ThreadGroup] result = true >10:32:21,675 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:12 status: ActionStatus.RUNNING, 300 ) >10:32:21,677 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:13 adding Thread[ServerService Thread Pool -- 29,5,ServerService ThreadGroup] result = true >10:32:21,677 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:13 status: ActionStatus.RUNNING, 300 ) >10:32:21,678 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:13 status: ActionStatus.RUNNING, 300 ) >10:32:21,678 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:12 status: ActionStatus.RUNNING, 300 ) >10:32:21,678 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) ReaperThread.run () >10:32:21,678 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check () >10:32:21,679 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check - comparing 1396255041678 >10:32:21,679 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,main] sleeping for 299999 >10:32:21,680 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 29) TestXAResourceRecoveryHelper starting >10:32:21,680 INFO [org.jboss.as.test.jbossts.common.TransactionStoreBrowserService] (ServerService Thread Pool -- 30) TransactionStoreBrowserService.start >10:32:21,682 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 29) Using file /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser for saving state of the org.jboss.as.test.jbossts.common.TestXAResource XA resource >10:32:21,683 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 29) file /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser does not exists - no data for recovery >10:32:21,684 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 29) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:21,685 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 29) BaseTransaction.commit >10:32:21,685 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 29) TransactionImple.commitAndDisassociate >10:32:21,685 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:13 >10:32:21,685 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 30) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:21,685 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 30) BaseTransaction.commit >10:32:21,685 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 30) TransactionImple.commitAndDisassociate >10:32:21,685 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:12 >10:32:21,685 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:13) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:21,685 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:12) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:21,685 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:13 removing TSThread:2 >10:32:21,686 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:13 removing TSThread:2 result = true >10:32:21,686 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:12 removing TSThread:1 >10:32:21,686 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 29) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:13 status: ActionStatus.COMMITTED ) >10:32:21,686 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:12 removing TSThread:1 result = true >10:32:21,686 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 30) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:12 status: ActionStatus.COMMITTED ) >10:32:21,872 INFO [org.jboss.as.server] (management-handler-thread - 2) JBAS018559: Deployed "crashrecovery-jms.jar" (runtime-name : "crashrecovery-jms.jar") >10:32:23,354 TRACE [com.arjuna.ats.jts] (Finalizer) ArjunaTransactionImple.finalize - called for < 0:0:0:0:0 > >10:32:23,354 TRACE [com.arjuna.ats.arjuna] (Finalizer) BasicAction::finalize() >10:32:23,867 TRACE [com.arjuna.ats.arjuna] (pool-5-thread-1) InputObjectState::InputObjectState() >10:32:23,867 TRACE [com.arjuna.ats.arjuna] (pool-5-thread-1) FileSystemStore.allTypes(InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: ) >10:32:23,867 TRACE [com.arjuna.ats.arjuna] (pool-5-thread-1) OutputObjectState::OutputObjectState() >10:32:23,914 TRACE [com.arjuna.ats.arjuna] (pool-5-thread-1) InputObjectState::InputObjectState() >10:32:23,914 TRACE [com.arjuna.ats.arjuna] (pool-5-thread-1) FileSystemStore.allTypes(InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: ) >10:32:23,914 TRACE [com.arjuna.ats.arjuna] (pool-5-thread-1) OutputObjectState::OutputObjectState() >10:32:29,197 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery second pass at Mon, 31 Mar 2014 10:32:29 >10:32:29,198 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:32:29,198 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:29,198 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:29,198 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) FileSystemStore.allObjUids(/StateManager/BasicAction/TwoPhaseCoordinator/AtomicActionConnectable, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 40 >InputObjectState Buffer: , -1) >10:32:29,198 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:32:29,198 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:29,198 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule second pass >10:32:29,198 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:29,199 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - second pass >10:32:29,199 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:29,199 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - second pass >10:32:29,199 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.transactionInitiatedRecovery completed >10:32:29,199 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:32:29,199 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@5469814a, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@1d93f216}, transactionOriginNodeIdentifier='1'} 1396254734751 1396254749199 false >10:32:29,199 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.resourceInitiatedRecovery completed >10:32:29,200 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:32:29,200 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== INACTIVE >10:32:29,200 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread backing off >10:32:34,110 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.begin >10:32:34,110 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) StateManager::StateManager( 2, 0 ) >10:32:34,110 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::BasicAction() >10:32:34,110 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:18 >10:32:34,111 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:18 >10:32:34,111 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::ActionHierarchy(5) >10:32:34,111 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:18, 1) >10:32:34,111 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:18 adding Thread[EJB default - 1,5,EJB default] >10:32:34,111 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:18 adding Thread[EJB default - 1,5,EJB default] result = true >10:32:34,111 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:18 status: ActionStatus.RUNNING, 300 ) >10:32:34,111 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:18 status: ActionStatus.RUNNING, 300 ) >10:32:34,112 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:34,113 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.commit >10:32:34,113 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.commitAndDisassociate >10:32:34,113 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:18 >10:32:34,113 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:18) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:34,113 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:18 removing TSThread:3 >10:32:34,113 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:18 removing TSThread:3 result = true >10:32:34,113 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:18 status: ActionStatus.COMMITTED ) >10:32:34,114 INFO [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 1) wipe out in-doubt txs: wipeOutTxsInDoubt() called >10:32:34,115 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.begin >10:32:34,115 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) StateManager::StateManager( 2, 0 ) >10:32:34,115 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::BasicAction() >10:32:34,115 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:19 >10:32:34,115 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:19 >10:32:34,116 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::ActionHierarchy(5) >10:32:34,116 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:19, 1) >10:32:34,116 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:19 adding Thread[EJB default - 1,5,EJB default] >10:32:34,116 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:19 adding Thread[EJB default - 1,5,EJB default] result = true >10:32:34,116 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:19 status: ActionStatus.RUNNING, 300 ) >10:32:34,116 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:19 status: ActionStatus.RUNNING, 300 ) >10:32:34,118 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) getXAResources() >10:32:34,118 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:34,118 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.commit >10:32:34,118 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.commitAndDisassociate >10:32:34,118 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:19 >10:32:34,118 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:19) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:34,118 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:19 removing TSThread:3 >10:32:34,118 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:19 removing TSThread:3 result = true >10:32:34,119 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:19 status: ActionStatus.COMMITTED ) >10:32:34,119 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.begin >10:32:34,119 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) StateManager::StateManager( 2, 0 ) >10:32:34,119 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::BasicAction() >10:32:34,119 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1a >10:32:34,119 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1a >10:32:34,120 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::ActionHierarchy(5) >10:32:34,120 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:1a, 1) >10:32:34,120 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1a adding Thread[EJB default - 1,5,EJB default] >10:32:34,120 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1a adding Thread[EJB default - 1,5,EJB default] result = true >10:32:34,120 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1a status: ActionStatus.RUNNING, 300 ) >10:32:34,120 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1a status: ActionStatus.RUNNING, 300 ) >10:32:34,120 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) getXAResources() >10:32:34,120 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:34,120 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.commit >10:32:34,120 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.commitAndDisassociate >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1a >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:1a) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1a removing TSThread:3 >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1a removing TSThread:3 result = true >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1a status: ActionStatus.COMMITTED ) >10:32:34,121 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.begin >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) StateManager::StateManager( 2, 0 ) >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::BasicAction() >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1b >10:32:34,121 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1b >10:32:34,122 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::ActionHierarchy(5) >10:32:34,122 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:1b, 1) >10:32:34,122 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1b adding Thread[EJB default - 1,5,EJB default] >10:32:34,122 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1b adding Thread[EJB default - 1,5,EJB default] result = true >10:32:34,122 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1b status: ActionStatus.RUNNING, 300 ) >10:32:34,122 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1b status: ActionStatus.RUNNING, 300 ) >10:32:34,122 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) getXAResources() >10:32:34,122 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:34,122 TRACE [com.arjuna.ats.jta] (EJB default - 1) BaseTransaction.commit >10:32:34,122 TRACE [com.arjuna.ats.jta] (EJB default - 1) TransactionImple.commitAndDisassociate >10:32:34,123 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1b >10:32:34,123 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:1b) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:34,123 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1b removing TSThread:3 >10:32:34,123 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1b removing TSThread:3 result = true >10:32:34,123 TRACE [com.arjuna.ats.arjuna] (EJB default - 1) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1b status: ActionStatus.COMMITTED ) >10:32:34,123 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (EJB default - 1) TestXAResourceRecovered.recover(i=16777216) >10:32:34,123 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) recover() >10:32:34,124 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) returning 0 Xids >10:32:34,133 TRACE [com.arjuna.ats.jta] (EJB default - 2) BaseTransaction.begin >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) StateManager::StateManager( 2, 0 ) >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::BasicAction() >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1c >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1c >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) ActionHierarchy::ActionHierarchy(5) >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:1c, 1) >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1c adding Thread[EJB default - 2,5,EJB default] >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1c adding Thread[EJB default - 2,5,EJB default] result = true >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1c status: ActionStatus.RUNNING, 300 ) >10:32:34,134 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1c status: ActionStatus.RUNNING, 300 ) >10:32:34,135 TRACE [com.arjuna.ats.jta] (EJB default - 2) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:34,135 TRACE [com.arjuna.ats.jta] (EJB default - 2) BaseTransaction.commit >10:32:34,135 TRACE [com.arjuna.ats.jta] (EJB default - 2) TransactionImple.commitAndDisassociate >10:32:34,135 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1c >10:32:34,135 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:1c) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:34,135 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1c removing TSThread:4 >10:32:34,135 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1c removing TSThread:4 result = true >10:32:34,135 TRACE [com.arjuna.ats.arjuna] (EJB default - 2) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1c status: ActionStatus.COMMITTED ) >10:32:34,146 TRACE [com.arjuna.ats.jta] (EJB default - 2) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:34,146 TRACE [com.arjuna.ats.jta] (EJB default - 2) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:34,170 INFO [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 2) There are 0 xids in doubt >10:32:34,757 TRACE [com.arjuna.ats.jta] (EJB default - 3) BaseTransaction.begin >10:32:34,757 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) StateManager::StateManager( 2, 0 ) >10:32:34,757 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::BasicAction() >10:32:34,757 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1d >10:32:34,758 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1d >10:32:34,758 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) ActionHierarchy::ActionHierarchy(5) >10:32:34,758 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:1d, 1) >10:32:34,758 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1d adding Thread[EJB default - 3,5,EJB default] >10:32:34,758 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1d adding Thread[EJB default - 3,5,EJB default] result = true >10:32:34,758 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1d status: ActionStatus.RUNNING, 300 ) >10:32:34,758 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1d status: ActionStatus.RUNNING, 300 ) >10:32:34,760 TRACE [com.arjuna.ats.jta] (EJB default - 3) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:34,760 TRACE [com.arjuna.ats.jta] (EJB default - 3) BaseTransaction.commit >10:32:34,760 TRACE [com.arjuna.ats.jta] (EJB default - 3) TransactionImple.commitAndDisassociate >10:32:34,760 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1d >10:32:34,760 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:1d) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:34,760 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1d removing TSThread:5 >10:32:34,760 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1d removing TSThread:5 result = true >10:32:34,760 TRACE [com.arjuna.ats.arjuna] (EJB default - 3) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1d status: ActionStatus.COMMITTED ) >10:32:34,761 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (EJB default - 3) Looking for connection factory java:/JmsXA >10:32:34,762 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (EJB default - 3) Looking for queue java:jboss/queue/MDBTriggerQueue >10:32:34,762 TRACE [com.arjuna.ats.jta] (EJB default - 3) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:34,763 TRACE [com.arjuna.ats.jta] (EJB default - 3) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:34,771 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (EJB default - 3) waiting to receive a message from java:jboss/queue/MDBTriggerQueue ... >10:32:41,051 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (EJB default - 4) Looking for connection factory java:/JmsXA >10:32:41,051 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (EJB default - 4) Looking for queue java:jboss/queue/crashRecoveryQueue >10:32:41,051 TRACE [com.arjuna.ats.jta] (EJB default - 4) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:41,053 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (EJB default - 4) waiting to receive a message from java:jboss/queue/crashRecoveryQueue ... >10:32:46,066 TRACE [com.arjuna.ats.jta] (EJB default - 5) BaseTransaction.begin >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) StateManager::StateManager( 2, 0 ) >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) BasicAction::BasicAction() >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1e >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1e >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) ActionHierarchy::ActionHierarchy(5) >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:1e, 1) >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e adding Thread[EJB default - 5,5,EJB default] >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e adding Thread[EJB default - 5,5,EJB default] result = true >10:32:46,066 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1e status: ActionStatus.RUNNING, 300 ) >10:32:46,067 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1e status: ActionStatus.RUNNING, 300 ) >10:32:46,068 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,068 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getResource >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.putResource >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getResource >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getResource >10:32:46,069 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getResource >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.getResource >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.putResource >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,073 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,075 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionSynchronizationRegistryImple.registerInterposedSynchronization >10:32:46,075 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,076 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.enlistResource ( XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA] ) >10:32:46,077 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:46,077 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) InputObjectState::InputObjectState() >10:32:46,077 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) FileSystemStore.allObjUids(EISNAME, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:32:46,077 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) OutputObjectState::OutputObjectState() >10:32:46,077 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) OutputObjectState::OutputObjectState() >10:32:46,077 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) FileSystemStore.write_committed(0:ffff7f000001:6d5ac93c:5339280e:22, EISNAME) >10:32:46,078 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) ShadowingStore.write_state(0:ffff7f000001:6d5ac93c:5339280e:22, EISNAME, StateType.OS_ORIGINAL) >10:32:46,078 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) ShadowingStore.genPathName(0:ffff7f000001:6d5ac93c:5339280e:22, EISNAME, StateType.OS_ORIGINAL) >10:32:46,078 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) FileSystemStore.genPathName(0:ffff7f000001:6d5ac93c:5339280e:22, EISNAME, 11) >10:32:46,079 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) FileSystemStore.openAndLock(/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/ShadowNoFileLockStore/defaultStore/EISNAME/0_ffff7f000001_6d5ac93c_5339280e_22, FileLock.F_WRLCK, true) >10:32:46,079 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) FileSystemStore.createHierarchy(/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/ShadowNoFileLockStore/defaultStore/EISNAME/0_ffff7f000001_6d5ac93c_5339280e_22) >10:32:46,518 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) FileSystemStore.closeAndUnlock(/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/ShadowNoFileLockStore/defaultStore/EISNAME/0_ffff7f000001_6d5ac93c_5339280e_22, null, java.io.FileOutputStream@ec89301) >10:32:46,522 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) StateManager::StateManager( 1, 0 ) >10:32:46,522 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) AbstractRecord::AbstractRecord (0:ffff7f000001:6d5ac93c:5339280e:24, 1) >10:32:46,522 TRACE [com.arjuna.ats.jta] (EJB default - 5) XAResourceRecord.XAResourceRecord ( < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:1e, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:23, subordinatenodename=null, eis_name=java:/JmsXA >, XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA] ), record id=0:ffff7f000001:6d5ac93c:5339280e:24 >10:32:46,526 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/XAResourceRecord for 0:ffff7f000001:6d5ac93c:5339280e:24 >10:32:46,538 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (EJB default - 5) Sending message MDB:none to queue queue/MDBTriggerQueue >10:32:46,541 TRACE [com.arjuna.ats.jta] (EJB default - 5) TransactionImpleManager.suspend >10:32:46,542 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e removing TSThread:6 >10:32:46,542 TRACE [com.arjuna.ats.arjuna] (EJB default - 5) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e removing TSThread:6 result = true >10:32:56,552 TRACE [com.arjuna.ats.jta] (EJB default - 6) TransactionImpleManager.resume >10:32:56,553 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e adding Thread[EJB default - 6,5,EJB default] >10:32:56,553 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e adding Thread[EJB default - 6,5,EJB default] result = true >10:32:56,553 TRACE [com.arjuna.ats.jta] (EJB default - 6) BaseTransaction.commit >10:32:56,553 TRACE [com.arjuna.ats.jta] (EJB default - 6) TransactionImple.commitAndDisassociate >10:32:56,553 TRACE [com.arjuna.ats.jta] (EJB default - 6) SynchronizationImple.beforeCompletion >10:32:56,553 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1e >10:32:56,554 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) BasicAction::onePhaseCommit() for action-id 0:ffff7f000001:6d5ac93c:5339280e:1e >10:32:56,554 TRACE [com.arjuna.ats.jta] (EJB default - 6) XAResourceRecord.topLevelOnePhaseCommit for XAResourceRecord < resource:XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA], txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:1e, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:23, subordinatenodename=null, eis_name=java:/JmsXA >, heuristic: TwoPhaseOutcome.FINISH_OK, product: HornetQ/2.0, jndiName: java:/JmsXA com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@183ea8d >, record id=0:ffff7f000001:6d5ac93c:5339280e:24 >10:32:56,693 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:1e) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,693 TRACE [com.arjuna.ats.jta] (EJB default - 6) SynchronizationImple.afterCompletion >10:32:56,695 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImpleManager.suspend >10:32:56,695 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) BaseTransaction.begin >10:32:56,695 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) StateManager::StateManager( 2, 0 ) >10:32:56,695 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::BasicAction() >10:32:56,695 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:26 >10:32:56,695 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:26 >10:32:56,695 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ActionHierarchy::ActionHierarchy(5) >10:32:56,695 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:26, 1) >10:32:56,696 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 adding Thread[Thread-2 (HornetQ-client-global-threads-39329233),5,HornetQ-client-global-threads-39329233] >10:32:56,696 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 adding Thread[Thread-2 (HornetQ-client-global-threads-39329233),5,HornetQ-client-global-threads-39329233] result = true >10:32:56,696 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:26 status: ActionStatus.RUNNING, 300 ) >10:32:56,696 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:26 status: ActionStatus.RUNNING, 300 ) >10:32:56,696 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.enlistResource ( DelegatingSession [session=ClientSessionImpl [name=fa71d121-b8ae-11e3-b4a8-9b998317edfe, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=(resource-adapter=inbound,jms-session=,)]@36a4646e] ) >10:32:56,696 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,697 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) StateManager::StateManager( 1, 0 ) >10:32:56,698 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) AbstractRecord::AbstractRecord (0:ffff7f000001:6d5ac93c:5339280e:28, 1) >10:32:56,698 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) XAResourceRecord.XAResourceRecord ( < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:27, subordinatenodename=null, eis_name=unknown eis name >, DelegatingSession [session=ClientSessionImpl [name=fa71d121-b8ae-11e3-b4a8-9b998317edfe, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=(resource-adapter=inbound,jms-session=,)]@36a4646e] ), record id=0:ffff7f000001:6d5ac93c:5339280e:28 >10:32:56,697 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e removing TSThread:7 >10:32:56,699 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/XAResourceRecord for 0:ffff7f000001:6d5ac93c:5339280e:28 >10:32:56,699 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:1e removing TSThread:7 result = true >10:32:56,699 TRACE [com.arjuna.ats.arjuna] (EJB default - 6) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:1e status: ActionStatus.COMMITTED ) >10:32:56,702 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImpleManager.suspend >10:32:56,702 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 removing TSThread:8 >10:32:56,702 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 removing TSThread:8 result = true >10:32:56,702 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) BaseTransaction.begin >10:32:56,702 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) StateManager::StateManager( 2, 0 ) >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::BasicAction() >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:2a >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:2a >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ActionHierarchy::ActionHierarchy(5) >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:2a, 1) >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:2a adding Thread[Thread-2 (HornetQ-client-global-threads-39329233),5,HornetQ-client-global-threads-39329233] >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:2a adding Thread[Thread-2 (HornetQ-client-global-threads-39329233),5,HornetQ-client-global-threads-39329233] result = true >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:2a status: ActionStatus.RUNNING, 300 ) >10:32:56,703 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:2a status: ActionStatus.RUNNING, 300 ) >10:32:56,704 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,704 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) BaseTransaction.commit >10:32:56,705 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.commitAndDisassociate >10:32:56,705 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:2a >10:32:56,705 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:2a) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,706 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:2a removing TSThread:8 >10:32:56,706 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:2a removing TSThread:8 result = true >10:32:56,706 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:2a status: ActionStatus.COMMITTED ) >10:32:56,706 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImpleManager.resume >10:32:56,706 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 adding Thread[Thread-2 (HornetQ-client-global-threads-39329233),5,HornetQ-client-global-threads-39329233] >10:32:56,706 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 adding Thread[Thread-2 (HornetQ-client-global-threads-39329233),5,HornetQ-client-global-threads-39329233] result = true >10:32:56,706 INFO [org.jboss.as.test.jbossts.crashrec.jms.mdb.JMSCrashMessageDrivenBean] (Thread-2 (HornetQ-client-global-threads-39329233)) Receiving message HornetQMessage[ID:0942d806-b8af-11e3-b4a8-9b998317edfe]:PERSISTENT in MDB with text 'MDB:none' >10:32:56,766 TRACE [com.arjuna.ats.jta] (EJB default - 7) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:56,766 INFO [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 7) There are 0 xids in doubt >10:32:56,771 INFO [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) CrashBeanCommon.enlistSynchronization >10:32:56,772 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.registerSynchronization >10:32:56,773 INFO [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) CrashBeanCommon.enlistXAResource >10:32:56,773 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.enlistResource ( TestXAResource(TestXAResourceCommon(0, null)) ) >10:32:56,773 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,773 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) TestXAResourceCommon.setTransactionTimeout(i=300) >10:32:56,773 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) StateManager::StateManager( 1, 0 ) >10:32:56,773 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) AbstractRecord::AbstractRecord (0:ffff7f000001:6d5ac93c:5339280e:2d, 1) >10:32:56,774 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) XAResourceRecord.XAResourceRecord ( < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >, TestXAResource(TestXAResourceCommon(300, null)) ), record id=0:ffff7f000001:6d5ac93c:5339280e:2d >10:32:56,774 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) TestXAResourceCommon.start(Xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >, i=0) >10:32:56,774 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) RecordList::insert(RecordList: 0:ffff7f000001:6d5ac93c:5339280e:28) : appending /StateManager/AbstractRecord/XAResourceRecord for 0:ffff7f000001:6d5ac93c:5339280e:2d >10:32:56,774 INFO [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) CrashBeanCommon.beforeEntityUpdate >10:32:56,774 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,774 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:56,774 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:56,774 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getResource >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getResource >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.putResource >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getResource >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getTransactionKey >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getResource >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.getResource >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.putResource >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,775 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,776 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionSynchronizationRegistryImple.registerInterposedSynchronization >10:32:56,776 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,776 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.enlistResource ( XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA] ) >10:32:56,776 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,776 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) TestXAResourceCommon.isSameRM(xaResource=XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA]) - returning false >10:32:56,777 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) StateManager::StateManager( 1, 0 ) >10:32:56,777 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) AbstractRecord::AbstractRecord (0:ffff7f000001:6d5ac93c:5339280e:31, 1) >10:32:56,777 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) XAResourceRecord.XAResourceRecord ( < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:30, subordinatenodename=null, eis_name=java:/JmsXA >, XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA] ), record id=0:ffff7f000001:6d5ac93c:5339280e:31 >10:32:56,777 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) RecordList::insert(RecordList: 0:ffff7f000001:6d5ac93c:5339280e:28 0:ffff7f000001:6d5ac93c:5339280e:2d) : appending /StateManager/AbstractRecord/XAResourceRecord for 0:ffff7f000001:6d5ac93c:5339280e:31 >10:32:56,778 INFO [org.jboss.as.test.jbossts.crashrec.jms.MessageHelper] (Thread-2 (HornetQ-client-global-threads-39329233)) Sending message JMSCrashMessageDrivenBean to queue queue/crashRecoveryQueue >10:32:56,782 INFO [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) CrashBeanCommon.afterEntityUpdate >10:32:56,782 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,783 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) BaseTransaction.commit >10:32:56,783 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionImple.commitAndDisassociate >10:32:56,783 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) SynchronizationImple.beforeCompletion >10:32:56,783 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) SynchronizationImple.beforeCompletion >10:32:56,783 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:26 >10:32:56,783 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::prepare () for action-id 0:ffff7f000001:6d5ac93c:5339280e:26 >10:32:56,783 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) XAResourceRecord.topLevelPrepare for XAResourceRecord < resource:DelegatingSession [session=ClientSessionImpl [name=fa71d121-b8ae-11e3-b4a8-9b998317edfe, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=(resource-adapter=inbound,jms-session=,)]@36a4646e], txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:27, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@4f61b6a6 >, record id=0:ffff7f000001:6d5ac93c:5339280e:28 >10:32:56,788 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 65) BaseTransaction.begin >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) StateManager::StateManager( 2, 0 ) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::BasicAction() >10:32:56,789 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 66) BaseTransaction.begin >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:33 >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) StateManager::StateManager( 2, 0 ) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:33 >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::BasicAction() >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) ActionHierarchy::ActionHierarchy(5) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:34 >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:33, 1) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:34 >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:33 adding Thread[ServerService Thread Pool -- 65,5,ServerService ThreadGroup] >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) ActionHierarchy::ActionHierarchy(5) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:33 adding Thread[ServerService Thread Pool -- 65,5,ServerService ThreadGroup] result = true >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:34, 1) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:33 status: ActionStatus.RUNNING, 300 ) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:34 adding Thread[ServerService Thread Pool -- 66,5,ServerService ThreadGroup] >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:33 status: ActionStatus.RUNNING, 300 ) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:34 adding Thread[ServerService Thread Pool -- 66,5,ServerService ThreadGroup] result = true >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:34 status: ActionStatus.RUNNING, 300 ) >10:32:56,789 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:34 status: ActionStatus.RUNNING, 300 ) >10:32:56,790 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 66) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,790 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 66) BaseTransaction.commit >10:32:56,790 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 66) TransactionImple.commitAndDisassociate >10:32:56,789 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 65) TestXAResourceRecoveryHelper stopping >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:34 >10:32:56,790 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 65) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:34) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,790 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 65) BaseTransaction.commit >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:34 removing TSThread:a >10:32:56,790 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 65) TransactionImple.commitAndDisassociate >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:34 removing TSThread:a result = true >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:33 >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 66) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:34 status: ActionStatus.COMMITTED ) >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:33) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:33 removing TSThread:9 >10:32:56,790 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:33 removing TSThread:9 result = true >10:32:56,791 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 65) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:33 status: ActionStatus.COMMITTED ) >10:32:56,791 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 69) BaseTransaction.begin >10:32:56,791 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) StateManager::StateManager( 2, 0 ) >10:32:56,791 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::BasicAction() >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:35 >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:35 >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) ActionHierarchy::ActionHierarchy(5) >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:35, 1) >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:35 adding Thread[ServerService Thread Pool -- 69,5,ServerService ThreadGroup] >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:35 adding Thread[ServerService Thread Pool -- 69,5,ServerService ThreadGroup] result = true >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:35 status: ActionStatus.RUNNING, 300 ) >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:35 status: ActionStatus.RUNNING, 300 ) >10:32:56,792 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) BaseTransaction.begin >10:32:56,792 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) StateManager::StateManager( 2, 0 ) >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::BasicAction() >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:36 >10:32:56,792 INFO [org.jboss.as.test.jbossts.common.TransactionStoreBrowserService] (ServerService Thread Pool -- 69) TransactionStoreBrowserService.stop >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:36 >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) ActionHierarchy::ActionHierarchy(5) >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:36, 1) >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:36 adding Thread[ServerService Thread Pool -- 67,5,ServerService ThreadGroup] >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:36 adding Thread[ServerService Thread Pool -- 67,5,ServerService ThreadGroup] result = true >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:36 status: ActionStatus.RUNNING, 300 ) >10:32:56,793 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:36 status: ActionStatus.RUNNING, 300 ) >10:32:56,793 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,793 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 69) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,793 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) BaseTransaction.commit >10:32:56,794 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 69) BaseTransaction.commit >10:32:56,794 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) TransactionImple.commitAndDisassociate >10:32:56,794 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 69) TransactionImple.commitAndDisassociate >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:36 >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:35 >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:36) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:35) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:36 removing TSThread:c >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:35 removing TSThread:b >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:36 removing TSThread:c result = true >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:35 removing TSThread:b result = true >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:36 status: ActionStatus.COMMITTED ) >10:32:56,794 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 69) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:35 status: ActionStatus.COMMITTED ) >10:32:56,797 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) BaseTransaction.begin >10:32:56,797 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) StateManager::StateManager( 2, 0 ) >10:32:56,797 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::BasicAction() >10:32:56,797 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:37 >10:32:56,797 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:37 >10:32:56,797 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) ActionHierarchy::ActionHierarchy(5) >10:32:56,798 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:37, 1) >10:32:56,798 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:37 adding Thread[ServerService Thread Pool -- 68,5,ServerService ThreadGroup] >10:32:56,792 WARN [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) ARJUNA016041: prepare on < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:27, subordinatenodename=null, eis_name=unknown eis name > (DelegatingSession [session=ClientSessionImpl [name=fa71d121-b8ae-11e3-b4a8-9b998317edfe, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=(resource-adapter=inbound,jms-session=,)]@36a4646e]) failed with exception -: org.hornetq.api.core.HornetQInterruptedException: java.lang.InterruptedException > at org.hornetq.core.protocol.core.impl.ChannelImpl.sendBlocking(ChannelImpl.java:363) > at org.hornetq.core.client.impl.ClientSessionImpl.prepare(ClientSessionImpl.java:1576) > at org.hornetq.core.client.impl.DelegatingSession.prepare(DelegatingSession.java:436) > at com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord.topLevelPrepare(XAResourceRecord.java:213) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.arjuna.coordinator.BasicAction.doPrepare(BasicAction.java:2522) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.arjuna.coordinator.BasicAction.doPrepare(BasicAction.java:2489) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(BasicAction.java:2066) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1481) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1189) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75) > at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.afterDelivery(MessageEndpointInvocationHandler.java:72) [jboss-as-ejb3-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6] > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51] > at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.handle(AbstractInvocationHandler.java:60) [jboss-as-ejb3-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6] > at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.doInvoke(MessageEndpointInvocationHandler.java:136) [jboss-as-ejb3-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6] > at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.invoke(AbstractInvocationHandler.java:73) [jboss-as-ejb3-7.4.0.Final-redhat-6.jar:7.4.0.Final-redhat-6] > at com.sun.proxy.$Proxy20.afterDelivery(Unknown Source) > at org.hornetq.ra.inflow.HornetQMessageHandler.onMessage(HornetQMessageHandler.java:337) > at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:1117) > at org.hornetq.core.client.impl.ClientConsumerImpl.access$500(ClientConsumerImpl.java:57) > at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:1252) > at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:105) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] >Caused by: java.lang.InterruptedException > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2017) [rt.jar:1.7.0_51] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2188) [rt.jar:1.7.0_51] > at org.hornetq.core.protocol.core.impl.ChannelImpl.sendBlocking(ChannelImpl.java:359) > ... 29 more > >10:32:56,798 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:37 adding Thread[ServerService Thread Pool -- 68,5,ServerService ThreadGroup] result = true >10:32:56,798 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:37 status: ActionStatus.RUNNING, 300 ) >10:32:56,798 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:37 status: ActionStatus.RUNNING, 300 ) >10:32:56,798 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,798 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::doPrepare() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:26) on record id: (0:ffff7f000001:6d5ac93c:5339280e:28) is (TwoPhaseOutcome.PREPARE_NOTOK) node id: (1) >10:32:56,799 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) BaseTransaction.commit >10:32:56,799 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) RecordList::insert(RecordList: 0:ffff7f000001:6d5ac93c:5339280e:2d 0:ffff7f000001:6d5ac93c:5339280e:31) : inserting /StateManager/AbstractRecord/XAResourceRecord for 0:ffff7f000001:6d5ac93c:5339280e:28 before /StateManager/AbstractRecord/XAResourceRecord >10:32:56,799 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) TransactionImple.commitAndDisassociate >10:32:56,799 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:37 >10:32:56,799 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:37) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,799 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:37 removing TSThread:d >10:32:56,799 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:37 removing TSThread:d result = true >10:32:56,799 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:37 status: ActionStatus.COMMITTED ) >10:32:56,799 WARN [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ARJUNA012073: BasicAction.End() - prepare phase of action-id 0:ffff7f000001:6d5ac93c:5339280e:26 failed. >10:32:56,800 WARN [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) ARJUNA012075: Action Aborting >10:32:56,800 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::phase2Abort() for action-id 0:ffff7f000001:6d5ac93c:5339280e:26 >10:32:56,800 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::doAbort (XAResourceRecord < resource:DelegatingSession [session=ClientSessionImpl [name=fa71d121-b8ae-11e3-b4a8-9b998317edfe, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=(resource-adapter=inbound,jms-session=,)]@36a4646e], txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:27, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@4f61b6a6 >) >10:32:56,801 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) XAResourceRecord.topLevelAbort for XAResourceRecord < resource:DelegatingSession [session=ClientSessionImpl [name=fa71d121-b8ae-11e3-b4a8-9b998317edfe, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=f9288835-b8ae-11e3-b4a8-9b998317edfe, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=(resource-adapter=inbound,jms-session=,)]@36a4646e], txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:27, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@4f61b6a6 >, record id=0:ffff7f000001:6d5ac93c:5339280e:28 >10:32:56,825 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::doAbort() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:26) on record id: (0:ffff7f000001:6d5ac93c:5339280e:28) is (TwoPhaseOutcome.FINISH_OK) node id: (1) >10:32:56,825 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::doAbort (XAResourceRecord < resource:TestXAResource(TestXAResourceCommon(300, < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >)), txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@7e626ef7 >) >10:32:56,825 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) XAResourceRecord.topLevelAbort for XAResourceRecord < resource:TestXAResource(TestXAResourceCommon(300, < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >)), txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >, heuristic: TwoPhaseOutcome.FINISH_OK com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@7e626ef7 >, record id=0:ffff7f000001:6d5ac93c:5339280e:2d >10:32:56,825 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) TestXAResourceCommon.end(Xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >, b=536870912) >10:32:56,826 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Thread-2 (HornetQ-client-global-threads-39329233)) TestXAResourceCommon.rollback(Xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name >) >10:32:56,826 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Thread-2 (HornetQ-client-global-threads-39329233)) removeLog(xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name > >10:32:56,826 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Thread-2 (HornetQ-client-global-threads-39329233)) no log present for < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:2c, subordinatenodename=null, eis_name=unknown eis name > >10:32:56,826 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::doAbort() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:26) on record id: (0:ffff7f000001:6d5ac93c:5339280e:2d) is (TwoPhaseOutcome.FINISH_OK) node id: (1) >10:32:56,827 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::doAbort (XAResourceRecord < resource:XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA], txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:30, subordinatenodename=null, eis_name=java:/JmsXA >, heuristic: TwoPhaseOutcome.FINISH_OK, product: HornetQ/2.0, jndiName: java:/JmsXA com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@70635af3 >) >10:32:56,827 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) XAResourceRecord.topLevelAbort for XAResourceRecord < resource:XAResourceWrapperImpl@6b4907a6[xaResource=org.hornetq.ra.HornetQRAXAResource@7955a51 pad=false overrideRmValue=null productName=HornetQ productVersion=2.0 jndiName=java:/JmsXA], txid:< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:6d5ac93c:5339280e:26, node_name=1, branch_uid=0:ffff7f000001:6d5ac93c:5339280e:30, subordinatenodename=null, eis_name=java:/JmsXA >, heuristic: TwoPhaseOutcome.FINISH_OK, product: HornetQ/2.0, jndiName: java:/JmsXA com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@70635af3 >, record id=0:ffff7f000001:6d5ac93c:5339280e:31 >10:32:56,840 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::doAbort() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:26) on record id: (0:ffff7f000001:6d5ac93c:5339280e:31) is (TwoPhaseOutcome.FINISH_OK) node id: (1) >10:32:56,840 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::updateState() for action-id 0:ffff7f000001:6d5ac93c:5339280e:26 >10:32:56,840 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:26) is (TwoPhaseOutcome.FINISH_OK) node id: (1) >10:32:56,840 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) SynchronizationImple.afterCompletion >10:32:56,841 TRACE [com.arjuna.ats.jta] (Thread-2 (HornetQ-client-global-threads-39329233)) SynchronizationImple.afterCompletion >10:32:56,841 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 removing TSThread:8 >10:32:56,841 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:26 removing TSThread:8 result = true >10:32:56,841 TRACE [com.arjuna.ats.arjuna] (Thread-2 (HornetQ-client-global-threads-39329233)) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:26 status: ActionStatus.ABORTED ) >10:32:56,842 WARN [org.hornetq.ra] (Thread-2 (HornetQ-client-global-threads-39329233)) HQ152006: Unable to call after delivery: javax.resource.spi.LocalTransactionException: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction. > at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.afterDelivery(MessageEndpointInvocationHandler.java:88) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_51] > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_51] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_51] > at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_51] > at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.handle(AbstractInvocationHandler.java:60) > at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.doInvoke(MessageEndpointInvocationHandler.java:136) > at org.jboss.as.ejb3.inflow.AbstractInvocationHandler.invoke(AbstractInvocationHandler.java:73) > at com.sun.proxy.$Proxy20.afterDelivery(Unknown Source) > at org.hornetq.ra.inflow.HornetQMessageHandler.onMessage(HornetQMessageHandler.java:337) [hornetq-ra-2.3.18.Final.jar:] > at org.hornetq.core.client.impl.ClientConsumerImpl.callOnMessage(ClientConsumerImpl.java:1117) [hornetq-core-client-2.3.18.Final.jar:] > at org.hornetq.core.client.impl.ClientConsumerImpl.access$500(ClientConsumerImpl.java:57) [hornetq-core-client-2.3.18.Final.jar:] > at org.hornetq.core.client.impl.ClientConsumerImpl$Runner.run(ClientConsumerImpl.java:1252) [hornetq-core-client-2.3.18.Final.jar:] > at org.hornetq.utils.OrderedExecutorFactory$OrderedExecutor$1.run(OrderedExecutorFactory.java:105) [hornetq-core-client-2.3.18.Final.jar:] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51] > at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51] >Caused by: javax.transaction.RollbackException: ARJUNA016053: Could not commit transaction. > at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1201) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126) [jbossjts-jacorb-4.17.18.Final.jar:4.17.18.Final] > at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75) > at org.jboss.as.ejb3.inflow.MessageEndpointInvocationHandler.afterDelivery(MessageEndpointInvocationHandler.java:72) > ... 16 more > >10:32:56,846 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 64) BaseTransaction.begin >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) StateManager::StateManager( 2, 0 ) >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::BasicAction() >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::Begin() for action-id 0:ffff7f000001:6d5ac93c:5339280e:38 >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::actionInitialise() for action-id 0:ffff7f000001:6d5ac93c:5339280e:38 >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) ActionHierarchy::ActionHierarchy(5) >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) ActionHierarchy::add(0:ffff7f000001:6d5ac93c:5339280e:38, 1) >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:38 adding Thread[ServerService Thread Pool -- 64,5,ServerService ThreadGroup] >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::addChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:38 adding Thread[ServerService Thread Pool -- 64,5,ServerService ThreadGroup] result = true >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:38 status: ActionStatus.RUNNING, 300 ) >10:32:56,846 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:38 status: ActionStatus.RUNNING, 300 ) >10:32:56,847 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 64) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:32:56,847 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 64) BaseTransaction.commit >10:32:56,847 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 64) TransactionImple.commitAndDisassociate >10:32:56,847 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::End() for action-id 0:ffff7f000001:6d5ac93c:5339280e:38 >10:32:56,847 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::End() result for action-id (0:ffff7f000001:6d5ac93c:5339280e:38) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:32:56,847 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:38 removing TSThread:e >10:32:56,847 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) BasicAction::removeChildThread () action 0:ffff7f000001:6d5ac93c:5339280e:38 removing TSThread:e result = true >10:32:56,847 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 64) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:6d5ac93c:5339280e:38 status: ActionStatus.COMMITTED ) >10:32:56,862 INFO [org.jboss.as.server.deployment] (MSC service thread 1-7) JBAS015877: Stopped deployment crashrecovery-jms.jar (runtime-name: crashrecovery-jms.jar) in 83ms >10:32:57,188 INFO [org.jboss.as.repository] (management-handler-thread - 3) JBAS014901: Content removed from location /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/content/59/eb3602cadad01b61bfb668e5f99a8843a5cf09/content >10:32:57,189 INFO [org.jboss.as.server] (management-handler-thread - 3) JBAS018558: Undeployed "crashrecovery-jms.jar" (runtime-name: "crashrecovery-jms.jar") >10:32:57,198 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 64) JBAS011605: Unbound messaging object to jndi name java:jboss/queue/MDBTriggerQueue >10:32:57,435 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 64) JBAS011605: Unbound messaging object to jndi name java:jboss/queue/crashRecoveryQueue >10:32:57,674 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-4) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA] >10:32:57,682 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-7) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS] >10:32:57,686 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost.localdomain/127.0.0.1:8080 >10:32:57,689 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 68) JBAS011605: Unbound messaging object to jndi name java:/jms/queue/DLQ >10:32:57,704 INFO [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost.localdomain/127.0.0.1:8080 >10:32:57,711 INFO [org.jboss.jaxr] (MSC service thread 1-2) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory >10:32:57,738 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 65) JBAS011605: Unbound messaging object to jndi name java:/ConnectionFactory >10:32:57,757 INFO [org.apache.catalina.core] (MSC service thread 1-3) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started >10:32:57,768 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015877: Stopped deployment dsdriver.jar (runtime-name: dsdriver.jar) in 69ms >10:32:57,776 INFO [org.hornetq.ra] (ServerService Thread Pool -- 67) HQ151003: HornetQ resource adaptor stopped >10:32:58,016 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 67) HQ221002: HornetQ Server version 2.3.18.Final (2.3.18, 123) [f63f71f6-b8ae-11e3-b4a8-9b998317edfe] stopped >10:32:58,022 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-8) ARJUNA032018: Destroying TransactionManagerService >10:32:58,026 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-5) ARJUNA032014: Stopping transaction recovery manager >10:32:58,029 DEBUG [com.arjuna.ats.arjuna] (Listener:4712) Recovery listener existing com.arjuna.ats.internal.arjuna.recovery.WorkerService >10:32:58,029 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-5) PeriodicRecovery: Mode <== TERMINATED >10:32:58,029 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-5) PeriodicRecovery: shutdown scan wait complete >10:32:58,029 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread exiting >10:32:58,032 INFO [org.jboss.as] (MSC service thread 1-5) JBAS015950: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-6) stopped in 319ms
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1082652
: 880787 |
880788
|
880789
|
902145