TestATSubordinateCrashDuringPrepare failure ------------------------------------------- Key: JBTM-2793 URL: https://issues.jboss.org/browse/JBTM-2793 Project: JBoss Transaction Manager Issue Type: Bug Components: XTS Reporter: Gytis Trikleris Assignee: Gytis Trikleris Priority: Minor http://narayanaci1.eng.hst.ams2.redhat.com/job/narayana-codeCoverage/13/ {code} ------------------------------------------------------------------------------- Test set: com.arjuna.qa.junit.TestATSubordinateCrashDuringPrepare ------------------------------------------------------------------------------- Tests run: 1, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 630.486 sec <<< FAILURE! - in com.arjuna.qa.junit.TestATSubordinateCrashDuringPrepare subordinateMultiParticipantPrepareAndCommitTest(com.arjuna.qa.junit.TestATSubordinateCrashDuringPrepare) Time elapsed: 630.486 sec <<< ERROR! java.lang.RuntimeException: jboss-as was not killed by Byteman, this indicates a test failure at com.arjuna.qa.junit.TestATSubordinateCrashDuringPrepare.subordinateMultiParticipantPrepareAndCommitTest(TestATSubordinateCrashDuringPrepare.java:17) {code} {code} Starting arquillian with java VM args: -server -Xms64m -Xmx1024m -XX:MaxPermSize=512m -Dorg.jboss.byteman.verbose -Djboss.modules.system.pkgs=org.jboss.byteman -Dorg.jboss.byteman.transform.all -javaagent:/home/jenkins/workspace/narayana-codeCoverage/XTS/localjunit/crash-recovery-tests/target/lib/byteman.jar=script:target/test-classes/scripts/ATSubordinateCrashDuringPrepare.btm,boot:target/lib/byteman.jar,listener:true isIPv6: false Deleting: /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/tx-object-store remove tx-object-store: /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/tx-object-store remove /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/content/75 remove /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/content/d8 copy /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/docs/examples/configs/standalone-xts.xml to /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/configuration/standalone-xts.xml Nov 15, 2016 4:41:31 PM com.arjuna.qa.junit.BaseCrashTest runTest INFO: Test starting, server should be down: ATSubordinateCrashDuringPrepare:subordinate.MultiParticipantPrepareAndCommitTest Nov 15, 2016 4:41:31 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController start INFO: Manual starting of a server instance with overridden configuration. New configuration: {managementAddress=127.0.0.1, javaVmArguments=-server -Xms64m -Xmx1024m -XX:MaxPermSize=512m -Dorg.jboss.byteman.verbose -Djboss.modules.system.pkgs=org.jboss.byteman -Dorg.jboss.byteman.transform.all -javaagent:/home/jenkins/workspace/narayana-codeCoverage/XTS/localjunit/crash-recovery-tests/target/lib/byteman.jar=script:target/test-classes/scripts/ATSubordinateCrashDuringPrepare.btm,boot:target/lib/byteman.jar,listener:true -Dorg.jboss.jbossts.xts.servicetests.XTSServiceTestName=org.jboss.jbossts.xts.servicetests.test.at.subordinate.MultiParticipantPrepareAndCommitTest, startupTimeoutInSeconds=60, serverConfig=standalone-xts.xml} Nov 15, 2016 4:41:31 PM org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal WARNING: Bundles path is deprecated and no longer used. Nov 15, 2016 4:41:31 PM org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal INFO: Starting container with: [/usr/lib/jvm/java-1.8.0-openjdk/bin/java, -D[Standalone], -server, -Xms64m, -Xmx1024m, -XX:MaxPermSize=512m, -Dorg.jboss.byteman.verbose, -Djboss.modules.system.pkgs=org.jboss.byteman, -Dorg.jboss.byteman.transform.all, -javaagent:/home/jenkins/workspace/narayana-codeCoverage/XTS/localjunit/crash-recovery-tests/target/lib/byteman.jar=script:target/test-classes/scripts/ATSubordinateCrashDuringPrepare.btm,boot:target/lib/byteman.jar,listener:true, -Dorg.jboss.jbossts.xts.servicetests.XTSServiceTestName=org.jboss.jbossts.xts.servicetests.test.at.subordinate.MultiParticipantPrepareAndCommitTest, -ea, -Djboss.home.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT, -Dorg.jboss.boot.log.file=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/log/server.log, -Dlogging.configuration=file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/configuration/logging.properties, -jar, /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/jboss-modules.jar, -mp, /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/modules, org.jboss.as.standalone, -Djboss.home.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT, -Djboss.server.base.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone, -Djboss.server.log.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/log, -Djboss.server.config.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/configuration, -c=standalone-xts.xml] OpenJDK 64-Bit Server VM warning: ignoring option MaxPermSize=512m; support was removed in 8.0 TransformListener() : accepting requests on localhost:9091 16:41:31,887 INFO [org.jboss.modules] (main) JBoss Modules version 1.6.0.Beta3 16:41:32,074 INFO [org.jboss.msc] (main) JBoss MSC version 1.2.7.Final 16:41:32,173 INFO [org.jboss.as] (MSC service thread 1-6) WFLYSRV0049: WildFly Core 3.0.0.Alpha12 "Kenny" starting 16:41:32,321 INFO [org.jboss.as.domain.management] (MSC service thread 1-8) WFLYDM0136: Registered OpenSSL provider 16:41:33,042 INFO [org.jboss.as.controller.management-deprecated] (ServerService Thread Pool -- 13) WFLYCTL0028: Attribute 'security-realm' in the resource at address '/subsystem=undertow/server=default-server/https-listener=https' is deprecated, and may be removed in future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation. 16:41:33,066 INFO [org.jboss.as.repository] (ServerService Thread Pool -- 25) WFLYDR0001: Content added at location /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/content/75/4c26bdb7730dd705b361ba38f432c0cfa843bd/content 16:41:33,084 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating http management service using socket-binding (management-http) 16:41:33,095 INFO [org.xnio] (MSC service thread 1-4) XNIO version 3.4.1.Final 16:41:33,101 INFO [org.xnio.nio] (MSC service thread 1-4) XNIO NIO Implementation Version 3.4.1.Final 16:41:33,119 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 40) WFLYCLINF0001: Activating Infinispan subsystem. 16:41:33,121 INFO [org.wildfly.extension.io] (ServerService Thread Pool -- 39) WFLYIO001: Worker 'default' has auto-configured to 48 core threads with 384 task threads based on your 24 available processors 16:41:33,132 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 47) WFLYJSF0007: Activated the following JSF Implementations: [main] 16:41:33,135 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 51) WFLYNAM0001: Activating Naming Subsystem 16:41:33,135 INFO [org.wildfly.iiop.openjdk] (ServerService Thread Pool -- 41) WFLYIIOP0001: Activating IIOP Subsystem 16:41:33,135 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 59) WFLYTX0013: Node identifier property is set to the default value. Please make sure it is unique. 16:41:33,139 INFO [org.jboss.as.security] (ServerService Thread Pool -- 58) WFLYSEC0002: Activating Security Subsystem 16:41:33,139 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 61) WFLYWS0002: Activating WebServices Extension 16:41:33,150 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 35) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) 16:41:33,152 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-8) WFLYJCA0018: Started Driver service with driver-name = h2 16:41:33,155 INFO [org.jboss.as.connector] (MSC service thread 1-5) WFLYJCA0009: Starting JCA Subsystem (WildFly/IronJacamar 1.3.4.Final) 16:41:33,158 INFO [org.jboss.as.security] (MSC service thread 1-8) WFLYSEC0001: Current PicketBox version=5.0.0.Alpha3 16:41:33,185 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : possible trigger for rule create participant deletes counter in class org.jboss.jbossts.XTSService 16:41:33,191 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0003: Undertow 1.4.4.Final starting 16:41:33,196 INFO [org.jboss.remoting] (MSC service thread 1-4) JBoss Remoting version 5.0.0.Beta7 16:41:33,201 INFO [org.jboss.as.mail.extension] (MSC service thread 1-8) WFLYMAIL0001: Bound mail session [java:jboss/mail/Default] 16:41:33,201 INFO [org.jboss.as.naming] (MSC service thread 1-1) WFLYNAM0003: Starting Naming Service 16:41:33,201 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : possible trigger for rule reset periodic recovery wait time in class com.arjuna.ats.arjuna.common.RecoveryEnvironmentBean 16:41:33,220 INFO [stdout] (MSC service thread 1-2) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.common.RecoveryEnvironmentBean.getPeriodicRecoveryPeriod() int for rule reset periodic recovery wait time 16:41:33,221 INFO [stdout] (ServerService Thread Pool -- 63) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.jbossts.XTSService.start() void for rule create participant deletes counter 16:41:33,237 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : inserted trigger for create participant deletes counter in class org.jboss.jbossts.XTSService 16:41:33,238 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : possible trigger for rule open trace file in class org.jboss.jbossts.XTSService 16:41:33,239 INFO [stdout] (ServerService Thread Pool -- 63) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.jbossts.XTSService.start() void for rule open trace file 16:41:33,241 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : inserted trigger for reset periodic recovery wait time in class com.arjuna.ats.arjuna.common.RecoveryEnvironmentBean 16:41:33,243 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : inserted trigger for open trace file in class org.jboss.jbossts.XTSService 16:41:33,260 INFO [org.wildfly.security] (MSC service thread 1-4) ELY00001: WildFly Elytron version 1.1.0.Beta13 16:41:33,278 INFO [org.jboss.as.ejb3] (MSC service thread 1-1) WFLYEJB0481: Strict pool slsb-strict-max-pool is using a max instance size of 384 (per class), which is derived from thread worker pool sizing. 16:41:33,278 INFO [org.jboss.as.ejb3] (MSC service thread 1-7) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 96 (per class), which is derived from the number of CPUs on this host. 16:41:33,284 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 60) WFLYUT0014: Creating file handler for path '/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/welcome-content' with options [directory-listing: 'false', follow-symlink: 'false', case-sensitive: 'true', safe-symlink-paths: '[]'] 16:41:33,286 INFO [stdout] (MSC service thread 1-2) Rule.execute called for reset periodic recovery wait time_0 16:41:33,289 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0012: Started server default-server. 16:41:33,289 INFO [stdout] (MSC service thread 1-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:33,289 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0018: Host default-host starting 16:41:33,289 INFO [stdout] (MSC service thread 1-2) calling activated() for helper class org.jboss.byteman.rule.helper.Helper 16:41:33,290 INFO [stdout] (MSC service thread 1-2) Default helper activated 16:41:33,291 INFO [stdout] (MSC service thread 1-2) calling installed(reset periodic recovery wait time) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:33,291 INFO [stdout] (MSC service thread 1-2) Installed rule using default helper : reset periodic recovery wait time 16:41:33,291 INFO [stdout] (MSC service thread 1-2) reset periodic recovery wait time execute 16:41:33,295 INFO [stdout] (MSC service thread 1-2) caught ReturnException 16:41:33,322 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : possible trigger for rule trace TX save at prepare in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:33,351 INFO [stdout] (MSC service thread 1-2) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(boolean) int for rule trace TX save at prepare 16:41:33,355 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : inserted trigger for trace TX save at prepare in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:33,356 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0006: Undertow HTTP listener default listening on 127.0.0.1:8080 16:41:33,360 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : possible trigger for rule trace remove committed state and exit JVM in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:33,379 INFO [stdout] (MSC service thread 1-2) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.updateState() void for rule trace remove committed state and exit JVM 16:41:33,382 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : inserted trigger for trace remove committed state and exit JVM in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:33,386 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : possible trigger for rule trace remove committed state in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:33,424 INFO [stdout] (MSC service thread 1-2) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.updateState() void for rule trace remove committed state 16:41:33,425 INFO [stdout] (MSC service thread 1-2) org.jboss.byteman.agent.Transformer : inserted trigger for trace remove committed state in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:33,612 INFO [org.wildfly.iiop.openjdk] (MSC service thread 1-8) WFLYIIOP0009: CORBA ORB Service started 16:41:33,636 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS] 16:41:33,646 INFO [org.jboss.as.patching] (MSC service thread 1-7) WFLYPAT0050: WildFly cumulative patch ID is: base, one-off patches include: none 16:41:33,663 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-6) WFLYDS0013: Started FileSystemDeploymentService for directory /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/deployments 16:41:33,668 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0027: Starting deployment of "restat-web-5.4.1.Final-SNAPSHOT.war" (runtime-name: "restat-web-5.4.1.Final-SNAPSHOT.war") 16:41:33,700 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221000: live Message Broker is starting with configuration Broker Configuration (clustered=false,journalDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/journal,bindingsDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/bindings,largeMessagesDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/largemessages,pagingDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/paging) 16:41:33,740 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221012: Using AIO Journal 16:41:33,873 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-1) ISPN000128: Infinispan version: Infinispan 'Chakra' 8.2.4.Final 16:41:33,959 WARN [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0088: HTTP/2 will not be enabled as TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256 is not enabled. You may need to install JCE to enable strong ciphers to allow HTTP/2 to function. 16:41:33,981 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0006: Undertow HTTPS listener https listening on 127.0.0.1:8443 16:41:34,003 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-1) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,004 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,004 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-3) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,004 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,004 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-3) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,005 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,005 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-3) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,005 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-3) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,005 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,007 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-1) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,007 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-1) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,007 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-4) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,007 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-1) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,008 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-4) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,055 INFO [org.jboss.ws.common.management] (MSC service thread 1-5) JBWS022052: Starting JBossWS 5.1.5.Final (Apache CXF 3.1.8) 16:41:34,057 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-c11 16:41:34,057 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-t11-participant 16:41:34,057 INFO [org.jboss.as.webservices] (MSC service thread 1-3) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-t11-client 16:41:34,057 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-t11-coordinator 16:41:34,060 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-server]. Adding protocol support for: CORE 16:41:34,063 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-amqp-protocol]. Adding protocol support for: AMQP 16:41:34,064 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-hornetq-protocol]. Adding protocol support for: HORNETQ 16:41:34,065 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-stomp-protocol]. Adding protocol support for: STOMP 16:41:34,102 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=TerminationParticipantService address=http://localhost:8080/ws-t11-client/TerminationParticipantService implementor=com.arjuna.webservices11.wsarjtx.sei.TerminationParticipantPortTypeImpl serviceName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationPart... portName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationPartici... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,102 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-5) JBWS024061: Adding service endpoint metadata: id=ActivationService address=http://localhost:8080/ws-c11/ActivationService implementor=com.arjuna.webservices11.wscoor.sei.ActivationPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationSe... portName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationPortType annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,102 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=CompletionCoordinatorService address=http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorService implementor=com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoor... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,102 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-6) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithCoordinatorCompletionParticipantService address=http://localhost:8080/ws-t11-participant/BusinessAgreementWithCoordinatorCompletionParticipantService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithCoordinatorCompletionParticipantPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,102 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=CompletionInitiatorService address=http://localhost:8080/ws-t11-client/CompletionInitiatorService implementor=com.arjuna.webservices11.wsat.sei.CompletionInitiatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionInit... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionInitiat... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,103 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=TerminationCoordinatorService address=http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorService implementor=com.arjuna.webservices11.wsarjtx.sei.TerminationCoordinatorPortTypeImpl serviceName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoor... portName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,103 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-6) JBWS024061: Adding service endpoint metadata: id=ParticipantService address=http://localhost:8080/ws-t11-participant/ParticipantService implementor=com.arjuna.webservices11.wsat.sei.ParticipantPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantSer... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantPortType annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,103 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-5) JBWS024061: Adding service endpoint metadata: id=RegistrationService address=http://localhost:8080/ws-c11/RegistrationService implementor=com.arjuna.webservices11.wscoor.sei.RegistrationPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}Registration... portName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationPor... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,103 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-6) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithParticipantCompletionParticipantService address=http://localhost:8080/ws-t11-participant/BusinessAgreementWithParticipantCompletionParticipantService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionParticipantPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,103 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=TerminationCoordinatorRPCService address=http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorRPCService implementor=com.arjuna.webservices11.wsarjtx.sei.TerminationCoordinatorRPCPortTypeImpl serviceName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoor... portName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,103 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithCoordinatorCompletionCoordinatorService address=http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithCoordinatorCompletionCoordinatorService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithCoordinatorCompletionCoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,103 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithParticipantCompletionCoordinatorService address=http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithParticipantCompletionCoordinatorService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionCoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,104 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=CoordinatorService address=http://localhost:8080/ws-t11-coordinator/CoordinatorService implementor=com.arjuna.webservices11.wsat.sei.CoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorSer... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorPortType annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,104 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=CompletionCoordinatorRPCService address=http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorRPCService implementor=com.arjuna.webservices11.wsat.sei.CompletionCoordinatorRPCPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoor... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:34,151 WARN [org.jboss.as.dependency.private] (MSC service thread 1-2) WFLYSRV0018: Deployment "deployment.restat-web-5.4.1.Final-SNAPSHOT.war" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. 16:41:34,152 WARN [org.jboss.as.dependency.private] (MSC service thread 1-2) WFLYSRV0018: Deployment "deployment.restat-web-5.4.1.Final-SNAPSHOT.war" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. 16:41:34,270 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-4) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor acceptor 16:41:34,270 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-7) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor-throughput acceptor 16:41:34,270 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-8) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor acceptor 16:41:34,271 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-8) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor-throughput acceptor 16:41:34,302 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,303 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:34,338 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221007: Server is now live 16:41:34,338 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221001: Apache ActiveMQ Artemis Message Broker version 1.5.0 [default, nodeID=ef9290cb-ab4d-11e6-b97f-0242ac110007] 16:41:34,340 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 69) AMQ221003: Deploying queue jms.queue.DLQ 16:41:34,374 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221003: Deploying queue jms.queue.ExpiryQueue 16:41:34,390 INFO [org.wildfly.extension.messaging-activemq] (ServerService Thread Pool -- 67) WFLYMSGAMQ0002: Bound messaging object to jndi name java:/ConnectionFactory 16:41:34,391 INFO [org.wildfly.extension.messaging-activemq] (ServerService Thread Pool -- 68) WFLYMSGAMQ0002: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory 16:41:34,435 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-4) WFLYJCA0007: Registered connection factory java:/JmsXA 16:41:34,474 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 65) WFLYUT0021: Registered web context: '/rest-tx' for server 'default-server' 16:41:34,475 INFO [org.apache.activemq.artemis.ra] (MSC service thread 1-4) Resource adaptor started 16:41:34,475 INFO [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-4) IJ020002: Deployed: file://RaActivatoractivemq-ra 16:41:34,477 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-2) WFLYJCA0002: Bound JCA ConnectionFactory [java:/JmsXA] 16:41:34,477 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-7) WFLYMSGAMQ0002: Bound messaging object to jndi name java:jboss/DefaultJMSConnectionFactory 16:41:34,536 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-5) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationService from class com.arjuna.webservices11.wscoor.sei.ActivationPortTypeImpl 16:41:34,536 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationParticipantService from class com.arjuna.schemas.ws._2005._10.wsarjtx.TerminationParticipantPortType 16:41:34,536 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-6) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithCoordinatorCompletionParticipantService from class org.oasis_open.docs.ws_tx.wsba._2006._06.BusinessAgreementWithCoordinatorCompletionParticipantPortType 16:41:34,536 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordinatorService from class com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl 16:41:34,949 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/ws-t11-client/TerminationParticipantService 16:41:34,949 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-6) Setting the server's publish address to be http://localhost:8080/ws-t11-participant/BusinessAgreementWithCoordinator... 16:41:34,949 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-5) Setting the server's publish address to be http://localhost:8080/ws-c11/ActivationService 16:41:34,949 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorService 16:41:35,055 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/CompletionCoordinatorService.wsdl 16:41:35,056 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-5) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-c11.deployment/ActivationService.wsdl 16:41:35,056 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordinatorService from class com.arjuna.schemas.ws._2005._10.wsarjtx.TerminationCoordinatorPortType 16:41:35,057 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-5) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from class com.arjuna.webservices11.wscoor.sei.RegistrationPortTypeImpl 16:41:35,057 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-client.deployment/TerminationParticipantService.wsdl 16:41:35,058 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionInitiatorService from class com.arjuna.webservices11.wsat.sei.CompletionInitiatorPortTypeImpl 16:41:35,060 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-6) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-participant.deployment/BusinessAgreementWithCoordinatorCompletionParticipantService.wsdl 16:41:35,060 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-6) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from class com.arjuna.webservices11.wsat.sei.ParticipantPortTypeImpl 16:41:35,065 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorService 16:41:35,070 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/ws-t11-client/CompletionInitiatorService 16:41:35,073 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-6) Setting the server's publish address to be http://localhost:8080/ws-t11-participant/ParticipantService 16:41:35,073 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/TerminationCoordinatorService.wsdl 16:41:35,074 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordinatorRPCPortTypeImplService from class com.arjuna.webservices11.wsarjtx.sei.TerminationCoordinatorRPCPortTypeImpl 16:41:35,075 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-5) Setting the server's publish address to be http://localhost:8080/ws-c11/RegistrationService 16:41:35,076 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorRPCService 16:41:35,078 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-client.deployment/CompletionInitiatorService.wsdl 16:41:35,079 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-6) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-participant.deployment/ParticipantService.wsdl 16:41:35,080 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-6) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithParticipantCompletionParticipantService from class com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionParticipantPortTypeImpl 16:41:35,080 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/TerminationCoordinatorRPCPortTypeImplService.wsdl 16:41:35,081 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithCoordinatorCompletionCoordinatorService from class com.arjuna.webservices11.wsba.sei.BusinessAgreementWithCoordinatorCompletionCoordinatorPortTypeImpl 16:41:35,083 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-5) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-c11.deployment/RegistrationService.wsdl 16:41:35,085 INFO [org.jboss.as.webservices] (MSC service thread 1-3) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-client.deployment".CompletionInitiatorService 16:41:35,085 INFO [org.jboss.as.webservices] (MSC service thread 1-8) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-client.deployment".TerminationParticipantService 16:41:35,086 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-6) Setting the server's publish address to be http://localhost:8080/ws-t11-participant/BusinessAgreementWithParticipant... 16:41:35,086 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint."ws-c11.deployment".ActivationService 16:41:35,086 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0003: Starting service jboss.ws.endpoint."ws-c11.deployment".RegistrationService 16:41:35,091 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-t11-client 16:41:35,091 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-c11 16:41:35,094 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-6) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-participant.deployment/BusinessAgreementWithParticipantCompletionParticipantService.wsdl 16:41:35,095 INFO [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0021: Registered web context: '/ws-t11-client' for server 'default-server' 16:41:35,095 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0021: Registered web context: '/ws-c11' for server 'default-server' 16:41:35,098 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-participant.deployment".BusinessAgreementWithParticipantCompletionParticipantService 16:41:35,098 INFO [org.jboss.as.webservices] (MSC service thread 1-8) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-participant.deployment".BusinessAgreementWithCoordinatorCompletionParticipantService 16:41:35,098 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-participant.deployment".ParticipantService 16:41:35,098 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithCoordinator... 16:41:35,099 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-t11-participant 16:41:35,099 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0021: Registered web context: '/ws-t11-participant' for server 'default-server' 16:41:35,106 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/BusinessAgreementWithCoordinatorCompletionCoordinatorService.wsdl 16:41:35,107 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithParticipantCompletionCoordinatorService from class com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionCoordinatorPortTypeImpl 16:41:35,112 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithParticipant... 16:41:35,120 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/BusinessAgreementWithParticipantCompletionCoordinatorService.wsdl 16:41:35,120 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from class com.arjuna.webservices11.wsat.sei.CoordinatorPortTypeImpl 16:41:35,123 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/CoordinatorService 16:41:35,129 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/CoordinatorService.wsdl 16:41:35,130 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordinatorRPCService from class com.arjuna.webservices11.wsat.sei.CompletionCoordinatorRPCPortTypeImpl 16:41:35,132 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorRPCService 16:41:35,138 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/CompletionCoordinatorRPCService.wsdl 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".TerminationCoordinatorRPCService 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-3) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".CoordinatorService 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".TerminationCoordinatorService 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".BusinessAgreementWithParticipantCompletionCoordinatorService 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".BusinessAgreementWithCoordinatorCompletionCoordinatorService 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".CompletionCoordinatorRPCService 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-8) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".CompletionCoordinatorService 16:41:35,142 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-t11-coordinator 16:41:35,143 INFO [org.wildfly.extension.undertow] (MSC service thread 1-6) WFLYUT0021: Registered web context: '/ws-t11-coordinator' for server 'default-server' 16:41:35,147 INFO [stdout] (MSC service thread 1-8) Rule.execute called for open trace file_2 16:41:35,148 INFO [stdout] (MSC service thread 1-8) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:35,148 INFO [stdout] (MSC service thread 1-8) calling installed(open trace file) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:35,148 INFO [stdout] (MSC service thread 1-8) Installed rule using default helper : open trace file 16:41:35,148 INFO [stdout] (MSC service thread 1-8) open trace file execute 16:41:35,149 INFO [stdout] (MSC service thread 1-8) Rule.execute called for create participant deletes counter_1 16:41:35,149 INFO [stdout] (MSC service thread 1-8) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:35,149 INFO [stdout] (MSC service thread 1-8) calling installed(create participant deletes counter) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:35,149 INFO [stdout] (MSC service thread 1-8) Installed rule using default helper : create participant deletes counter 16:41:35,149 INFO [stdout] (MSC service thread 1-8) create participant deletes counter execute 16:41:35,159 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : possible trigger for rule trace Participant send rollback in class com.arjuna.wst11.messaging.CoordinatorProcessorImpl 16:41:35,160 INFO [stdout] (MSC service thread 1-8) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.CoordinatorProcessorImpl.prepared(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace Participant send rollback 16:41:35,163 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : inserted trigger for trace Participant send rollback in class com.arjuna.wst11.messaging.CoordinatorProcessorImpl 16:41:35,179 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate coordinator close in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:35,180 INFO [stdout] (MSC service thread 1-8) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator.rollback() void for rule trace subordinate coordinator close 16:41:35,181 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate coordinator close in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:35,182 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate coordinator prepare in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:35,182 INFO [stdout] (MSC service thread 1-8) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator.prepare() int for rule trace subordinate coordinator prepare 16:41:35,183 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate coordinator prepare in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:35,184 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate coordinator rollback 2 in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:35,185 INFO [stdout] (MSC service thread 1-8) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator.rollback() void for rule trace subordinate coordinator rollback 2 16:41:35,186 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate coordinator rollback 2 in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:35,197 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : possible trigger for rule trace delete participant and exit JVM in class org.jboss.jbossts.xts.recovery.participant.at.XTSATRecoveryManagerImple 16:41:35,197 INFO [stdout] (MSC service thread 1-8) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.jbossts.xts.recovery.participant.at.XTSATRecoveryManagerImple.deleteParticipantRecoveryRecord(java.lang.String) boolean for rule trace delete participant and exit JVM 16:41:35,198 INFO [stdout] (MSC service thread 1-8) org.jboss.byteman.agent.Transformer : inserted trigger for trace delete participant and exit JVM in class org.jboss.jbossts.xts.recovery.participant.at.XTSATRecoveryManagerImple 16:41:35,221 INFO [org.jboss.jbossts.txbridge] (MSC service thread 1-4) ARJUNA033013: OutboundBridgeRecoveryManager starting 16:41:35,221 INFO [org.jboss.jbossts.txbridge] (MSC service thread 1-8) ARJUNA033006: InboundBridgeRecoveryManager starting 16:41:35,235 INFO [org.jboss.as.server] (ServerService Thread Pool -- 36) WFLYSRV0010: Deployed "restat-web-5.4.1.Final-SNAPSHOT.war" (runtime-name : "restat-web-5.4.1.Final-SNAPSHOT.war") 16:41:35,301 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:9990/management 16:41:35,301 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:9990 16:41:35,301 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Core 3.0.0.Alpha12 "Kenny" started in 3688ms - Started 508 of 752 services (451 services are lazy, passive or on-demand) 16:41:35,378 INFO [org.jboss.as.repository] (management-handler-thread - 1) WFLYDR0001: Content added at location /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/content/67/eecc8af4663400c3ea658c818ff35814a26f3a/content 16:41:35,380 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2) WFLYSRV0027: Starting deployment of "xtstest.war" (runtime-name: "xtstest.war") 16:41:35,661 WARN [org.jboss.as.dependency.private] (MSC service thread 1-4) WFLYSRV0018: Deployment "deployment.xtstest.war" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. 16:41:35,675 INFO [org.jboss.weld.deployer] (MSC service thread 1-6) WFLYWELD0003: Processing weld deployment xtstest.war 16:41:35,699 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread 1-6) HV000001: Hibernate Validator 5.2.4.Final 16:41:35,830 INFO [org.jboss.weld.Version] (MSC service thread 1-3) WELD-000900: 2.4.0 (Final) 16:41:35,837 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Service_One address=http://localhost:8080/xtstest/xtsservicetest1 implementor=org.jboss.jbossts.xts.servicetests.service.XTSServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:35,838 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Service_Two address=http://localhost:8080/xtstest/xtsservicetest2 implementor=org.jboss.jbossts.xts.servicetests.service.XTSServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:35,838 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Service_Three address=http://localhost:8080/xtstest/xtsservicetest3 implementor=org.jboss.jbossts.xts.servicetests.service.XTSServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:35,838 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Subordinate_Service_One address=http://localhost:8080/xtstest/xtssubordinateservicetest1 implementor=org.jboss.jbossts.xts.servicetests.service.subordinate.XTSSubordinateServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:35,848 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:35,881 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/xtstest/xtsservicetest1 16:41:35,887 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:35,888 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:35,890 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/xtstest/xtsservicetest2 16:41:35,892 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:35,893 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:35,894 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/xtstest/xtsservicetest3 16:41:35,897 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:35,897 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:35,898 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/xtstest/xtssubordinateservicetest1 16:41:35,902 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:35,909 INFO [org.jboss.as.webservices] (MSC service thread 1-3) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_One 16:41:35,910 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_Two 16:41:35,910 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_Three 16:41:35,910 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Subordinate_Service_One 16:41:35,913 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:35,914 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:36,099 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-1) WELD-000169: Jandex cannot distinguish inner and static nested classes! Update Jandex to 2.0.3.Final version or newer to improve scanning performance. 16:41:36,386 INFO [org.jboss.jbossts.xts.servicetests.bean.XTSServiceTestRunnerBean] (ServerService Thread Pool -- 13) Starting XTSServiceTestRunner 16:41:36,386 INFO [org.jboss.jbossts.xts.servicetests.bean.XTSServiceTestRunnerBean] (ServerService Thread Pool -- 13) Starting XTS Service Test org.jboss.jbossts.xts.servicetests.test.at.subordinate.MultiParticipantPrepareAndCommitTest 16:41:36,386 INFO [org.jboss.jbossts.xts.servicetests.bean.XTSServiceTestRunnerBean] (ServerService Thread Pool -- 13) Started XTSServiceTestRunner 16:41:36,394 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 13) Initializing Mojarra 2.2.13.SP1 20160303-1204 for context '/xtstest' 16:41:36,784 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 13) WFLYUT0021: Registered web context: '/xtstest' for server 'default-server' 16:41:36,811 INFO [org.jboss.as.server] (management-handler-thread - 1) WFLYSRV0010: Deployed "xtstest.war" (runtime-name : "xtstest.war") Nov 15, 2016 4:41:36 PM org.jboss.remotingjmx.Util warnDeprecated WARN: The protocol 'http-remoting-jmx' is deprecated, instead you should use 'remote+http'. Nov 15, 2016 4:41:36 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController kill INFO: Hard killing of a server instance Nov 15, 2016 4:41:36 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: waiting for byteman to kill the server Nov 15, 2016 4:41:36 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' 16:41:41,421 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Thread-123) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-activation-binding.wsdl 16:41:41,431 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Thread-123) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-activation-binding.wsdl 16:41:41,785 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Thread-123) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-registration-binding.wsdl 16:41:41,817 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Thread-123) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: vfs:/content/xtstest.war/WEB-INF/classes/org/jboss/jbossts/xts/servicetests/generated/wsdl/xtsservicetests.wsdl 16:41:41,862 INFO [stdout] (default task-3) service /xtsservicetest1 16:41:41,862 INFO [stdout] (default task-3) command enlistDurable 16:41:41,862 INFO [stdout] (default task-3) command prepare 16:41:41,862 INFO [stdout] (default task-3) command commit 16:41:41,880 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (default task-3) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-registration-binding.wsdl 16:41:41,901 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace Coordinator engine create in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,902 INFO [stdout] (default task-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.<init>(java.lang.String,boolean,javax.xml.ws.wsaddressing.W3CEndpointReference,boolean,com.arjuna.webservices11.wsat.State) void for rule trace Coordinator engine create 16:41:41,911 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace Coordinator engine create in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,912 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive Participant aborted in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,913 INFO [stdout] (default task-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.aborted(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive Participant aborted 16:41:41,918 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive Participant aborted in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,919 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive participant prepared in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,920 INFO [stdout] (default task-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.prepared(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive participant prepared 16:41:41,926 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive participant prepared in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,927 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send participant prepare in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,928 INFO [stdout] (default task-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.prepare() com.arjuna.webservices11.wsat.State for rule trace send participant prepare 16:41:41,933 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace send participant prepare in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,934 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send Participant rollback in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,935 INFO [stdout] (default task-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.rollback() com.arjuna.webservices11.wsat.State for rule trace send Participant rollback 16:41:41,940 INFO [stdout] (default task-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace send Participant rollback in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:41:41,942 INFO [stdout] (default task-4) Rule.execute called for trace Coordinator engine create_11 16:41:41,943 INFO [stdout] (default task-4) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:41,943 INFO [stdout] (default task-4) calling installed(trace Coordinator engine create) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:41,943 INFO [stdout] (default task-4) Installed rule using default helper : trace Coordinator engine create 16:41:41,943 INFO [stdout] (default task-4) trace Coordinator engine create execute 16:41:41,955 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : possible trigger for rule trace Participant create in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,956 INFO [stdout] (default task-3) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.<init>(com.arjuna.wst.Participant,java.lang.String,com.arjuna.webservices11.wsat.State,javax.xml.ws.wsaddressing.W3CEndpointReference,boolean) void for rule trace Participant create 16:41:41,968 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : inserted trigger for trace Participant create in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,969 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive participant prepare in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,970 INFO [stdout] (default task-3) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.prepare(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive participant prepare 16:41:41,976 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive participant prepare in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,978 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send Participant prepared in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,984 INFO [stdout] (default task-3) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.sendPrepared(boolean) void for rule trace send Participant prepared 16:41:41,984 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : inserted trigger for trace send Participant prepared in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,986 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive Participant rollback in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,986 INFO [stdout] (default task-3) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.rollback(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive Participant rollback 16:41:41,993 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive Participant rollback in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:41,994 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send Participant aborted in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:42,000 INFO [stdout] (default task-3) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.sendAborted() void for rule trace send Participant aborted 16:41:42,000 INFO [stdout] (default task-3) org.jboss.byteman.agent.Transformer : inserted trigger for trace send Participant aborted in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:41:42,002 INFO [stdout] (default task-3) Rule.execute called for trace Participant create_16 16:41:42,002 INFO [stdout] (default task-3) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,002 INFO [stdout] (default task-3) calling installed(trace Participant create) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,002 INFO [stdout] (default task-3) Installed rule using default helper : trace Participant create 16:41:42,002 INFO [stdout] (default task-3) trace Participant create execute 16:41:42,029 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate participant stub rollback in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,030 INFO [stdout] (default task-5) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.stub.SubordinateDurable2PCStub.rollback() void for rule trace subordinate participant stub rollback 16:41:42,031 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate participant stub rollback in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,031 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate participant stub prepare in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,031 INFO [stdout] (default task-5) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.stub.SubordinateDurable2PCStub.prepare() com.arjuna.wst.Vote for rule trace subordinate participant stub prepare 16:41:42,032 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate participant stub prepare in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,032 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate participant stub prepare 2 in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,033 INFO [stdout] (default task-5) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.stub.SubordinateDurable2PCStub.prepare() com.arjuna.wst.Vote for rule trace subordinate participant stub prepare 2 16:41:42,033 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate participant stub prepare 2 in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,034 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate participant stub rollback forward in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,034 INFO [stdout] (default task-5) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.stub.SubordinateDurable2PCStub.rollback() void for rule trace subordinate participant stub rollback forward 16:41:42,035 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate participant stub rollback forward in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,036 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate participant stub rollback with no coordinator in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,036 INFO [stdout] (default task-5) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.stub.SubordinateDurable2PCStub.rollback() void for rule trace subordinate participant stub rollback with no coordinator 16:41:42,037 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate participant stub rollback with no coordinator in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,037 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate participant stub rollback with unactivated coordinator in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,038 INFO [stdout] (default task-5) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.stub.SubordinateDurable2PCStub.rollback() void for rule trace subordinate participant stub rollback with unactivated coordinator 16:41:42,038 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate participant stub rollback with unactivated coordinator in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,039 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate participant stub rollback return in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,039 INFO [stdout] (default task-5) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.stub.SubordinateDurable2PCStub.rollback() void for rule trace subordinate participant stub rollback return 16:41:42,040 INFO [stdout] (default task-5) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate participant stub rollback return in class com.arjuna.wst11.stub.SubordinateDurable2PCStub 16:41:42,055 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (default task-5) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-registration-binding.wsdl 16:41:42,071 INFO [stdout] (default task-6) Rule.execute called for trace Coordinator engine create_11 16:41:42,072 INFO [stdout] (default task-6) trace Coordinator engine create execute 16:41:42,081 INFO [stdout] (default task-5) Rule.execute called for trace Participant create_16 16:41:42,082 INFO [stdout] (default task-5) trace Participant create execute 16:41:42,091 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (default task-5) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-registration-binding.wsdl 16:41:42,104 INFO [stdout] (default task-7) Rule.execute called for trace Coordinator engine create_11 16:41:42,105 INFO [stdout] (default task-7) trace Coordinator engine create execute 16:41:42,112 INFO [stdout] (default task-5) Rule.execute called for trace Participant create_16 16:41:42,112 INFO [stdout] (default task-5) trace Participant create execute 16:41:42,116 INFO [stdout] (default task-5) service /xtssubordinateservicetest1 16:41:42,116 INFO [stdout] (default task-5) command enlistDurable 16:41:42,116 INFO [stdout] (default task-5) command prepare 16:41:42,116 INFO [stdout] (default task-5) command commit 16:41:42,128 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (default task-5) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-registration-binding.wsdl 16:41:42,140 INFO [stdout] (default task-8) Rule.execute called for trace Coordinator engine create_11 16:41:42,141 INFO [stdout] (default task-8) trace Coordinator engine create execute 16:41:42,148 INFO [stdout] (default task-5) Rule.execute called for trace Participant create_16 16:41:42,148 INFO [stdout] (default task-5) trace Participant create execute 16:41:42,169 INFO [stdout] (default task-9) service /xtssubordinateservicetest1 16:41:42,170 INFO [stdout] (default task-9) command enlistDurable 16:41:42,170 INFO [stdout] (default task-9) command prepare 16:41:42,170 INFO [stdout] (default task-9) command commit 16:41:42,181 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (default task-9) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wscoor/_2006/_06/wsdl/wscoor-registration-binding.wsdl 16:41:42,193 INFO [stdout] (default task-10) Rule.execute called for trace Coordinator engine create_11 16:41:42,193 INFO [stdout] (default task-10) trace Coordinator engine create execute 16:41:42,199 INFO [stdout] (default task-9) Rule.execute called for trace Participant create_16 16:41:42,199 INFO [stdout] (default task-9) trace Participant create execute 16:41:42,220 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Thread-123) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-completion-coordinator-binding.wsdl 16:41:42,254 INFO [stdout] (TaskWorker-1) Rule.execute called for trace send participant prepare_14 16:41:42,255 INFO [stdout] (TaskWorker-1) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,255 INFO [stdout] (TaskWorker-1) calling installed(trace send participant prepare) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,255 INFO [stdout] (TaskWorker-1) Installed rule using default helper : trace send participant prepare 16:41:42,255 INFO [stdout] (TaskWorker-1) trace send participant prepare execute 16:41:42,255 INFO [stdout] (TaskWorker-1) rule.debug{trace send participant prepare} : prepare sent for Coordinator engine V0:ffffac110007:-3ffa9bb3:582b3abd:1b 16:41:42,274 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:41:42,300 INFO [stdout] (TaskWorker-2) Rule.execute called for trace receive participant prepare_17 16:41:42,301 INFO [stdout] (TaskWorker-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,301 INFO [stdout] (TaskWorker-2) calling installed(trace receive participant prepare) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,301 INFO [stdout] (TaskWorker-2) Installed rule using default helper : trace receive participant prepare 16:41:42,301 INFO [stdout] (TaskWorker-2) trace receive participant prepare execute 16:41:42,301 INFO [stdout] (TaskWorker-2) rule.debug{trace receive participant prepare} : prepare received for participant org.jboss.jbossts.xts.at.subordinate.participant.0:ffffac110007:-3ffa9bb3:582b3abd:19_V 16:41:42,302 INFO [stdout] (TaskWorker-2) Rule.execute called for trace send Participant prepared_18 16:41:42,303 INFO [stdout] (TaskWorker-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,303 INFO [stdout] (TaskWorker-2) calling installed(trace send Participant prepared) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,303 INFO [stdout] (TaskWorker-2) Installed rule using default helper : trace send Participant prepared 16:41:42,303 INFO [stdout] (TaskWorker-2) trace send Participant prepared execute 16:41:42,303 INFO [stdout] (TaskWorker-2) rule.debug{trace send Participant prepared} : prepared sent for Participant org.jboss.jbossts.xts.at.subordinate.participant.0:ffffac110007:-3ffa9bb3:582b3abd:19_V 16:41:42,321 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-2) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:41:42,342 INFO [stdout] (TaskWorker-2) Rule.execute called for trace receive participant prepared_13 16:41:42,342 INFO [stdout] (TaskWorker-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,342 INFO [stdout] (TaskWorker-2) calling installed(trace receive participant prepared) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,342 INFO [stdout] (TaskWorker-2) Installed rule using default helper : trace receive participant prepared 16:41:42,342 INFO [stdout] (TaskWorker-2) trace receive participant prepared execute 16:41:42,342 INFO [stdout] (TaskWorker-2) rule.debug{trace receive participant prepared} : prepared received for Coordinator engine V0:ffffac110007:-3ffa9bb3:582b3abd:1b 16:41:42,343 INFO [stdout] (TaskWorker-1) Rule.execute called for trace send participant prepare_14 16:41:42,343 INFO [stdout] (TaskWorker-1) trace send participant prepare execute 16:41:42,343 INFO [stdout] (TaskWorker-1) rule.debug{trace send participant prepare} : prepare sent for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:16 16:41:42,352 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:41:42,367 INFO [stdout] (TaskWorker-2) Rule.execute called for trace receive participant prepare_17 16:41:42,368 INFO [stdout] (TaskWorker-2) trace receive participant prepare execute 16:41:42,368 INFO [stdout] (TaskWorker-2) rule.debug{trace receive participant prepare} : prepare received for participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.0 16:41:42,396 INFO [stdout] (TaskWorker-2) Rule.execute called for trace send Participant prepared_18 16:41:42,396 INFO [stdout] (TaskWorker-2) trace send Participant prepared execute 16:41:42,396 INFO [stdout] (TaskWorker-2) rule.debug{trace send Participant prepared} : prepared sent for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.0 16:41:42,404 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-2) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:41:42,422 INFO [stdout] (TaskWorker-2) Rule.execute called for trace receive participant prepared_13 16:41:42,422 INFO [stdout] (TaskWorker-2) trace receive participant prepared execute 16:41:42,422 INFO [stdout] (TaskWorker-2) rule.debug{trace receive participant prepared} : prepared received for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:16 16:41:42,423 INFO [stdout] (TaskWorker-1) Rule.execute called for trace send participant prepare_14 16:41:42,423 INFO [stdout] (TaskWorker-1) trace send participant prepare execute 16:41:42,423 INFO [stdout] (TaskWorker-1) rule.debug{trace send participant prepare} : prepare sent for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:1d 16:41:42,430 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:41:42,445 INFO [stdout] (TaskWorker-2) Rule.execute called for trace receive participant prepare_17 16:41:42,445 INFO [stdout] (TaskWorker-2) trace receive participant prepare execute 16:41:42,446 INFO [stdout] (TaskWorker-2) rule.debug{trace receive participant prepare} : prepare received for participant org.jboss.jbossts.xts.at.subordinate.participant.0:ffffac110007:-3ffa9bb3:582b3abd:19_D 16:41:42,446 INFO [stdout] (TaskWorker-2) Rule.execute called for trace subordinate participant stub prepare_22 16:41:42,446 INFO [stdout] (TaskWorker-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,446 INFO [stdout] (TaskWorker-2) calling installed(trace subordinate participant stub prepare) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,446 INFO [stdout] (TaskWorker-2) Installed rule using default helper : trace subordinate participant stub prepare 16:41:42,446 INFO [stdout] (TaskWorker-2) trace subordinate participant stub prepare execute 16:41:42,446 INFO [stdout] (TaskWorker-2) rule.debug{trace subordinate participant stub prepare} : prepare called for pseudo-participant com.arjuna.wst11.stub.SubordinateDurable2PCStub@422a3fd3 16:41:42,446 INFO [stdout] (TaskWorker-2) Rule.execute called for trace subordinate coordinator prepare_8 16:41:42,447 INFO [stdout] (TaskWorker-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,447 INFO [stdout] (TaskWorker-2) calling installed(trace subordinate coordinator prepare) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,447 INFO [stdout] (TaskWorker-2) Installed rule using default helper : trace subordinate coordinator prepare 16:41:42,447 INFO [stdout] (TaskWorker-2) trace subordinate coordinator prepare execute 16:41:42,447 INFO [stdout] (TaskWorker-2) rule.debug{trace subordinate coordinator prepare} : prepare for subordinate transaction 0:ffffac110007:-3ffa9bb3:582b3abd:19 16:41:42,447 INFO [stdout] (TaskWorker-2) Rule.execute called for trace send participant prepare_14 16:41:42,448 INFO [stdout] (TaskWorker-2) trace send participant prepare execute 16:41:42,448 INFO [stdout] (TaskWorker-2) rule.debug{trace send participant prepare} : prepare sent for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:20 16:41:42,456 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-2) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:41:42,471 INFO [stdout] (TaskWorker-3) Rule.execute called for trace receive participant prepare_17 16:41:42,471 INFO [stdout] (TaskWorker-3) trace receive participant prepare execute 16:41:42,471 INFO [stdout] (TaskWorker-3) rule.debug{trace receive participant prepare} : prepare received for participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.1 16:41:42,706 INFO [stdout] (TaskWorker-3) Rule.execute called for trace send Participant prepared_18 16:41:42,707 INFO [stdout] (TaskWorker-3) trace send Participant prepared execute 16:41:42,707 INFO [stdout] (TaskWorker-3) rule.debug{trace send Participant prepared} : prepared sent for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.1 16:41:42,714 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-3) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:41:42,729 INFO [stdout] (TaskWorker-3) Rule.execute called for trace receive participant prepared_13 16:41:42,729 INFO [stdout] (TaskWorker-3) trace receive participant prepared execute 16:41:42,729 INFO [stdout] (TaskWorker-3) rule.debug{trace receive participant prepared} : prepared received for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:20 16:41:42,730 INFO [stdout] (TaskWorker-2) Rule.execute called for trace send participant prepare_14 16:41:42,730 INFO [stdout] (TaskWorker-2) trace send participant prepare execute 16:41:42,730 INFO [stdout] (TaskWorker-2) rule.debug{trace send participant prepare} : prepare sent for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:23 16:41:42,739 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-2) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:41:42,751 INFO [stdout] (TaskWorker-3) Rule.execute called for trace receive participant prepare_17 16:41:42,751 INFO [stdout] (TaskWorker-3) trace receive participant prepare execute 16:41:42,751 INFO [stdout] (TaskWorker-3) rule.debug{trace receive participant prepare} : prepare received for participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.2 16:41:42,753 INFO [stdout] (TaskWorker-3) Rule.execute called for trace send Participant prepared_18 16:41:42,753 INFO [stdout] (TaskWorker-3) trace send Participant prepared execute 16:41:42,753 INFO [stdout] (TaskWorker-3) rule.debug{trace send Participant prepared} : prepared sent for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.2 16:41:42,759 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-3) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:41:42,773 INFO [stdout] (TaskWorker-3) Rule.execute called for trace receive participant prepared_13 16:41:42,773 INFO [stdout] (TaskWorker-3) trace receive participant prepared execute 16:41:42,774 INFO [stdout] (TaskWorker-3) rule.debug{trace receive participant prepared} : prepared received for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:23 16:41:42,777 INFO [stdout] (TaskWorker-2) Rule.execute called for trace TX save at prepare_3 16:41:42,778 INFO [stdout] (TaskWorker-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,778 INFO [stdout] (TaskWorker-2) calling installed(trace TX save at prepare) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,778 INFO [stdout] (TaskWorker-2) Installed rule using default helper : trace TX save at prepare 16:41:42,778 INFO [stdout] (TaskWorker-2) trace TX save at prepare execute 16:41:42,778 INFO [stdout] (TaskWorker-2) rule.debug{trace TX save at prepare} : saved to disk transaction 0:ffffac110007:-3ffa9bb3:582b3abd:19 16:41:42,782 INFO [stdout] (TaskWorker-2) Rule.execute called for trace subordinate participant stub prepare 2_23 16:41:42,782 INFO [stdout] (TaskWorker-2) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:42,782 INFO [stdout] (TaskWorker-2) calling installed(trace subordinate participant stub prepare 2) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:42,782 INFO [stdout] (TaskWorker-2) Installed rule using default helper : trace subordinate participant stub prepare 2 16:41:42,782 INFO [stdout] (TaskWorker-2) trace subordinate participant stub prepare 2 execute 16:41:42,782 INFO [stdout] (TaskWorker-2) rule.debug{trace subordinate participant stub prepare 2} : JVM exit Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep '\[java\] <defunct>'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as killed by byteman scirpt Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommand INFO: Executing shell command: 'mkdir target/surefire-reports/processes' std error mkdir: cannot create directory 'target/surefire-reports/processes': File exists Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.BaseServerKillProcessor dumpStream INFO: Execution result: 'mkdir: cannot create directory 'target/surefire-reports/processes': File exists' Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommand INFO: Executing shell command: 'ps aux > target/surefire-reports/processes/null:null_12.txt' Nov 15, 2016 4:41:46 PM com.arjuna.qa.extension.BaseServerKillProcessor dumpProcesses INFO: Logged current running processes to: target/surefire-reports/processes/null:null_12.txt Nov 15, 2016 4:41:46 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController start INFO: Manual starting of a server instance with overridden configuration. New configuration: {managementAddress=127.0.0.1, javaVmArguments=-server -Xms64m -Xmx1024m -XX:MaxPermSize=512m -Dorg.jboss.byteman.verbose -Djboss.modules.system.pkgs=org.jboss.byteman -Dorg.jboss.byteman.transform.all -javaagent:/home/jenkins/workspace/narayana-codeCoverage/XTS/localjunit/crash-recovery-tests/target/lib/byteman.jar=script:target/test-classes/scripts/ATSubordinateCrashDuringPrepare.btm,boot:target/lib/byteman.jar,listener:true , startupTimeoutInSeconds=60, serverConfig=standalone-xts.xml} Nov 15, 2016 4:41:46 PM org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal WARNING: Bundles path is deprecated and no longer used. Nov 15, 2016 4:41:46 PM org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal INFO: Starting container with: [/usr/lib/jvm/java-1.8.0-openjdk/bin/java, -D[Standalone], -server, -Xms64m, -Xmx1024m, -XX:MaxPermSize=512m, -Dorg.jboss.byteman.verbose, -Djboss.modules.system.pkgs=org.jboss.byteman, -Dorg.jboss.byteman.transform.all, -javaagent:/home/jenkins/workspace/narayana-codeCoverage/XTS/localjunit/crash-recovery-tests/target/lib/byteman.jar=script:target/test-classes/scripts/ATSubordinateCrashDuringPrepare.btm,boot:target/lib/byteman.jar,listener:true, -ea, -Djboss.home.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT, -Dorg.jboss.boot.log.file=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/log/server.log, -Dlogging.configuration=file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/configuration/logging.properties, -jar, /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/jboss-modules.jar, -mp, /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/modules, org.jboss.as.standalone, -Djboss.home.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT, -Djboss.server.base.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone, -Djboss.server.log.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/log, -Djboss.server.config.dir=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/configuration, -c=standalone-xts.xml] OpenJDK 64-Bit Server VM warning: ignoring option MaxPermSize=512m; support was removed in 8.0 TransformListener() : accepting requests on localhost:9091 16:41:47,298 INFO [org.jboss.modules] (main) JBoss Modules version 1.6.0.Beta3 16:41:47,473 INFO [org.jboss.msc] (main) JBoss MSC version 1.2.7.Final 16:41:47,565 INFO [org.jboss.as] (MSC service thread 1-7) WFLYSRV0049: WildFly Core 3.0.0.Alpha12 "Kenny" starting 16:41:47,703 INFO [org.jboss.as.domain.management] (MSC service thread 1-1) WFLYDM0136: Registered OpenSSL provider 16:41:48,403 INFO [org.jboss.as.controller.management-deprecated] (ServerService Thread Pool -- 7) WFLYCTL0028: Attribute 'security-realm' in the resource at address '/subsystem=undertow/server=default-server/https-listener=https' is deprecated, and may be removed in future version. See the attribute description in the output of the read-resource-description operation to learn more about the deprecation. 16:41:48,449 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0039: Creating http management service using socket-binding (management-http) 16:41:48,460 INFO [org.xnio] (MSC service thread 1-8) XNIO version 3.4.1.Final 16:41:48,465 INFO [org.xnio.nio] (MSC service thread 1-8) XNIO NIO Implementation Version 3.4.1.Final 16:41:48,483 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 40) WFLYCLINF0001: Activating Infinispan subsystem. 16:41:48,484 INFO [org.wildfly.iiop.openjdk] (ServerService Thread Pool -- 41) WFLYIIOP0001: Activating IIOP Subsystem 16:41:48,485 INFO [org.wildfly.extension.io] (ServerService Thread Pool -- 39) WFLYIO001: Worker 'default' has auto-configured to 48 core threads with 384 task threads based on your 24 available processors 16:41:48,487 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 47) WFLYJSF0007: Activated the following JSF Implementations: [main] 16:41:48,493 INFO [org.jboss.as.connector] (MSC service thread 1-2) WFLYJCA0009: Starting JCA Subsystem (WildFly/IronJacamar 1.3.4.Final) 16:41:48,536 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 35) WFLYJCA0004: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3) 16:41:48,537 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 51) WFLYNAM0001: Activating Naming Subsystem 16:41:48,540 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-6) WFLYJCA0018: Started Driver service with driver-name = h2 16:41:48,550 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 59) WFLYTX0013: Node identifier property is set to the default value. Please make sure it is unique. 16:41:48,557 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 61) WFLYWS0002: Activating WebServices Extension 16:41:48,558 INFO [org.jboss.as.security] (ServerService Thread Pool -- 58) WFLYSEC0002: Activating Security Subsystem 16:41:48,560 INFO [org.jboss.as.security] (MSC service thread 1-7) WFLYSEC0001: Current PicketBox version=5.0.0.Alpha3 16:41:48,599 INFO [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0003: Undertow 1.4.4.Final starting 16:41:48,602 INFO [org.jboss.as.naming] (MSC service thread 1-4) WFLYNAM0003: Starting Naming Service 16:41:48,603 INFO [org.jboss.as.mail.extension] (MSC service thread 1-7) WFLYMAIL0001: Bound mail session [java:jboss/mail/Default] 16:41:48,616 INFO [org.jboss.remoting] (MSC service thread 1-8) JBoss Remoting version 5.0.0.Beta7 16:41:48,636 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : possible trigger for rule create participant deletes counter in class org.jboss.jbossts.XTSService 16:41:48,663 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : possible trigger for rule reset periodic recovery wait time in class com.arjuna.ats.arjuna.common.RecoveryEnvironmentBean 16:41:48,675 INFO [stdout] (MSC service thread 1-7) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.common.RecoveryEnvironmentBean.getPeriodicRecoveryPeriod() int for rule reset periodic recovery wait time 16:41:48,676 INFO [stdout] (ServerService Thread Pool -- 63) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.jbossts.XTSService.start() void for rule create participant deletes counter 16:41:48,684 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : inserted trigger for reset periodic recovery wait time in class com.arjuna.ats.arjuna.common.RecoveryEnvironmentBean 16:41:48,691 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : inserted trigger for create participant deletes counter in class org.jboss.jbossts.XTSService 16:41:48,693 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : possible trigger for rule open trace file in class org.jboss.jbossts.XTSService 16:41:48,695 INFO [stdout] (ServerService Thread Pool -- 63) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.jbossts.XTSService.start() void for rule open trace file 16:41:48,699 INFO [stdout] (ServerService Thread Pool -- 63) org.jboss.byteman.agent.Transformer : inserted trigger for open trace file in class org.jboss.jbossts.XTSService 16:41:48,707 INFO [org.wildfly.security] (MSC service thread 1-8) ELY00001: WildFly Elytron version 1.1.0.Beta13 16:41:48,710 INFO [org.jboss.as.ejb3] (MSC service thread 1-1) WFLYEJB0482: Strict pool mdb-strict-max-pool is using a max instance size of 96 (per class), which is derived from the number of CPUs on this host. 16:41:48,710 INFO [org.jboss.as.ejb3] (MSC service thread 1-3) WFLYEJB0481: Strict pool slsb-strict-max-pool is using a max instance size of 384 (per class), which is derived from thread worker pool sizing. 16:41:48,723 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 60) WFLYUT0014: Creating file handler for path '/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/welcome-content' with options [directory-listing: 'false', follow-symlink: 'false', case-sensitive: 'true', safe-symlink-paths: '[]'] 16:41:48,730 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0012: Started server default-server. 16:41:48,731 INFO [stdout] (MSC service thread 1-7) Rule.execute called for reset periodic recovery wait time_1 16:41:48,731 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0018: Host default-host starting 16:41:48,733 INFO [stdout] (MSC service thread 1-7) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:48,735 INFO [stdout] (MSC service thread 1-7) calling activated() for helper class org.jboss.byteman.rule.helper.Helper 16:41:48,735 INFO [stdout] (MSC service thread 1-7) Default helper activated 16:41:48,735 INFO [stdout] (MSC service thread 1-7) calling installed(reset periodic recovery wait time) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:48,736 INFO [stdout] (MSC service thread 1-7) Installed rule using default helper : reset periodic recovery wait time 16:41:48,736 INFO [stdout] (MSC service thread 1-7) reset periodic recovery wait time execute 16:41:48,736 INFO [stdout] (MSC service thread 1-7) caught ReturnException 16:41:48,761 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : possible trigger for rule trace TX save at prepare in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:48,790 INFO [stdout] (MSC service thread 1-7) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(boolean) int for rule trace TX save at prepare 16:41:48,794 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : inserted trigger for trace TX save at prepare in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:48,794 INFO [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0006: Undertow HTTP listener default listening on 127.0.0.1:8080 16:41:48,799 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : possible trigger for rule trace remove committed state and exit JVM in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:48,817 INFO [stdout] (MSC service thread 1-7) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.updateState() void for rule trace remove committed state and exit JVM 16:41:48,818 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : inserted trigger for trace remove committed state and exit JVM in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:48,822 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : possible trigger for rule trace remove committed state in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:48,840 INFO [stdout] (MSC service thread 1-7) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.ats.arjuna.coordinator.BasicAction.updateState() void for rule trace remove committed state 16:41:48,843 INFO [stdout] (MSC service thread 1-7) org.jboss.byteman.agent.Transformer : inserted trigger for trace remove committed state in class com.arjuna.ats.arjuna.coordinator.BasicAction 16:41:48,923 INFO [org.wildfly.iiop.openjdk] (MSC service thread 1-6) WFLYIIOP0009: CORBA ORB Service started 16:41:49,091 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-3) WFLYJCA0001: Bound data source [java:jboss/datasources/ExampleDS] 16:41:49,106 INFO [org.jboss.as.patching] (MSC service thread 1-4) WFLYPAT0050: WildFly cumulative patch ID is: base, one-off patches include: none 16:41:49,145 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "restat-web-5.4.1.Final-SNAPSHOT.war" (runtime-name: "restat-web-5.4.1.Final-SNAPSHOT.war") 16:41:49,147 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) WFLYSRV0027: Starting deployment of "xtstest.war" (runtime-name: "xtstest.war") 16:41:49,148 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-3) WFLYDS0013: Started FileSystemDeploymentService for directory /home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/deployments 16:41:49,324 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221000: live Message Broker is starting with configuration Broker Configuration (clustered=false,journalDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/journal,bindingsDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/bindings,largeMessagesDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/largemessages,pagingDirectory=/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/activemq/paging) 16:41:49,375 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221012: Using AIO Journal 16:41:49,389 INFO [org.infinispan.factories.GlobalComponentRegistry] (MSC service thread 1-5) ISPN000128: Infinispan version: Infinispan 'Chakra' 8.2.4.Final 16:41:49,429 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-5) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,430 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-4) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,430 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-8) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,431 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-8) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,431 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-5) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,432 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-3) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,432 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-3) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,433 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-4) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,434 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-4) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,434 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,434 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,435 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-4) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,442 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-5) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,442 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-5) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,445 WARN [org.wildfly.extension.undertow] (MSC service thread 1-8) WFLYUT0088: HTTP/2 will not be enabled as TLS_ECDHE_RSA_WITH_AES_128_GCM_SHA256 is not enabled. You may need to install JCE to enable strong ciphers to allow HTTP/2 to function. 16:41:49,456 INFO [org.wildfly.extension.undertow] (MSC service thread 1-8) WFLYUT0006: Undertow HTTPS listener https listening on 127.0.0.1:8443 16:41:49,537 INFO [org.jboss.ws.common.management] (MSC service thread 1-8) JBWS022052: Starting JBossWS 5.1.5.Final (Apache CXF 3.1.8) 16:41:49,539 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-t11-client 16:41:49,539 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-t11-coordinator 16:41:49,539 INFO [org.jboss.as.webservices] (MSC service thread 1-3) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-t11-participant 16:41:49,539 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint-deploy.ws-c11 16:41:49,569 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-server]. Adding protocol support for: CORE 16:41:49,571 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-amqp-protocol]. Adding protocol support for: AMQP 16:41:49,572 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithCoordinatorCompletionParticipantService address=http://localhost:8080/ws-t11-participant/BusinessAgreementWithCoordinatorCompletionParticipantService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithCoordinatorCompletionParticipantPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,572 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-4) JBWS024061: Adding service endpoint metadata: id=TerminationParticipantService address=http://localhost:8080/ws-t11-client/TerminationParticipantService implementor=com.arjuna.webservices11.wsarjtx.sei.TerminationParticipantPortTypeImpl serviceName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationPart... portName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationPartici... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,572 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-5) JBWS024061: Adding service endpoint metadata: id=ActivationService address=http://localhost:8080/ws-c11/ActivationService implementor=com.arjuna.webservices11.wscoor.sei.ActivationPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationSe... portName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationPortType annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,572 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=CompletionCoordinatorService address=http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorService implementor=com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoor... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,572 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-hornetq-protocol]. Adding protocol support for: HORNETQ 16:41:49,572 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=ParticipantService address=http://localhost:8080/ws-t11-participant/ParticipantService implementor=com.arjuna.webservices11.wsat.sei.ParticipantPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantSer... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantPortType annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,572 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=TerminationCoordinatorService address=http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorService implementor=com.arjuna.webservices11.wsarjtx.sei.TerminationCoordinatorPortTypeImpl serviceName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoor... portName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,573 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-5) JBWS024061: Adding service endpoint metadata: id=RegistrationService address=http://localhost:8080/ws-c11/RegistrationService implementor=com.arjuna.webservices11.wscoor.sei.RegistrationPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}Registration... portName={http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationPor... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,573 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-3) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithParticipantCompletionParticipantService address=http://localhost:8080/ws-t11-participant/BusinessAgreementWithParticipantCompletionParticipantService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionParticipantPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,573 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-4) JBWS024061: Adding service endpoint metadata: id=CompletionInitiatorService address=http://localhost:8080/ws-t11-client/CompletionInitiatorService implementor=com.arjuna.webservices11.wsat.sei.CompletionInitiatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionInit... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionInitiat... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,573 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221043: Protocol module found: [artemis-stomp-protocol]. Adding protocol support for: STOMP 16:41:49,573 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=TerminationCoordinatorRPCService address=http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorRPCService implementor=com.arjuna.webservices11.wsarjtx.sei.TerminationCoordinatorRPCPortTypeImpl serviceName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoor... portName={http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,573 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithCoordinatorCompletionCoordinatorService address=http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithCoordinatorCompletionCoordinatorService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithCoordinatorCompletionCoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,574 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=BusinessAgreementWithParticipantCompletionCoordinatorService address=http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithParticipantCompletionCoordinatorService implementor=com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionCoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreem... portName={http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreement... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,574 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=CoordinatorService address=http://localhost:8080/ws-t11-coordinator/CoordinatorService implementor=com.arjuna.webservices11.wsat.sei.CoordinatorPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorSer... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorPortType annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,574 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-1) JBWS024061: Adding service endpoint metadata: id=CompletionCoordinatorRPCService address=http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorRPCService implementor=com.arjuna.webservices11.wsat.sei.CompletionCoordinatorRPCPortTypeImpl serviceName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoor... portName={http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordin... annotationWsdlLocation=null wsdlLocationOverride=null mtomEnabled=false 16:41:49,629 WARN [org.jboss.as.dependency.private] (MSC service thread 1-7) WFLYSRV0018: Deployment "deployment.restat-web-5.4.1.Final-SNAPSHOT.war" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. 16:41:49,629 WARN [org.jboss.as.dependency.private] (MSC service thread 1-7) WFLYSRV0018: Deployment "deployment.restat-web-5.4.1.Final-SNAPSHOT.war" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. 16:41:49,806 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,807 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-6) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:49,815 WARN [org.jboss.as.dependency.private] (MSC service thread 1-8) WFLYSRV0018: Deployment "deployment.xtstest.war" is using a private module ("org.jboss.jts:main") which may be changed or removed in future versions without notice. 16:41:49,833 INFO [org.jboss.weld.deployer] (MSC service thread 1-8) WFLYWELD0003: Processing weld deployment xtstest.war 16:41:49,863 INFO [org.hibernate.validator.internal.util.Version] (MSC service thread 1-8) HV000001: Hibernate Validator 5.2.4.Final 16:41:49,897 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-7) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor-throughput acceptor 16:41:49,897 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-6) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor-throughput acceptor 16:41:49,897 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-2) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor acceptor 16:41:49,898 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-2) WFLYMSGAMQ0016: Registered HTTP upgrade for activemq-remoting protocol handled by http-acceptor acceptor 16:41:49,937 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 65) WFLYUT0021: Registered web context: '/rest-tx' for server 'default-server' 16:41:49,960 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221007: Server is now live 16:41:49,960 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221001: Apache ActiveMQ Artemis Message Broker version 1.5.0 [default, nodeID=ef9290cb-ab4d-11e6-b97f-0242ac110007] 16:41:49,962 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 64) AMQ221003: Deploying queue jms.queue.DLQ 16:41:49,992 INFO [org.wildfly.extension.messaging-activemq] (ServerService Thread Pool -- 67) WFLYMSGAMQ0002: Bound messaging object to jndi name java:/ConnectionFactory 16:41:49,993 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 70) AMQ221003: Deploying queue jms.queue.ExpiryQueue 16:41:49,995 INFO [org.wildfly.extension.messaging-activemq] (ServerService Thread Pool -- 65) WFLYMSGAMQ0002: Bound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory 16:41:50,010 INFO [org.jboss.weld.Version] (MSC service thread 1-8) WELD-000900: 2.4.0 (Final) 16:41:50,018 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-8) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Service_One address=http://localhost:8080/xtstest/xtsservicetest1 implementor=org.jboss.jbossts.xts.servicetests.service.XTSServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:50,019 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-8) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Service_Two address=http://localhost:8080/xtstest/xtsservicetest2 implementor=org.jboss.jbossts.xts.servicetests.service.XTSServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:50,019 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-8) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Service_Three address=http://localhost:8080/xtstest/xtsservicetest3 implementor=org.jboss.jbossts.xts.servicetests.service.XTSServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:50,019 INFO [org.jboss.ws.cxf.metadata] (MSC service thread 1-8) JBWS024061: Adding service endpoint metadata: id=XTS_Service_Test_Subordinate_Service_One address=http://localhost:8080/xtstest/xtssubordinateservicetest1 implementor=org.jboss.jbossts.xts.servicetests.service.subordinate.XTSSubordinateServiceTestPortTypeImpl serviceName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServi... portName={http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceT... annotationWsdlLocation=WEB-INF/wsdl/xtsservicetests.wsdl wsdlLocationOverride=null mtomEnabled=false 16:41:50,026 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-6) WFLYJCA0007: Registered connection factory java:/JmsXA 16:41:50,036 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-8) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:50,036 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-5) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}ActivationService from class com.arjuna.webservices11.wscoor.sei.ActivationPortTypeImpl 16:41:50,036 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordinatorService from class com.arjuna.webservices11.wsat.sei.CompletionCoordinatorPortTypeImpl 16:41:50,036 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-4) Creating Service {http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationParticipantService from class com.arjuna.schemas.ws._2005._10.wsarjtx.TerminationParticipantPortType 16:41:50,036 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithCoordinatorCompletionParticipantService from class org.oasis_open.docs.ws_tx.wsba._2006._06.BusinessAgreementWithCoordinatorCompletionParticipantPortType 16:41:50,062 INFO [org.apache.activemq.artemis.ra] (MSC service thread 1-6) Resource adaptor started 16:41:50,062 INFO [org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator] (MSC service thread 1-6) IJ020002: Deployed: file://RaActivatoractivemq-ra 16:41:50,063 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-6) WFLYJCA0002: Bound JCA ConnectionFactory [java:/JmsXA] 16:41:50,063 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-2) WFLYMSGAMQ0002: Bound messaging object to jndi name java:jboss/DefaultJMSConnectionFactory 16:41:50,339 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-8) Setting the server's publish address to be http://localhost:8080/xtstest/xtsservicetest1 16:41:50,386 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-4) Setting the server's publish address to be http://localhost:8080/ws-t11-client/TerminationParticipantService 16:41:50,390 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorService 16:41:50,390 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/ws-t11-participant/BusinessAgreementWithCoordinator... 16:41:50,400 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-5) Setting the server's publish address to be http://localhost:8080/ws-c11/ActivationService 16:41:50,429 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-8) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:50,430 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-8) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:50,432 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-8) Setting the server's publish address to be http://localhost:8080/xtstest/xtsservicetest2 16:41:50,436 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-8) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:50,436 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-8) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:50,438 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-8) Setting the server's publish address to be http://localhost:8080/xtstest/xtsservicetest3 16:41:50,442 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-8) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:50,442 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-8) Creating Service {http://jbossts.jboss.org/xts/servicetests/generated}XTSServiceTestService from WSDL: WEB-INF/wsdl/xtsservicetests.wsdl 16:41:50,444 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-8) Setting the server's publish address to be http://localhost:8080/xtstest/xtssubordinateservicetest1 16:41:50,448 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/CompletionCoordinatorService.wsdl 16:41:50,449 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordinatorService from class com.arjuna.schemas.ws._2005._10.wsarjtx.TerminationCoordinatorPortType 16:41:50,449 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-8) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/xtstest.war/xtsservicetests.wsdl 16:41:50,450 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-5) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-c11.deployment/ActivationService.wsdl 16:41:50,450 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-4) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-client.deployment/TerminationParticipantService.wsdl 16:41:50,450 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-5) Creating Service {http://docs.oasis-open.org/ws-tx/wscoor/2006/06}RegistrationService from class com.arjuna.webservices11.wscoor.sei.RegistrationPortTypeImpl 16:41:50,451 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-4) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionInitiatorService from class com.arjuna.webservices11.wsat.sei.CompletionInitiatorPortTypeImpl 16:41:50,455 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorService 16:41:50,459 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-participant.deployment/BusinessAgreementWithCoordinatorCompletionParticipantService.wsdl 16:41:50,460 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-4) Setting the server's publish address to be http://localhost:8080/ws-t11-client/CompletionInitiatorService 16:41:50,460 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from class com.arjuna.webservices11.wsat.sei.ParticipantPortTypeImpl 16:41:50,462 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/TerminationCoordinatorService.wsdl 16:41:50,462 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/ws-t11-participant/ParticipantService 16:41:50,463 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://schemas.arjuna.com/ws/2005/10/wsarjtx}TerminationCoordinatorRPCPortTypeImplService from class com.arjuna.webservices11.wsarjtx.sei.TerminationCoordinatorRPCPortTypeImpl 16:41:50,463 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_One 16:41:50,463 INFO [org.jboss.as.webservices] (MSC service thread 1-8) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Subordinate_Service_One 16:41:50,463 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_Three 16:41:50,464 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0003: Starting service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_Two 16:41:50,465 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/TerminationCoordinatorRPCService 16:41:50,465 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-5) Setting the server's publish address to be http://localhost:8080/ws-c11/RegistrationService 16:41:50,466 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-4) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-client.deployment/CompletionInitiatorService.wsdl 16:41:50,469 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:50,470 INFO [org.infinispan.configuration.cache.EvictionConfigurationBuilder] (MSC service thread 1-2) ISPN000152: Passivation configured without an eviction policy being selected. Only manually evicted entities will be passivated. 16:41:50,470 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-participant.deployment/ParticipantService.wsdl 16:41:50,472 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-3) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithParticipantCompletionParticipantService from class com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionParticipantPortTypeImpl 16:41:50,472 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/TerminationCoordinatorRPCPortTypeImplService.wsdl 16:41:50,472 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-client.deployment".CompletionInitiatorService 16:41:50,472 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-client.deployment".TerminationParticipantService 16:41:50,473 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithCoordinatorCompletionCoordinatorService from class com.arjuna.webservices11.wsba.sei.BusinessAgreementWithCoordinatorCompletionCoordinatorPortTypeImpl 16:41:50,473 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-t11-client 16:41:50,478 INFO [org.wildfly.extension.undertow] (MSC service thread 1-6) WFLYUT0021: Registered web context: '/ws-t11-client' for server 'default-server' 16:41:50,482 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-3) Setting the server's publish address to be http://localhost:8080/ws-t11-participant/BusinessAgreementWithParticipant... 16:41:50,483 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-5) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-c11.deployment/RegistrationService.wsdl 16:41:50,487 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint."ws-c11.deployment".RegistrationService 16:41:50,487 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint."ws-c11.deployment".ActivationService 16:41:50,489 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-c11 16:41:50,490 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0021: Registered web context: '/ws-c11' for server 'default-server' 16:41:50,492 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-3) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-participant.deployment/BusinessAgreementWithParticipantCompletionParticipantService.wsdl 16:41:50,495 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-participant.deployment".BusinessAgreementWithParticipantCompletionParticipantService 16:41:50,495 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-participant.deployment".ParticipantService 16:41:50,495 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-participant.deployment".BusinessAgreementWithCoordinatorCompletionParticipantService 16:41:50,496 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-t11-participant 16:41:50,497 INFO [org.wildfly.extension.undertow] (MSC service thread 1-2) WFLYUT0021: Registered web context: '/ws-t11-participant' for server 'default-server' 16:41:50,502 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithCoordinator... 16:41:50,517 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/BusinessAgreementWithCoordinatorCompletionCoordinatorService.wsdl 16:41:50,518 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsba/2006/06}BusinessAgreementWithParticipantCompletionCoordinatorService from class com.arjuna.webservices11.wsba.sei.BusinessAgreementWithParticipantCompletionCoordinatorPortTypeImpl 16:41:50,522 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/BusinessAgreementWithParticipant... 16:41:50,533 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/BusinessAgreementWithParticipantCompletionCoordinatorService.wsdl 16:41:50,533 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from class com.arjuna.webservices11.wsat.sei.CoordinatorPortTypeImpl 16:41:50,537 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/CoordinatorService 16:41:50,544 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/CoordinatorService.wsdl 16:41:50,545 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (MSC service thread 1-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CompletionCoordinatorRPCService from class com.arjuna.webservices11.wsat.sei.CompletionCoordinatorRPCPortTypeImpl 16:41:50,549 INFO [org.apache.cxf.endpoint.ServerImpl] (MSC service thread 1-1) Setting the server's publish address to be http://localhost:8080/ws-t11-coordinator/CompletionCoordinatorRPCService 16:41:50,555 INFO [org.jboss.ws.cxf.deployment] (MSC service thread 1-1) JBWS024074: WSDL published to: file:/home/jenkins/workspace/narayana-codeCoverage/jboss-as/build/target/wildfly-11.0.0.Alpha1-SNAPSHOT/standalone/data/wsdl/ws-t11-coordinator.deployment/CompletionCoordinatorRPCService.wsdl 16:41:50,560 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".TerminationCoordinatorRPCService 16:41:50,560 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".CompletionCoordinatorService 16:41:50,561 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".CompletionCoordinatorRPCService 16:41:50,561 INFO [org.jboss.as.webservices] (MSC service thread 1-8) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".BusinessAgreementWithCoordinatorCompletionCoordinatorService 16:41:50,561 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".BusinessAgreementWithParticipantCompletionCoordinatorService 16:41:50,561 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".CoordinatorService 16:41:50,561 INFO [org.jboss.as.webservices] (MSC service thread 1-3) WFLYWS0003: Starting service jboss.ws.endpoint."ws-t11-coordinator.deployment".TerminationCoordinatorService 16:41:50,564 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0003: Starting service jboss.ws.endpoint-publish.ws-t11-coordinator 16:41:50,565 INFO [org.wildfly.extension.undertow] (MSC service thread 1-4) WFLYUT0021: Registered web context: '/ws-t11-coordinator' for server 'default-server' 16:41:50,569 INFO [stdout] (MSC service thread 1-4) Rule.execute called for open trace file_2 16:41:50,570 INFO [stdout] (MSC service thread 1-4) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:41:50,570 INFO [stdout] (MSC service thread 1-4) calling installed(open trace file) for helper classorg.jboss.byteman.rule.helper.Helper 16:41:50,570 INFO [stdout] (MSC service thread 1-4) Installed rule using default helper : open trace file 16:41:50,570 INFO [stdout] (MSC service thread 1-4) open trace file execute 16:41:50,570 INFO [stdout] (MSC service thread 1-4) Rule.execute called for create participant deletes counter_0 16:41:50,571 INFO [stdout] (MSC service thread 1-4) Rule.execute for decommissioned key create participant deletes counter_0 16:41:50,582 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace Participant send rollback in class com.arjuna.wst11.messaging.CoordinatorProcessorImpl 16:41:50,583 INFO [stdout] (MSC service thread 1-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.CoordinatorProcessorImpl.prepared(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace Participant send rollback 16:41:50,587 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace Participant send rollback in class com.arjuna.wst11.messaging.CoordinatorProcessorImpl 16:41:50,607 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate coordinator close in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:50,607 INFO [stdout] (MSC service thread 1-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator.rollback() void for rule trace subordinate coordinator close 16:41:50,608 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate coordinator close in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:50,609 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate coordinator prepare in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:50,609 INFO [stdout] (MSC service thread 1-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator.prepare() int for rule trace subordinate coordinator prepare 16:41:50,610 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate coordinator prepare in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:50,611 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate coordinator rollback 2 in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:50,612 INFO [stdout] (MSC service thread 1-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator.rollback() void for rule trace subordinate coordinator rollback 2 16:41:50,613 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate coordinator rollback 2 in class com.arjuna.mwlabs.wscf.model.twophase.arjunacore.subordinate.SubordinateATCoordinator 16:41:50,632 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : possible trigger for rule trace delete participant and exit JVM in class org.jboss.jbossts.xts.recovery.participant.at.XTSATRecoveryManagerImple 16:41:50,633 INFO [stdout] (MSC service thread 1-4) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.jbossts.xts.recovery.participant.at.XTSATRecoveryManagerImple.deleteParticipantRecoveryRecord(java.lang.String) boolean for rule trace delete participant and exit JVM 16:41:50,634 INFO [stdout] (MSC service thread 1-4) org.jboss.byteman.agent.Transformer : inserted trigger for trace delete participant and exit JVM in class org.jboss.jbossts.xts.recovery.participant.at.XTSATRecoveryManagerImple 16:41:50,661 INFO [org.jboss.jbossts.txbridge] (MSC service thread 1-2) ARJUNA033013: OutboundBridgeRecoveryManager starting 16:41:50,661 INFO [org.jboss.jbossts.txbridge] (MSC service thread 1-4) ARJUNA033006: InboundBridgeRecoveryManager starting 16:41:50,749 WARN [org.jboss.weld.Bootstrap] (MSC service thread 1-7) WELD-000169: Jandex cannot distinguish inner and static nested classes! Update Jandex to 2.0.3.Final version or newer to improve scanning performance. 16:41:51,008 INFO [org.jboss.jbossts.xts.servicetests.bean.XTSServiceTestRunnerBean] (ServerService Thread Pool -- 73) Starting XTSServiceTestRunner 16:41:51,008 INFO [org.jboss.jbossts.xts.servicetests.bean.XTSServiceTestRunnerBean] (ServerService Thread Pool -- 73) Started XTSServiceTestRunner 16:41:51,017 INFO [javax.enterprise.resource.webcontainer.jsf.config] (ServerService Thread Pool -- 73) Initializing Mojarra 2.2.13.SP1 20160303-1204 for context '/xtstest' 16:41:51,377 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 73) WFLYUT0021: Registered web context: '/xtstest' for server 'default-server' 16:41:51,390 INFO [org.jboss.as.server] (Controller Boot Thread) WFLYSRV0010: Deployed "xtstest.war" (runtime-name : "xtstest.war") 16:41:51,391 INFO [org.jboss.as.server] (ServerService Thread Pool -- 36) WFLYSRV0010: Deployed "restat-web-5.4.1.Final-SNAPSHOT.war" (runtime-name : "restat-web-5.4.1.Final-SNAPSHOT.war") 16:41:51,449 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0060: Http management interface listening on http://127.0.0.1:9990/management 16:41:51,449 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0051: Admin console listening on http://127.0.0.1:9990 16:41:51,450 INFO [org.jboss.as] (Controller Boot Thread) WFLYSRV0025: WildFly Core 3.0.0.Alpha12 "Kenny" started in 4409ms - Started 671 of 918 services (462 services are lazy, passive or on-demand) Nov 15, 2016 4:41:51 PM org.jboss.arquillian.container.test.impl.client.container.ClientContainerController kill INFO: Hard killing of a server instance Nov 15, 2016 4:41:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: waiting for byteman to kill the server Nov 15, 2016 4:41:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:42:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:42:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:42:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:42:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' 16:42:18,935 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace subordinate prepared replay in class org.jboss.jbossts.xts.recovery.coordinator.at.RecoverySubordinateATCoordinator 16:42:18,937 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into org.jboss.jbossts.xts.recovery.coordinator.at.RecoverySubordinateATCoordinator.replayPhase2() void for rule trace subordinate prepared replay 16:42:18,938 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace subordinate prepared replay in class org.jboss.jbossts.xts.recovery.coordinator.at.RecoverySubordinateATCoordinator 16:42:18,977 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace Coordinator engine create in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:18,978 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.<init>(java.lang.String,boolean,javax.xml.ws.wsaddressing.W3CEndpointReference,boolean,com.arjuna.webservices11.wsat.State) void for rule trace Coordinator engine create 16:42:18,991 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace Coordinator engine create in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:18,992 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive Participant aborted in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:18,992 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.aborted(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive Participant aborted 16:42:19,002 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive Participant aborted in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:19,004 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive participant prepared in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:19,004 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.prepared(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive participant prepared 16:42:19,015 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive participant prepared in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:19,017 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send participant prepare in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:19,018 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.prepare() com.arjuna.webservices11.wsat.State for rule trace send participant prepare 16:42:19,028 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace send participant prepare in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:19,029 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send Participant rollback in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:19,030 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.CoordinatorEngine.rollback() com.arjuna.webservices11.wsat.State for rule trace send Participant rollback 16:42:19,040 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace send Participant rollback in class com.arjuna.wst11.messaging.engines.CoordinatorEngine 16:42:19,042 INFO [stdout] (Periodic Recovery) Rule.execute called for trace Coordinator engine create_12 16:42:19,042 INFO [stdout] (Periodic Recovery) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,042 INFO [stdout] (Periodic Recovery) calling installed(trace Coordinator engine create) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,042 INFO [stdout] (Periodic Recovery) Installed rule using default helper : trace Coordinator engine create 16:42:19,042 INFO [stdout] (Periodic Recovery) trace Coordinator engine create execute 16:42:19,043 INFO [stdout] (Periodic Recovery) rule.debug{trace Coordinator engine create} : created recovered Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:20 16:42:19,054 INFO [stdout] (Periodic Recovery) Rule.execute called for trace Coordinator engine create_12 16:42:19,054 INFO [stdout] (Periodic Recovery) trace Coordinator engine create execute 16:42:19,054 INFO [stdout] (Periodic Recovery) rule.debug{trace Coordinator engine create} : created recovered Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:23 16:42:19,054 INFO [stdout] (Periodic Recovery) Rule.execute called for trace subordinate prepared replay_11 16:42:19,057 INFO [stdout] (Periodic Recovery) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,057 INFO [stdout] (Periodic Recovery) calling installed(trace subordinate prepared replay) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,057 INFO [stdout] (Periodic Recovery) Installed rule using default helper : trace subordinate prepared replay 16:42:19,057 INFO [stdout] (Periodic Recovery) trace subordinate prepared replay execute 16:42:19,057 INFO [stdout] (Periodic Recovery) rule.debug{trace subordinate prepared replay} : reinstated prepared subordinate transaction 0:ffffac110007:-3ffa9bb3:582b3abd:19 16:42:19,062 INFO [stdout] (Periodic Recovery) xts service test : attempting to deserialize WS-AT participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.2 16:42:19,064 INFO [stdout] (Periodic Recovery) xts service test : deserialized WS-AT participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.2 16:42:19,065 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace Participant create in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,066 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.<init>(com.arjuna.wst.Participant,java.lang.String,com.arjuna.webservices11.wsat.State,javax.xml.ws.wsaddressing.W3CEndpointReference,boolean) void for rule trace Participant create 16:42:19,078 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace Participant create in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,079 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive participant prepare in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,079 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.prepare(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive participant prepare 16:42:19,086 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive participant prepare in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,087 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send Participant prepared in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,093 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.sendPrepared(boolean) void for rule trace send Participant prepared 16:42:19,093 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace send Participant prepared in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,095 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace receive Participant rollback in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,096 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.rollback(org.oasis_open.docs.ws_tx.wsat._2006._06.Notification,org.jboss.ws.api.addressing.MAP,com.arjuna.webservices11.wsarj.ArjunaContext) void for rule trace receive Participant rollback 16:42:19,102 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace receive Participant rollback in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,103 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : possible trigger for rule trace send Participant aborted in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,109 INFO [stdout] (Periodic Recovery) RuleTriggerMethodAdapter.injectTriggerPoint : inserting trigger into com.arjuna.wst11.messaging.engines.ParticipantEngine.sendAborted() void for rule trace send Participant aborted 16:42:19,109 INFO [stdout] (Periodic Recovery) org.jboss.byteman.agent.Transformer : inserted trigger for trace send Participant aborted in class com.arjuna.wst11.messaging.engines.ParticipantEngine 16:42:19,111 INFO [stdout] (Periodic Recovery) Rule.execute called for trace Participant create_17 16:42:19,111 INFO [stdout] (Periodic Recovery) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,111 INFO [stdout] (Periodic Recovery) calling installed(trace Participant create) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,111 INFO [stdout] (Periodic Recovery) Installed rule using default helper : trace Participant create 16:42:19,111 INFO [stdout] (Periodic Recovery) trace Participant create execute 16:42:19,111 INFO [stdout] (Periodic Recovery) rule.debug{trace Participant create} : created recovered Participant engine org.jboss.jbossts.xts.servicetests.DurableTestParticipant.2 16:42:19,118 INFO [stdout] (Periodic Recovery) Rule.execute called for trace send Participant prepared_19 16:42:19,118 INFO [stdout] (Periodic Recovery) Rule.execute for decommissioned key trace send Participant prepared_19 16:42:19,227 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Periodic Recovery) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:42:19,520 INFO [stdout] (Periodic Recovery) xts service test : attempting to deserialize WS-AT participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.1 16:42:19,520 INFO [stdout] (Periodic Recovery) xts service test : deserialized WS-AT participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.1 16:42:19,520 INFO [stdout] (Periodic Recovery) Rule.execute called for trace Participant create_17 16:42:19,520 INFO [stdout] (Periodic Recovery) trace Participant create execute 16:42:19,520 INFO [stdout] (Periodic Recovery) rule.debug{trace Participant create} : created recovered Participant engine org.jboss.jbossts.xts.servicetests.DurableTestParticipant.1 16:42:19,521 INFO [stdout] (Periodic Recovery) Rule.execute called for trace send Participant prepared_19 16:42:19,521 INFO [stdout] (Periodic Recovery) Rule.execute for decommissioned key trace send Participant prepared_19 16:42:19,530 INFO [stdout] (TaskWorker-1) Rule.execute called for trace receive participant prepared_14 16:42:19,530 INFO [stdout] (TaskWorker-1) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,530 INFO [stdout] (TaskWorker-1) calling installed(trace receive participant prepared) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,531 INFO [stdout] (TaskWorker-1) Installed rule using default helper : trace receive participant prepared 16:42:19,531 INFO [stdout] (TaskWorker-1) trace receive participant prepared execute 16:42:19,531 INFO [stdout] (TaskWorker-1) rule.debug{trace receive participant prepared} : prepared received for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:23 16:42:19,532 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Periodic Recovery) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:42:19,550 INFO [stdout] (Periodic Recovery) xts service test : attempting to deserialize WS-AT participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.0 16:42:19,550 INFO [stdout] (Periodic Recovery) xts service test : deserialized WS-AT participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.0 16:42:19,551 INFO [stdout] (TaskWorker-1) Rule.execute called for trace receive participant prepared_14 16:42:19,551 INFO [stdout] (Periodic Recovery) Rule.execute called for trace Participant create_17 16:42:19,551 INFO [stdout] (TaskWorker-1) trace receive participant prepared execute 16:42:19,551 INFO [stdout] (Periodic Recovery) trace Participant create execute 16:42:19,551 INFO [stdout] (TaskWorker-1) rule.debug{trace receive participant prepared} : prepared received for Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:20 16:42:19,551 INFO [stdout] (Periodic Recovery) rule.debug{trace Participant create} : created recovered Participant engine org.jboss.jbossts.xts.servicetests.DurableTestParticipant.0 16:42:19,551 INFO [stdout] (Periodic Recovery) Rule.execute called for trace send Participant prepared_19 16:42:19,551 INFO [stdout] (Periodic Recovery) Rule.execute for decommissioned key trace send Participant prepared_19 16:42:19,560 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Periodic Recovery) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:42:19,576 WARN [com.arjuna.wsrecovery] (Periodic Recovery) ARJUNA046033: Compensating orphaned subordinate WS-AT transcation 0:ffffac110007:-3ffa9bb3:582b3abd:19 16:42:19,576 INFO [stdout] (Periodic Recovery) Rule.execute called for trace subordinate coordinator close_7 16:42:19,576 INFO [stdout] (Periodic Recovery) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,577 INFO [stdout] (Periodic Recovery) calling installed(trace subordinate coordinator close) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,577 INFO [stdout] (Periodic Recovery) Installed rule using default helper : trace subordinate coordinator close 16:42:19,577 INFO [stdout] (Periodic Recovery) trace subordinate coordinator close execute 16:42:19,577 INFO [stdout] (Periodic Recovery) rule.debug{trace subordinate coordinator close} : rollback called for subordinate coordinator BasicAction: 0:ffffac110007:-3ffa9bb3:582b3abd:19 status: ActionStatus.COMMITTING 16:42:19,577 INFO [stdout] (Periodic Recovery) Rule.execute called for trace send Participant rollback_16 16:42:19,577 INFO [stdout] (Periodic Recovery) Rule.execute for decommissioned key trace send Participant rollback_16 16:42:19,583 WARN [com.arjuna.wst] (TaskWorker-1) ARJUNA043134: Prepared called on unknown coordinator: D0:ffffac110007:-3ffa9bb3:582b3abd:16 16:42:19,583 INFO [stdout] (TaskWorker-1) Rule.execute called for trace Participant send rollback_6 16:42:19,583 INFO [stdout] (TaskWorker-1) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,583 INFO [stdout] (TaskWorker-1) calling installed(trace Participant send rollback) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,583 INFO [stdout] (TaskWorker-1) Installed rule using default helper : trace Participant send rollback 16:42:19,584 INFO [stdout] (TaskWorker-1) trace Participant send rollback execute 16:42:19,584 INFO [stdout] (TaskWorker-1) rule.debug{trace Participant send rollback} : sent rollback for unknown Coordinator engine D0:ffffac110007:-3ffa9bb3:582b3abd:16 16:42:19,588 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:42:19,599 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Periodic Recovery) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:42:19,600 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:42:19,638 INFO [stdout] (TaskWorker-1) Rule.execute called for trace receive Participant rollback_20 16:42:19,638 INFO [stdout] (TaskWorker-1) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,638 INFO [stdout] (TaskWorker-1) calling installed(trace receive Participant rollback) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,638 INFO [stdout] (TaskWorker-1) Installed rule using default helper : trace receive Participant rollback 16:42:19,638 INFO [stdout] (TaskWorker-1) trace receive Participant rollback execute 16:42:19,639 INFO [stdout] (TaskWorker-1) rule.debug{trace receive Participant rollback} : rollback received for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.0 16:42:19,639 INFO [stdout] (TaskWorker-1) Rule.execute called for trace delete participant and exit JVM_10 16:42:19,639 INFO [stdout] (TaskWorker-1) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,639 INFO [stdout] (TaskWorker-1) calling installed(trace delete participant and exit JVM) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,639 INFO [stdout] (TaskWorker-1) Installed rule using default helper : trace delete participant and exit JVM 16:42:19,639 INFO [stdout] (TaskWorker-1) trace delete participant and exit JVM execute 16:42:19,640 INFO [stdout] (TaskWorker-1) Rule.execute called for trace send Participant aborted_21 16:42:19,640 INFO [stdout] (TaskWorker-1) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,640 INFO [stdout] (TaskWorker-1) calling installed(trace send Participant aborted) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,640 INFO [stdout] (TaskWorker-1) Installed rule using default helper : trace send Participant aborted 16:42:19,640 INFO [stdout] (TaskWorker-1) trace send Participant aborted execute 16:42:19,640 INFO [stdout] (TaskWorker-1) rule.debug{trace send Participant aborted} : aborted sent for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.0 16:42:19,650 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:42:19,667 INFO [stdout] (TaskWorker-1) Rule.execute called for trace receive Participant rollback_20 16:42:19,668 INFO [stdout] (TaskWorker-1) trace receive Participant rollback execute 16:42:19,668 INFO [stdout] (TaskWorker-1) rule.debug{trace receive Participant rollback} : rollback received for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.1 16:42:19,669 INFO [stdout] (TaskWorker-1) Rule.execute called for trace delete participant and exit JVM_10 16:42:19,669 INFO [stdout] (TaskWorker-1) trace delete participant and exit JVM execute 16:42:19,669 INFO [stdout] (TaskWorker-1) Rule.execute called for trace send Participant aborted_21 16:42:19,670 INFO [stdout] (TaskWorker-1) trace send Participant aborted execute 16:42:19,669 WARN [com.arjuna.wst] (TaskWorker-2) ARJUNA043130: Aborted called on unknown coordinator: D0:ffffac110007:-3ffa9bb3:582b3abd:16 16:42:19,670 INFO [stdout] (TaskWorker-1) rule.debug{trace send Participant aborted} : aborted sent for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.1 16:42:19,678 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:42:19,694 INFO [stdout] (TaskWorker-2) Rule.execute called for trace receive Participant aborted_13 16:42:19,694 INFO [stdout] (TaskWorker-2) Rule.execute for decommissioned key trace receive Participant aborted_13 16:42:19,694 INFO [stdout] (Periodic Recovery) Rule.execute called for trace send Participant rollback_16 16:42:19,694 INFO [stdout] (Periodic Recovery) Rule.execute for decommissioned key trace send Participant rollback_16 16:42:19,710 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (Periodic Recovery) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}ParticipantService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-participant-binding.wsdl 16:42:19,728 INFO [stdout] (TaskWorker-1) Rule.execute called for trace receive Participant rollback_20 16:42:19,728 INFO [stdout] (TaskWorker-1) trace receive Participant rollback execute 16:42:19,729 INFO [stdout] (TaskWorker-1) rule.debug{trace receive Participant rollback} : rollback received for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.2 16:42:19,729 INFO [stdout] (TaskWorker-1) Rule.execute called for trace delete participant and exit JVM_10 16:42:19,729 INFO [stdout] (TaskWorker-1) trace delete participant and exit JVM execute 16:42:19,729 INFO [stdout] (TaskWorker-1) Rule.execute called for trace send Participant aborted_21 16:42:19,729 INFO [stdout] (TaskWorker-1) trace send Participant aborted execute 16:42:19,730 INFO [stdout] (TaskWorker-1) rule.debug{trace send Participant aborted} : aborted sent for Participant org.jboss.jbossts.xts.servicetests.DurableTestParticipant.2 16:42:19,738 INFO [org.apache.cxf.wsdl.service.factory.ReflectionServiceFactoryBean] (TaskWorker-1) Creating Service {http://docs.oasis-open.org/ws-tx/wsat/2006/06}CoordinatorService from WSDL: jar:file:/home/jenkins/.m2/repository/org/jboss/narayana/xts/jbossxts/5.4.1.Final-SNAPSHOT/jbossxts-5.4.1.Final-SNAPSHOT.jar!/org/oasis_open/docs/ws_tx/wsat/_2006/_06/wsdl/wsat-coordinator-binding.wsdl 16:42:19,752 INFO [stdout] (TaskWorker-2) Rule.execute called for trace receive Participant aborted_13 16:42:19,752 INFO [stdout] (TaskWorker-2) Rule.execute for decommissioned key trace receive Participant aborted_13 16:42:19,753 INFO [stdout] (Periodic Recovery) Rule.execute called for trace remove committed state_5 16:42:19,753 INFO [stdout] (Periodic Recovery) Rule.execute for decommissioned key trace remove committed state_5 16:42:19,753 INFO [stdout] (Periodic Recovery) Rule.execute called for trace remove committed state and exit JVM_4 16:42:19,753 INFO [stdout] (Periodic Recovery) Rule.execute for decommissioned key trace remove committed state and exit JVM_4 16:42:19,753 INFO [stdout] (Periodic Recovery) Rule.execute called for trace subordinate coordinator rollback 2_9 16:42:19,754 INFO [stdout] (Periodic Recovery) HelperManager.install for helper class org.jboss.byteman.rule.helper.Helper 16:42:19,754 INFO [stdout] (Periodic Recovery) calling installed(trace subordinate coordinator rollback 2) for helper classorg.jboss.byteman.rule.helper.Helper 16:42:19,754 INFO [stdout] (Periodic Recovery) Installed rule using default helper : trace subordinate coordinator rollback 2 16:42:19,754 INFO [stdout] (Periodic Recovery) trace subordinate coordinator rollback 2 execute 16:42:19,754 INFO [stdout] (Periodic Recovery) rule.debug{trace subordinate coordinator rollback 2} : rollback returned for subordinate coordinator BasicAction: 0:ffffac110007:-3ffa9bb3:582b3abd:19 status: ActionStatus.ABORTED Nov 15, 2016 4:42:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:42:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:42:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:42:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:42:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:42:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:42:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:42:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:43:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:43:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:43:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:43:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:43:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:43:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:43:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:43:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:43:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:43:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:43:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:43:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:44:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:44:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:44:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:44:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:44:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:44:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:44:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:44:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:44:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:44:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:44:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:44:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:45:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:45:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:45:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:45:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:45:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:45:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:45:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:45:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:45:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:45:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:45:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:45:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:46:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:46:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:46:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:46:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:46:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:46:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:46:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:46:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:46:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:46:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:46:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:46:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:47:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:47:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:47:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:47:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:47:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:47:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:47:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:47:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:47:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:47:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:47:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:47:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:48:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:48:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:48:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:48:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:48:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:48:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:48:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:48:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:48:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:48:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:48:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:48:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:49:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:49:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:49:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:49:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:49:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:49:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:49:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:49:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:49:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:49:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:49:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:49:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:50:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:50:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:50:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:50:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:50:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:50:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:50:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:50:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:50:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:50:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:50:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:50:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:51:01 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:51:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:51:11 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:51:11 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:51:21 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:51:21 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:51:31 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:51:31 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:51:41 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:51:41 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:51:51 PM com.arjuna.qa.extension.BaseServerKillProcessor kill INFO: jboss-as is still alive, sleeping for a further 10000ms Nov 15, 2016 4:51:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommand INFO: Executing shell command: 'ps aux | grep jboss-module[s] | awk '{print $2}' | xargs kill' Nov 15, 2016 4:51:51 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' 16:51:51,969 INFO [org.jboss.as.server] (Thread-3) WFLYSRV0220: Server shutdown has been requested via an OS signal 16:51:51,982 INFO [org.jboss.jbossts.txbridge] (MSC service thread 1-6) ARJUNA033007: InboundBridgeRecoveryManager stopping 16:51:51,982 INFO [org.wildfly.extension.messaging-activemq] (MSC service thread 1-8) WFLYMSGAMQ0006: Unbound messaging object to jndi name java:jboss/DefaultJMSConnectionFactory 16:51:51,982 INFO [org.jboss.jbossts.txbridge] (MSC service thread 1-7) ARJUNA033014: OutboundBridgeRecoveryManager stopping 16:51:51,984 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-1) WFLYJCA0011: Unbound JCA ConnectionFactory [java:/JmsXA] 16:51:51,984 INFO [org.wildfly.extension.messaging-activemq] (ServerService Thread Pool -- 77) WFLYMSGAMQ0006: Unbound messaging object to jndi name java:jboss/exported/jms/RemoteConnectionFactory 16:51:51,990 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 78) WFLYUT0022: Unregistered web context: '/rest-tx' from server 'default-server' 16:51:51,990 INFO [org.wildfly.extension.undertow] (ServerService Thread Pool -- 80) WFLYUT0022: Unregistered web context: '/xtstest' from server 'default-server' 16:51:51,996 INFO [org.jboss.jbossts.xts.servicetests.bean.XTSServiceTestRunnerBean] (ServerService Thread Pool -- 80) Stopping XTSServiceTestRunner 16:51:51,996 INFO [org.jboss.jbossts.xts.servicetests.bean.XTSServiceTestRunnerBean] (ServerService Thread Pool -- 80) Stopped XTSServiceTestRunner 16:51:51,997 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0004: Stopping service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Subordinate_Service_One 16:51:51,997 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0004: Stopping service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_Two 16:51:51,998 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0004: Stopping service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_Three 16:51:51,998 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0004: Stopping service jboss.ws.endpoint."xtstest.war".XTS_Service_Test_Service_One 16:51:52,012 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) WFLYJCA0010: Unbound data source [java:jboss/datasources/ExampleDS] 16:51:52,013 INFO [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-6) WFLYJCA0019: Stopped Driver service with driver-name = h2 16:51:52,017 INFO [org.apache.activemq.artemis.ra] (ServerService Thread Pool -- 78) AMQ151003: resource adaptor stopped 16:51:52,026 INFO [org.jboss.as.server.deployment] (MSC service thread 1-5) WFLYSRV0028: Stopped deployment restat-web-5.4.1.Final-SNAPSHOT.war (runtime-name: restat-web-5.4.1.Final-SNAPSHOT.war) in 50ms 16:51:52,031 INFO [org.jboss.as.server.deployment] (MSC service thread 1-6) WFLYSRV0028: Stopped deployment xtstest.war (runtime-name: xtstest.war) in 56ms 16:51:52,053 INFO [org.apache.activemq.artemis.core.server] (ServerService Thread Pool -- 78) AMQ221002: Apache ActiveMQ Artemis Message Broker version 1.5.0 [ef9290cb-ab4d-11e6-b97f-0242ac110007] stopped, uptime 10 minutes Nov 15, 2016 4:51:56 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' 16:51:59,834 WARN [org.apache.activemq.artemis.service.extensions.xa.recovery] (Periodic Recovery) AMQ122015: Can not connect to XARecoveryConfig [transportConfiguration=[TransportConfiguration(name=, factory=org-apache-activemq-artemis-core-remoting-impl-invm-InVMConnectorFactory) ?serverId=0], discoveryConfiguration=null, username=null, password=****, JNDI_NAME=java:/JmsXA] on auto-generated resource recovery: ActiveMQNotConnectedException[errorType=NOT_CONNECTED message=AMQ119007: Cannot connect to server(s). Tried with all available servers.] at org.apache.activemq.artemis.core.client.impl.ServerLocatorImpl.createSessionFactory(ServerLocatorImpl.java:785) at org.apache.activemq.artemis.service.extensions.xa.recovery.ActiveMQXAResourceWrapper.connect(ActiveMQXAResourceWrapper.java:311) at org.apache.activemq.artemis.service.extensions.xa.recovery.ActiveMQXAResourceWrapper.getDelegate(ActiveMQXAResourceWrapper.java:239) at org.apache.activemq.artemis.service.extensions.xa.recovery.ActiveMQXAResourceWrapper.recover(ActiveMQXAResourceWrapper.java:69) at org.apache.activemq.artemis.service.extensions.xa.ActiveMQXAResourceWrapperImpl.recover(ActiveMQXAResourceWrapperImpl.java:106) at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.xaRecoverySecondPass(XARecoveryModule.java:770) at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.bottomUpRecovery(XARecoveryModule.java:461) at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.periodicWorkSecondPass(XARecoveryModule.java:218) at com.arjuna.ats.internal.arjuna.recovery.PeriodicRecovery.doWorkInternal(PeriodicRecovery.java:811) at com.arjuna.ats.internal.arjuna.recovery.PeriodicRecovery.run(PeriodicRecovery.java:377) 16:51:59,834 WARN [org.apache.activemq.artemis.service.extensions.xa.recovery] (Periodic Recovery) AMQ122008: XA Recovery can not connect to any broker on recovery [XARecoveryConfig [transportConfiguration=[TransportConfiguration(name=, factory=org-apache-activemq-artemis-core-remoting-impl-invm-InVMConnectorFactory) ?serverId=0], discoveryConfiguration=null, username=null, password=****, JNDI_NAME=java:/JmsXA]] 16:51:59,835 WARN [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016027: Local XARecoveryModule.xaRecovery got XA exception XAException.XAER_RMFAIL: javax.transaction.xa.XAException: Error trying to connect to any providers for xa recovery at org.apache.activemq.artemis.service.extensions.xa.recovery.ActiveMQXAResourceWrapper.getDelegate(ActiveMQXAResourceWrapper.java:258) at org.apache.activemq.artemis.service.extensions.xa.recovery.ActiveMQXAResourceWrapper.recover(ActiveMQXAResourceWrapper.java:69) at org.apache.activemq.artemis.service.extensions.xa.ActiveMQXAResourceWrapperImpl.recover(ActiveMQXAResourceWrapperImpl.java:106) at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.xaRecoverySecondPass(XARecoveryModule.java:770) at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.bottomUpRecovery(XARecoveryModule.java:461) at com.arjuna.ats.internal.jta.recovery.arjunacore.XARecoveryModule.periodicWorkSecondPass(XARecoveryModule.java:218) at com.arjuna.ats.internal.arjuna.recovery.PeriodicRecovery.doWorkInternal(PeriodicRecovery.java:811) at com.arjuna.ats.internal.arjuna.recovery.PeriodicRecovery.run(PeriodicRecovery.java:377) Caused by: ActiveMQNotConnectedException[errorType=NOT_CONNECTED message=null] at org.apache.activemq.artemis.service.extensions.xa.recovery.ActiveMQXAResourceWrapper.connect(ActiveMQXAResourceWrapper.java:345) at org.apache.activemq.artemis.service.extensions.xa.recovery.ActiveMQXAResourceWrapper.getDelegate(ActiveMQXAResourceWrapper.java:239) ... 7 more 16:51:59,836 INFO [org.jboss.as.webservices] (MSC service thread 1-5) WFLYWS0004: Stopping service jboss.ws.endpoint-publish.ws-t11-coordinator 16:51:59,836 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0004: Stopping service jboss.ws.endpoint-publish.ws-c11 16:51:59,836 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0004: Stopping service jboss.ws.endpoint-publish.ws-t11-client 16:51:59,836 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0004: Stopping service jboss.ws.endpoint-publish.ws-t11-participant 16:51:59,837 INFO [org.wildfly.extension.undertow] (MSC service thread 1-5) WFLYUT0022: Unregistered web context: '/ws-t11-coordinator' from server 'default-server' 16:51:59,837 INFO [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0022: Unregistered web context: '/ws-c11' from server 'default-server' 16:51:59,837 INFO [org.wildfly.extension.undertow] (MSC service thread 1-6) WFLYUT0022: Unregistered web context: '/ws-t11-participant' from server 'default-server' 16:51:59,837 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0022: Unregistered web context: '/ws-t11-client' from server 'default-server' 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-coordinator.deployment".TerminationCoordinatorRPCService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-coordinator.deployment".CoordinatorService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-coordinator.deployment".BusinessAgreementWithParticipantCompletionCoordinatorService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-c11.deployment".ActivationService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-coordinator.deployment".CompletionCoordinatorRPCService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-c11.deployment".RegistrationService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-coordinator.deployment".CompletionCoordinatorService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-coordinator.deployment".TerminationCoordinatorService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-coordinator.deployment".BusinessAgreementWithCoordinatorCompletionCoordinatorService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-7) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-participant.deployment".ParticipantService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-2) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-participant.deployment".BusinessAgreementWithCoordinatorCompletionParticipantService 16:51:59,838 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-client.deployment".TerminationParticipantService 16:51:59,837 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-participant.deployment".BusinessAgreementWithParticipantCompletionParticipantService 16:51:59,838 INFO [org.jboss.as.webservices] (MSC service thread 1-8) WFLYWS0004: Stopping service jboss.ws.endpoint."ws-t11-client.deployment".CompletionInitiatorService 16:51:59,838 INFO [org.jboss.as.webservices] (MSC service thread 1-3) WFLYWS0004: Stopping service jboss.ws.endpoint-deploy.ws-c11 16:51:59,838 INFO [org.jboss.as.webservices] (MSC service thread 1-4) WFLYWS0004: Stopping service jboss.ws.endpoint-deploy.ws-t11-coordinator 16:51:59,838 INFO [org.jboss.as.webservices] (MSC service thread 1-6) WFLYWS0004: Stopping service jboss.ws.endpoint-deploy.ws-t11-client 16:51:59,838 INFO [org.jboss.as.webservices] (MSC service thread 1-1) WFLYWS0004: Stopping service jboss.ws.endpoint-deploy.ws-t11-participant 16:51:59,838 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0019: Host default-host stopping 16:51:59,841 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0008: Undertow HTTP listener default suspending 16:51:59,841 INFO [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0008: Undertow HTTPS listener https suspending 16:51:59,841 INFO [org.wildfly.extension.undertow] (MSC service thread 1-7) WFLYUT0007: Undertow HTTP listener default stopped, was bound to 127.0.0.1:8080 16:51:59,841 INFO [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0007: Undertow HTTPS listener https stopped, was bound to 127.0.0.1:8443 16:51:59,842 INFO [org.wildfly.extension.undertow] (MSC service thread 1-1) WFLYUT0004: Undertow 1.4.4.Final stopping 16:51:59,849 INFO [org.jboss.as] (MSC service thread 1-6) WFLYSRV0050: WildFly Core 3.0.0.Alpha12 "Kenny" stopped in 7870ms Nov 15, 2016 4:52:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor runShellCommandExitCode INFO: Executing shell command: 'if [ "x`ps aux | grep 'jboss-module[s]'`" = "x" ]; then exit 1; fi' Nov 15, 2016 4:52:01 PM com.arjuna.qa.extension.JBossAS7ServerKillProcessor shutdownJBoss INFO: jboss-as shutdown after sending shutdown command {code}