Juho
Please subscribe to the mailing list or you won't see the responses -
I let your message through, but in general this is a subscriber only
list.
I don't have an AIX box to test on - can you please use TCPMON to
trace the wire messages between the client and server and post them
here.
Let me know if you need help with that.
Paul
On Thu, Jul 31, 2008 at 12:51 PM, Juho Jussila <[EMAIL PROTECTED]> wrote:
> Hi
>
> I installed Synapse 1.2 on Aix 5.2 and tried to run the samples.
> Even the simplest sample (sample 0) doesn't work.
> I think there is some communication problem between
> axis2server.sh and synapse.sh because of this strange exception:
>
> org.apache.axis2.AxisFault: com.ctc.wstx.exc.WstxEOFException:
> Unexpected EOF in prolog at [row,col {unknown-source}]: [1,0]
>
> Samples work fine on Linux but on not Aix.
>
> All help appreciated,
> Juho Jussila
>
>
> java version "1.5.0"
> Java(TM) 2 Runtime Environment, Standard Edition (build
> pap64devifx-20071025 (SR6b))
> IBM J9 VM (build 2.3, J2RE 1.5.0 IBM J9 2.3 AIX ppc64-64
> j9vmap6423-20071007 (JIT enabled)
> J9VM - 20071004_14218_BHdSMr
> JIT - 20070820_1846ifx1_r8
> GC - 200708_10)
> JCL - 20071025
>
>
> axis2Server>./axis2server.sh -name foobar -https 8608
>
> Using Bouncy castle JAR for Java 1.5
> Using JAVA_HOME: /home/my/sdk/jre/
> Using AXIS2 Repository :
> /home/my/synapse-1.2/samples/axis2Server/repository
> Using AXIS2 Configuration :
> /home/my/synapse-1.2/samples/axis2Server/repository/conf/axis2.xml
> 2008-07-31 14:10:11,041 [-] [main] INFO SampleAxis2ServerManager
> [SimpleAxisServer] Starting
> [SimpleAxisServer] Using the Axis2 Repository :
> /home/my/synapse-1.2/samples/axis2Server/repository
> [SimpleAxisServer] Using the Axis2 Configuration File :
> /home/my/synapse-1.2/samples/axis2Server/repository/conf/axis2.xml
> 2008-07-31 14:10:13,317 [-] [main] INFO JMSSender JMS Sender started
> 2008-07-31 14:10:13,320 [-] [main] INFO HttpCoreNIOSender HTTP Sender
> starting
> 2008-07-31 14:10:13,444 [-] [main] INFO HttpCoreNIOSSLSender Loading
> Identity Keystore from : ../../lib/identity.jks
> 2008-07-31 14:10:13,516 [-] [main] INFO HttpCoreNIOSSLSender Loading
> Trust Keystore from : ../../lib/trust.jks
> 2008-07-31 14:10:13,565 [-] [main] INFO HttpCoreNIOSender HTTPS Sender
> starting
> 2008-07-31 14:10:14,706 [-] [main] INFO HttpCoreNIOListener HTTP Listener
> starting on port : 9000
> 2008-07-31 14:10:14,711 [-] [main] INFO MailTransportListener MAILTO
> Listener started
> 2008-07-31 14:10:14,720 [-] [main] WARN MailTransportListener Error
> configuring the Mail transport for Service : SimpleStockQuoteService ::
> Cannot find parameter : transport.mail.Address for service :
> SimpleStockQuoteService
> 2008-07-31 14:10:14,722 [-] [main] WARN MailTransportListener Disabling
> the mailto transport for the service SimpleStockQuoteService, because it
> is not configured properly for the service
> 2008-07-31 14:10:14,791 [-] [main] INFO HttpCoreNIOSSLListener Loading
> Identity Keystore from : ../../lib/identity.jks
> 2008-07-31 14:10:14,801 [-] [main] INFO HttpCoreNIOSSLListener Loading
> Trust Keystore from : ../../lib/trust.jks
> 2008-07-31 14:10:14,819 [-] [main] INFO HttpCoreNIOListener HTTPS
> Listener starting on port : 8608
> 2008-07-31 14:10:14,832 [-] [main] INFO SampleAxis2ServerManager
> [SimpleAxisServer] Started
> 2008-07-31 14:11:07,422 [-] [HttpServerWorker-1] DEBUG TransportUtils
> createSOAPEnvelope using Builder (class
> org.apache.axis2.builder.SOAPBuilder) selected from type (text/xml)
> 2008-07-31 14:12:07,315 [-] [HttpServerWorker-1] ERROR ServerWorker Error
> processing POST request
> org.apache.axis2.AxisFault: com.ctc.wstx.exc.WstxEOFException: Unexpected
> EOF in prolog
> at [row,col {unknown-source}]: [1,0]
> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
> at
> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:283)
> at
> org.apache.synapse.transport.nhttp.ServerWorker.processPost(ServerWorker.java:253)
> at
> org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:194)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:665)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:690)
> at java.lang.Thread.run(Thread.java:810)
> Caused by:
> org.apache.axiom.om.OMException: com.ctc.wstx.exc.WstxEOFException:
> Unexpected EOF in prolog
> at [row,col {unknown-source}]: [1,0]
> at
> org.apache.axiom.om.impl.builder.StAXOMBuilder.next(StAXOMBuilder.java:249)
> at
> org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder.getSOAPEnvelope(StAXSOAPModelBuilder.java:156)
> at
> org.apache.axiom.soap.impl.builder.StAXSOAPModelBuilder.<init>(StAXSOAPModelBuilder.java:105)
> at
> org.apache.axis2.builder.SOAPBuilder.processDocument(SOAPBuilder.java:53)
> at
> org.apache.axis2.transport.TransportUtils.createDocumentElement(TransportUtils.java:164)
> at
> org.apache.axis2.transport.TransportUtils.createSOAPMessage(TransportUtils.java:112)
> at
> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:270)
> ... 5 more
> Caused by:
> com.ctc.wstx.exc.WstxEOFException: Unexpected EOF in prolog
> at [row,col {unknown-source}]: [1,0]
> at
> com.ctc.wstx.sr.StreamScanner.throwUnexpectedEOF(StreamScanner.java:661)
> at
> com.ctc.wstx.sr.BasicStreamReader.handleEOF(BasicStreamReader.java:2134)
> at
> com.ctc.wstx.sr.BasicStreamReader.nextFromProlog(BasicStreamReader.java:2040)
> at
> com.ctc.wstx.sr.BasicStreamReader.next(BasicStreamReader.java:1069)
> at
> org.apache.axiom.om.impl.builder.StAXOMBuilder.parserNext(StAXOMBuilder.java:506)
> at
> org.apache.axiom.om.impl.builder.StAXOMBuilder.next(StAXOMBuilder.java:161)
>
> ... 11 more
> 2008-07-31 14:12:07,545 [-] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter contentType from the OMOutputFormat =text/xml
> 2008-07-31 14:12:07,546 [-] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter contentType returned =text/xml; charset=UTF-8
> 2008-07-31 14:12:07,571 [-] [HttpServerWorker-1] ERROR ServerHandler IO
> error submiting response : Connection is closed
> org.apache.http.ConnectionClosedException: Connection is closed
> at
> org.apache.http.impl.nio.NHttpConnectionBase.assertNotClosed(NHttpConnectionBase.java:238)
> at
> org.apache.http.impl.nio.DefaultNHttpServerConnection.submitResponse(DefaultNHttpServerConnection.java:200)
> at
> org.apache.synapse.transport.nhttp.ServerHandler.commitResponse(ServerHandler.java:213)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncResponse(HttpCoreNIOSender.java:409)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:264)
> at
> org.apache.axis2.engine.AxisEngine.sendFault(AxisEngine.java:530)
> at
> org.apache.synapse.transport.nhttp.ServerWorker.handleException(ServerWorker.java:489)
> at
> org.apache.synapse.transport.nhttp.ServerWorker.processPost(ServerWorker.java:260)
> at
> org.apache.synapse.transport.nhttp.ServerWorker.run(ServerWorker.java:194)
>
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:665)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:690)
> at java.lang.Thread.run(Thread.java:810)
> 2008-07-31 14:12:07,575 [-] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter start writeTo()
> 2008-07-31 14:12:07,575 [-] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter preserve=false
> 2008-07-31 14:12:07,576 [-] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter isOptimized=false
> 2008-07-31 14:12:07,577 [-] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter isDoingSWA=false
> 2008-07-31 14:12:07,690 [-] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter end writeTo()
>
>
>
>
>
> axis2Client>ant stockquote
> -Daddurl=http://localhost:9000/soap/SimpleStockQuoteService/
> -Dtrpurl=http://localhost:8280/
>
> Buildfile: build.xml
>
> init:
>
> compile:
>
> stockquote:
> [java] 2008-07-31 14:11:06,051 [-] [main] INFO JMSSender JMS Sender
> started
> [java] 2008-07-31 14:11:06,057 [-] [main] INFO MailTransportSender
> MAILTO Sender started
> [java] 2008-07-31 14:11:06,353 [-] [main] DEBUG SOAPMessageFormatter
> contentType from the OMOutputFormat =text/xml
> [java] 2008-07-31 14:11:06,353 [-] [main] DEBUG SOAPMessageFormatter
> contentType returned =text/xml; charset=UTF-8
> [java] 2008-07-31 14:11:06,607 [-] [main] DEBUG SOAPMessageFormatter
> start writeTo()
> [java] 2008-07-31 14:11:06,607 [-] [main] DEBUG SOAPMessageFormatter
> preserve=false
> [java] 2008-07-31 14:11:06,607 [-] [main] DEBUG SOAPMessageFormatter
> isOptimized=false
> [java] 2008-07-31 14:11:06,607 [-] [main] DEBUG SOAPMessageFormatter
> isDoingSWA=false
> [java] 2008-07-31 14:11:06,790 [-] [main] DEBUG SOAPMessageFormatter
> end writeTo()
> [java] 2008-07-31 14:11:37,434 [-] [main] INFO HTTPSender Unable to
> sendViaPost to url[http://localhost:8280/]
> [java] java.net.SocketTimeoutException: Read timed out
> [java] at java.net.SocketInputStream.socketRead0(Native Method)
> [java] at
> java.net.SocketInputStream.read(SocketInputStream.java:155)
> [java] at
> java.io.BufferedInputStream.fill(BufferedInputStream.java:229)
> [java] at
> java.io.BufferedInputStream.read(BufferedInputStream.java:246)
> [java] at
> org.apache.commons.httpclient.HttpParser.readRawLine(HttpParser.java:78)
> [java] at
> org.apache.commons.httpclient.HttpParser.readLine(HttpParser.java:106)
> [java] at
> org.apache.commons.httpclient.HttpConnection.readLine(HttpConnection.java:1116)
> [java] at
> org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.readLine(MultiThreadedHttpConnectionManager.java:1413)
> [java] at
> org.apache.commons.httpclient.HttpMethodBase.readStatusLine(HttpMethodBase.java:1973)
> [java] at
> org.apache.commons.httpclient.HttpMethodBase.readResponse(HttpMethodBase.java:1735)
> [java] at
> org.apache.commons.httpclient.HttpMethodBase.execute(HttpMethodBase.java:1098)
> [java] at
> org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:398)
> [java] at
> org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171)
> [java] at
> org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397)
> [java] at
> org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:346)
> [java] at
> org.apache.axis2.transport.http.AbstractHTTPSender.executeMethod(AbstractHTTPSender.java:542)
> [java] at
> org.apache.axis2.transport.http.HTTPSender.sendViaPost(HTTPSender.java:189)
> [java] at
> org.apache.axis2.transport.http.HTTPSender.send(HTTPSender.java:75)
> [java] at
> org.apache.axis2.transport.http.CommonsHTTPTransportSender.writeMessageWithCommons(CommonsHTTPTransportSender.java:364)
> [java] at
> org.apache.axis2.transport.http.CommonsHTTPTransportSender.invoke(CommonsHTTPTransportSender.java:208)
> [java] at
> org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
> [java] at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:401)
> [java] at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:228)
> [java] at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> [java] at
> org.apache.axis2.client.ServiceClient.sendReceive(ServiceClient.java:548)
> [java] at
> org.apache.axis2.client.ServiceClient.sendReceive(ServiceClient.java:528)
> [java] at
> samples.userguide.StockQuoteClient.executeClient(Unknown Source)
> [java] at samples.userguide.StockQuoteClient.main(Unknown Source)
> [java] 2008-07-31 14:11:37,480 [-] [main] DEBUG
> CommonsHTTPTransportSender org.apache.axis2.AxisFault: Read timed out
> [java] 2008-07-31 14:11:37,481 [-] [main] DEBUG
> CommonsHTTPTransportSender org.apache.axis2.AxisFault: Read timed out
> [java] org.apache.axis2.AxisFault: Read timed out
> [java] at
> org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
> [java] at
> org.apache.axis2.transport.http.HTTPSender.sendViaPost(HTTPSender.java:193)
> [java] at
> org.apache.axis2.transport.http.HTTPSender.send(HTTPSender.java:75)
> [java] at
> org.apache.axis2.transport.http.CommonsHTTPTransportSender.writeMessageWithCommons(CommonsHTTPTransportSender.java:364)
> [java] at
> org.apache.axis2.transport.http.CommonsHTTPTransportSender.invoke(CommonsHTTPTransportSender.java:208)
> [java] at
> org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
> [java] at
> org.apache.axis2.description.OutInAxisOperationClient.send(OutInAxisOperation.java:401)
> [java] at
> org.apache.axis2.description.OutInAxisOperationClient.executeImpl(OutInAxisOperation.java:228)
> [java] at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> [java] at
> org.apache.axis2.client.ServiceClient.sendReceive(ServiceClient.java:548)
> [java] at
> org.apache.axis2.client.ServiceClient.sendReceive(ServiceClient.java:528)
> [java] at
> samples.userguide.StockQuoteClient.executeClient(Unknown Source)
> [java] at samples.userguide.StockQuoteClient.main(Unknown Source)
> [java] Caused by: java.net.SocketTimeoutException: Read timed out
> [java] at java.net.SocketInputStream.socketRead0(Native Method)
> [java] at
> java.net.SocketInputStream.read(SocketInputStream.java:155)
> [java] at
> java.io.BufferedInputStream.fill(BufferedInputStream.java:229)
> [java] at
> java.io.BufferedInputStream.read(BufferedInputStream.java:246)
> [java] at
> org.apache.commons.httpclient.HttpParser.readRawLine(HttpParser.java:78)
> [java] at
> org.apache.commons.httpclient.HttpParser.readLine(HttpParser.java:106)
> [java] at
> org.apache.commons.httpclient.HttpConnection.readLine(HttpConnection.java:1116)
> [java] at
> org.apache.commons.httpclient.MultiThreadedHttpConnectionManager$HttpConnectionAdapter.readLine(MultiThreadedHttpConnectionManager.java:1413)
> [java] at
> org.apache.commons.httpclient.HttpMethodBase.readStatusLine(HttpMethodBase.java:1973)
> [java] at
> org.apache.commons.httpclient.HttpMethodBase.readResponse(HttpMethodBase.java:1735)
> [java] at
> org.apache.commons.httpclient.HttpMethodBase.execute(HttpMethodBase.java:1098)
> [java] at
> org.apache.commons.httpclient.HttpMethodDirector.executeWithRetry(HttpMethodDirector.java:398)
> [java] at
> org.apache.commons.httpclient.HttpMethodDirector.executeMethod(HttpMethodDirector.java:171)
> [java] at
> org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:397)
> [java] at
> org.apache.commons.httpclient.HttpClient.executeMethod(HttpClient.java:346)
> [java] at
> org.apache.axis2.transport.http.AbstractHTTPSender.executeMethod(AbstractHTTPSender.java:542)
> [java] at
> org.apache.axis2.transport.http.HTTPSender.sendViaPost(HTTPSender.java:189)
> [java] ... 11 more
>
> BUILD SUCCESSFUL
>
>
>
>
>
> synapse-1.2/bin>./synapse.sh -sample 0
> Using Bouncy castle JAR for Java 1.5
> Starting Synapse/Java ...
> Using SYNAPSE_HOME: /home/my/synapse-1.2
> Using JAVA_HOME: /home/my/sdk/jre/
> Using SYNAPSE_XML:
> /home/my/synapse-1.2/repository/conf/sample/synapse_sample_0.xml
> 2008-07-31 14:10:52,514 [-] [main] INFO ServerManager Using Synapse home
> as : /home/my/synapse-1.2
> 2008-07-31 14:10:52,520 [-] [main] INFO ServerManager Using the Axis2
> Repository : /home/my/synapse-1.2/repository
> 2008-07-31 14:10:52,520 [-] [main] INFO ServerManager Using the axis2.xml
> : /home/my/synapse-1.2/repository/conf/axis2.xml
> 2008-07-31 14:10:52,520 [-] [main] INFO ServerManager Using server name :
> localhost
> 2008-07-31 14:10:52,521 [-] [main] INFO ServerManager The timeout handler
> will run every : 15s
> 2008-07-31 14:10:54,821 [-] [main] INFO SynapseInitializationModule
> Initializing Synapse at : Thu Jul 31 14:10:54 EEST 2008
> 2008-07-31 14:10:54,822 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> SynapseInitializationModule Loading mediator extensions...
> 2008-07-31 14:10:54,823 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> SynapseInitializationModule Initializing the Synapse configuration ...
> 2008-07-31 14:10:54,827 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> SynapseConfigurationBuilder synapse.properties file is loading from
> classpath
> 2008-07-31 14:10:54,844 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> DataSourceRegistrar No DataSources defined for initialization..
> 2008-07-31 14:10:54,845 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> XMLConfigurationBuilder Generating the Synapse configuration model by
> parsing the XML configuration
> 2008-07-31 14:10:54,869 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> ConfigurationFactoryAndSerializerFinder
> getConfiguration({http://ws.apache.org/ns/synapse}definitions)
> 2008-07-31 14:10:54,950 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.spring.SpringMediatorFactory to handle
> {http://ws.apache.org/ns/synapse/spring}spring
> 2008-07-31 14:10:54,953 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.bsf.ScriptMediatorFactory to handle
> {http://ws.apache.org/ns/synapse}script
> 2008-07-31 14:10:54,961 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.throttle.ThrottleMediatorFactory to handle
> {http://ws.apache.org/ns/synapse}throttle
> 2008-07-31 14:10:54,966 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.xquery.XQueryMediatorFactory to handle
> {http://ws.apache.org/ns/synapse}xquery
> 2008-07-31 14:10:54,967 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}log)
> 2008-07-31 14:10:54,972 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}send)
> 2008-07-31 14:10:55,041 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> SynapseConfigurationBuilder Loaded Synapse configuration from :
> /home/my/synapse-1.2/repository/conf/sample/synapse_sample_0.xml
> 2008-07-31 14:10:55,059 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> SynapseConfiguration Initializing the Synapse Configuration
> 2008-07-31 14:10:55,060 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> SequenceMediator Initializing child mediators
> 2008-07-31 14:10:55,060 [xxx.xxx.xxx.xxx-hostname] [main] DEBUG
> SequenceMediator Initializing child mediators
> 2008-07-31 14:10:55,060 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> SynapseInitializationModule Deploying the Synapse service..
> 2008-07-31 14:10:55,100 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> SynapseInitializationModule Synapse server name : localhost
> 2008-07-31 14:10:55,101 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> SynapseInitializationModule Deploying Proxy services...
> 2008-07-31 14:10:55,101 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> SynapseInitializationModule Synapse initialized successfully...!
> 2008-07-31 14:10:55,233 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> VFSTransportSender VFS Sender started
> 2008-07-31 14:10:55,741 [xxx.xxx.xxx.xxx-hostname] [main] INFO JMSSender
> JMS Sender started
> 2008-07-31 14:10:55,744 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOSender HTTP Sender starting
> 2008-07-31 14:10:55,879 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOSSLSender Loading Identity Keystore from : lib/identity.jks
> 2008-07-31 14:10:55,968 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOSSLSender Loading Trust Keystore from : lib/trust.jks
> 2008-07-31 14:10:56,034 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOSender HTTPS Sender starting
> 2008-07-31 14:10:56,070 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> ServerManager Starting transport vfs
> 2008-07-31 14:10:56,143 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> VFSTransportListener VFS Listener started
> 2008-07-31 14:10:56,158 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> ServerManager Starting transport http on port 8280
> 2008-07-31 14:10:56,161 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOListener HTTP Listener starting on port : 8280
> 2008-07-31 14:10:56,218 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> ServerManager Starting transport mailto
> 2008-07-31 14:10:56,219 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> MailTransportListener MAILTO Listener started
> 2008-07-31 14:10:56,220 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> ServerManager Starting transport https on port 8243
> 2008-07-31 14:10:56,221 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOSSLListener Loading Identity Keystore from : lib/identity.jks
> 2008-07-31 14:10:56,226 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOSSLListener Loading Trust Keystore from : lib/trust.jks
> 2008-07-31 14:10:56,231 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> HttpCoreNIOListener HTTPS Listener starting on port : 8243
> 2008-07-31 14:10:56,244 [xxx.xxx.xxx.xxx-hostname] [main] INFO
> ServerManager Ready for processing
> 2008-07-31 14:11:06,784 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG TransportUtils createSOAPEnvelope using Builder (class
> org.apache.axis2.builder.SOAPBuilder) selected from type (text/xml)
> 2008-07-31 14:11:06,929 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SynapseMessageReceiver Synapse received a new message for message
> mediation...
> 2008-07-31 14:11:06,929 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SynapseMessageReceiver Received To:
> http://localhost:9000/soap/SimpleStockQuoteService/
> 2008-07-31 14:11:06,929 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SynapseMessageReceiver SOAPAction: urn:getQuote
> 2008-07-31 14:11:06,930 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SynapseMessageReceiver WSA-Action: urn:getQuote
> 2008-07-31 14:11:06,931 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG Axis2SynapseEnvironment Injecting MessageContext
> 2008-07-31 14:11:06,931 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG Axis2SynapseEnvironment Using Main Sequence for injected message
> 2008-07-31 14:11:06,932 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SequenceMediator Start : Sequence <main>
> 2008-07-31 14:11:06,932 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SequenceMediator Sequence <SequenceMediator> :: mediate()
> 2008-07-31 14:11:06,932 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG LogMediator Start : Log mediator
> 2008-07-31 14:11:07,018 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> INFO LogMediator To: http://localhost:9000/soap/SimpleStockQuoteService/,
> WSAction: urn:getQuote, SOAPAction: urn:getQuote, ReplyTo:
> http://www.w3.org/2005/08/addressing/anonymous, MessageID:
> urn:uuid:2E35DDC5B395559DDC1217502666264, Direction: request, Envelope:
> <?xml version='1.0' encoding='utf-8'?><soapenv:Envelope
> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";><soapenv:Header
> xmlns:wsa="http://www.w3.org/2005/08/addressing";><wsa:To>http://localhost:9000/soap/SimpleStockQuoteService/</wsa:To><wsa:MessageID>urn:uuid:2E35DDC5B395559DDC1217502666264</wsa:MessageID><wsa:Action>urn:getQuote</wsa:Action></soapenv:Header><soapenv:Body><m0:getQuote
> xmlns:m0="http://services.samples/xsd";><m0:request><m0:symbol>IBM</m0:symbol></m0:request></m0:getQuote></soapenv:Body></soapenv:Envelope>
> 2008-07-31 14:11:07,019 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG LogMediator End : Log mediator
> 2008-07-31 14:11:07,019 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SendMediator Start : Send mediator
> 2008-07-31 14:11:07,020 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SendMediator Sending request message using implicit message
> properties..
> Sending To: http://localhost:9000/soap/SimpleStockQuoteService/
> SOAPAction: urn:getQuote
> 2008-07-31 14:11:07,032 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG Axis2FlexibleMEPClient Sending [add = false] [sec = false] [rm =
> false] [to Address: http://localhost:9000/soap/SimpleStockQuoteService/]
> 2008-07-31 14:11:07,062 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> INFO TimeoutHandler This engine will expire all callbacks after : 86400
> seconds, irrespective of the timeout action, after the specified or
> optional timeout
> 2008-07-31 14:11:07,082 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SynapseCallbackReceiver Callback added. Total callbacks waiting for
> : 1
> 2008-07-31 14:11:07,104 [xxx.xxx.xxx.xxx-hostname] [I/O dispatcher 1] DEBUG
> SOAPMessageFormatter contentType from the OMOutputFormat =text/xml
> 2008-07-31 14:11:07,104 [xxx.xxx.xxx.xxx-hostname] [I/O dispatcher 1] DEBUG
> SOAPMessageFormatter contentType returned =text/xml; charset=UTF-8
> 2008-07-31 14:11:07,122 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SOAPMessageFormatter start writeTo()
> 2008-07-31 14:11:07,123 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SOAPMessageFormatter preserve=false
> 2008-07-31 14:11:07,123 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SOAPMessageFormatter isOptimized=false
> 2008-07-31 14:11:07,123 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SOAPMessageFormatter isDoingSWA=false
> 2008-07-31 14:11:07,346 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SOAPMessageFormatter end writeTo()
> 2008-07-31 14:12:07,128 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SendMediator End : Send mediator
> 2008-07-31 14:12:07,128 [xxx.xxx.xxx.xxx-hostname] [HttpServerWorker-1]
> DEBUG SequenceMediator End : Sequence <main>
> 2008-07-31 14:12:07,203 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG SynapseCallbackReceiver Callback removed. Pending callbacks count :
> 0
> 2008-07-31 14:12:07,205 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> WARN FaultHandler Fault handler - setting ERROR_MESSAGE : Connection
> timeout
> 2008-07-31 14:12:07,209 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> WARN FaultHandler Fault handler - setting ERROR_DETAIL :
> java.lang.Exception: Connection timeout
> at
> org.apache.synapse.core.axis2.SynapseCallbackReceiver.handleMessage(SynapseCallbackReceiver.java:172)
> at
> org.apache.synapse.core.axis2.SynapseCallbackReceiver.receive(SynapseCallbackReceiver.java:131)
> at
> org.apache.synapse.transport.nhttp.ClientHandler$1.run(ClientHandler.java:298)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:665)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:690)
> at java.lang.Thread.run(Thread.java:810)
>
> 2008-07-31 14:12:07,209 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> WARN FaultHandler Fault handler - setting ERROR_EXCEPTION :
> java.lang.Exception: Connection timeout
> 2008-07-31 14:12:07,210 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> WARN MediatorFaultHandler Executing fault handler mediator : fault
> 2008-07-31 14:12:07,210 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG SequenceMediator Start : Sequence <fault>
> 2008-07-31 14:12:07,211 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG SequenceMediator Sequence <SequenceMediator> :: mediate()
> 2008-07-31 14:12:07,211 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG LogMediator Start : Log mediator
> 2008-07-31 14:12:07,352 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> INFO LogMediator To: http://localhost:9000/soap/SimpleStockQuoteService/,
> WSAction: urn:getQuote, SOAPAction: urn:getQuote, ReplyTo:
> http://www.w3.org/2005/08/addressing/anonymous, MessageID:
> urn:uuid:2E35DDC5B395559DDC1217502666264, Direction: request, MESSAGE =
> Executing default "fault" sequence, ERROR_CODE = 00000, ERROR_MESSAGE =
> Connection timeout, Envelope: <?xml version='1.0'
> encoding='utf-8'?><soapenv:Envelope
> xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/";><soapenv:Body><m0:getQuote
> xmlns:m0="http://services.samples/xsd";><m0:request><m0:symbol>IBM</m0:symbol></m0:request></m0:getQuote></soapenv:Body></soapenv:Envelope>
> 2008-07-31 14:12:07,354 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG LogMediator End : Log mediator
> 2008-07-31 14:12:07,355 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG DropMediator Start : Drop mediator
> 2008-07-31 14:12:07,356 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG DropMediator End : Drop mediator
> 2008-07-31 14:12:07,357 [xxx.xxx.xxx.xxx-hostname] [HttpClientWorker-1]
> DEBUG SequenceMediator End : Sequence <fault>
>
--
Paul Fremantle
Co-Founder and CTO, WSO2
Apache Synapse PMC Chair
OASIS WS-RX TC Co-chair
blog: http://pzf.fremantle.org
[EMAIL PROTECTED]
"Oxygenating the Web Service Platform", www.wso2.com
