See <
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/15...
------------------------------------------
[...truncated 146664 lines...]
at
org.jboss.netty.handler.codec.frame.FrameDecoder.messageReceived(FrameDecoder.java:303)
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:268)
at org.jboss.netty.channel.Channels.fireMessageReceived(Channels.java:255)
at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:88)
at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:109)
at
org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:312)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.run(AbstractNioWorker.java:90)
at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[rt.jar:1.7.0_67]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[rt.jar:1.7.0_67]
at java.lang.Thread.run(Thread.java:745) [rt.jar:1.7.0_67]
[0m[0m16:29:46,192 INFO
[org.switchyard.quickstarts.camel.netty.binding.SecuredGreetingServiceBean] (Camel
(camel-1) thread #21 - NettyOrderedWorker) :: SecuredGreetingService :: Hello
org.switchyard.test.quickstarts.CamelNettyBindingQuickstartTest! (caller
principal=UserPrincipal@20722777[name=kermit], in roles? 'friend'=true
'enemy'=false)
[0m[33m16:29:47,072 WARN [org.apache.camel.component.netty.NettyConsumer] (Camel
(camel-1) thread #7 - NettyOrderedWorker) Closing channel as an exception was thrown from
Netty. Caused by: [java.io.IOException - Connection reset by peer]: java.io.IOException:
Connection reset by peer
at sun.nio.ch.FileDispatcherImpl.read0(Native Method) [rt.jar:1.7.0_67]
at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) [rt.jar:1.7.0_67]
at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) [rt.jar:1.7.0_67]
at sun.nio.ch.IOUtil.read(IOUtil.java:192) [rt.jar:1.7.0_67]
at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:379) [rt.jar:1.7.0_67]
at org.jboss.netty.channel.socket.nio.NioWorker.read(NioWorker.java:64)
at
org.jboss.netty.channel.socket.nio.AbstractNioWorker.process(AbstractNioWorker.java:109)
at
org.jboss.netty.channel.socket.nio.AbstractNioSelector.run(AbstractNioSelector.java:312)
at org.jboss.netty.channel.socket.nio.AbstractNioWorker.run(AbstractNioWorker.java:90)
at org.jboss.netty.channel.socket.nio.NioWorker.run(NioWorker.java:178)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[rt.jar:1.7.0_67]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[rt.jar:1.7.0_67]
at java.lang.Thread.run(Thread.java:745) [rt.jar:1.7.0_67]
[0m[0m16:29:47,109 INFO
[org.switchyard.quickstarts.camel.netty.binding.DefaultGreetingServiceBean] (Camel
(camel-1) thread #8 - NettyOrderedWorker) :: DefaultGreetingService :: Hello
org.switchyard.test.quickstarts.CamelNettyBindingQuickstartTest! (caller principal=null,
in roles? 'friend'=false 'enemy'=false)
[0m[0m16:29:48,146 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Starting to graceful shutdown 1 routes (timeout 30 seconds)
[0m[0m16:29:48,159 INFO
[org.apache.camel.component.netty.SingleTCPNettyServerBootstrapFactory] (Camel (camel-1)
thread #23 - ShutdownTask) ServerBootstrap unbinding from localhost:3939
[0m[0m16:29:48,175 INFO [org.apache.camel.component.netty.NettyConsumer] (Camel
(camel-1) thread #23 - ShutdownTask) Netty consumer unbound from: localhost:3939
[0m[0m16:29:48,183 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (Camel
(camel-1) thread #23 - ShutdownTask) Route:
V1CamelNettyTcpBindingModel/SecuredGreetingService@_SecuredGreetingService_tcp_1#-1766359606
shutdown complete, was consuming from: Endpoint[tcp://localhost:3939]
[0m[0m16:29:48,185 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Graceful shutdown of 1 routes completed in 0 seconds
[0m[0m16:29:48,189 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Route:
V1CamelNettyTcpBindingModel/SecuredGreetingService@_SecuredGreetingService_tcp_1#-1766359606
is stopped, was consuming from: Endpoint[tcp://localhost:3939]
[0m[0m16:29:48,196 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Route:
V1CamelNettyTcpBindingModel/SecuredGreetingService@_SecuredGreetingService_tcp_1#-1766359606
is shutdown and removed, was consuming from: Endpoint[tcp://localhost:3939]
[0m[0m16:29:48,199 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Starting to graceful shutdown 1 routes (timeout 30 seconds)
[0m[0m16:29:48,201 INFO
[org.apache.camel.component.netty.SingleUDPNettyServerBootstrapFactory] (Camel (camel-1)
thread #23 - ShutdownTask) ConnectionlessBootstrap disconnecting from localhost:3940
[0m[0m16:29:48,210 INFO [org.apache.camel.component.netty.NettyConsumer] (Camel
(camel-1) thread #23 - ShutdownTask) Netty consumer unbound from: localhost:3940
[0m[0m16:29:48,215 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (Camel
(camel-1) thread #23 - ShutdownTask) Route:
V1CamelNettyUdpBindingModel/DefaultGreetingService@_DefaultGreetingService_udp_1#1833197758
shutdown complete, was consuming from: Endpoint[udp://localhost:3940]
[0m[0m16:29:48,218 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Graceful shutdown of 1 routes completed in 0 seconds
[0m[0m16:29:48,221 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Route:
V1CamelNettyUdpBindingModel/DefaultGreetingService@_DefaultGreetingService_udp_1#1833197758
is stopped, was consuming from: Endpoint[udp://localhost:3940]
[0m[0m16:29:48,226 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Route:
V1CamelNettyUdpBindingModel/DefaultGreetingService@_DefaultGreetingService_udp_1#1833197758
is shutdown and removed, was consuming from: Endpoint[udp://localhost:3940]
[0m[0m16:29:48,244 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) is shutting down
[0m[0m16:29:48,247 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Starting to graceful shutdown 2 routes (timeout 30 seconds)
[0m[0m16:29:48,251 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (Camel
(camel-1) thread #23 - ShutdownTask) Route:
direct:{urn:switchyard-quickstart:camel-netty-binding:0.1.0}DefaultGreetingService
shutdown complete, was consuming from:
Endpoint[direct://%7Burn:switchyard-quickstart:camel-netty-binding:0.1.0%7DDefaultGreetingService]
[0m[0m16:29:48,253 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (Camel
(camel-1) thread #23 - ShutdownTask) Route:
direct:{urn:switchyard-quickstart:camel-netty-binding:0.1.0}SecuredGreetingService
shutdown complete, was consuming from:
Endpoint[direct://%7Burn:switchyard-quickstart:camel-netty-binding:0.1.0%7DSecuredGreetingService]
[0m[0m16:29:48,256 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Graceful shutdown of 2 routes completed in 0 seconds
[0m[0m16:29:48,308 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) uptime 33.194 seconds
[0m[0m16:29:48,310 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) is shutdown in 0.063
seconds
[0m[0m16:29:48,318 INFO [org.jboss.weld.deployer] (MSC service thread 1-2) JBAS016009:
Stopping weld service for deployment switchyard-camel-netty-binding-2.0.0.CR1.jar
[0m[0m16:29:48,363 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2)
JBAS015877: Stopped deployment switchyard-camel-netty-binding-2.0.0.CR1.jar (runtime-name:
switchyard-camel-netty-binding-2.0.0.CR1.jar) in 231ms
[0m[0m16:29:49,319 INFO [org.jboss.as.repository] (management-handler-thread - 1)
JBAS014901: Content removed from location
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
[0m[0m16:29:49,321 INFO [org.jboss.as.server] (management-handler-thread - 1)
JBAS018558: Undeployed "switchyard-camel-netty-binding-2.0.0.CR1.jar"
(runtime-name: "switchyard-camel-netty-binding-2.0.0.CR1.jar")
[0m@SLTests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 62.415 sec
Running org.switchyard.test.quickstarts.RemoteInvokerQuickstartTest
log4j:WARN No appenders could be found for logger (org.jboss.logging).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See
http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
Feb 24, 2015 4:29:55 PM
org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal
INFO: Starting container with: [/qa/tools/opt/jdk1.7.0_last/bin/java, -Xms256m, -Xmx1024m,
-XX:MaxPermSize=512m, -ea,
-Djboss.home.dir=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3,>
-Dorg.jboss.boot.log.file=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3/standalone/log/boot.log,>
-Dlogging.configuration=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3/standalone/configuration/logging.properties,>
-Djboss.bundles.dir=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3/bundles,>
-jar,
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
-mp,
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
-jaxpmodule, javax.xml.jaxp-provider, org.jboss.as.standalone, -server-config,
standalone-full.xml]
[0m16:29:56,121 INFO [org.jboss.modules] (main) JBoss Modules version
1.3.3.Final-redhat-1
[0m[0m16:29:56,564 INFO [org.jboss.msc] (main) JBoss MSC version 1.1.5.Final-redhat-1
[0m[0m16:29:56,716 INFO [org.jboss.as] (MSC service thread 1-4) JBAS015899: JBoss EAP
6.3.0.GA (AS 7.4.0.Final-redhat-19) starting
[0m[0m16:29:59,201 INFO [org.switchyard] (ServerService Thread Pool -- 5) SwitchYard
version 2.0.0.CR1
[0m[0m16:29:59,673 INFO [org.xnio] (MSC service thread 1-3) XNIO Version
3.0.10.GA-redhat-1
[0m[0m16:29:59,677 INFO [org.jboss.as.server] (Controller Boot Thread) JBAS015888:
Creating http management service using socket-binding (management-http)
[0m[0m16:29:59,693 INFO [org.xnio.nio] (MSC service thread 1-3) XNIO NIO Implementation
Version 3.0.10.GA-redhat-1
[0m[0m16:29:59,752 INFO [org.jboss.remoting] (MSC service thread 1-3) JBoss Remoting
version (unknown)
[0m[0m16:29:59,915 INFO [org.jboss.as.jacorb] (ServerService Thread Pool -- 37)
JBAS016300: Activating JacORB Subsystem
[0m[0m16:29:59,967 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool
-- 36) JBAS010280: Activating Infinispan subsystem.
[0m[0m16:30:00,042 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 48)
JBAS011800: Activating Naming Subsystem
[0m[0m16:30:00,068 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService
Thread Pool -- 32) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver
(version 1.3)
[0m[33m16:30:00,105 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 55)
JBAS010153: Node identifier property is set to the default value. Please make sure it is
unique.
[0m[0m16:30:00,114 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 57)
JBAS015537: Activating WebServices Extension
[0m[0m16:30:00,133 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 44)
JBAS012605: Activated the following JSF Implementations: [main, 1.2]
[0m[0m16:30:00,062 INFO [org.jboss.as.security] (ServerService Thread Pool -- 53)
JBAS013171: Activating Security Subsystem
[0m[0m16:30:00,187 INFO [org.switchyard] (ServerService Thread Pool -- 59) Activating
SwitchYard Subsystem
[0m[0m16:30:00,283 INFO [org.jboss.as.naming] (MSC service thread 1-2) JBAS011802:
Starting Naming Service
[0m[0m16:30:00,297 INFO [org.jboss.as.security] (MSC service thread 1-5) JBAS013170:
Current PicketBox version=4.0.19.SP8-redhat-1
[0m[0m16:30:00,350 INFO [org.jboss.as.mail.extension] (MSC service thread 1-4)
JBAS015400: Bound mail session [java:jboss/mail/Default]
[0m[0m16:30:00,356 INFO [org.jboss.as.connector.logging] (MSC service thread 1-4)
JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.26.Final-redhat-1)
[0m[0m16:30:00,542 INFO [org.jboss.jaxr] (MSC service thread 1-2) JBAS014000: Started
JAXR subsystem, binding JAXR connection factory into JNDI as:
java:jboss/jaxr/ConnectionFactory
[0m[0m16:30:01,595 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread
1-1) JBWEB003001: Coyote HTTP/1.1 initializing on : http-/127.0.0.1:8080
[0m[0m16:30:01,682 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread
1-1) JBWEB003000: Coyote HTTP/1.1 starting on: http-/127.0.0.1:8080
[0m[0m16:30:02,238 INFO [org.jboss.ws.common.management] (MSC service thread 1-6)
JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.3.0.Final-redhat-3
[0m[0m16:30:02,379 INFO [org.jboss.as.jacorb] (MSC service thread 1-3) JBAS016330:
CORBA ORB Service started
[0m[0m16:30:02,584 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread
1-5) JBAS015012: Started FileSystemDeploymentService for directory
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
[0m[0m16:30:02,610 INFO [org.jboss.as.remoting] (MSC service thread 1-6) JBAS017100:
Listening on 127.0.0.1:4447
[0m[0m16:30:02,610 INFO [org.jboss.as.remoting] (MSC service thread 1-4) JBAS017100:
Listening on 127.0.0.1:9999
[0m[0m16:30:03,288 INFO [org.jboss.as.jacorb] (MSC service thread 1-3) JBAS016328:
CORBA Naming Service started
[0m[0m16:30:03,733 INFO [org.switchyard.component.bpel] (MSC service thread 1-2)
SWITCHYARD030800: Init BPEL component
[0m[0m16:30:03,799 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221000: live server is starting with configuration HornetQ Configuration
(
clustered=false,backup=false,sharedStore=true,journalDirectory=<http:/...
[0m[0m16:30:03,802 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221006: Waiting to obtain live lock
[0m[0m16:30:03,833 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service
thread 1-3) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
[0m[0m16:30:03,864 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221013: Using NIO Journal
[0m[0m16:30:05,182 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221034: Waiting to obtain live lock
[0m[0m16:30:05,183 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221035: Live Server Obtained live lock
[0m[33m16:30:18,763 WARN [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016037: Could
not find new XAResource to use for recovering non-serializable XAResource XAResourceRecord
< resource:null, txid:< formatId=131077, gtrid_length=29, bqual_length=36,
tx_uid=0:ffff0a1059e0:75b5ecfe:54eceae0:52, node_name=1,
branch_uid=0:ffff0a1059e0:75b5ecfe:54eceae0:53, subordinatenodename=null, eis_name=unknown
eis name >, heuristic: TwoPhaseOutcome.FINISH_OK
com.arjuna.ats.internal.jta.resources.arjunacore.XAResourceRecord@15e3f1 >
[0m[33m16:30:23,732 WARN [com.arjuna.ats.jta] (Periodic Recovery) ARJUNA016038: No
XAResource to recover < formatId=131077, gtrid_length=29, bqual_length=36,
tx_uid=0:ffff0a1059e0:75b5ecfe:54eceae0:52, node_name=1,
branch_uid=0:ffff0a1059e0:75b5ecfe:54eceae0:53, subordinatenodename=null, eis_name=unknown
eis name >
[0m[0m16:30:26,942 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221020: Started Netty Acceptor version 3.6.9.Final-redhat-1-4cc400c 127.0.0.1:5445 for
CORE protocol
[0m[0m16:30:26,947 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221020: Started Netty Acceptor version 3.6.9.Final-redhat-1-4cc400c 127.0.0.1:5455 for
CORE protocol
[0m[0m16:30:26,952 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221007: Server is now live
[0m[0m16:30:26,953 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221001: HornetQ Server version 2.3.20.Final (2.3.20, 123)
[f2426136-bc66-11e4-972a-41bd5806d934]
[0m[0m16:30:26,979 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 60)
HQ221003: trying to deploy queue jms.queue.ExpiryQueue
[0m[0m16:30:27,009 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 60)
JBAS011601: Bound messaging object to jndi name java:/jms/queue/ExpiryQueue
[0m[0m16:30:27,011 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 70)
HQ221003: trying to deploy queue jms.queue.JCAInflowGreetingServiceQueue
[0m[0m16:30:28,522 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-5)
JBAS010406: Registered connection factory java:/JmsXA
[0m[0m16:30:28,674 INFO [org.hornetq.ra] (MSC service thread 1-5) HornetQ resource
adaptor started
[0m[0m16:30:28,680 INFO
[org.jboss.as.connector.services.resourceadapters.ResourceAdapterActivatorService$ResourceAdapterActivator]
(MSC service thread 1-5) IJ020002: Deployed: file://RaActivatorhornetq-ra
[0m[0m16:30:28,687 INFO [org.jboss.as.connector.deployment] (MSC service thread 1-6)
JBAS010401: Bound JCA ConnectionFactory [java:/JmsXA]
[0m[0m16:30:38,467 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 70)
JBAS011601: Bound messaging object to jndi name
java:jboss/exported/jms/JCAInflowGreetingServiceQueue
[0m[0m16:30:38,469 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 70)
JBAS011601: Bound messaging object to jndi name java:/JCAInflowGreetingServiceQueue
[0m[0m16:30:38,470 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 69)
HQ221003: trying to deploy queue jms.queue.TestQueue
[0m[0m16:30:38,475 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 69)
JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/TestQueue
[0m[0m16:30:38,476 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 69)
JBAS011601: Bound messaging object to jndi name java:/TestQueue
[0m[0m16:30:38,478 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 67)
HQ221003: trying to deploy queue jms.queue.StoreFaultQueue
[0m[0m16:30:38,482 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 67)
JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/StoreFaultQueue
[0m[0m16:30:38,483 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 67)
JBAS011601: Bound messaging object to jndi name java:/StoreFaultQueue
[0m[0m16:30:38,484 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 76)
HQ221003: trying to deploy queue jms.queue.InOutTestQueue_replyTo
[0m[0m16:30:38,488 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 76)
JBAS011601: Bound messaging object to jndi name java:/InOutTestQueue_replyTo
[0m[0m16:30:38,489 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 76)
JBAS011601: Bound messaging object to jndi name
java:jboss/exported/jms/InOutTestQueue_replyTo
[0m[0m16:30:38,490 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 68)
HQ221003: trying to deploy queue jms.queue.ShippingQueue
[0m[0m16:30:38,494 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 68)
JBAS011601: Bound messaging object to jndi name java:/ShippingQueue
[0m[0m16:30:38,495 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 68)
JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/ShippingQueue
[0m[0m16:30:38,496 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 75)
HQ221003: trying to deploy queue jms.queue.OrderQueue
[0m[0m16:30:38,499 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 75)
JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/OrderQueue
[0m[0m16:30:38,501 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 75)
JBAS011601: Bound messaging object to jndi name java:/OrderQueue
[0m[0m16:30:38,501 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 74)
HQ221003: trying to deploy queue jms.queue.InOutTestQueue_faultTo
[0m[0m16:30:38,506 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 74)
JBAS011601: Bound messaging object to jndi name
java:jboss/exported/jms/InOutTestQueue_faultTo
[0m[0m16:30:38,507 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 74)
JBAS011601: Bound messaging object to jndi name java:/InOutTestQueue_faultTo
[0m[0m16:30:38,508 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 64)
HQ221003: trying to deploy queue jms.queue.InOutPhysicalNameTestQueue_replyTo_physical
[0m[0m16:30:38,513 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 64)
JBAS011601: Bound messaging object to jndi name
java:jboss/exported/jms/InOutPhysicalNameTestQueue_replyTo_jndi
[0m[0m16:30:38,514 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 64)
JBAS011601: Bound messaging object to jndi name
java:/InOutPhysicalNameTestQueue_replyTo_jndi
[0m[0m16:30:38,515 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 73)
HQ221003: trying to deploy queue jms.queue.InOutTestQueue
[0m[0m16:30:38,519 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 73)
JBAS011601: Bound messaging object to jndi name java:/InOutTestQueue
[0m[0m16:30:38,521 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 73)
JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/InOutTestQueue
[0m[0m16:30:38,522 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 72)
HQ221003: trying to deploy queue jms.queue.FillingStockQueue
[0m[0m16:30:38,527 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 72)
JBAS011601: Bound messaging object to jndi name java:/FillingStockQueue
[0m[0m16:30:38,528 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 72)
JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/FillingStockQueue
[0m[0m16:30:38,529 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 71)
HQ221003: trying to deploy queue jms.queue.InOutPhysicalNameTestQueue_physical
[0m[0m16:30:38,533 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 71)
JBAS011601: Bound messaging object to jndi name java:/InOutPhysicalNameTestQueue_jndi
[0m[0m16:30:38,534 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 71)
JBAS011601: Bound messaging object to jndi name
java:jboss/exported/jms/InOutPhysicalNameTestQueue_jndi
[0m[0m16:30:38,535 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 63)
HQ221003: trying to deploy queue jms.queue.InOutPhysicalNameTestQueue_faultTo_physical
[0m[0m16:30:38,539 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 63)
JBAS011601: Bound messaging object to jndi name
java:jboss/exported/jms/InOutPhysicalNameTestQueue_faultTo_jndi
[0m[0m16:30:38,540 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 63)
JBAS011601: Bound messaging object to jndi name
java:/InOutPhysicalNameTestQueue_faultTo_jndi
[0m[0m16:30:38,541 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 65)
HQ221003: trying to deploy queue jms.queue.StoreResultQueue
[0m[0m16:30:38,545 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 65)
JBAS011601: Bound messaging object to jndi name java:jboss/exported/jms/StoreResultQueue
[0m[0m16:30:38,546 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 65)
JBAS011601: Bound messaging object to jndi name java:/StoreResultQueue
[0m[0m16:30:38,547 INFO [org.hornetq.core.server] (ServerService Thread Pool -- 62)
HQ221003: trying to deploy queue jms.queue.DLQ
[0m[0m16:30:38,549 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 62)
JBAS011601: Bound messaging object to jndi name java:/jms/queue/DLQ
[0m[0m16:30:38,564 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 66)
JBAS011601: Bound messaging object to jndi name java:/ConnectionFactory
[0m[0m16:30:38,567 INFO [org.jboss.as.messaging] (ServerService Thread Pool -- 61)
JBAS011601: Bound messaging object to jndi name
java:jboss/exported/jms/RemoteConnectionFactory
[0m[0m16:30:39,603 INFO [org.jboss.as] (Controller Boot Thread) JBAS015961: Http
management interface listening on
http://127.0.0.1:9990/management
[0m[0m16:30:39,605 INFO [org.jboss.as] (Controller Boot Thread) JBAS015951: Admin
console listening on
http://127.0.0.1:9990
[0m[0m16:30:39,606 INFO [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss EAP
6.3.0.GA (AS 7.4.0.Final-redhat-19) started in 44305ms - Started 232 of 269 services (80
services are lazy, passive or on-demand)
[0m[0m16:30:40,248 INFO [org.jboss.as.repository] (management-handler-thread - 4)
JBAS014900: Content added at location
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
[0m[0m16:30:40,308 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2)
JBAS015876: Starting deployment of "switchyard-remote-invoker-2.0.0.CR1.jar"
(runtime-name: "switchyard-remote-invoker-2.0.0.CR1.jar")
[0m[0m16:30:41,068 INFO [org.jboss.weld.deployer] (MSC service thread 1-5) JBAS016002:
Processing weld deployment switchyard-remote-invoker-2.0.0.CR1.jar
[0m[0m16:30:41,277 INFO [org.jboss.weld.deployer] (MSC service thread 1-2) JBAS016005:
Starting Services for CDI deployment: switchyard-remote-invoker-2.0.0.CR1.jar
[0m[0m16:30:41,452 INFO [org.jboss.weld.Version] (MSC service thread 1-2) WELD-000900
1.1.23 (redhat)
[0m[0m16:30:41,505 INFO [org.switchyard] (MSC service thread 1-2) Deploying SwitchYard
application 'switchyard-remote-invoker-2.0.0.CR1.jar'
[0m[0m16:30:41,554 INFO [org.jboss.weld.deployer] (MSC service thread 1-1) JBAS016008:
Starting weld service for deployment switchyard-remote-invoker-2.0.0.CR1.jar
[0m[0m16:30:42,374 INFO [org.switchyard] (MSC service thread 1-2) Starting SwitchYard
service
[0m[0m16:30:42,705 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-2) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) is starting
[0m[0m16:30:42,707 INFO [org.apache.camel.management.ManagedManagementStrategy] (MSC
service thread 1-2) JMX is enabled
[0m[0m16:30:42,975 INFO [org.apache.deltaspike.core.util.ProjectStageProducer] (MSC
service thread 1-2) Computed the following DeltaSpike ProjectStage: Production
[0m[0m16:30:42,990 INFO [org.apache.camel.impl.converter.DefaultTypeConverter] (MSC
service thread 1-2) Loaded 220 type converters
[0m[0m16:30:43,031 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-2) AllowUseOriginalMessage is enabled. If access to the original message
is not needed, then its recommended to turn this option off as it may improve
performance.
[0m[0m16:30:43,032 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-2) StreamCaching is not in use. If using streams then its recommended to
enable stream caching. See more details at
http://camel.apache.org/stream-caching.html
[0m[0m16:30:43,032 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-2) Total 0 routes, of which 0 is started.
[0m[0m16:30:43,034 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-2) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) started in 0.328
seconds
[0m[0m16:30:43,059 INFO [org.switchyard.component.sca] (MSC service thread 1-2)
SWITCHYARD039200: Unable to resolve cache-container cluster. clustering for
<binding.sca> will not be available for services and references.
[0m[0m16:30:43,324 INFO [org.apache.camel.impl.converter.DefaultTypeConverter] (MSC
service thread 1-2) Loaded 220 type converters
[0m[0m16:30:44,600 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-2) Route:
direct:{urn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0}DealerBean/CreditCheck
started and consuming from:
Endpoint[direct://%7Burn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0%7DDealerBean/CreditCheck]
[0m[0m16:30:47,453 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-2) Route:
direct:{urn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0}Dealer started
and consuming from:
Endpoint[direct://%7Burn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0%7DDealer]
[0m[0m16:30:47,468 INFO [org.switchyard.as7.extension.cluster.RemoteEndpointListener]
(MSC service thread 1-2) Published Remote Service Endpoint /switchyard-remote
[0m[0m16:30:50,311 INFO [org.jboss.as.server] (management-handler-thread - 4)
JBAS018559: Deployed "switchyard-remote-invoker-2.0.0.CR1.jar" (runtime-name :
"switchyard-remote-invoker-2.0.0.CR1.jar")
[0m[0m16:30:56,314 INFO [stdout] (http-/127.0.0.1:8080-1) Approving credit for Magesh
[0m[0m16:30:56,803 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) is shutting down
[0m[0m16:30:56,806 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Starting to graceful shutdown 2 routes (timeout 30 seconds)
[0m[0m16:30:56,822 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (Camel
(camel-1) thread #0 - ShutdownTask) Route:
direct:{urn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0}Dealer
shutdown complete, was consuming from:
Endpoint[direct://%7Burn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0%7DDealer]
[0m[0m16:30:56,824 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (Camel
(camel-1) thread #0 - ShutdownTask) Route:
direct:{urn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0}DealerBean/CreditCheck
shutdown complete, was consuming from:
Endpoint[direct://%7Burn:com.example.switchyard:switchyard-quickstart-remote-invoker:1.0%7DDealerBean/CreditCheck]
[0m[0m16:30:56,826 INFO [org.apache.camel.impl.DefaultShutdownStrategy] (MSC service
thread 1-6) Graceful shutdown of 2 routes completed in 0 seconds
[0m[0m16:30:56,871 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) uptime 14.166 seconds
[0m[0m16:30:56,872 INFO [org.switchyard.common.camel.SwitchYardCamelContextImpl] (MSC
service thread 1-6) Apache Camel 2.0.0.CR1 (CamelContext: camel-1) is shutdown in 0.067
seconds
[0m[0m16:30:56,877 INFO [org.jboss.weld.deployer] (MSC service thread 1-1) JBAS016009:
Stopping weld service for deployment switchyard-remote-invoker-2.0.0.CR1.jar
[0m[0m16:30:56,912 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2)
JBAS015877: Stopped deployment switchyard-remote-invoker-2.0.0.CR1.jar (runtime-name:
switchyard-remote-invoker-2.0.0.CR1.jar) in 140ms
[0m[0m16:30:57,585 INFO [org.jboss.as.repository] (management-handler-thread - 1)
JBAS014901: Content removed from location
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
[0m[0m16:30:57,586 INFO [org.jboss.as.server] (management-handler-thread - 1)
JBAS018558: Undeployed "switchyard-remote-invoker-2.0.0.CR1.jar" (runtime-name:
"switchyard-remote-invoker-2.0.0.CR1.jar")
[0m@SLTests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 64.347 sec
Running
org.switchyard.test.quickstarts.demo.PolicySecurityWssSignencryptDemoQuickstartTest
log4j:WARN No appenders could be found for logger (org.jboss.logging).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See
http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
Feb 24, 2015 4:31:17 PM
org.jboss.as.arquillian.container.managed.ManagedDeployableContainer startInternal
INFO: Starting container with: [/qa/tools/opt/jdk1.7.0_last/bin/java, -Xms256m, -Xmx1024m,
-XX:MaxPermSize=512m, -ea,
-Djboss.home.dir=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3,>
-Dorg.jboss.boot.log.file=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3/standalone/log/boot.log,>
-Dlogging.configuration=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3/standalone/configuration/logging.properties,>
-Djboss.bundles.dir=<http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws/jboss-eap-6.3/bundles,>
-jar,
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
-mp,
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
-jaxpmodule, javax.xml.jaxp-provider, org.jboss.as.standalone, -server-config,
standalone-full.xml]
[0m16:31:18,845 INFO [org.jboss.modules] (main) JBoss Modules version
1.3.3.Final-redhat-1
[0m[0m16:31:19,294 INFO [org.jboss.msc] (main) JBoss MSC version 1.1.5.Final-redhat-1
[0m[0m16:31:19,466 INFO [org.jboss.as] (MSC service thread 1-3) JBAS015899: JBoss EAP
6.3.0.GA (AS 7.4.0.Final-redhat-19) starting
[0m[0m16:31:21,912 INFO [org.switchyard] (ServerService Thread Pool -- 27) SwitchYard
version 2.0.0.CR1
[0m[0m16:31:22,389 INFO [org.xnio] (MSC service thread 1-5) XNIO Version
3.0.10.GA-redhat-1
[0m[0m16:31:22,397 INFO [org.jboss.as.server] (Controller Boot Thread) JBAS015888:
Creating http management service using socket-binding (management-http)
[0m[0m16:31:22,413 INFO [org.xnio.nio] (MSC service thread 1-5) XNIO NIO Implementation
Version 3.0.10.GA-redhat-1
[0m[0m16:31:22,495 INFO [org.jboss.remoting] (MSC service thread 1-5) JBoss Remoting
version (unknown)
[0m[0m16:31:22,593 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool
-- 36) JBAS010280: Activating Infinispan subsystem.
[0m[0m16:31:22,607 INFO [org.jboss.as.jacorb] (ServerService Thread Pool -- 37)
JBAS016300: Activating JacORB Subsystem
[0m[0m16:31:22,797 INFO [org.jboss.as.jsf] (ServerService Thread Pool -- 44)
JBAS012605: Activated the following JSF Implementations: [main, 1.2]
[0m[0m16:31:22,866 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 48)
JBAS011800: Activating Naming Subsystem
[0m[0m16:31:22,842 INFO [org.jboss.as.security] (ServerService Thread Pool -- 53)
JBAS013171: Activating Security Subsystem
[0m[33m16:31:22,899 WARN [org.jboss.as.txn] (ServerService Thread Pool -- 55)
JBAS010153: Node identifier property is set to the default value. Please make sure it is
unique.
[0m[0m16:31:22,980 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 57)
JBAS015537: Activating WebServices Extension
[0m[0m16:31:23,010 INFO [org.jboss.as.naming] (MSC service thread 1-4) JBAS011802:
Starting Naming Service
[0m[0m16:31:23,056 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService
Thread Pool -- 32) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver
(version 1.3)
[0m[0m16:31:23,078 INFO [org.jboss.as.mail.extension] (MSC service thread 1-3)
JBAS015400: Bound mail session [java:jboss/mail/Default]
[0m[0m16:31:23,339 INFO [org.switchyard] (ServerService Thread Pool -- 59) Activating
SwitchYard Subsystem
[0m[0m16:31:23,387 INFO [org.jboss.jaxr] (MSC service thread 1-5) JBAS014000: Started
JAXR subsystem, binding JAXR connection factory into JNDI as:
java:jboss/jaxr/ConnectionFactory
[0m[0m16:31:23,461 INFO [org.jboss.as.security] (MSC service thread 1-5) JBAS013170:
Current PicketBox version=4.0.19.SP8-redhat-1
[0m[0m16:31:23,472 INFO [org.jboss.as.connector.logging] (MSC service thread 1-3)
JBAS010408: Starting JCA Subsystem (IronJacamar 1.0.26.Final-redhat-1)
[0m[0m16:31:24,790 INFO [org.jboss.as.jacorb] (MSC service thread 1-2) JBAS016330:
CORBA ORB Service started
[0m[0m16:31:24,862 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread
1-4) JBWEB003001: Coyote HTTP/1.1 initializing on : http-/127.0.0.1:8080
[0m[0m16:31:24,994 INFO [org.apache.coyote.http11.Http11Protocol] (MSC service thread
1-4) JBWEB003000: Coyote HTTP/1.1 starting on: http-/127.0.0.1:8080
[0m[0m16:31:25,453 INFO [org.jboss.ws.common.management] (MSC service thread 1-1)
JBWS022052: Starting JBoss Web Services - Stack CXF Server 4.3.0.Final-redhat-3
Build timed out (after 120 minutes). Marking the build as failed.
Build was aborted
Archiving artifacts
[0m[0m16:31:25,520 INFO [org.jboss.as.remoting] (MSC service thread 1-1) JBAS017100:
Listening on 127.0.0.1:4447
[0m[0m16:31:25,520 INFO [org.jboss.as.remoting] (MSC service thread 1-2) JBAS017100:
Listening on 127.0.0.1:9999
[0m[0m16:31:25,592 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread
1-3) JBAS015012: Started FileSystemDeploymentService for directory
<
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/SwitchYard-Release/ws...
[0m[31m16:31:25,597 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-5)
MSC000001: Failed to start service jboss.jacorb.naming-service:
org.jboss.msc.service.StartException in service jboss.jacorb.naming-service: Failed to
start service
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1936)
[jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[rt.jar:1.7.0_67]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[rt.jar:1.7.0_67]
at java.lang.Thread.run(Thread.java:745) [rt.jar:1.7.0_67]
Caused by: java.lang.IllegalStateException: Container is down
at org.jboss.msc.service.ServiceContainerImpl.install(ServiceContainerImpl.java:690)
[jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
at org.jboss.msc.service.ServiceTargetImpl.install(ServiceTargetImpl.java:243)
[jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
at
org.jboss.msc.service.ServiceControllerImpl$ChildServiceTarget.install(ServiceControllerImpl.java:2433)
[jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
at org.jboss.msc.service.ServiceBuilderImpl.install(ServiceBuilderImpl.java:345)
[jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
at org.jboss.as.jacorb.service.CorbaServiceUtil.bindObject(CorbaServiceUtil.java:62)
at org.jboss.as.jacorb.service.CorbaNamingService.start(CorbaNamingService.java:89)
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1980)
[jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
at
org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1913)
[jboss-msc-1.1.5.Final-redhat-1.jar:1.1.5.Final-redhat-1]
... 3 more
Destroying 1 processes
Destroying process..
Destroyed 1 processes
Recording test results