Attachment 878338 Details for Bug 1080179 – server.log #prepareHaltBefore

server.log #prepareHaltBefore

JPAProxyCMRCrashRecoveryTestCase_prepareHaltBefore_jta_server.log (text/x-log), 65.76 KB, created by Ondrej Chaloupka on 2014-03-25 08:21:46 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Ondrej Chaloupka

Created: 2014-03-25 08:21:46 UTC

Size: 65.76 KB

patch

obsolete

>09:07:41,095 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 36) JBAS010280: Activating Infinispan subsystem.
>09:07:41,099 INFO  [org.jboss.as.jacorb] (ServerService Thread Pool -- 37) JBAS016300: Activating JacORB Subsystem
>09:07:41,135 INFO  [org.jboss.as.jsf] (ServerService Thread Pool -- 44) JBAS012605: Activated the following JSF Implementations: [main, 1.2]
>09:07:41,150 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 54) JBAS013171: Activating Security Subsystem
>09:07:41,152 INFO  [org.jboss.as.connector.logging] (MSC service thread 1-1) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.24.Final)
>09:07:41,162 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 48) JBAS011800: Activating Naming Subsystem
>09:07:41,165 WARN  [org.jboss.as.txn] (ServerService Thread Pool -- 56) JBAS010153: Node identifier property is set to the default value. Please make sure it is unique.
>09:07:41,180 INFO  [org.jboss.as.security] (MSC service thread 1-4) JBAS013170: Current PicketBox version=4.0.19.SP4-redhat-1
>09:07:41,183 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 58) JBAS015537: Activating WebServices Extension
>09:07:41,248 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-6) JBAS015400: Bound mail session [java:jboss/mail/Default]
>09:07:41,249 INFO  [org.jboss.as.naming] (MSC service thread 1-3) JBAS011802: Starting Naming Service
>09:07:41,296 INFO  [org.jboss.jaxr] (MSC service thread 1-7) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory
>09:07:41,321 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 32) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
>09:07:41,782 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-4) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost.localdomain/127.0.0.1:8080
>09:07:41,833 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-4) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost.localdomain/127.0.0.1:8080
>09:07:41,904 INFO  [org.jboss.as.jacorb] (MSC service thread 1-5) JBAS016330: CORBA ORB Service started
>09:07:41,936 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingjournal,bindingsDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingbindings,largeMessagesDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messaginglargemessages,pagingDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingpaging)
>09:07:41,940 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221006: Waiting to obtain live lock
>09:07:41,948 INFO  [org.jboss.ws.common.management] (MSC service thread 1-8) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.3.0.CR2
>09:07:42,089 INFO  [org.jboss.as.remoting] (MSC service thread 1-2) JBAS017100: Listening on 127.0.0.1:4447
>09:07:42,091 INFO  [org.jboss.as.server.deployment.scanner] (MSC service thread 1-5) JBAS015012: Started FileSystemDeploymentService for directory /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/deployments
>09:07:42,094 INFO  [org.jboss.as.remoting] (MSC service thread 1-7) JBAS017100: Listening on 127.0.0.1:9999
>09:07:42,093 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221013: Using NIO Journal
>09:07:42,106 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "dsdriver.jar" (runtime-name: "dsdriver.jar")
>09:07:42,108 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-7) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
>09:07:42,159 INFO  [org.jboss.as.jacorb] (MSC service thread 1-6) JBAS016328: CORBA Naming Service started
>09:07:42,292 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221034: Waiting to obtain live lock
>09:07:42,293 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221035: Live Server Obtained live lock
>09:07:42,493 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-7) JBAS010404: Deploying non-JDBC-compliant driver class org.postgresql.Driver (version 9.3)
>09:07:43,324 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5445 for CORE protocol
>09:07:43,326 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5455 for CORE protocol
>09:07:43,327 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221007: Server is now live
>09:07:43,328 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221001: HornetQ Server version 2.3.18.Final (2.3.18, 123) [89f0ab87-b3f4-11e3-b836-e930f6b5360e] 
>09:07:43,352 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 60) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
>09:07:43,354 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
>09:07:43,357 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 62) HQ221003: trying to deploy queue jms.queue.ExpiryQueue
>09:07:43,393 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-2) JBAS010406: Registered connection factory java:/JmsXA
>09:07:43,423 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/jms/queue/ExpiryQueue
>09:07:43,424 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 61) HQ221003: trying to deploy queue jms.queue.DLQ
>09:07:43,425 INFO  [org.hornetq.ra] (MSC service thread 1-2) HornetQ resource adaptor started
>09:07:43,425 INFO  [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-2) IJ020002: Deployed: file://RaActivatorhornetq-ra
>09:07:43,427 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-8) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
>09:07:43,432 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 61) JBAS011601: Bound messaging object to jndi name java:/jms/queue/DLQ
>09:07:43,472 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) JBAS018559: Deployed "dsdriver.jar" (runtime-name : "dsdriver.jar")
>09:07:43,629 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management
>09:07:43,630 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990
>09:07:43,630 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-5) started in 4462ms - Started 230 of 267 services (71 services are lazy, passive or on-demand)
>09:07:45,913 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-7) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS]
>09:07:45,920 INFO  [org.jboss.jaxr] (MSC service thread 1-1) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory
>09:07:45,921 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-2) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost.localdomain/127.0.0.1:8080
>09:07:45,922 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-2) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost.localdomain/127.0.0.1:8080
>09:07:45,924 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 3) JBAS011605: Unbound messaging object to jndi name java:/jms/queue/ExpiryQueue
>09:07:45,928 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 29) JBAS011605: Unbound messaging object to jndi name java:/ConnectionFactory
>09:07:45,928 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-5) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA]
>09:07:45,930 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 5) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
>09:07:45,932 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 4) JBAS011605: Unbound messaging object to jndi name java:/jms/queue/DLQ
>09:07:45,931 INFO  [org.apache.tomcat.util] (http-localhost.localdomain/127.0.0.1:8080-Acceptor) JBWEB003022: Channel processing failed
>09:07:45,945 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015877: Stopped deployment dsdriver.jar (runtime-name: dsdriver.jar) in 46ms
>09:07:45,949 INFO  [org.apache.catalina.core] (MSC service thread 1-6) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started
>09:07:45,968 INFO  [org.hornetq.ra] (ServerService Thread Pool -- 29) HQ151003: HornetQ resource adaptor stopped
>09:07:46,071 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 29) HQ221002: HornetQ Server version 2.3.18.Final (2.3.18, 123) [89f0ab87-b3f4-11e3-b836-e930f6b5360e] stopped
>09:07:46,077 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-7) JBAS015401: Unbound mail session [java:jboss/mail/Default]
>09:07:46,083 INFO  [org.jboss.as] (MSC service thread 1-5) JBAS015950: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-5) stopped in 185ms
>09:07:46,084 INFO  [org.jboss.as] (MSC service thread 1-1) JBAS015899: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-5) starting
>09:07:46,085 DEBUG [org.jboss.as.config] (MSC service thread 1-1) Configured system properties:
>	awt.toolkit = sun.awt.X11.XToolkit
>	catalina.home = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/tmp
>	file.encoding = UTF-8
>	file.encoding.pkg = sun.io
>	file.separator = /
>	java.awt.graphicsenv = sun.awt.X11GraphicsEnvironment
>	java.awt.printerjob = sun.print.PSPrinterJob
>	java.class.path = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/jboss-modules.jar:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/byteman/byteman.jar
>	java.class.version = 51.0
>	java.endorsed.dirs = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/endorsed
>	java.ext.dirs = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/ext:/usr/java/packages/lib/ext
>	java.home = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre
>	java.io.tmpdir = /tmp
>	java.library.path = /usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
>	java.naming.factory.url.pkgs = org.jboss.as.naming.interfaces:org.jboss.ejb.client.naming
>	java.net.preferIPv4Stack = true
>	java.net.preferIPv6Addresses = false
>	java.protocol.handler.pkgs = org.jboss.net.protocol|org.jboss.vfs.protocol
>	java.runtime.name = OpenJDK Runtime Environment
>	java.runtime.version = 1.7.0_51-mockbuild_2014_01_31_16_21-b00
>	java.specification.name = Java Platform API Specification
>	java.specification.vendor = Oracle Corporation
>	java.specification.version = 1.7
>	java.util.logging.manager = org.jboss.logmanager.LogManager
>	java.vendor = Oracle Corporation
>	java.vendor.url = http://java.oracle.com/
>	java.vendor.url.bug = http://bugreport.sun.com/bugreport/
>	java.version = 1.7.0_51
>	java.vm.info = mixed mode
>	java.vm.name = OpenJDK 64-Bit Server VM
>	java.vm.specification.name = Java Virtual Machine Specification
>	java.vm.specification.vendor = Oracle Corporation
>	java.vm.specification.version = 1.7
>	java.vm.vendor = Oracle Corporation
>	java.vm.version = 24.51-b03
>	javax.management.builder.initial = org.jboss.as.jmx.PluggableMBeanServerBuilder
>	javax.xml.datatype.DatatypeFactory = __redirected.__DatatypeFactory
>	javax.xml.parsers.DocumentBuilderFactory = __redirected.__DocumentBuilderFactory
>	javax.xml.parsers.SAXParserFactory = __redirected.__SAXParserFactory
>	javax.xml.stream.XMLEventFactory = __redirected.__XMLEventFactory
>	javax.xml.stream.XMLInputFactory = __redirected.__XMLInputFactory
>	javax.xml.stream.XMLOutputFactory = __redirected.__XMLOutputFactory
>	javax.xml.transform.TransformerFactory = __redirected.__TransformerFactory
>	javax.xml.validation.SchemaFactory:http://www.w3.org/2001/XMLSchema = __redirected.__SchemaFactory
>	javax.xml.xpath.XPathFactory:http://java.sun.com/jaxp/xpath/dom = __redirected.__XPathFactory
>	jboss.bundles.dir = /home/ochaloup/jboss/jboss-eap-6.3.0.DR5/bundles
>	jboss.dist = /home/ochaloup/jboss/jboss-eap-6.3.0.DR5
>	jboss.home.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts
>	jboss.host.name = localhost
>	jboss.modules.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/modules
>	jboss.modules.system.pkgs = org.jboss.byteman
>	jboss.node.name = jbossts
>	jboss.qualified.host.name = localhost.localdomain
>	jboss.server.base.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone
>	jboss.server.config.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/configuration
>	jboss.server.data.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data
>	jboss.server.deploy.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/content
>	jboss.server.log.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/log
>	jboss.server.name = localhost
>	jboss.server.persist.config = true
>	jboss.server.temp.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/tmp
>	jbossas.project.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../../..
>	jbossas.ts.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../..
>	jbossas.ts.integ.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/..
>	jbossas.ts.submodule.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts
>	line.separator = 
>
>	logging.configuration = file:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/configuration/logging.properties
>	mcast = 230.0.0.4
>	module.path = /home/ochaloup/jboss/jboss-eap-6.3.0.DR5/modules:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/modules
>	node0 = 127.0.0.1
>	node1 = 127.0.0.1
>	node2 = 127.0.0.1
>	org.apache.xml.security.ignoreLineBreaks = true
>	org.jboss.boot.log.file = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/log/boot.log
>	org.jboss.byteman.contrib.dtest.rmiregistry.port = 1199
>	org.jboss.byteman.debug = true
>	org.jboss.byteman.verbose = true
>	org.jboss.com.sun.CORBA.ORBUseDynamicStub = true
>	org.jboss.logmanager.nocolor = true
>	org.jboss.resolver.warning = true
>	org.jboss.security.context.ThreadLocal = true
>	org.omg.CORBA.ORBClass = org.jacorb.orb.ORB
>	org.omg.CORBA.ORBSingletonClass = org.jacorb.orb.ORBSingleton
>	org.xml.sax.driver = __redirected.__XMLReaderFactory
>	os.arch = amd64
>	os.name = Linux
>	os.version = 3.13.6-200.fc20.x86_64
>	path.separator = :
>	sun.arch.data.model = 64
>	sun.boot.class.path = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/resources.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/rt.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/sunrsasign.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/jsse.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/jce.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/charsets.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/netx.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/plugin.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/rhino.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/jfr.jar:/usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/classes
>	sun.boot.library.path = /usr/lib/jvm/java-1.7.0-openjdk-1.7.0.60-2.4.5.1.fc20.x86_64/jre/lib/amd64
>	sun.cpu.endian = little
>	sun.cpu.isalist = 
>	sun.io.unicode.encoding = UnicodeLittle
>	sun.java.command = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/jboss-modules.jar -mp /home/ochaloup/jboss/jboss-eap-6.3.0.DR5/modules:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/modules -jaxpmodule javax.xml.jaxp-provider org.jboss.as.standalone -server-config standalone-full.xml
>	sun.java.launcher = SUN_STANDARD
>	sun.jnu.encoding = UTF-8
>	sun.management.compiler = HotSpot 64-Bit Tiered Compilers
>	sun.nio.ch.bugLevel = 
>	sun.os.patch.level = unknown
>	ts.timeout.factor = 100
>	user.country = US
>	user.dir = /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/workdir
>	user.home = /home/ochaloup
>	user.language = en
>	user.name = ochaloup
>	user.timezone = Europe/Prague
>09:07:46,086 DEBUG [org.jboss.as.config] (MSC service thread 1-1) VM Arguments: -Xmx1303m -XX:MaxPermSize=256m -Djboss.dist=/home/ochaloup/jboss/jboss-eap-6.3.0.DR5 -Djava.net.preferIPv4Stack=true -Djava.net.preferIPv6Addresses=false -Dts.timeout.factor=100 -Dnode0=127.0.0.1 -Dnode1=127.0.0.1 -Dnode2=127.0.0.1 -Dmcast=230.0.0.4 -Djbossas.ts.submodule.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts -Djbossas.ts.integ.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/.. -Djbossas.ts.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../.. -Djbossas.project.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/../../.. -Djboss.dist=/home/ochaloup/jboss/jboss-eap-6.3.0.DR5 -Dorg.jboss.logmanager.nocolor=true -Dorg.jboss.byteman.debug=true -Dorg.jboss.byteman.verbose=true -Dorg.jboss.byteman.contrib.dtest.rmiregistry.port=1199 -Djboss.modules.system.pkgs=org.jboss.byteman -javaagent:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/byteman/byteman.jar=address:127.0.0.1,port:9091,listener:true,sys:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/byteman/byteman-dtest.jar -Djboss.node.name=jbossts -ea -Djboss.home.dir=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts -Dorg.jboss.boot.log.file=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/log/boot.log -Dlogging.configuration=file:/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/configuration/logging.properties -Djboss.bundles.dir=/home/ochaloup/jboss/jboss-eap-6.3.0.DR5/bundles 
>09:07:46,188 INFO  [org.jboss.as.server] (Controller Boot Thread) JBAS015888: Creating http management service using socket-binding (management-http)
>09:07:46,197 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 36) JBAS010280: Activating Infinispan subsystem.
>09:07:46,198 INFO  [org.jboss.as.jacorb] (ServerService Thread Pool -- 37) JBAS016300: Activating JacORB Subsystem
>09:07:46,202 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 32) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
>09:07:46,205 INFO  [org.jboss.as.connector.logging] (MSC service thread 1-4) JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.24.Final)
>09:07:46,210 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 48) JBAS011800: Activating Naming Subsystem
>09:07:46,215 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 54) JBAS013171: Activating Security Subsystem
>09:07:46,218 WARN  [org.jboss.as.txn] (ServerService Thread Pool -- 56) JBAS010153: Node identifier property is set to the default value. Please make sure it is unique.
>09:07:46,219 INFO  [org.jboss.as.security] (MSC service thread 1-3) JBAS013170: Current PicketBox version=4.0.19.SP4-redhat-1
>09:07:46,226 INFO  [org.jboss.as.naming] (MSC service thread 1-5) JBAS011802: Starting Naming Service
>09:07:46,227 INFO  [org.jboss.jaxr] (MSC service thread 1-7) JBAS014000: Started JAXR subsystem, binding JAXR connection factory into JNDI as: java:jboss/jaxr/ConnectionFactory
>09:07:46,227 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-2) JBAS015400: Bound mail session [java:jboss/mail/Default]
>09:07:46,229 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 58) JBAS015537: Activating WebServices Extension
>09:07:46,236 INFO  [org.jboss.as.jacorb] (MSC service thread 1-6) JBAS016330: CORBA ORB Service started
>09:07:46,241 INFO  [org.jboss.ws.common.management] (MSC service thread 1-2) JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.3.0.CR2
>09:07:46,264 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003001: Coyote HTTP/1.1 initializing on : http-localhost.localdomain/127.0.0.1:8080
>09:07:46,267 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221000: live server is starting with configuration HornetQ Configuration (clustered=false,backup=false,sharedStore=true,journalDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingjournal,bindingsDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingbindings,largeMessagesDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messaginglargemessages,pagingDirectory=/home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/messagingpaging)
>09:07:46,268 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221006: Waiting to obtain live lock
>09:07:46,268 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-8) JBWEB003000: Coyote HTTP/1.1 starting on: http-localhost.localdomain/127.0.0.1:8080
>09:07:46,268 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221013: Using NIO Journal
>09:07:46,268 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-8) JBAS015876: Starting deployment of "dsdriver.jar" (runtime-name: "dsdriver.jar")
>09:07:46,275 INFO  [org.jboss.as.remoting] (MSC service thread 1-6) JBAS017100: Listening on 127.0.0.1:9999
>09:07:46,275 INFO  [org.jboss.as.server.deployment.scanner] (MSC service thread 1-4) JBAS015012: Started FileSystemDeploymentService for directory /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/deployments
>09:07:46,288 INFO  [org.jboss.as.remoting] (MSC service thread 1-7) JBAS017100: Listening on 127.0.0.1:4447
>09:07:46,296 INFO  [org.jboss.as.jacorb] (MSC service thread 1-5) JBAS016328: CORBA Naming Service started
>09:07:46,301 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-7) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
>09:07:46,336 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-2) JBAS010404: Deploying non-JDBC-compliant driver class org.postgresql.Driver (version 9.3)
>09:07:46,393 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221034: Waiting to obtain live lock
>09:07:46,393 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221035: Live Server Obtained live lock
>09:07:46,668 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5455 for CORE protocol
>09:07:46,669 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221020: Started Netty Acceptor version 3.6.7.Final-99b1b26 127.0.0.1:5445 for CORE protocol
>09:07:46,669 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221007: Server is now live
>09:07:46,669 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 60) HQ221001: HornetQ Server version 2.3.18.Final (2.3.18, 123) [89f0ab87-b3f4-11e3-b836-e930f6b5360e] 
>09:07:46,670 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 60) JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
>09:07:46,670 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 63) HQ221003: trying to deploy queue jms.queue.ExpiryQueue
>09:07:46,671 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 63) JBAS011601: Bound messaging object to jndi name java:/jms/queue/ExpiryQueue
>09:07:46,672 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 61) HQ221003: trying to deploy queue jms.queue.DLQ
>09:07:46,672 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 61) JBAS011601: Bound messaging object to jndi name java:/jms/queue/DLQ
>09:07:46,673 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 62) JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
>09:07:46,675 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-4) JBAS010406: Registered connection factory java:/JmsXA
>09:07:46,679 INFO  [org.hornetq.ra] (MSC service thread 1-4) HornetQ resource adaptor started
>09:07:46,679 INFO  [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-4) IJ020002: Deployed: file://RaActivatorhornetq-ra
>09:07:46,680 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-1) JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
>09:07:46,706 INFO  [org.jboss.as.server] (ServerService Thread Pool -- 33) JBAS018559: Deployed "dsdriver.jar" (runtime-name : "dsdriver.jar")
>09:07:46,710 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015961: Http management interface listening on http://127.0.0.1:9990/management
>09:07:46,711 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin console listening on http://127.0.0.1:9990
>09:07:46,711 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-5) started in 624ms - Started 230 of 267 services (71 services are lazy, passive or on-demand)
>09:07:48,844 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/xa-datasources/CrashRecoveryDS]
>09:07:49,216 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/xa-datasources/VerificationDS]
>09:07:49,499 INFO  [org.jboss.as.repository] (management-handler-thread - 1) JBAS014900: Content added at location /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/content/65/306a66a0637b815678c2c60b8b407405edec40/content
>09:07:49,504 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-6) JBAS015876: Starting deployment of "crashrecovery-jpa.jar" (runtime-name: "crashrecovery-jpa.jar")
>09:07:49,542 INFO  [org.jboss.as.jpa] (MSC service thread 1-2) JBAS011401: Read persistence.xml for jbossts-crash-rec
>09:07:49,551 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-6) JBAS018567: Deployment "deployment.crashrecovery-jpa.jar" is using a private module ("org.jboss.ironjacamar.jdbcadapters:main") which may be changed or removed in future versions without notice.
>09:07:49,552 WARN  [org.jboss.as.dependency.private] (MSC service thread 1-6) JBAS018567: Deployment "deployment.crashrecovery-jpa.jar" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice.
>09:07:49,595 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named TestEntityHelper in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperRemote
>	java:app/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/crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperRemote
>	java:global/crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperLocal
>	java:app/crashrecovery-jpa/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperLocal
>	java:module/TestEntityHelper!org.jboss.as.test.jbossts.crashrec.jpa.TestEntityHelperLocal
>
>09:07:49,595 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named ResourcePrepareBean in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/ResourcePrepareBean!org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBeanRemote
>	java:app/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/crashrecovery-jpa/ResourcePrepareBean!org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBeanRemote
>	java:global/crashrecovery-jpa/ResourcePrepareBean
>	java:app/crashrecovery-jpa/ResourcePrepareBean
>	java:module/ResourcePrepareBean
>
>09:07:49,596 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named JPACrashHelper in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/JPACrashHelper!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashHelperRemote
>	java:app/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/crashrecovery-jpa/JPACrashHelper!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashHelperRemote
>	java:global/crashrecovery-jpa/JPACrashHelper
>	java:app/crashrecovery-jpa/JPACrashHelper
>	java:module/JPACrashHelper
>
>09:07:49,596 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named MultiXACrashBean in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanRemote
>	java:app/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/crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanRemote
>	java:global/crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanHome
>	java:app/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/crashrecovery-jpa/MultiXACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.MultiXACrashBeanHome
>
>09:07:49,596 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named CrashHelperTestXAResource in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote
>	java:app/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/crashrecovery-jpa/CrashHelperTestXAResource!org.jboss.as.test.jbossts.crashrec.common.CrashHelperRemote
>	java:global/crashrecovery-jpa/CrashHelperTestXAResource
>	java:app/crashrecovery-jpa/CrashHelperTestXAResource
>	java:module/CrashHelperTestXAResource
>
>09:07:49,596 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named JPACrashBean in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanRemote
>	java:app/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/crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanRemote
>	java:global/crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanHome
>	java:app/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/crashrecovery-jpa/JPACrashBean!org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBeanHome
>
>09:07:49,597 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named TestXAResourceRecoveryHelper in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper
>	java:app/crashrecovery-jpa/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper
>	java:module/TestXAResourceRecoveryHelper!com.arjuna.ats.jta.recovery.XAResourceRecoveryHelper
>	java:global/crashrecovery-jpa/TestXAResourceRecoveryHelper
>	java:app/crashrecovery-jpa/TestXAResourceRecoveryHelper
>	java:module/TestXAResourceRecoveryHelper
>
>09:07:49,597 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named TransactionStoreBrowserService in deployment unit deployment "crashrecovery-jpa.jar" are as follows:
>
>	java:global/crashrecovery-jpa/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService
>	java:app/crashrecovery-jpa/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService
>	java:module/TransactionStoreBrowserService!org.jboss.as.test.jbossts.common.TransactionStoreBrowserService
>	java:global/crashrecovery-jpa/TransactionStoreBrowserService
>	java:app/crashrecovery-jpa/TransactionStoreBrowserService
>	java:module/TransactionStoreBrowserService
>
>09:07:49,733 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 17) JBAS011402: Starting Persistence Unit Service 'crashrecovery-jpa.jar#jbossts-crash-rec'
>09:07:49,853 INFO  [org.hibernate.annotations.common.Version] (ServerService Thread Pool -- 17) HCANN000001: Hibernate Commons Annotations {4.0.1.Final-redhat-2}
>09:07:49,858 INFO  [org.hibernate.Version] (ServerService Thread Pool -- 17) HHH000412: Hibernate Core {4.2.10.Final}
>09:07:49,860 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 17) HHH000206: hibernate.properties not found
>09:07:49,862 INFO  [org.hibernate.cfg.Environment] (ServerService Thread Pool -- 17) HHH000021: Bytecode provider name : javassist
>09:07:49,880 INFO  [org.hibernate.ejb.Ejb3Configuration] (ServerService Thread Pool -- 17) HHH000204: Processing PersistenceUnitInfo [
>	name: jbossts-crash-rec
>	...]
>09:07:49,983 INFO  [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (ServerService Thread Pool -- 17) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
>09:09:21,912 INFO  [org.hibernate.dialect.Dialect] (ServerService Thread Pool -- 17) HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL82Dialect
>09:09:21,923 INFO  [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] (ServerService Thread Pool -- 17) HHH000424: Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException
>09:09:22,023 INFO  [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (ServerService Thread Pool -- 17) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
>09:09:22,028 INFO  [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (ServerService Thread Pool -- 17) HHH000397: Using ASTQueryTranslatorFactory
>09:09:22,075 INFO  [org.hibernate.validator.internal.util.Version] (ServerService Thread Pool -- 17) HV000001: Hibernate Validator 4.3.1.Final-redhat-1
>09:09:22,416 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 17) HHH000228: Running hbm2ddl schema update
>09:09:22,417 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 17) HHH000102: Fetching database metadata
>09:09:22,466 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 17) HHH000396: Updating schema
>09:09:22,551 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 17) HHH000262: Table not found: testentity
>09:09:22,633 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 17) HHH000262: Table not found: testentity
>09:09:22,716 INFO  [java.sql.DatabaseMetaData] (ServerService Thread Pool -- 17) HHH000262: Table not found: testentity
>09:09:22,904 INFO  [org.hibernate.tool.hbm2ddl.SchemaUpdate] (ServerService Thread Pool -- 17) HHH000232: Schema update complete
>09:09:22,953 INFO  [org.jboss.as.test.jbossts.common.TransactionStoreBrowserService] (ServerService Thread Pool -- 17) TransactionStoreBrowserService.start
>09:09:22,953 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 67) TestXAResourceRecoveryHelper starting
>09:09:22,955 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 67) Using file /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser for saving state of the org.jboss.as.test.jbossts.common.TestXAResource XA resource
>09:09:22,961 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 67) file /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser does not exists - no data for recovery
>09:09:23,125 INFO  [org.jboss.as.server] (management-handler-thread - 1) JBAS018559: Deployed "crashrecovery-jpa.jar" (runtime-name : "crashrecovery-jpa.jar")
>09:09:37,312 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 1) wipe out in-doubt txs: wipeOutTxsInDoubt() called
>09:09:37,314 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) getXAResources()
>09:09:37,315 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) getXAResources()
>09:09:37,315 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) getXAResources()
>09:09:37,316 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (EJB default - 1) TestXAResourceRecovered.recover(i=16777216)
>09:09:37,316 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) recover()
>09:09:37,317 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 1) returning 0 Xids
>09:09:37,328 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 2) getting XA resource of java:jboss/xa-datasources/VerificationDS datasource
>09:09:37,704 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 2) There are 0 xids in doubt
>09:09:39,344 INFO  [org.jboss.ejb.client] (EJB default - 3) JBoss EJB Client version 1.0.25.Final-redhat-1
>09:09:39,359 INFO  [org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBean] (EJB default - 3) prepareMarkableTable called with jndiName=java:jboss/xa-datasources/CrashRecoveryDS and db=postgresql
>09:09:39,400 INFO  [org.jboss.as.test.jbossts.crashrec.jpa.ResourcePrepareBean] (EJB default - 3) dropMarkableTable called with jndiName=java:jboss/xa-datasources/CrashRecoveryDS and db=postgresql
>09:09:40,887 INFO  [org.jboss.as.test.jbossts.crashrec.jpa.JPACrashBean] (EJB default - 6) JPACrashBean.testXA called with testEntityPK=prepare_halt_before_proxy
>09:09:40,927 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (EJB default - 6) CrashBeanCommon.enlistSynchronization
>09:09:40,942 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (EJB default - 6) CrashBeanCommon.enlistXAResource
>09:09:41,026 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (EJB default - 6) TestXAResourceCommon.setTransactionTimeout(i=300)
>09:09:41,033 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (EJB default - 6) TestXAResourceCommon.start(Xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:682d0566:5331394d:3c, node_name=1, branch_uid=0:ffff7f000001:682d0566:5331394d:3f, subordinatenodename=null, eis_name=unknown eis name >, i=0)
>09:09:41,033 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (EJB default - 6) CrashBeanCommon.beforeEntityUpdate
>09:09:41,046 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (EJB default - 6) TestXAResourceCommon.isSameRM(xaResource=LocalXAResourceImpl@10ba18e2[connectionListener=12f95ed7 connectionManager=2128d156 warned=false currentXid=null productName=PostgreSQL productVersion=9.3.3 jndiName=java:jboss/xa-datasources/CrashRecoveryDS]) - returning false
>09:09:41,055 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashBeanCommon] (EJB default - 6) CrashBeanCommon.afterEntityUpdate
>09:09:41,236 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (EJB default - 6) TestXAResourceCommon.end(Xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:682d0566:5331394d:3c, node_name=1, branch_uid=0:ffff7f000001:682d0566:5331394d:3f, subordinatenodename=null, eis_name=unknown eis name >, b=67108864)
>09:09:41,247 INFO  [org.jboss.as.test.jbossts.crashrec.common.SystemProcessKiller] (EJB default - 6) Killing process with pid [4183]
>09:09:41,248 INFO  [org.jboss.as.test.jbossts.crashrec.common.SystemProcessKiller] (EJB default - 6) Executing shell command: 'kill 4183'
>09:09:43,263 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (EJB default - 6) TestXAResourceCommon.prepare(Xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:682d0566:5331394d:3c, node_name=1, branch_uid=0:ffff7f000001:682d0566:5331394d:3f, subordinatenodename=null, eis_name=unknown eis name >) returning 0
>09:09:43,264 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 6) logPrepared(xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:682d0566:5331394d:3c, node_name=1, branch_uid=0:ffff7f000001:682d0566:5331394d:3f, subordinatenodename=null, eis_name=unknown eis name >)
>09:09:43,264 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 6) Using file /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser for saving state of the org.jboss.as.test.jbossts.common.TestXAResource XA resource
>09:09:43,264 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 6) logging 1 records to /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser
>09:09:43,391 ERROR [com.arjuna.ats.arjuna] (EJB default - 6) Could not commit the preparedConnection: org.jboss.jca.core.spi.transaction.local.LocalXAException: IJ001156: Could not commit local transaction
>	at org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl.commit(LocalXAResourceImpl.java:180)
>	at com.arjuna.ats.internal.jta.resources.arjunacore.CommitMarkableResourceRecord.commit(CommitMarkableResourceRecord.java:451) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.internal.jta.resources.arjunacore.CommitMarkableResourceRecord.topLevelCommit(CommitMarkableResourceRecord.java:436) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.arjuna.coordinator.BasicAction.doCommit(BasicAction.java:2746) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.arjuna.coordinator.BasicAction.doCommit(BasicAction.java:2662) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.arjuna.coordinator.BasicAction.phase2Commit(BasicAction.java:1820) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1504) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:98) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:162) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.commitAndDisassociate(TransactionImple.java:1189) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.internal.jta.transaction.arjunacore.BaseTransaction.commit(BaseTransaction.java:126) [jbossjts-jacorb-4.17.17.Final.jar:4.17.17.Final]
>	at com.arjuna.ats.jbossatx.BaseTransactionManagerDelegate.commit(BaseTransactionManagerDelegate.java:75)
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.endTransaction(CMTTxInterceptor.java:92) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:276) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:339) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:238) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.remote.EJBRemoteTransactionPropagatingInterceptor.processInvocation(EJBRemoteTransactionPropagatingInterceptor.java:79) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.EjbExceptionTransformingInterceptorFactories$1.processInvocation(EjbExceptionTransformingInterceptorFactories.java:65) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:55) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.2.Final-redhat-1.jar:1.1.2.Final-redhat-1]
>	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:185) [jboss-as-ee-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.as.ejb3.remote.protocol.versionone.MethodInvocationMessageHandler.invokeMethod(MethodInvocationMessageHandler.java:329) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.as.ejb3.remote.protocol.versionone.MethodInvocationMessageHandler.access$100(MethodInvocationMessageHandler.java:70) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at org.jboss.as.ejb3.remote.protocol.versionone.MethodInvocationMessageHandler$1.run(MethodInvocationMessageHandler.java:203) [jboss-as-ejb3-7.4.0.Final-redhat-5.jar:7.4.0.Final-redhat-5]
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_51]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_51]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_51]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_51]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_51]
>	at org.jboss.threads.JBossThread.run(JBossThread.java:122)
>Caused by: javax.resource.ResourceException: SQLException
>	at org.jboss.jca.adapters.jdbc.BaseWrapperManagedConnection.checkException(BaseWrapperManagedConnection.java:1202) [ironjacamar-jdbc-1.0.24.Final.jar:1.0.24.Final]
>	at org.jboss.jca.adapters.jdbc.local.LocalManagedConnection.commit(LocalManagedConnection.java:99) [ironjacamar-jdbc-1.0.24.Final.jar:1.0.24.Final]
>	at org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl.commit(LocalXAResourceImpl.java:175)
>	... 43 more
>Caused by: org.postgresql.util.PSQLException: An I/O error occurred while sending to the backend.
>	at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:281)
>	at org.postgresql.jdbc2.AbstractJdbc2Connection.executeTransactionCommand(AbstractJdbc2Connection.java:808)
>	at org.postgresql.jdbc2.AbstractJdbc2Connection.commit(AbstractJdbc2Connection.java:832)
>	at org.jboss.jca.adapters.jdbc.local.LocalManagedConnection.commit(LocalManagedConnection.java:95) [ironjacamar-jdbc-1.0.24.Final.jar:1.0.24.Final]
>	... 44 more
>Caused by: java.io.EOFException
>	at org.postgresql.core.PGStream.ReceiveChar(PGStream.java:271)
>	at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1704)
>	at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:255)
>	... 47 more
>
>09:09:43,419 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (EJB default - 6) TestXAResourceCommon.commit(Xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:682d0566:5331394d:3c, node_name=1, branch_uid=0:ffff7f000001:682d0566:5331394d:3f, subordinatenodename=null, eis_name=unknown eis name >, b=false)
>09:09:43,420 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 6) removeLog(xid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff7f000001:682d0566:5331394d:3c, node_name=1, branch_uid=0:ffff7f000001:682d0566:5331394d:3f, subordinatenodename=null, eis_name=unknown eis name >
>09:09:43,420 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 6) Using file /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser for saving state of the org.jboss.as.test.jbossts.common.TestXAResource XA resource
>09:09:43,420 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (EJB default - 6) logging 0 records to /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/tx-object-store/TestXAResourceStateStore/TestXAResource.ser
>09:09:45,060 ERROR [com.arjuna.ats.arjuna] (Periodic Recovery) Update was not successful, expected: 4 actual:1
>09:09:45,636 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) getXAResources()
>09:09:45,761 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=16777216)
>09:09:45,762 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover()
>09:09:45,762 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids
>09:09:45,768 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=d3b5b5f1-b3f4-11e3-b836-e930f6b5360e, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@69c2e673], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1034444091]) - returning false
>09:09:45,772 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@c86c81d, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@6e1cc56b}, transactionOriginNodeIdentifier='1'}) - returning false
>09:09:45,777 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=XAResourceWrapperImpl@1e0ce652[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3bc6e7e1 pad=false overrideRmValue=null productName=PostgreSQL productVersion=9.3.3 jndiName=java:jboss/xa-datasources/VerificationDS]) - returning false
>09:09:45,782 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=d3b5b5f1-b3f4-11e3-b836-e930f6b5360e, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@69c2e673], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1034444091]) - returning false
>09:09:45,787 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@c86c81d, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@6e1cc56b}, transactionOriginNodeIdentifier='1'}) - returning false
>09:09:45,798 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=XAResourceWrapperImpl@1e0ce652[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3bc6e7e1 pad=false overrideRmValue=null productName=PostgreSQL productVersion=9.3.3 jndiName=java:jboss/xa-datasources/VerificationDS]) - returning false
>09:09:55,803 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=8388608)
>09:09:55,803 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover()
>09:09:55,803 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids
>09:09:55,851 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) getXAResources()
>09:09:55,935 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=16777216)
>09:09:55,935 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover()
>09:09:55,935 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids
>09:09:55,944 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=TestXAResourceRecovered(TestXAResourceCommon(0, null))) - returning false
>09:09:55,947 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=RecoveryOnlyEJBXAResource{receiverContext=EJBReceiverContext{clientContext=org.jboss.ejb.client.EJBClientContext@c86c81d, receiver=org.jboss.as.ejb3.remote.LocalEjbReceiver@6e1cc56b}, transactionOriginNodeIdentifier='1'}) - returning false
>09:09:55,950 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=HornetQXAResourceWrapper [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], csf=ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], delegate=DelegatingSession [session=ClientSessionImpl [name=d3b5b5f1-b3f4-11e3-b836-e930f6b5360e, username=null, closed=false, factory = ClientSessionFactoryImpl [serverLocator=ServerLocatorImpl [initialConnectors=[TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryGroupConfiguration=null], connectorConfig=TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0, backupConfig=null], metaData=()]@69c2e673], xaRecoveryConfigs=[XARecoveryConfig [transportConfiguration = [TransportConfiguration(name=8ccb69ed-b3f4-11e3-b836-e930f6b5360e, factory=org-hornetq-core-remoting-impl-invm-InVMConnectorFactory) ?server-id=0], discoveryConfiguration = null, username=null, password=****]], instance=1034444091]) - returning false
>09:09:55,959 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceCommon] (Periodic Recovery) TestXAResourceCommon.isSameRM(xaResource=XAResourceWrapperImpl@2fd16b29[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3bc6e7e1 pad=false overrideRmValue=null productName=PostgreSQL productVersion=9.3.3 jndiName=java:jboss/xa-datasources/VerificationDS]) - returning false
>09:10:05,962 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecovered] (Periodic Recovery) TestXAResourceRecovered.recover(i=8388608)
>09:10:05,963 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) recover()
>09:10:05,963 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (Periodic Recovery) returning 0 Xids
>09:10:06,303 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 9) getting XA resource of java:jboss/xa-datasources/VerificationDS datasource
>09:10:06,641 INFO  [org.jboss.as.test.jbossts.crashrec.common.CrashHelperCommon] (EJB default - 9) There are 0 xids in doubt
>09:10:06,696 INFO  [org.jboss.as.test.jbossts.common.TransactionStoreBrowserService] (ServerService Thread Pool -- 74) TransactionStoreBrowserService.stop
>09:10:06,705 INFO  [org.jboss.as.test.jbossts.common.TestXAResourceRecoveryHelper] (ServerService Thread Pool -- 77) TestXAResourceRecoveryHelper stopping
>09:10:06,712 INFO  [org.jboss.as.jpa] (ServerService Thread Pool -- 77) JBAS011403: Stopping Persistence Unit Service 'crashrecovery-jpa.jar#jbossts-crash-rec'
>09:10:06,733 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015877: Stopped deployment crashrecovery-jpa.jar (runtime-name: crashrecovery-jpa.jar) in 80ms
>09:10:06,971 INFO  [org.jboss.as.repository] (management-handler-thread - 7) JBAS014901: Content removed from location /home/ochaloup/Transactions/eap-tests-transactions/integration/jbossts/target/jbossas-jbossts/standalone/data/content/65/306a66a0637b815678c2c60b8b407405edec40/content
>09:10:06,972 INFO  [org.jboss.as.server] (management-handler-thread - 7) JBAS018558: Undeployed "crashrecovery-jpa.jar" (runtime-name: "crashrecovery-jpa.jar")
>09:10:07,011 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-6) JBWEB003075: Coyote HTTP/1.1 pausing on: http-localhost.localdomain/127.0.0.1:8080
>09:10:07,012 INFO  [org.apache.coyote.http11.Http11NioProtocol] (MSC service thread 1-6) JBWEB003077: Coyote HTTP/1.1 stopping on : http-localhost.localdomain/127.0.0.1:8080
>09:10:07,013 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 77) JBAS011605: Unbound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory
>09:10:07,039 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-6) JBAS010409: Unbound data source [java:jboss/xa-datasources/VerificationDS]
>09:10:07,040 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-7) JBAS010409: Unbound data source [java:jboss/xa-datasources/CrashRecoveryDS]
>09:10:07,037 INFO  [org.jboss.as.connector.deployment] (MSC service thread 1-3) JBAS010410: Unbound JCA ConnectionFactory [java:/JmsXA]
>09:10:07,039 INFO  [org.apache.tomcat.util] (http-localhost.localdomain/127.0.0.1:8080-Acceptor) JBWEB003022: Channel processing failed
>09:10:07,060 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) JBAS010409: Unbound data source [java:jboss/datasources/ExampleDS]
>09:10:07,066 INFO  [org.jboss.jaxr] (MSC service thread 1-6) JBAS014002: UnBinding JAXR ConnectionFactory: java:jboss/jaxr/ConnectionFactory
>09:10:07,073 INFO  [org.jboss.as.messaging] (ServerService Thread Pool -- 72) JBAS011605: Unbound messaging object to jndi name java:/ConnectionFactory
>09:10:07,132 INFO  [org.apache.catalina.core] (MSC service thread 1-8) JBWEB001079: Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started
>09:10:07,150 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-5) JBAS015877: Stopped deployment dsdriver.jar (runtime-name: dsdriver.jar) in 111ms
>09:10:07,156 INFO  [org.hornetq.ra] (ServerService Thread Pool -- 73) HQ151003: HornetQ resource adaptor stopped
>09:10:07,260 INFO  [org.hornetq.core.server] (ServerService Thread Pool -- 73) HQ221002: HornetQ Server version 2.3.18.Final (2.3.18, 123) [89f0ab87-b3f4-11e3-b836-e930f6b5360e] stopped
>09:10:07,270 INFO  [org.jboss.as] (MSC service thread 1-3) JBAS015950: JBoss EAP 6.3.0.Alpha2 (AS 7.4.0.Final-redhat-5) stopped in 214ms

Actions: View

Attachments on bug 1080179: 878167 | 878338