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 907536 Details for
Bug 1107991
When password is not defined in xa datasource recovery is not run in any case
[?]
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.
server.log with trace on Narayana
server.log (text/x-log), 107.98 KB, created by
Ondrej Chaloupka
on 2014-06-11 08:57:55 UTC
(
hide
)
Description:
server.log with trace on Narayana
Filename:
MIME Type:
Creator:
Ondrej Chaloupka
Created:
2014-06-11 08:57:55 UTC
Size:
107.98 KB
patch
obsolete
>10:06:25,124 INFO [org.jboss.modules] (main) JBoss Modules version 1.3.3.Final-redhat-1 >10:06:25,376 INFO [org.jboss.msc] (main) JBoss MSC version 1.1.5.Final-redhat-1 >10:06:25,511 INFO [org.jboss.as] (MSC service thread 1-8) JBAS015899: JBoss EAP 6.3.0.Beta2 (AS 7.4.0.Final-redhat-15) starting >10:06:25,518 DEBUG [org.jboss.as.config] (MSC service thread 1-8) Configured system properties: > [Standalone] = > awt.toolkit = sun.awt.X11.XToolkit > file.encoding = UTF-8 > file.encoding.pkg = sun.io > file.separator = / > java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment > java.awt.headless = true > java.awt.printerjob = sun.print.PSPrinterJob > java.class.path = /home/ochaloup/jboss/jboss-eap-6.3/jboss-modules.jar > java.class.version = 51.0 > java.endorsed.dirs = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/endorsed > java.ext.dirs = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.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.7.4.fc20.x86_64/jre > java.io.tmpdir = /tmp > java.library.path = /usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib > java.net.preferIPv4Stack = true > java.runtime.name = OpenJDK Runtime Environment > java.runtime.version = 1.7.0_55-mockbuild_2014_05_22_13_12-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_55 > 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.home.dir = /home/ochaloup/jboss/jboss-eap-6.3 > jboss.host.name = localhost > jboss.modules.dir = /home/ochaloup/jboss/jboss-eap-6.3/modules > jboss.modules.policy-permissions = true > jboss.modules.system.pkgs = org.jboss.byteman > jboss.node.name = localhost > jboss.qualified.host.name = localhost.localdomain > jboss.server.base.dir = /home/ochaloup/jboss/jboss-eap-6.3/standalone > jboss.server.config.dir = /home/ochaloup/jboss/jboss-eap-6.3/standalone/configuration > jboss.server.data.dir = /home/ochaloup/jboss/jboss-eap-6.3/standalone/data > jboss.server.deploy.dir = /home/ochaloup/jboss/jboss-eap-6.3/standalone/data/content > jboss.server.log.dir = /home/ochaloup/jboss/jboss-eap-6.3/standalone/log > jboss.server.name = localhost > jboss.server.persist.config = true > jboss.server.temp.dir = /home/ochaloup/jboss/jboss-eap-6.3/standalone/tmp > line.separator = > > logging.configuration = file:/home/ochaloup/jboss/jboss-eap-6.3/standalone/configuration/logging.properties > module.path = /home/ochaloup/jboss/jboss-eap-6.3/modules > org.jboss.boot.log.file = /home/ochaloup/jboss/jboss-eap-6.3/standalone/log/server.log > org.jboss.resolver.warning = true > org.xml.sax.driver = __redirected.__XMLReaderFactory > os.arch = amd64 > os.name = Linux > os.version = 3.14.5-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.7.4.fc20.x86_64/jre/lib/resources.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/rt.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/sunrsasign.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/jsse.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/jce.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/charsets.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/netx.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/plugin.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/rhino.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/jfr.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/classes > sun.boot.library.path = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.7.4.fc20.x86_64/jre/lib/amd64 > sun.cpu.endian = little > sun.cpu.isalist = > sun.io.unicode.encoding = UnicodeLittle > sun.java.command = /home/ochaloup/jboss/jboss-eap-6.3/jboss-modules.jar -mp /home/ochaloup/jboss/jboss-eap-6.3/modules -jaxpmodule javax.xml.jaxp-provider org.jboss.as.standalone -Djboss.home.dir=/home/ochaloup/jboss/jboss-eap-6.3 -Djboss.server.base.dir=/home/ochaloup/jboss/jboss-eap-6.3/standalone -c standalone-full.jon.xml > sun.java.launcher = SUN_STANDARD > sun.jnu.encoding = UTF-8 > sun.management.compiler = HotSpot 64-Bit Tiered Compilers > sun.os.patch.level = unknown > user.country = US > user.dir = /home/ochaloup/jboss/jboss-eap-6.3 > user.home = /home/ochaloup > user.language = en > user.name = ochaloup > user.timezone = Europe/Prague >10:06:25,521 DEBUG [org.jboss.as.config] (MSC service thread 1-8) VM Arguments: -D[Standalone] -XX:+UseCompressedOops -verbose:gc -Xloggc:/home/ochaloup/jboss/jboss-eap-6.3/standalone/log/gc.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles=5 -XX:GCLogFileSize=3M -XX:-TraceClassUnloading -Xms1303m -Xmx1303m -XX:MaxPermSize=256m -Djava.net.preferIPv4Stack=true -Djboss.modules.system.pkgs=org.jboss.byteman -Djava.awt.headless=true -Djboss.modules.policy-permissions=true -Dorg.jboss.boot.log.file=/home/ochaloup/jboss/jboss-eap-6.3/standalone/log/server.log -Dlogging.configuration=file:/home/ochaloup/jboss/jboss-eap-6.3/standalone/configuration/logging.properties >10:06:26,912 INFO [org.xnio] (MSC service thread 1-8) XNIO Version 3.0.10.GA-redhat-1 >10:06:26,917 INFO [org.jboss.as.server] (Controller Boot Thread) JBAS015888: Creating http management service using socket-binding (management-http) >10:06:26,925 INFO [org.xnio.nio] (MSC service thread 1-8) XNIO NIO Implementation Version 3.0.10.GA-redhat-1 >10:06:26,961 INFO [org.jboss.remoting] (MSC service thread 1-8) JBoss Remoting version (unknown) >10:06:27,007 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 35) JBAS010280: Activating Infinispan subsystem. >10:06:27,024 INFO [org.jboss.as.jacorb] (ServerService Thread Pool -- 36) JBAS016300: Activating JacORB Subsystem >10:06:27,080 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 43) JBAS012605: Activated the following JSF Implementations: [main, 1.2] >10:06:27,108 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 47) JBAS011800: Activating Naming Subsystem >10:06:27,130 INFO [org.jboss.as.security] (ServerService Thread Pool -- 52) JBAS013171: Activating Security Subsystem >10:06:27,130 INFO [org.jboss.as.connector.logging] (MSC service thread 1-2) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.26.Final-redhat-1) >10:06:27,154 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 54) JBAS010153: Node identifier property is set to the default value. Please make sure it is unique. >10:06:27,178 INFO [org.jboss.as.security] (MSC service thread 1-3) JBAS013170: Current PicketBox version=4.0.19.SP8-redhat-1 >10:06:27,231 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 31) JBAS010404: Deploying non-JDBC-compliant driver class org.postgresql.Driver (version 9.2) >10:06:27,234 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 56) JBAS015537: Activating WebServices Extension >10:06:27,304 INFO [org.jboss.as.mail.extension] (MSC service thread 1-6) JBAS015400: Bound mail session [java:jboss/mail/Default] >10:06:27,306 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 31) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) >10:06:27,307 INFO [org.jboss.as.naming] (MSC service thread 1-8) JBAS011802: Starting Naming Service >10:06:27,367 INFO [org.jboss.jaxr] (MSC service thread 1-1) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory >10:06:27,696 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-6) ARJUNA032010: JBossTS Recovery Service (tag: bea48879928270f8b0933725f6baf1c10cce649c) - JBoss Inc. >10:06:27,791 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-6) ARJUNA012324: Start RecoveryActivators >10:06:27,799 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) Expiry scan interval set to 43200 seconds >10:06:27,800 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.arjuna.recovery.ExpiredTransactionStatusManagerScanner >10:06:27,800 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) Expiry scan interval set to 43200 seconds >10:06:27,800 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) ExpiredTransactionStatusManagerScanner created, with expiry time of 43200 seconds >10:06:27,801 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.arjuna.objectstore.ShadowNoFileLockStore >10:06:27,814 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) FileSystemStore.createHierarchy(/home/ochaloup/jboss/jboss-eap-6.3/standalone/data/tx-object-store/ShadowNoFileLockStore/defaultStore/) >10:06:27,823 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-5) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost.localdomain/127.0.0.1:8080 >10:06:27,827 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) ExpiredEntryMonitor - constructed >10:06:27,873 INFO [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ARJUNA012296: ExpiredEntryMonitor running at Wed, 11 Jun 2014 10:06:27 >10:06:27,874 DEBUG [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ExpiredTransactionStatusManagerScanner - scanning to remove items from before Tue, 10 Jun 2014 22:06:27 >10:06:27,875 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) InputObjectState::InputObjectState() >10:06:27,877 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:06:27,877 TRACE [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) OutputObjectState::OutputObjectState() >10:06:27,878 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.CommitMarkableResourceRecordRecoveryModule >10:06:27,879 DEBUG [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) >10:06:27,883 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-5) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost.localdomain/127.0.0.1:8080 >10:06:27,894 INFO [org.jboss.as.jacorb] (MSC service thread 1-2) JBAS016330: CORBA ORB Service started >10:06:27,907 INFO [org.jboss.ws.common.management] (MSC service thread 1-8) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.3.0.Final-redhat-3 >10:06:27,908 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.arjuna.coordinator.CheckedActionFactoryImple >10:06:27,914 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) StateManager::StateManager( 2, 0 ) >10:06:27,914 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) BasicAction::BasicAction() >10:06:27,917 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) InputObjectState::InputObjectState() >10:06:27,918 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) FileSystemStore.allObjUids(/Recovery/TransactionStatusManager, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:06:27,918 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) OutputObjectState::OutputObjectState() >10:06:27,918 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.arjuna.recovery.AtomicActionRecoveryModule >10:06:27,918 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) StateManager::StateManager( 2, 0 ) >10:06:27,919 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) BasicAction::BasicAction() >10:06:27,919 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) AtomicActionRecoveryModule created >10:06:27,919 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) InputObjectState::InputObjectState() >10:06:27,919 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) FileSystemStore.allObjUids(/Recovery/TransactionStatusManager, InputObjectState Uid : 0:0:0:0:0 >InputObjectState Type : null >InputObjectState Size : 0 >InputObjectState Buffer: , -1) >10:06:27,919 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) OutputObjectState::OutputObjectState() >10:06:27,919 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.txoj.recovery.TORecoveryModule >10:06:27,922 DEBUG [com.arjuna.ats.txoj] (MSC service thread 1-6) TORecoveryModule created >10:06:27,922 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.arjuna.objectstore.ShadowNoFileLockStore >10:06:27,923 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) FileSystemStore.createHierarchy(/home/ochaloup/jboss/jboss-eap-6.3/standalone/data/tx-object-store/ShadowNoFileLockStore/defaultStore/) >10:06:27,923 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule >10:06:27,925 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) InputObjectState::InputObjectState() >10:06:27,925 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.JTATransactionLogXAResourceOrphanFilter >10:06:27,925 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.JTANodeNameXAResourceOrphanFilter >10:06:27,926 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.internal.jta.recovery.arjunacore.SubordinateJTAXAResourceOrphanFilter >10:06:27,930 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-6) ARJUNA012310: Recovery manager listening on endpoint 127.0.0.1:4712 >10:06:27,930 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) PeriodicRecovery: starting listener worker thread >10:06:27,932 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-6) ARJUNA012344: RecoveryManagerImple is ready on port 4712 >10:06:27,932 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-6) ARJUNA032013: Starting transaction recovery manager >10:06:27,933 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== SCANNING >10:06:27,933 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread scanning >10:06:27,933 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery first pass at Wed, 11 Jun 2014 10:06:27 >10:06:27,934 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) CommitMarkableResourceRecordRecoveryModule::periodicWorkFirstPass >10:06:27,934 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:06:27,934 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:06:27,934 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) ObjectStoreDir: /home/ochaloup/jboss/jboss-eap-6.3/standalone/data/tx-object-store >10:06:27,934 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:06:27,934 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:06:27,934 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) StateManager::StateManager( 2, 0 ) >10:06:27,935 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:06:27,935 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) BasicAction::BasicAction() >10:06:27,935 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:06:27,935 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:06:27,935 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:27,935 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:06:27,935 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule first pass >10:06:27,935 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:06:27,935 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:06:27,936 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:06:27,936 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:27,936 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - first pass >10:06:27,936 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:06:27,936 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:06:27,936 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:06:27,936 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:27,942 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - first pass >10:06:27,943 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:06:27,961 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) StateManager::StateManager( 0:0:0:0:0 ) >10:06:27,961 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) BasicAction::BasicAction(0:0:0:0:0) >10:06:27,964 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:06:27,964 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:06:27,971 TRACE [com.arjuna.ats.jts] (MSC service thread 1-6) ArjunaTransactionImple::ArjunaTransactionImple ( 0:0:0:0:0 ) >10:06:27,979 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} >10:06:27,990 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) Loading class com.arjuna.ats.jbossatx.jta.TransactionManagerDelegate >10:06:27,999 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:06:28,000 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids new recoveryXids RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402473988000 >10:06:28,000 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:27,994 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-6) ARJUNA032017: JBossTS Transaction Service (JTA version - tag: bea48879928270f8b0933725f6baf1c10cce649c) - JBoss Inc. >10:06:28,006 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) TransactionReaper::instantiate() >10:06:28,006 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/home/ochaloup/jboss/jboss-eap-6.3/standalone/data/messagingjournal,bindingsDirectory=/home/ochaloup/jboss/jboss-eap-6.3/standalone/data/messagingbindings,largeMessagesDirectory=/home/ochaloup/jboss/jboss-eap-6.3/standalone/data/messaginglargemessages,pagingDirectory=/home/ochaloup/jboss/jboss-eap-6.3/standalone/data/messagingpaging) >10:06:28,007 TRACE [com.arjuna.ats.arjuna] (MSC service thread 1-6) TransactionReaper::TransactionReaper ( 9223372036854775807 ) >10:06:28,007 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221006: Waiting to obtain live lock >10:06:28,018 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) ReaperThread.run () >10:06:28,019 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,main] sleeping for 9223370634380787788 >10:06:28,027 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) registering bean jboss.jta:type=ObjectStore >10:06:28,032 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) ReaperWorkerThread.run () >10:06:28,032 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper Worker 0) Thread Thread[Transaction Reaper Worker 0,5,main] waiting for cancelled TXs >10:06:28,053 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221013: Using NIO Journal >10:06:28,124 INFO [org.jboss.as.remoting] (MSC service thread 1-1) JBAS017100: Listening on 127.0.0.1:4447 >10:06:28,127 INFO [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015876: Starting deployment of "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" (runtime-name: "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar") >10:06:28,129 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-3) JBAS015012: Started FileSystemDeploymentService for directory /home/ochaloup/jboss/jboss-eap-6.3/standalone/deployments >10:06:28,129 INFO [org.jboss.as.remoting] (MSC service thread 1-7) JBAS017100: Listening on 127.0.0.1:9999 >10:06:28,162 INFO [org.jboss.as.jacorb] (MSC service thread 1-2) JBAS016328: CORBA Naming Service started >10:06:28,194 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) JBAS010400: Bound data source [java:jboss/xa-datasources/CrashRecoveryDS] >10:06:28,271 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221034: Waiting to obtain live lock >10:06:28,271 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221035: Live Server Obtained live lock >10:06:28,316 INFO [org.jboss.as.jpa] (MSC service thread 1-6) JBAS011401: Read persistence.xml for jbossts-crash-rec >10:06:28,339 WARN [org.jboss.as.dependency.private] (MSC service thread 1-5) JBAS018567: Deployment "deployment.jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" is using a private module ("org.jboss.ironjacamar.jdbcadapters:main") which may be changed or removed in future versions without notice. >10:06:28,340 WARN [org.jboss.as.dependency.private] (MSC service thread 1-5) JBAS018567: Deployment "deployment.jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. >10:06:28,407 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named TestEntityHelper in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperLocal > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperLocal > java:module/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperLocal > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperRemote > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperRemote > java:module/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperRemote > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperRemote > >10:06:28,408 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named ResourcePrepareBean in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/ResourcePrepareBean!org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBeanRemote > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/ResourcePrepareBean!org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBeanRemote > java:module/ResourcePrepareBean!org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBeanRemote > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/ResourcePrepareBean!org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBeanRemote > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/ResourcePrepareBean > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/ResourcePrepareBean > java:module/ResourcePrepareBean > >10:06:28,408 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named JPACrashHelper in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashHelper!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashHelperRemote > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashHelper!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashHelperRemote > java:module/JPACrashHelper!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashHelperRemote > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashHelper!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashHelperRemote > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashHelper > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashHelper > java:module/JPACrashHelper > >10:06:28,409 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named MultiXACrashBean in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanHome > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanHome > java:module/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanHome > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanHome > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanRemote > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanRemote > java:module/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanRemote > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanRemote > >10:06:28,410 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named CrashHelperTestXAResource in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:module/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/CrashHelperTestXAResource > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/CrashHelperTestXAResource > java:module/CrashHelperTestXAResource > >10:06:28,411 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named JPACrashBean in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanHome > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanHome > java:module/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanHome > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanHome > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanRemote > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanRemote > java:module/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanRemote > java:jboss/exported/jbossts_crashrecovery-jpa_crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanRemote > >10:06:28,411 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named TestXAResourceRecoveryHelper in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper > java:module/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestXAResourceRecoveryHelper > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/TestXAResourceRecoveryHelper > java:module/TestXAResourceRecoveryHelper > >10:06:28,412 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named TransactionStoreBrowserService in deployment unit deployment "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" are as follows: > > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService > java:module/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService > java:global/jbossts_crashrecovery-jpa_crashrecovery-jpa/TransactionStoreBrowserService > java:app/jbossts_crashrecovery-jpa_crashrecovery-jpa/TransactionStoreBrowserService > java:module/TransactionStoreBrowserService > >10:06:28,727 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 59) JBAS011402: Starting Persistence Unit Service 'jbossts_crashrecovery-jpa_crashrecovery-jpa.jar#jbossts-crash-rec' >10:06:28,889 INFO [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 59) HCANN000001: Hibernate Commons Annotations {4.0.1.Final-redhat-2} >10:06:28,893 INFO [org.hibernate.Version] (ServerService Thread Pool -- 59) HHH000412: Hibernate Core {4.2.13.Final-redhat-1} >10:06:28,895 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 59) HHH000206: hibernate.properties not found >10:06:28,896 INFO [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 59) HHH000021: Bytecode provider name : javassist >10:06:28,913 INFO [org.hibernate.ejb.Ejb3Configuration] (ServerService Thread Pool -- 59) HHH000204: Processing PersistenceUnitInfo [ > name: jbossts-crash-rec > ...] >10:06:28,992 INFO [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (ServerService Thread Pool -- 59) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider >10:06:29,025 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 59) TransactionSynchronizationRegistryImple.getTransactionKey >10:06:29,025 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 59) TransactionSynchronizationRegistryImple.getTransactionKey >10:06:29,356 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221020: Started Netty Acceptor version 3.6.7.Final-redhat-1-3c0a3ac 127.0.0.1:5445 for CORE protocol >10:06:29,362 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221020: Started Netty Acceptor version 3.6.7.Final-redhat-1-3c0a3ac 127.0.0.1:5455 for CORE protocol >10:06:29,365 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221007: Server is now live >10:06:29,366 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 58) HQ221001: HornetQ Server version 2.3.19.Final (2.3.19, 123) [4a176e33-f13f-11e3-b99e-edb62e9026b4] >10:06:29,404 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 58) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory >10:06:29,407 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221003: trying to deploy queue jms.queue.ExpiryQueue >10:06:29,421 INFO [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 59) HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL82Dialect >10:06:29,434 INFO [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] (ServerService Thread Pool -- 59) HHH000424: Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException >10:06:29,452 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-2) JBAS010406: Registered connection factory java:/JmsXA >10:06:29,491 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 60) JBAS011601: Bound messaging object to jndi name java:/jms/queue/ExpiryQueue >10:06:29,493 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 62) HQ221003: trying to deploy queue jms.queue.DLQ >10:06:29,503 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/jms/queue/DLQ >10:06:29,505 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 61) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory >10:06:29,514 INFO [org.hornetq.ra] (MSC service thread 1-2) HornetQ resource adaptor started >10:06:29,515 INFO [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-2) IJ020002: Deployed: file://RaActivatorhornetq-ra >10:06:29,519 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-6) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA] >10:06:29,544 INFO [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (ServerService Thread Pool -- 59) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory >10:06:29,548 INFO [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (ServerService Thread Pool -- 59) HHH000397: Using ASTQueryTranslatorFactory >10:06:29,588 INFO [org.hibernate.validator.internal.util.Version] (ServerService Thread Pool -- 59) HV000001: Hibernate Validator 4.3.1.Final-redhat-1 >10:06:29,827 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 59) HHH000228: Running hbm2ddl schema update >10:06:29,827 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 59) HHH000102: Fetching database metadata >10:06:29,827 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 59) TransactionSynchronizationRegistryImple.getTransactionKey >10:06:29,893 TRACE [com.arjuna.ats.jts] (Finalizer) ArjunaTransactionImple.finalize - called for < 0:0:0:0:0 > >10:06:29,894 TRACE [com.arjuna.ats.arjuna] (Finalizer) BasicAction::finalize() >10:06:29,911 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 59) HHH000396: Updating schema >10:06:29,956 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 59) HHH000261: Table found: public.testentity >10:06:29,957 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 59) HHH000037: Columns: [id, a] >10:06:29,958 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 59) HHH000108: Foreign keys: [] >10:06:29,958 INFO [org.hibernate.tool.hbm2ddl.TableMetadata] (ServerService Thread Pool -- 59) HHH000126: Indexes: [testentity_pkey] >10:06:29,959 INFO [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 59) HHH000232: Schema update complete >10:06:30,004 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 61) BaseTransaction.begin >10:06:30,004 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 63) BaseTransaction.begin >10:06:30,005 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) StateManager::StateManager( 2, 0 ) >10:06:30,005 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) StateManager::StateManager( 2, 0 ) >10:06:30,005 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::BasicAction() >10:06:30,005 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::BasicAction() >10:06:30,006 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::Begin() for action-id 0:ffff7f000001:23aed888:53980e03:c >10:06:30,006 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::Begin() for action-id 0:ffff7f000001:23aed888:53980e03:d >10:06:30,006 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::actionInitialise() for action-id 0:ffff7f000001:23aed888:53980e03:c >10:06:30,006 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::actionInitialise() for action-id 0:ffff7f000001:23aed888:53980e03:d >10:06:30,006 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) ActionHierarchy::ActionHierarchy(5) >10:06:30,006 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) ActionHierarchy::ActionHierarchy(5) >10:06:30,007 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) ActionHierarchy::add(0:ffff7f000001:23aed888:53980e03:d, 1) >10:06:30,007 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) ActionHierarchy::add(0:ffff7f000001:23aed888:53980e03:c, 1) >10:06:30,009 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:c adding Thread[ServerService Thread Pool -- 61,5,ServerService ThreadGroup] >10:06:30,009 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:d adding Thread[ServerService Thread Pool -- 63,5,ServerService ThreadGroup] >10:06:30,010 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:d adding Thread[ServerService Thread Pool -- 63,5,ServerService ThreadGroup] result = true >10:06:30,010 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:c adding Thread[ServerService Thread Pool -- 61,5,ServerService ThreadGroup] result = true >10:06:30,010 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:23aed888:53980e03:d status: ActionStatus.RUNNING, 300 ) >10:06:30,010 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:23aed888:53980e03:c status: ActionStatus.RUNNING, 300 ) >10:06:30,011 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:23aed888:53980e03:c status: ActionStatus.RUNNING, 300 ) >10:06:30,011 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:23aed888:53980e03:d status: ActionStatus.RUNNING, 300 ) >10:06:30,011 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) ReaperThread.run () >10:06:30,011 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check () >10:06:30,011 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check - comparing 1402474290011 >10:06:30,011 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,main] sleeping for 300000 >10:06:30,011 INFO [org.jboss.as.test.jbossts.common.TransactionStoreBrowserService] (ServerService Thread Pool -- 61) TransactionStoreBrowserService.start >10:06:30,012 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 63) TestXAResourceRecoveryHelper starting >10:06:30,013 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 63) Using file /home/ochaloup/jboss/jboss-eap-6.3/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser for saving state of the org.jboss.as.test.jbossts.common.TestXAResource XA resource >10:06:30,013 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 63) file /home/ochaloup/jboss/jboss-eap-6.3/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser does not exists - no data for recovery >10:06:30,014 INFO [stdout] (ServerService Thread Pool -- 61) registering bean jboss.jta:type=com.arjuna.ats.arjuna.tools.osb.api.mbeans.RecoveryStoreBean,name=store1 >10:06:30,014 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 63) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:06:30,014 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 63) BaseTransaction.commit >10:06:30,015 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 63) TransactionImple.commitAndDisassociate >10:06:30,015 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::End() for action-id 0:ffff7f000001:23aed888:53980e03:d >10:06:30,015 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::End() result for action-id (0:ffff7f000001:23aed888:53980e03:d) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:06:30,015 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:d removing TSThread:1 >10:06:30,015 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:d removing TSThread:1 result = true >10:06:30,016 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 63) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:23aed888:53980e03:d status: ActionStatus.COMMITTED ) >10:06:30,016 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 61) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:06:30,016 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 61) BaseTransaction.commit >10:06:30,016 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 61) TransactionImple.commitAndDisassociate >10:06:30,016 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::End() for action-id 0:ffff7f000001:23aed888:53980e03:c >10:06:30,016 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::End() result for action-id (0:ffff7f000001:23aed888:53980e03:c) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:06:30,016 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:c removing TSThread:2 >10:06:30,016 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:c removing TSThread:2 result = true >10:06:30,016 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 61) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:23aed888:53980e03:c status: ActionStatus.COMMITTED ) >10:06:30,066 INFO [org.jboss.as.server] (ServerService Thread Pool -- 32) JBAS018559: Deployed "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar" (runtime-name : "jbossts_crashrecovery-jpa_crashrecovery-jpa.jar") >10:06:30,218 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management >10:06:30,220 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990 >10:06:30,221 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss EAP 6.3.0.Beta2 (AS 7.4.0.Final-redhat-15) started in 5494ms - Started 407 of 444 services (71 services are lazy, passive or on-demand) >10:06:38,001 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery second pass at Wed, 11 Jun 2014 10:06:38 >10:06:38,002 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:06:38,002 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:06:38,002 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:06:38,003 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:06:38,003 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:06:38,003 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:38,004 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule second pass >10:06:38,004 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:38,004 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - second pass >10:06:38,004 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:38,005 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - second pass >10:06:38,005 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.transactionInitiatedRecovery completed >10:06:38,006 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:06:38,006 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402473988000 1402473998006 false >10:06:38,007 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.resourceInitiatedRecovery completed >10:06:38,007 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:06:38,007 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== INACTIVE >10:06:38,007 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread backing off >10:08:38,008 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== SCANNING >10:08:38,008 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread scanning >10:08:38,009 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery first pass at Wed, 11 Jun 2014 10:08:38 >10:08:38,009 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) CommitMarkableResourceRecordRecoveryModule::periodicWorkFirstPass >10:08:38,009 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:08:38,009 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:08:38,010 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:08:38,010 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:08:38,010 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:08:38,011 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:08:38,011 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:08:38,011 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:38,012 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:08:38,012 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule first pass >10:08:38,012 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:08:38,012 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:08:38,013 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:08:38,013 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:38,013 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - first pass >10:08:38,013 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:08:38,014 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:08:38,014 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:08:38,014 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:38,015 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - first pass >10:08:38,015 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:08:38,015 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:08:38,015 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:08:38,018 WARN [org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl] (Periodic Recovery) IJ000904: No security domain defined for crash recovery: java:jboss/xa-datasources/CrashRecoveryDS >10:08:38,019 WARN [org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl] (Periodic Recovery) IJ000905: Subject for crash recovery was null: java:jboss/xa-datasources/CrashRecoveryDS >10:08:38,020 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) getXAResources() >10:08:38,021 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} >10:08:38,022 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:08:38,024 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids updateIfEquivalentRM2 RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402474118024 >10:08:38,025 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of HornetQXAResourceWrapper [serverLocator=null, csf=null, delegate=null, xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] >10:08:38,082 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:08:38,083 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids new recoveryXids HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] 1402474118082 >10:08:38,084 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of TestXAResourceRecovered(TestXAResourceCommon(0, null)) >10:08:38,084 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=16777216) >10:08:38,085 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover() >10:08:38,085 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids >10:08:38,086 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:08:38,086 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939]) - returning false >10:08:38,088 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'}) - returning false >10:08:38,089 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'}) - returning false >10:08:38,090 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939]) - returning false >10:08:38,091 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids new recoveryXids TestXAResourceRecovered(TestXAResourceCommon(0, null)) 1402474118091 >10:08:38,091 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:48,092 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery second pass at Wed, 11 Jun 2014 10:08:48 >10:08:48,092 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:08:48,092 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:08:48,093 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:08:48,093 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:08:48,093 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:08:48,094 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:48,094 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule second pass >10:08:48,094 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:48,094 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - second pass >10:08:48,095 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:48,095 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - second pass >10:08:48,095 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.transactionInitiatedRecovery completed >10:08:48,096 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:08:48,096 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:08:48,096 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:08:48,096 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=8388608) >10:08:48,097 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover() >10:08:48,098 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids >10:08:48,099 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] 1402474118082 1402474128098 false >10:08:48,099 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check TestXAResourceRecovered(TestXAResourceCommon(0, null)) 1402474118091 1402474128099 false >10:08:48,100 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402474118024 1402474128100 false >10:08:48,100 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.resourceInitiatedRecovery completed >10:08:48,100 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:08:48,100 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== INACTIVE >10:08:48,101 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread backing off >10:10:48,101 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== SCANNING >10:10:48,102 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread scanning >10:10:48,102 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery first pass at Wed, 11 Jun 2014 10:10:48 >10:10:48,102 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) CommitMarkableResourceRecordRecoveryModule::periodicWorkFirstPass >10:10:48,102 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:10:48,103 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:10:48,103 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:10:48,103 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:10:48,104 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:10:48,104 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:10:48,104 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:10:48,105 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:48,105 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:10:48,105 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule first pass >10:10:48,105 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:10:48,106 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:10:48,106 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:10:48,106 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:48,106 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - first pass >10:10:48,107 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:10:48,107 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:10:48,107 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:10:48,108 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:48,108 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - first pass >10:10:48,108 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:10:48,109 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:10:48,109 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:10:48,109 WARN [org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl] (Periodic Recovery) IJ000904: No security domain defined for crash recovery: java:jboss/xa-datasources/CrashRecoveryDS >10:10:48,110 WARN [org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl] (Periodic Recovery) IJ000905: Subject for crash recovery was null: java:jboss/xa-datasources/CrashRecoveryDS >10:10:48,111 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) getXAResources() >10:10:48,111 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} >10:10:48,111 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:10:48,112 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids updateIfEquivalentRM2 RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402474248112 >10:10:48,112 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] >10:10:48,114 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:10:48,114 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids updateIfEquivalentRM2 HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] 1402474248114 >10:10:48,115 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of TestXAResourceRecovered(TestXAResourceCommon(0, null)) >10:10:48,115 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=16777216) >10:10:48,116 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover() >10:10:48,116 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids >10:10:48,117 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:10:48,117 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939]) - returning false >10:10:48,118 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=TestXAResourceRecovered(TestXAResourceCommon(0, null))) - returning false >10:10:48,119 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'}) - returning false >10:10:48,120 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:58,120 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery second pass at Wed, 11 Jun 2014 10:10:58 >10:10:58,121 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:10:58,121 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:10:58,121 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:10:58,122 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:10:58,122 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:10:58,122 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:58,123 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule second pass >10:10:58,123 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:58,123 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - second pass >10:10:58,123 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:58,124 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - second pass >10:10:58,124 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.transactionInitiatedRecovery completed >10:10:58,124 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:10:58,125 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:10:58,125 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=8388608) >10:10:58,125 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover() >10:10:58,126 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids >10:10:58,126 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:10:58,127 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] 1402474248114 1402474258127 false >10:10:58,128 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check TestXAResourceRecovered(TestXAResourceCommon(0, null)) 1402474118091 1402474258128 true >10:10:58,128 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402474248112 1402474258128 false >10:10:58,128 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.resourceInitiatedRecovery completed >10:10:58,128 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:10:58,129 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== INACTIVE >10:10:58,129 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread backing off >10:11:30,012 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) ReaperThread.run () >10:11:30,012 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check () >10:11:30,012 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check - comparing 1402474290011 >10:11:30,013 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,main] sleeping for 9223370634380485794 >10:12:58,129 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== SCANNING >10:12:58,130 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread scanning >10:12:58,130 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery first pass at Wed, 11 Jun 2014 10:12:58 >10:12:58,130 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) CommitMarkableResourceRecordRecoveryModule::periodicWorkFirstPass >10:12:58,131 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:12:58,131 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:12:58,131 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:12:58,132 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:12:58,132 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:12:58,132 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:12:58,132 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:12:58,133 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:12:58,133 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:12:58,133 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule first pass >10:12:58,134 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:12:58,134 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:12:58,134 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions >10:12:58,134 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:12:58,135 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - first pass >10:12:58,135 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:12:58,135 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:12:58,136 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:12:58,136 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:12:58,136 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - first pass >10:12:58,136 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:12:58,137 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:12:58,137 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:12:58,137 WARN [org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl] (Periodic Recovery) IJ000904: No security domain defined for crash recovery: java:jboss/xa-datasources/CrashRecoveryDS >10:12:58,138 WARN [org.jboss.jca.core.tx.jbossts.XAResourceRecoveryImpl] (Periodic Recovery) IJ000905: Subject for crash recovery was null: java:jboss/xa-datasources/CrashRecoveryDS >10:12:58,139 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) getXAResources() >10:12:58,139 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} >10:12:58,140 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:12:58,140 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids updateIfEquivalentRM2 RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402474378140 >10:12:58,140 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] >10:12:58,142 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:12:58,142 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids updateIfEquivalentRM2 HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] 1402474378142 >10:12:58,143 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) xarecovery of TestXAResourceRecovered(TestXAResourceCommon(0, null)) >10:12:58,143 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=16777216) >10:12:58,144 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover() >10:12:58,144 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids >10:12:58,145 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Found 0 xids in doubt >10:12:58,145 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'}) - returning false >10:12:58,146 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939]) - returning false >10:12:58,147 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939]) - returning false >10:12:58,148 INFO [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'}) - returning false >10:12:58,149 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids new recoveryXids TestXAResourceRecovered(TestXAResourceCommon(0, null)) 1402474378149 >10:12:58,149 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:13:08,150 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) Periodic recovery second pass at Wed, 11 Jun 2014 10:13:08 >10:13:08,150 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) InputObjectState::InputObjectState() >10:13:08,151 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:13:08,151 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:13:08,152 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:13:08,152 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) OutputObjectState::OutputObjectState() >10:13:08,152 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:13:08,152 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) AtomicActionRecoveryModule second pass >10:13:08,153 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:13:08,153 DEBUG [com.arjuna.ats.txoj] (Periodic Recovery) TORecoveryModule - second pass >10:13:08,153 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:13:08,153 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule - second pass >10:13:08,154 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.transactionInitiatedRecovery completed >10:13:08,154 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:13:08,154 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:13:08,155 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=8388608) >10:13:08,155 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover() >10:13:08,156 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids >10:13:08,157 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Have 0 Xids to recover on this pass. >10:13:08,157 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@7a48d43e, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@4dd1b0f3}, transactionOriginNodeIdentifier='1'} 1402474378140 1402474388157 false >10:13:08,157 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=979fe932-f13f-11e3-b99e-edb62e9026b4, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@c984949], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=4b0550ae-f13f-11e3-b99e-edb62e9026b4, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1244242939] 1402474378142 1402474388157 false >10:13:08,158 TRACE [com.arjuna.ats.arjuna] (Periodic Recovery) RecoveryXids isStale Check TestXAResourceRecovered(TestXAResourceCommon(0, null)) 1402474378149 1402474388158 false >10:13:08,158 DEBUG [com.arjuna.ats.jta] (Periodic Recovery) Local XARecoveryModule.resourceInitiatedRecovery completed >10:13:08,158 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) >10:13:08,159 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread Status <== INACTIVE >10:13:08,159 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread backing off >10:14:10,120 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-4) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost.localdomain/127.0.0.1:8080 >10:14:10,123 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-4) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost.localdomain/127.0.0.1:8080 >10:14:10,129 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:14:10,160 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) BaseTransaction.begin >10:14:10,160 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) StateManager::StateManager( 2, 0 ) >10:14:10,161 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::BasicAction() >10:14:10,161 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::Begin() for action-id 0:ffff7f000001:23aed888:53980e03:25 >10:14:10,162 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::actionInitialise() for action-id 0:ffff7f000001:23aed888:53980e03:25 >10:14:10,162 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) ActionHierarchy::ActionHierarchy(5) >10:14:10,162 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) ActionHierarchy::add(0:ffff7f000001:23aed888:53980e03:25, 1) >10:14:10,163 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:25 adding Thread[ServerService Thread Pool -- 68,5,ServerService ThreadGroup] >10:14:10,163 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:25 adding Thread[ServerService Thread Pool -- 68,5,ServerService ThreadGroup] result = true >10:14:10,163 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:23aed888:53980e03:25 status: ActionStatus.RUNNING, 300 ) >10:14:10,163 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:23aed888:53980e03:25 status: ActionStatus.RUNNING, 300 ) >10:14:10,164 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) ReaperThread.run () >10:14:10,164 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check () >10:14:10,164 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) TransactionReaper::check - comparing 1402474750164 >10:14:10,164 INFO [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 68) TestXAResourceRecoveryHelper stopping >10:14:10,165 TRACE [com.arjuna.ats.arjuna] (Transaction Reaper) Thread Thread[Transaction Reaper,5,main] sleeping for 299999 >10:14:10,165 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:14:10,166 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) BaseTransaction.commit >10:14:10,166 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 68) TransactionImple.commitAndDisassociate >10:14:10,166 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::End() for action-id 0:ffff7f000001:23aed888:53980e03:25 >10:14:10,167 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) BaseTransaction.begin >10:14:10,167 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) StateManager::StateManager( 2, 0 ) >10:14:10,167 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-2) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA] >10:14:10,168 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::BasicAction() >10:14:10,168 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::Begin() for action-id 0:ffff7f000001:23aed888:53980e03:26 >10:14:10,168 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::actionInitialise() for action-id 0:ffff7f000001:23aed888:53980e03:26 >10:14:10,166 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::End() result for action-id (0:ffff7f000001:23aed888:53980e03:25) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:14:10,169 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:25 removing TSThread:3 >10:14:10,170 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) ActionHierarchy::ActionHierarchy(5) >10:14:10,170 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) ActionHierarchy::add(0:ffff7f000001:23aed888:53980e03:26, 1) >10:14:10,170 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:25 removing TSThread:3 result = true >10:14:10,170 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:26 adding Thread[ServerService Thread Pool -- 67,5,ServerService ThreadGroup] >10:14:10,170 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 68) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:23aed888:53980e03:25 status: ActionStatus.COMMITTED ) >10:14:10,171 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::addChildThread () action 0:ffff7f000001:23aed888:53980e03:26 adding Thread[ServerService Thread Pool -- 67,5,ServerService ThreadGroup] result = true >10:14:10,171 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) TransactionReaper::insert ( BasicAction: 0:ffff7f000001:23aed888:53980e03:26 status: ActionStatus.RUNNING, 300 ) >10:14:10,171 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) ReaperElement::ReaperElement ( BasicAction: 0:ffff7f000001:23aed888:53980e03:26 status: ActionStatus.RUNNING, 300 ) >10:14:10,172 INFO [org.jboss.as.test.jbossts.common.TransactionStoreBrowserService] (ServerService Thread Pool -- 67) TransactionStoreBrowserService.stop >10:14:10,173 INFO [stdout] (ServerService Thread Pool -- 67) unregistering bean jboss.jta:type=com.arjuna.ats.arjuna.tools.osb.api.mbeans.RecoveryStoreBean,name=store1 >10:14:10,174 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE >10:14:10,174 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) BaseTransaction.commit >10:14:10,174 TRACE [com.arjuna.ats.jta] (ServerService Thread Pool -- 67) TransactionImple.commitAndDisassociate >10:14:10,175 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::End() for action-id 0:ffff7f000001:23aed888:53980e03:26 >10:14:10,175 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::End() result for action-id (0:ffff7f000001:23aed888:53980e03:26) is (TwoPhaseOutcome.PREPARE_OK) node id: (1) >10:14:10,175 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:26 removing TSThread:4 >10:14:10,175 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) BasicAction::removeChildThread () action 0:ffff7f000001:23aed888:53980e03:26 removing TSThread:4 result = true >10:14:10,176 TRACE [com.arjuna.ats.arjuna] (ServerService Thread Pool -- 67) TransactionReaper::remove ( BasicAction: 0:ffff7f000001:23aed888:53980e03:26 status: ActionStatus.COMMITTED ) >10:14:10,173 INFO [org.jboss.jaxr] (MSC service thread 1-7) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory >10:14:10,180 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 66) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory >10:14:10,185 INFO [org.jboss.as.jpa] (ServerService Thread Pool -- 66) JBAS011403: Stopping Persistence Unit Service 'jbossts_crashrecovery-jpa_crashrecovery-jpa.jar#jbossts-crash-rec' >10:14:10,188 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 71) JBAS011605: Unbound messaging object to jndi name java:/jms/queue/ExpiryQueue >10:14:10,222 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010409: Unbound data source [java:jboss/xa-datasources/CrashRecoveryDS] >10:14:10,229 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 74) JBAS011605: Unbound messaging object to jndi name java:/ConnectionFactory >10:14:10,283 INFO [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015877: Stopped deployment jbossts_crashrecovery-jpa_crashrecovery-jpa.jar (runtime-name: jbossts_crashrecovery-jpa_crashrecovery-jpa.jar) in 182ms >10:14:10,289 INFO [org.hornetq.ra] (ServerService Thread Pool -- 66) HQ151003: HornetQ resource adaptor stopped >10:14:10,294 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-6) ARJUNA032018: Destroying TransactionManagerService >10:14:10,295 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-6) ARJUNA032014: Stopping transaction recovery manager >10:14:10,304 DEBUG [com.arjuna.ats.arjuna] (Listener:4712) Recovery listener existing com.arjuna.ats.internal.arjuna.recovery.WorkerService >10:14:10,305 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) PeriodicRecovery: Mode <== TERMINATED >10:14:10,305 DEBUG [com.arjuna.ats.arjuna] (MSC service thread 1-6) PeriodicRecovery: shutdown scan wait complete >10:14:10,305 DEBUG [com.arjuna.ats.arjuna] (Periodic Recovery) PeriodicRecovery: background thread exiting >10:14:10,452 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 66) HQ221002: HornetQ Server version 2.3.19.Final (2.3.19, 123) [4a176e33-f13f-11e3-b99e-edb62e9026b4] stopped >10:14:10,457 INFO [org.jboss.as] (MSC service thread 1-1) JBAS015950: JBoss EAP 6.3.0.Beta2 (AS 7.4.0.Final-redhat-15) stopped in 213ms
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=907536">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 1107991
: 907536