You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@synapse.apache.org by Pavel Křehula <pa...@nlm.cz> on 2008/06/11 11:00:17 UTC
Error when forcing HTTP/1.0
Hello,
I have problem using HTTP/1.0 in Synapse 1.2
Using sample 0:
if I modify configuration of sample 0 and add
<property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
<!-- Introduction to Synapse -->
<definitions xmlns="http://ws.apache.org/ns/synapse">
<property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
<!-- log all attributes of messages passing through -->
<log level="full"/>
<!-- Send the messageto implicit destination -->
<send/>
</definitions>
to force using HTTP/1.0 and switch off chunked transfer encoding I get following error:
2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1] ERROR HttpCoreNIOSender
IO Error while submiting request message for sending
org.apache.axis2.AxisFault: problem accessing the parser. Parser already accessed!
at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
at org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
at
org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
at
org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
at org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
Complete log attached.
Thanks.
--
Best regards,
Pavel Krehula
Re: Error when forcing HTTP/1.0
Posted by "Asankha C. Perera" <as...@wso2.com>.
Pavel
This is a defect caused by a performance improvement we did at the last
minute.. can you raise a JIRA and we will make the fix available ASAP as
a snapshot
asankha
Pavel Křehula wrote:
> Hello,
> I have problem using HTTP/1.0 in Synapse 1.2
>
> Using sample 0:
> if I modify configuration of sample 0 and add
> <property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
>
> <!-- Introduction to Synapse -->
> <definitions xmlns="http://ws.apache.org/ns/synapse">
> <property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
>
> <!-- log all attributes of messages passing through -->
> <log level="full"/>
>
> <!-- Send the messageto implicit destination -->
> <send/>
> </definitions>
>
> to force using HTTP/1.0 and switch off chunked transfer encoding I get
> following error:
>
> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1]
> ERROR HttpCoreNIOSender IO Error while submiting request message for
> sending
> org.apache.axis2.AxisFault: problem accessing the parser. Parser
> already accessed!
> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
>
> at
> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
>
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
>
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
>
> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
>
>
> Complete log attached.
> Thanks.
>
Re: Error when forcing HTTP/1.0
Posted by Pavel Křehula <pa...@nlm.cz>.
Thanks for answer,
I will use 1.2-beta until it will be fixed.
Pavel
Ruwan Linton napsal(a):
> Hi Pavel,
>
> This is due to the last minute performance improvements that we have done.
> We are in the process of providing a switch in the send mediator to turn
> that perf improvement off in the JIRA [image:
> Improvement]<https://issues.apache.org/jira/browse/SYNAPSE-357>
> SYNAPSE-357 <https://issues.apache.org/jira/browse/SYNAPSE-357>
>
> Once the fix to that is available you will be able to get this working. I
> will work on this issue ASAP. Please keep an eye on that issue.
>
> Thanks,
> Ruwan
>
> On Wed, Jun 11, 2008 at 2:30 PM, Pavel Křehula <pa...@nlm.cz> wrote:
>
>> Hello,
>> I have problem using HTTP/1.0 in Synapse 1.2
>>
>> Using sample 0:
>> if I modify configuration of sample 0 and add
>> <property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
>>
>> <!-- Introduction to Synapse -->
>> <definitions xmlns="http://ws.apache.org/ns/synapse">
>> <property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
>>
>> <!-- log all attributes of messages passing through -->
>> <log level="full"/>
>>
>> <!-- Send the messageto implicit destination -->
>> <send/>
>> </definitions>
>>
>> to force using HTTP/1.0 and switch off chunked transfer encoding I get
>> following error:
>>
>> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1] ERROR
>> HttpCoreNIOSender IO Error while submiting request message for sending
>> org.apache.axis2.AxisFault: problem accessing the parser. Parser already
>> accessed!
>> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
>> at
>> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
>> at
>> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
>> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
>>
>>
>> Complete log attached.
>> Thanks.
>>
>> --
>> Best regards,
>> Pavel Krehula
>>
>>
>> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
>> Using Synapse home as : .
>> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
>> Using the Axis2 Repository : C:\TEMP\SYNAPS~1.2\repository
>> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
>> Using the axis2.xml : C:\TEMP\SYNAPS~1.2\repository\conf\axis2.xml
>> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
>> Using server name : localhost
>> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager The
>> timeout handler will run every : 15s
>> 2008-06-11 10:41:48,648 [-] [WrapperSimpleAppMain] INFO
>> SynapseInitializationModule Initializing Synapse at : Wed Jun 11 10:41:48
>> CEST 2008
>> 2008-06-11 10:41:48,663 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> SynapseInitializationModule Loading mediator extensions...
>> 2008-06-11 10:41:48,663 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> SynapseInitializationModule Initializing the Synapse configuration ...
>> 2008-06-11 10:41:48,663 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> SynapseConfigurationBuilder synapse.properties file is loading from
>> classpath
>> 2008-06-11 10:41:48,679 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> DataSourceRegistrar No DataSources defined for initialization..
>> 2008-06-11 10:41:48,679 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> XMLConfigurationBuilder Generating the Synapse configuration model by
>> parsing the XML configuration
>> 2008-06-11 10:41:48,694 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> ConfigurationFactoryAndSerializerFinder getConfiguration({
>> http://ws.apache.org/ns/synapse}definitions<http://ws.apache.org/ns/synapse%7Ddefinitions>
>> )
>> 2008-06-11 10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> MediatorFactoryFinder Added MediatorFactory class
>> org.apache.synapse.mediators.spring.SpringMediatorFactory to handle {
>> http://ws.apache.org/ns/synapse/spring}spring
>> 2008-06-11 <http://ws.apache.org/ns/synapse/spring%7Dspring2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> MediatorFactoryFinder Added MediatorFactory class
>> org.apache.synapse.mediators.bsf.ScriptMediatorFactory to handle {
>> http://ws.apache.org/ns/synapse}script
>> 2008-06-11 <http://ws.apache.org/ns/synapse%7Dscript2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> MediatorFactoryFinder Added MediatorFactory class
>> org.apache.synapse.mediators.throttle.ThrottleMediatorFactory to handle {
>> http://ws.apache.org/ns/synapse}throttle
>> 2008-06-11 <http://ws.apache.org/ns/synapse%7Dthrottle2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> MediatorFactoryFinder Added MediatorFactory class
>> org.apache.synapse.mediators.xquery.XQueryMediatorFactory to handle {
>> http://ws.apache.org/ns/synapse}xquery
>> 2008-06-11 <http://ws.apache.org/ns/synapse%7Dxquery2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> MediatorFactoryFinder getMediator({
>> http://ws.apache.org/ns/synapse}property<http://ws.apache.org/ns/synapse%7Dproperty>
>> )
>> 2008-06-11 10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}log<http://ws.apache.org/ns/synapse%7Dlog>
>> )
>> 2008-06-11 10:41:48,819 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}send<http://ws.apache.org/ns/synapse%7Dsend>
>> )
>> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> SynapseConfigurationBuilder Loaded Synapse configuration from :
>> C:\TEMP\SYNAPS~1.2\bin\..\repository\conf\sample\synapse_sample_0.xml
>> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> SynapseConfiguration Initializing the Synapse Configuration
>> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> SequenceMediator Initializing child mediators
>> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> SequenceMediator Initializing child mediators
>> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> SynapseInitializationModule Deploying the Synapse service..
>> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> SynapseInitializationModule Synapse server name : localhost
>> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> SynapseInitializationModule Deploying Proxy services...
>> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> SynapseInitializationModule Synapse initialized successfully...!
>> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOSSLSender Loading Identity Keystore from :
>> repository/conf/gsb-acc.jks
>> 2008-06-11 10:41:49,007 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOSSLSender Loading Trust Keystore from :
>> repository/conf/gsb-trust.jks
>> 2008-06-11 10:41:49,069 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOSender HTTPS Sender starting
>> 2008-06-11 10:41:49,194 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOSender HTTP Sender starting
>> 2008-06-11 10:41:49,194 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> JMSSender JMS Sender started
>> 2008-06-11 10:41:49,194 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> VFSTransportSender VFS Sender started
>> 2008-06-11 10:41:49,210 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] DEBUG
>> NativeWorkerPool Using native util.concurrent package..
>> 2008-06-11 10:41:49,241 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] DEBUG
>> NativeWorkerPool Using native util.concurrent package..
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> ServerManager Starting transport mailto
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> NativeWorkerPool Using native util.concurrent package..
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> MailTransportListener MAILTO Listener started
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> ServerManager Starting transport https on port 8443
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOSSLListener Loading Identity Keystore from : lib/identity.jks
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOSSLListener Loading Trust Keystore from : lib/trust.jks
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> HttpCoreNIOListener Starting Listener...
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOListener HTTPS Listener starting on port : 8443
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> ServerManager Starting transport http on port 8080
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> HttpCoreNIOListener Starting Listener...
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> HttpCoreNIOListener HTTP Listener starting on port : 8080
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> ServerManager Starting transport vfs
>> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
>> NativeWorkerPool Using native util.concurrent package..
>> 2008-06-11 10:41:49,429 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> VFSTransportListener VFS Listener started
>> 2008-06-11 10:41:49,429 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
>> ServerManager Ready for processing
>> 2008-06-11 10:41:49,460 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] DEBUG
>> NativeWorkerPool Using native util.concurrent package..
>> 2008-06-11 10:41:49,476 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] DEBUG
>> NativeWorkerPool Using native util.concurrent package..
>> 2008-06-11 10:42:38,351 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> ServerHandler HTTP connection [/127.0.0.1:51337]: Connected
>> 2008-06-11 10:42:38,476 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> ServerHandler HTTP connection [/127.0.0.1:51337]: POST / HTTP/1.1
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> headers >> POST / HTTP/1.1
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> headers >> Content-Type: text/xml; charset=UTF-8
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> headers >> SOAPAction: "urn:getQuote"
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> headers >> User-Agent: Axis2
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> headers >> Host: localhost:8080
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> headers >> Transfer-Encoding: chunked
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Input ready
>> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Content decoder
>> [chunk-coded; completed: false]
>> 2008-06-11 10:42:38,538 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Input ready
>> 2008-06-11 10:42:38,538 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Content decoder
>> [chunk-coded; completed: true]
>> 2008-06-11 10:42:38,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> TransportUtils createSOAPEnvelope using Builder (class
>> org.apache.axis2.builder.SOAPBuilder) selected from type (text/xml)
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SynapseMessageReceiver Synapse received a new message for message
>> mediation...
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SynapseMessageReceiver Received To:
>> http://localhost:9000/soap/SimpleStockQuoteService
>> 2008-06-11 <http://localhost:9000/soap/SimpleStockQuoteService2008-06-11>10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SynapseMessageReceiver SOAPAction: urn:getQuote
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SynapseMessageReceiver WSA-Action: urn:getQuote
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> Axis2SynapseEnvironment Injecting MessageContext
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> Axis2SynapseEnvironment Using Main Sequence for injected message
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SequenceMediator Start : Sequence <main>
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SequenceMediator Sequence <SequenceMediator> :: mediate()
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> PropertyMediator Start : Property mediator
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> PropertyMediator Setting property : FORCE_HTTP_1.0 at scope : axis2-client
>> to : true (i.e. constant : true)
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> PropertyMediator End : Property mediator
>> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> LogMediator Start : Log mediator
>> 2008-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [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:FBFE5786B51B3C8B4F1213173758175, 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:FBFE5786B51B3C8B4F1213173758175</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-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> LogMediator End : Log mediator
>> 2008-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SendMediator Start : Send mediator
>> 2008-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SendMediator Sending request message using implicit message properties..
>> Sending To: http://localhost:9000/soap/SimpleStockQuoteService
>> SOAPAction: urn:getQuote
>> 2008-06-11 10:42:38,741 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> Axis2FlexibleMEPClient Sending [add = false] [sec = false] [rm = false] [to
>> Address: http://localhost:9000/soap/SimpleStockQuoteService]
>> 2008-06-11 10:42:38,757 [192.168.0.84-PAVEL3] [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-06-11 10:42:38,757 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SynapseCallbackReceiver Callback added. Total callbacks waiting for : 1
>> 2008-06-11 10:42:38,773 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> ConnectionPool No connections available for reuse
>> 2008-06-11 10:42:38,788 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Connected
>> (org.apache.synapse.transport.nhttp.Axis2HttpRequest@3228a1)
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler ClientHandler connected : [localhost/127.0.0.1:9000]
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> SOAPMessageFormatter start writeTo()
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> SOAPMessageFormatter preserve=false
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> SOAPMessageFormatter isOptimized=false
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> SOAPMessageFormatter isDoingSWA=false
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> SOAPMessageFormatter end writeTo()
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> SOAPMessageFormatter contentType from the OMOutputFormat =text/xml
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> SOAPMessageFormatter contentType returned =text/xml; charset=UTF-8
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> headers >> POST http://localhost:9000/soap/SimpleStockQuoteServiceHTTP/1.0
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> headers >> Host: localhost:8080
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> headers >> SOAPAction: urn:getQuote
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> headers >> Content-Type: text/xml; charset=UTF-8
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> headers >> Content-Length: 346
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> headers >> Connection: Keep-Alive
>> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> headers >> User-Agent: Synapse-HttpComponents-NIO
>> 2008-06-11 10:42:38,819 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Output ready
>> 2008-06-11 10:42:38,819 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Content encoder
>> [content length: 346; pos: 0; completed: false]
>> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> HttpCoreNIOSender A new connection established to : localhost:9000
>> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> Axis2HttpRequest start streaming outgoing http request
>> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SOAPMessageFormatter start writeTo()
>> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SOAPMessageFormatter preserve=false
>> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SOAPMessageFormatter isOptimized=false
>> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SOAPMessageFormatter isDoingSWA=false
>> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SOAPMessageFormatter end writeTo()
>> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Output ready
>> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Content encoder
>> [content length: 346; pos: 0; completed: true]
>> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Request ready
>> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1] ERROR
>> HttpCoreNIOSender IO Error while submiting request message for sending
>> org.apache.axis2.AxisFault: problem accessing the parser. Parser already
>> accessed!
>> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
>> at
>> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
>> at
>> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
>> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
>> at
>> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.send(DynamicAxisOperation.java:190)
>> at
>> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.executeImpl(DynamicAxisOperation.java:174)
>> at
>> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>> at
>> org.apache.synapse.core.axis2.Axis2FlexibleMEPClient.send(Axis2FlexibleMEPClient.java:288)
>> at
>> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:57)
>> at
>> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.send(Axis2SynapseEnvironment.java:222)
>> at
>> org.apache.synapse.mediators.builtin.SendMediator.mediate(SendMediator.java:154)
>> at
>> org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:58)
>> at
>> org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:125)
>> at
>> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:176)
>> at
>> org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:89)
>> at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
>> at
>> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
>> 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(Unknown
>> Source)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
>> Source)
>> at java.lang.Thread.run(Unknown Source)
>> Caused by: javax.xml.stream.XMLStreamException: problem accessing the
>> parser. Parser already accessed!
>> at
>> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:960)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serializeNode(StreamingOMSerializer.java:100)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:68)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:57)
>> at
>> org.apache.axiom.om.impl.util.OMSerializerUtil.serializeByPullStream(OMSerializerUtil.java:548)
>> at
>> org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl.internalSerialize(SOAPEnvelopeImpl.java:232)
>> at
>> org.apache.axiom.om.impl.llom.OMElementImpl.internalSerializeAndConsume(OMElementImpl.java:947)
>> at
>> org.apache.axiom.om.impl.llom.OMNodeImpl.serializeAndConsume(OMNodeImpl.java:471)
>> at
>> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:79)
>> ... 22 more
>> Caused by: java.lang.IllegalStateException: Parser already accessed!
>> at
>> org.apache.axiom.om.impl.builder.StAXBuilder.getParser(StAXBuilder.java:497)
>> at
>> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:958)
>> ... 30 more
>> 2008-06-11 10:42:39,148 [192.168.0.84-PAVEL3] [HttpServerWorker-1] ERROR
>> Axis2Sender Unexpected error during sending message out
>> org.apache.axis2.AxisFault: IO Error while submiting request message for
>> sending
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.handleException(HttpCoreNIOSender.java:550)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:351)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
>> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
>> at
>> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.send(DynamicAxisOperation.java:190)
>> at
>> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.executeImpl(DynamicAxisOperation.java:174)
>> at
>> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>> at
>> org.apache.synapse.core.axis2.Axis2FlexibleMEPClient.send(Axis2FlexibleMEPClient.java:288)
>> at
>> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:57)
>> at
>> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.send(Axis2SynapseEnvironment.java:222)
>> at
>> org.apache.synapse.mediators.builtin.SendMediator.mediate(SendMediator.java:154)
>> at
>> org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:58)
>> at
>> org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:125)
>> at
>> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:176)
>> at
>> org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:89)
>> at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
>> at
>> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
>> 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(Unknown
>> Source)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
>> Source)
>> at java.lang.Thread.run(Unknown Source)
>> Caused by: org.apache.axis2.AxisFault: problem accessing the parser. Parser
>> already accessed!
>> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
>> at
>> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
>> at
>> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
>> ... 20 more
>> Caused by: javax.xml.stream.XMLStreamException: problem accessing the
>> parser. Parser already accessed!
>> at
>> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:960)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serializeNode(StreamingOMSerializer.java:100)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:68)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:57)
>> at
>> org.apache.axiom.om.impl.util.OMSerializerUtil.serializeByPullStream(OMSerializerUtil.java:548)
>> at
>> org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl.internalSerialize(SOAPEnvelopeImpl.java:232)
>> at
>> org.apache.axiom.om.impl.llom.OMElementImpl.internalSerializeAndConsume(OMElementImpl.java:947)
>> at
>> org.apache.axiom.om.impl.llom.OMNodeImpl.serializeAndConsume(OMNodeImpl.java:471)
>> at
>> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:79)
>> ... 22 more
>> Caused by: java.lang.IllegalStateException: Parser already accessed!
>> at
>> org.apache.axiom.om.impl.builder.StAXBuilder.getParser(StAXBuilder.java:497)
>> at
>> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:958)
>> ... 30 more
>> 2008-06-11 10:42:39,288 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
>> SynapseMessageReceiver Executing fault handler due to exception encountered
>> 2008-06-11 10:42:39,304 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
>> FaultHandler Fault handler - setting ERROR_MESSAGE : Unexpected error during
>> sending message out
>> 2008-06-11 10:42:39,304 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
>> FaultHandler Fault handler - setting ERROR_DETAIL :
>> org.apache.synapse.SynapseException: Unexpected error during sending message
>> out
>> at
>> org.apache.synapse.core.axis2.Axis2Sender.handleException(Axis2Sender.java:147)
>> at
>> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:64)
>> at
>> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.send(Axis2SynapseEnvironment.java:222)
>> at
>> org.apache.synapse.mediators.builtin.SendMediator.mediate(SendMediator.java:154)
>> at
>> org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:58)
>> at
>> org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:125)
>> at
>> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:176)
>> at
>> org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:89)
>> at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
>> at
>> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
>> 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(Unknown
>> Source)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
>> Source)
>> at java.lang.Thread.run(Unknown Source)
>> Caused by: org.apache.axis2.AxisFault: IO Error while submiting request
>> message for sending
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.handleException(HttpCoreNIOSender.java:550)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:351)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
>> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
>> at
>> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.send(DynamicAxisOperation.java:190)
>> at
>> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.executeImpl(DynamicAxisOperation.java:174)
>> at
>> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
>> at
>> org.apache.synapse.core.axis2.Axis2FlexibleMEPClient.send(Axis2FlexibleMEPClient.java:288)
>> at
>> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:57)
>> ... 13 more
>> Caused by: org.apache.axis2.AxisFault: problem accessing the parser. Parser
>> already accessed!
>> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
>> at
>> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
>> at
>> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
>> at
>> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
>> ... 20 more
>> Caused by: javax.xml.stream.XMLStreamException: problem accessing the
>> parser. Parser already accessed!
>> at
>> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:960)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serializeNode(StreamingOMSerializer.java:100)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:68)
>> at
>> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:57)
>> at
>> org.apache.axiom.om.impl.util.OMSerializerUtil.serializeByPullStream(OMSerializerUtil.java:548)
>> at
>> org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl.internalSerialize(SOAPEnvelopeImpl.java:232)
>> at
>> org.apache.axiom.om.impl.llom.OMElementImpl.internalSerializeAndConsume(OMElementImpl.java:947)
>> at
>> org.apache.axiom.om.impl.llom.OMNodeImpl.serializeAndConsume(OMNodeImpl.java:471)
>> at
>> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:79)
>> ... 22 more
>> Caused by: java.lang.IllegalStateException: Parser already accessed!
>> at
>> org.apache.axiom.om.impl.builder.StAXBuilder.getParser(StAXBuilder.java:497)
>> at
>> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:958)
>> ... 30 more
>>
>> 2008-06-11 10:42:39,444 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
>> FaultHandler Fault handler - setting ERROR_EXCEPTION :
>> org.apache.synapse.SynapseException: Unexpected error during sending message
>> out
>> 2008-06-11 10:42:39,460 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
>> MediatorFaultHandler Executing fault handler mediator : fault
>> 2008-06-11 10:42:39,460 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SequenceMediator Start : Sequence <fault>
>> 2008-06-11 10:42:39,476 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SequenceMediator Sequence <SequenceMediator> :: mediate()
>> 2008-06-11 10:42:39,476 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> LogMediator Start : Log mediator
>> 2008-06-11 10:42:39,523 [192.168.0.84-PAVEL3] [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:FBFE5786B51B3C8B4F1213173758175, Direction: request, MESSAGE =
>> Executing default "fault" sequence, ERROR_CODE = 00000, ERROR_MESSAGE =
>> Unexpected error during sending message out, 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-06-11 10:42:39,538 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> LogMediator End : Log mediator
>> 2008-06-11 10:42:39,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> DropMediator Start : Drop mediator
>> 2008-06-11 10:42:39,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> DropMediator End : Drop mediator
>> 2008-06-11 10:42:39,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
>> SequenceMediator End : Sequence <fault>
>> 2008-06-11 10:43:08,023 [192.168.0.84-PAVEL3] [I/O dispatcher 7] ERROR
>> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Stávající
>> pøipojení bylo vynucenì ukonèeno vzdáleným hostitelem
>> java.io.IOException: Stávající pøipojení bylo vynucenì ukonèeno vzdáleným
>> hostitelem
>> at sun.nio.ch.SocketDispatcher.read0(Native Method)
>> at sun.nio.ch.SocketDispatcher.read(Unknown Source)
>> at sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source)
>> at sun.nio.ch.IOUtil.read(Unknown Source)
>> at sun.nio.ch.SocketChannelImpl.read(Unknown Source)
>> at
>> org.apache.http.impl.nio.reactor.SessionInputBufferImpl.fill(SessionInputBufferImpl.java:85)
>> at
>> org.apache.http.impl.nio.codecs.AbstractMessageParser.fillBuffer(AbstractMessageParser.java:97)
>> at
>> org.apache.http.impl.nio.DefaultNHttpServerConnection.consumeInput(DefaultNHttpServerConnection.java:110)
>> at
>> org.apache.synapse.transport.nhttp.LoggingNHttpServerIOTarget.consumeInput(LoggingNHttpServerIOTarget.java:154)
>> at
>> org.apache.http.impl.nio.DefaultServerIOEventDispatch.inputReady(DefaultServerIOEventDispatch.java:98)
>> at
>> org.apache.http.impl.nio.reactor.BaseIOReactor.readable(BaseIOReactor.java:98)
>> at
>> org.apache.http.impl.nio.reactor.AbstractIOReactor.processEvent(AbstractIOReactor.java:195)
>> at
>> org.apache.http.impl.nio.reactor.AbstractIOReactor.processEvents(AbstractIOReactor.java:180)
>> at
>> org.apache.http.impl.nio.reactor.AbstractIOReactor.execute(AbstractIOReactor.java:142)
>> at
>> org.apache.http.impl.nio.reactor.BaseIOReactor.execute(BaseIOReactor.java:70)
>> at
>> org.apache.http.impl.nio.reactor.AbstractMultiworkerIOReactor$Worker.run(AbstractMultiworkerIOReactor.java:318)
>> at java.lang.Thread.run(Unknown Source)
>> 2008-06-11 10:43:08,023 [192.168.0.84-PAVEL3] [I/O dispatcher 7] ERROR
>> ServerHandler I/O error: Stávající pøipojení bylo vynucenì ukonèeno
>> vzdáleným hostitelem
>> 2008-06-11 10:43:08,023 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
>> ServerHandler HTTP connection [closed]: Closed
>> 2008-06-11 10:43:15,273 [192.168.0.84-PAVEL3] [Thread-4] INFO
>> SynapseServer Shutting down Apache Synapse ...
>> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] INFO
>> HttpCoreNIOListener HTTPS Listener Shutdown
>> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [Thread-4] INFO
>> MailTransportListener MAILTO Listener Shutdown
>> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] INFO
>> HttpCoreNIOListener HTTP Listener Shutdown
>> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [Thread-4] INFO
>> VFSTransportListener VFS Listener Shutdown
>> 2008-06-11 10:43:15,304 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] INFO
>> HttpCoreNIOSender HTTPS Sender Shutdown
>> 2008-06-11 10:43:15,304 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
>> ClientHandler HTTP connection [closed]: Closed
>> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] INFO
>> HttpCoreNIOSender HTTP Sender Shutdown
>> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] INFO JMSSender
>> JMS Sender Shutdown
>> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] INFO
>> VFSTransportSender VFS Sender Shutdown
>> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] DEBUG
>> SynapseConfiguration Destroying the Synapse Configuration
>> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] DEBUG
>> SequenceMediator Destroying child mediators
>> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] DEBUG
>> SequenceMediator Destroying child mediators
>> 2008-06-11 10:43:15,663 [192.168.0.84-PAVEL3] [Thread-4] INFO
>> SynapseServer Shutdown complete
>> 2008-06-11 10:43:15,663 [192.168.0.84-PAVEL3] [Thread-4] INFO
>> SynapseServer Halting JVM
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> SynapseCallbackReceiver Callback removed. Pending callbacks count : 0
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
>> FaultHandler Fault handler - setting ERROR_MESSAGE : Abnormal connection
>> close
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
>> FaultHandler Fault handler - setting ERROR_DETAIL : java.lang.Exception:
>> Abnormal connection close
>> 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(Unknown
>> Source)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
>> Source)
>> at java.lang.Thread.run(Unknown Source)
>>
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
>> FaultHandler Fault handler - setting ERROR_EXCEPTION : java.lang.Exception:
>> Abnormal connection close
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
>> MediatorFaultHandler Executing fault handler mediator : fault
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> SequenceMediator Start : Sequence <fault>
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> SequenceMediator Sequence <SequenceMediator> :: mediate()
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> LogMediator Start : Log mediator
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] INFO
>> LogMediator To: , WSAction: urn:getQuote, SOAPAction: urn:getQuote, ReplyTo:
>> http://www.w3.org/2005/08/addressing/anonymous, MessageID:
>> urn:uuid:FBFE5786B51B3C8B4F1213173758175, Direction: request, MESSAGE =
>> Executing default "fault" sequence, ERROR_CODE = 00000, ERROR_MESSAGE =
>> Abnormal connection close, 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-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> LogMediator End : Log mediator
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> DropMediator Start : Drop mediator
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> DropMediator End : Drop mediator
>> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
>> SequenceMediator End : Sequence <fault>
>>
>>
>
>
--
Best regards,
Pavel Krehula
Re: Error when forcing HTTP/1.0
Posted by Ruwan Linton <ru...@gmail.com>.
Hi Pavel,
This is due to the last minute performance improvements that we have done.
We are in the process of providing a switch in the send mediator to turn
that perf improvement off in the JIRA [image:
Improvement]<https://issues.apache.org/jira/browse/SYNAPSE-357>
SYNAPSE-357 <https://issues.apache.org/jira/browse/SYNAPSE-357>
Once the fix to that is available you will be able to get this working. I
will work on this issue ASAP. Please keep an eye on that issue.
Thanks,
Ruwan
On Wed, Jun 11, 2008 at 2:30 PM, Pavel Křehula <pa...@nlm.cz> wrote:
> Hello,
> I have problem using HTTP/1.0 in Synapse 1.2
>
> Using sample 0:
> if I modify configuration of sample 0 and add
> <property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
>
> <!-- Introduction to Synapse -->
> <definitions xmlns="http://ws.apache.org/ns/synapse">
> <property scope="axis2-client" name="FORCE_HTTP_1.0" value="true"/>
>
> <!-- log all attributes of messages passing through -->
> <log level="full"/>
>
> <!-- Send the messageto implicit destination -->
> <send/>
> </definitions>
>
> to force using HTTP/1.0 and switch off chunked transfer encoding I get
> following error:
>
> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1] ERROR
> HttpCoreNIOSender IO Error while submiting request message for sending
> org.apache.axis2.AxisFault: problem accessing the parser. Parser already
> accessed!
> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
> at
> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
>
>
> Complete log attached.
> Thanks.
>
> --
> Best regards,
> Pavel Krehula
>
>
> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
> Using Synapse home as : .
> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
> Using the Axis2 Repository : C:\TEMP\SYNAPS~1.2\repository
> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
> Using the axis2.xml : C:\TEMP\SYNAPS~1.2\repository\conf\axis2.xml
> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager
> Using server name : localhost
> 2008-06-11 10:41:47,632 [-] [WrapperSimpleAppMain] INFO ServerManager The
> timeout handler will run every : 15s
> 2008-06-11 10:41:48,648 [-] [WrapperSimpleAppMain] INFO
> SynapseInitializationModule Initializing Synapse at : Wed Jun 11 10:41:48
> CEST 2008
> 2008-06-11 10:41:48,663 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> SynapseInitializationModule Loading mediator extensions...
> 2008-06-11 10:41:48,663 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> SynapseInitializationModule Initializing the Synapse configuration ...
> 2008-06-11 10:41:48,663 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> SynapseConfigurationBuilder synapse.properties file is loading from
> classpath
> 2008-06-11 10:41:48,679 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> DataSourceRegistrar No DataSources defined for initialization..
> 2008-06-11 10:41:48,679 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> XMLConfigurationBuilder Generating the Synapse configuration model by
> parsing the XML configuration
> 2008-06-11 10:41:48,694 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> ConfigurationFactoryAndSerializerFinder getConfiguration({
> http://ws.apache.org/ns/synapse}definitions<http://ws.apache.org/ns/synapse%7Ddefinitions>
> )
> 2008-06-11 10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.spring.SpringMediatorFactory to handle {
> http://ws.apache.org/ns/synapse/spring}spring
> 2008-06-11 <http://ws.apache.org/ns/synapse/spring%7Dspring2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.bsf.ScriptMediatorFactory to handle {
> http://ws.apache.org/ns/synapse}script
> 2008-06-11 <http://ws.apache.org/ns/synapse%7Dscript2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.throttle.ThrottleMediatorFactory to handle {
> http://ws.apache.org/ns/synapse}throttle
> 2008-06-11 <http://ws.apache.org/ns/synapse%7Dthrottle2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> MediatorFactoryFinder Added MediatorFactory class
> org.apache.synapse.mediators.xquery.XQueryMediatorFactory to handle {
> http://ws.apache.org/ns/synapse}xquery
> 2008-06-11 <http://ws.apache.org/ns/synapse%7Dxquery2008-06-11>10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> MediatorFactoryFinder getMediator({
> http://ws.apache.org/ns/synapse}property<http://ws.apache.org/ns/synapse%7Dproperty>
> )
> 2008-06-11 10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}log<http://ws.apache.org/ns/synapse%7Dlog>
> )
> 2008-06-11 10:41:48,819 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}send<http://ws.apache.org/ns/synapse%7Dsend>
> )
> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> SynapseConfigurationBuilder Loaded Synapse configuration from :
> C:\TEMP\SYNAPS~1.2\bin\..\repository\conf\sample\synapse_sample_0.xml
> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> SynapseConfiguration Initializing the Synapse Configuration
> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> SequenceMediator Initializing child mediators
> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> SequenceMediator Initializing child mediators
> 2008-06-11 10:41:48,866 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> SynapseInitializationModule Deploying the Synapse service..
> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> SynapseInitializationModule Synapse server name : localhost
> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> SynapseInitializationModule Deploying Proxy services...
> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> SynapseInitializationModule Synapse initialized successfully...!
> 2008-06-11 10:41:48,976 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOSSLSender Loading Identity Keystore from :
> repository/conf/gsb-acc.jks
> 2008-06-11 10:41:49,007 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOSSLSender Loading Trust Keystore from :
> repository/conf/gsb-trust.jks
> 2008-06-11 10:41:49,069 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOSender HTTPS Sender starting
> 2008-06-11 10:41:49,194 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOSender HTTP Sender starting
> 2008-06-11 10:41:49,194 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> JMSSender JMS Sender started
> 2008-06-11 10:41:49,194 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> VFSTransportSender VFS Sender started
> 2008-06-11 10:41:49,210 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] DEBUG
> NativeWorkerPool Using native util.concurrent package..
> 2008-06-11 10:41:49,241 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] DEBUG
> NativeWorkerPool Using native util.concurrent package..
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> ServerManager Starting transport mailto
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> NativeWorkerPool Using native util.concurrent package..
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> MailTransportListener MAILTO Listener started
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> ServerManager Starting transport https on port 8443
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOSSLListener Loading Identity Keystore from : lib/identity.jks
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOSSLListener Loading Trust Keystore from : lib/trust.jks
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> HttpCoreNIOListener Starting Listener...
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOListener HTTPS Listener starting on port : 8443
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> ServerManager Starting transport http on port 8080
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> HttpCoreNIOListener Starting Listener...
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> HttpCoreNIOListener HTTP Listener starting on port : 8080
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> ServerManager Starting transport vfs
> 2008-06-11 10:41:49,413 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
> NativeWorkerPool Using native util.concurrent package..
> 2008-06-11 10:41:49,429 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> VFSTransportListener VFS Listener started
> 2008-06-11 10:41:49,429 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] INFO
> ServerManager Ready for processing
> 2008-06-11 10:41:49,460 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] DEBUG
> NativeWorkerPool Using native util.concurrent package..
> 2008-06-11 10:41:49,476 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] DEBUG
> NativeWorkerPool Using native util.concurrent package..
> 2008-06-11 10:42:38,351 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> ServerHandler HTTP connection [/127.0.0.1:51337]: Connected
> 2008-06-11 10:42:38,476 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> ServerHandler HTTP connection [/127.0.0.1:51337]: POST / HTTP/1.1
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> headers >> POST / HTTP/1.1
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> headers >> Content-Type: text/xml; charset=UTF-8
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> headers >> SOAPAction: "urn:getQuote"
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> headers >> User-Agent: Axis2
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> headers >> Host: localhost:8080
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> headers >> Transfer-Encoding: chunked
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Input ready
> 2008-06-11 10:42:38,507 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Content decoder
> [chunk-coded; completed: false]
> 2008-06-11 10:42:38,538 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Input ready
> 2008-06-11 10:42:38,538 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Content decoder
> [chunk-coded; completed: true]
> 2008-06-11 10:42:38,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> TransportUtils createSOAPEnvelope using Builder (class
> org.apache.axis2.builder.SOAPBuilder) selected from type (text/xml)
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SynapseMessageReceiver Synapse received a new message for message
> mediation...
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SynapseMessageReceiver Received To:
> http://localhost:9000/soap/SimpleStockQuoteService
> 2008-06-11 <http://localhost:9000/soap/SimpleStockQuoteService2008-06-11>10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SynapseMessageReceiver SOAPAction: urn:getQuote
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SynapseMessageReceiver WSA-Action: urn:getQuote
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> Axis2SynapseEnvironment Injecting MessageContext
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> Axis2SynapseEnvironment Using Main Sequence for injected message
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SequenceMediator Start : Sequence <main>
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SequenceMediator Sequence <SequenceMediator> :: mediate()
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> PropertyMediator Start : Property mediator
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> PropertyMediator Setting property : FORCE_HTTP_1.0 at scope : axis2-client
> to : true (i.e. constant : true)
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> PropertyMediator End : Property mediator
> 2008-06-11 10:42:38,616 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> LogMediator Start : Log mediator
> 2008-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [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:FBFE5786B51B3C8B4F1213173758175, 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:FBFE5786B51B3C8B4F1213173758175</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-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> LogMediator End : Log mediator
> 2008-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SendMediator Start : Send mediator
> 2008-06-11 10:42:38,726 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SendMediator Sending request message using implicit message properties..
> Sending To: http://localhost:9000/soap/SimpleStockQuoteService
> SOAPAction: urn:getQuote
> 2008-06-11 10:42:38,741 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> Axis2FlexibleMEPClient Sending [add = false] [sec = false] [rm = false] [to
> Address: http://localhost:9000/soap/SimpleStockQuoteService]
> 2008-06-11 10:42:38,757 [192.168.0.84-PAVEL3] [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-06-11 10:42:38,757 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SynapseCallbackReceiver Callback added. Total callbacks waiting for : 1
> 2008-06-11 10:42:38,773 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> ConnectionPool No connections available for reuse
> 2008-06-11 10:42:38,788 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Connected
> (org.apache.synapse.transport.nhttp.Axis2HttpRequest@3228a1)
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler ClientHandler connected : [localhost/127.0.0.1:9000]
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> SOAPMessageFormatter start writeTo()
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> SOAPMessageFormatter preserve=false
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> SOAPMessageFormatter isOptimized=false
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> SOAPMessageFormatter isDoingSWA=false
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> SOAPMessageFormatter end writeTo()
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> SOAPMessageFormatter contentType from the OMOutputFormat =text/xml
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> SOAPMessageFormatter contentType returned =text/xml; charset=UTF-8
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> headers >> POST http://localhost:9000/soap/SimpleStockQuoteServiceHTTP/1.0
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> headers >> Host: localhost:8080
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> headers >> SOAPAction: urn:getQuote
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> headers >> Content-Type: text/xml; charset=UTF-8
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> headers >> Content-Length: 346
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> headers >> Connection: Keep-Alive
> 2008-06-11 10:42:38,804 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> headers >> User-Agent: Synapse-HttpComponents-NIO
> 2008-06-11 10:42:38,819 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Output ready
> 2008-06-11 10:42:38,819 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Content encoder
> [content length: 346; pos: 0; completed: false]
> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> HttpCoreNIOSender A new connection established to : localhost:9000
> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> Axis2HttpRequest start streaming outgoing http request
> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter start writeTo()
> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter preserve=false
> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter isOptimized=false
> 2008-06-11 10:42:39,023 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter isDoingSWA=false
> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SOAPMessageFormatter end writeTo()
> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Output ready
> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Content encoder
> [content length: 346; pos: 0; completed: true]
> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler HTTP connection [localhost/127.0.0.1:9000]: Request ready
> 2008-06-11 10:42:39,038 [192.168.0.84-PAVEL3] [HttpServerWorker-1] ERROR
> HttpCoreNIOSender IO Error while submiting request message for sending
> org.apache.axis2.AxisFault: problem accessing the parser. Parser already
> accessed!
> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
> at
> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
> at
> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.send(DynamicAxisOperation.java:190)
> at
> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.executeImpl(DynamicAxisOperation.java:174)
> at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> at
> org.apache.synapse.core.axis2.Axis2FlexibleMEPClient.send(Axis2FlexibleMEPClient.java:288)
> at
> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:57)
> at
> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.send(Axis2SynapseEnvironment.java:222)
> at
> org.apache.synapse.mediators.builtin.SendMediator.mediate(SendMediator.java:154)
> at
> org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:58)
> at
> org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:125)
> at
> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:176)
> at
> org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:89)
> at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
> at
> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
> 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(Unknown
> Source)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
> Source)
> at java.lang.Thread.run(Unknown Source)
> Caused by: javax.xml.stream.XMLStreamException: problem accessing the
> parser. Parser already accessed!
> at
> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:960)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serializeNode(StreamingOMSerializer.java:100)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:68)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:57)
> at
> org.apache.axiom.om.impl.util.OMSerializerUtil.serializeByPullStream(OMSerializerUtil.java:548)
> at
> org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl.internalSerialize(SOAPEnvelopeImpl.java:232)
> at
> org.apache.axiom.om.impl.llom.OMElementImpl.internalSerializeAndConsume(OMElementImpl.java:947)
> at
> org.apache.axiom.om.impl.llom.OMNodeImpl.serializeAndConsume(OMNodeImpl.java:471)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:79)
> ... 22 more
> Caused by: java.lang.IllegalStateException: Parser already accessed!
> at
> org.apache.axiom.om.impl.builder.StAXBuilder.getParser(StAXBuilder.java:497)
> at
> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:958)
> ... 30 more
> 2008-06-11 10:42:39,148 [192.168.0.84-PAVEL3] [HttpServerWorker-1] ERROR
> Axis2Sender Unexpected error during sending message out
> org.apache.axis2.AxisFault: IO Error while submiting request message for
> sending
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.handleException(HttpCoreNIOSender.java:550)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:351)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
> at
> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.send(DynamicAxisOperation.java:190)
> at
> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.executeImpl(DynamicAxisOperation.java:174)
> at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> at
> org.apache.synapse.core.axis2.Axis2FlexibleMEPClient.send(Axis2FlexibleMEPClient.java:288)
> at
> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:57)
> at
> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.send(Axis2SynapseEnvironment.java:222)
> at
> org.apache.synapse.mediators.builtin.SendMediator.mediate(SendMediator.java:154)
> at
> org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:58)
> at
> org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:125)
> at
> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:176)
> at
> org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:89)
> at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
> at
> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
> 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(Unknown
> Source)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
> Source)
> at java.lang.Thread.run(Unknown Source)
> Caused by: org.apache.axis2.AxisFault: problem accessing the parser. Parser
> already accessed!
> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
> at
> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
> ... 20 more
> Caused by: javax.xml.stream.XMLStreamException: problem accessing the
> parser. Parser already accessed!
> at
> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:960)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serializeNode(StreamingOMSerializer.java:100)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:68)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:57)
> at
> org.apache.axiom.om.impl.util.OMSerializerUtil.serializeByPullStream(OMSerializerUtil.java:548)
> at
> org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl.internalSerialize(SOAPEnvelopeImpl.java:232)
> at
> org.apache.axiom.om.impl.llom.OMElementImpl.internalSerializeAndConsume(OMElementImpl.java:947)
> at
> org.apache.axiom.om.impl.llom.OMNodeImpl.serializeAndConsume(OMNodeImpl.java:471)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:79)
> ... 22 more
> Caused by: java.lang.IllegalStateException: Parser already accessed!
> at
> org.apache.axiom.om.impl.builder.StAXBuilder.getParser(StAXBuilder.java:497)
> at
> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:958)
> ... 30 more
> 2008-06-11 10:42:39,288 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
> SynapseMessageReceiver Executing fault handler due to exception encountered
> 2008-06-11 10:42:39,304 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
> FaultHandler Fault handler - setting ERROR_MESSAGE : Unexpected error during
> sending message out
> 2008-06-11 10:42:39,304 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
> FaultHandler Fault handler - setting ERROR_DETAIL :
> org.apache.synapse.SynapseException: Unexpected error during sending message
> out
> at
> org.apache.synapse.core.axis2.Axis2Sender.handleException(Axis2Sender.java:147)
> at
> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:64)
> at
> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.send(Axis2SynapseEnvironment.java:222)
> at
> org.apache.synapse.mediators.builtin.SendMediator.mediate(SendMediator.java:154)
> at
> org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediator.java:58)
> at
> org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.java:125)
> at
> org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2SynapseEnvironment.java:176)
> at
> org.apache.synapse.core.axis2.SynapseMessageReceiver.receive(SynapseMessageReceiver.java:89)
> at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
> at
> org.apache.axis2.transport.http.HTTPTransportUtils.processHTTPPostRequest(HTTPTransportUtils.java:275)
> 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(Unknown
> Source)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
> Source)
> at java.lang.Thread.run(Unknown Source)
> Caused by: org.apache.axis2.AxisFault: IO Error while submiting request
> message for sending
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.handleException(HttpCoreNIOSender.java:550)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:351)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.invoke(HttpCoreNIOSender.java:256)
> at org.apache.axis2.engine.AxisEngine.send(AxisEngine.java:448)
> at
> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.send(DynamicAxisOperation.java:190)
> at
> org.apache.synapse.core.axis2.DynamicAxisOperation$DynamicOperationClient.executeImpl(DynamicAxisOperation.java:174)
> at
> org.apache.axis2.client.OperationClient.execute(OperationClient.java:163)
> at
> org.apache.synapse.core.axis2.Axis2FlexibleMEPClient.send(Axis2FlexibleMEPClient.java:288)
> at
> org.apache.synapse.core.axis2.Axis2Sender.sendOn(Axis2Sender.java:57)
> ... 13 more
> Caused by: org.apache.axis2.AxisFault: problem accessing the parser. Parser
> already accessed!
> at org.apache.axis2.AxisFault.makeFault(AxisFault.java:430)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:83)
> at
> org.apache.synapse.transport.nhttp.Axis2HttpRequest.streamMessageContents(Axis2HttpRequest.java:221)
> at
> org.apache.synapse.transport.nhttp.HttpCoreNIOSender.sendAsyncRequest(HttpCoreNIOSender.java:346)
> ... 20 more
> Caused by: javax.xml.stream.XMLStreamException: problem accessing the
> parser. Parser already accessed!
> at
> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:960)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serializeNode(StreamingOMSerializer.java:100)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:68)
> at
> org.apache.axiom.om.impl.serialize.StreamingOMSerializer.serialize(StreamingOMSerializer.java:57)
> at
> org.apache.axiom.om.impl.util.OMSerializerUtil.serializeByPullStream(OMSerializerUtil.java:548)
> at
> org.apache.axiom.soap.impl.llom.SOAPEnvelopeImpl.internalSerialize(SOAPEnvelopeImpl.java:232)
> at
> org.apache.axiom.om.impl.llom.OMElementImpl.internalSerializeAndConsume(OMElementImpl.java:947)
> at
> org.apache.axiom.om.impl.llom.OMNodeImpl.serializeAndConsume(OMNodeImpl.java:471)
> at
> org.apache.axis2.transport.http.SOAPMessageFormatter.writeTo(SOAPMessageFormatter.java:79)
> ... 22 more
> Caused by: java.lang.IllegalStateException: Parser already accessed!
> at
> org.apache.axiom.om.impl.builder.StAXBuilder.getParser(StAXBuilder.java:497)
> at
> org.apache.axiom.om.impl.llom.OMStAXWrapper.next(OMStAXWrapper.java:958)
> ... 30 more
>
> 2008-06-11 10:42:39,444 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
> FaultHandler Fault handler - setting ERROR_EXCEPTION :
> org.apache.synapse.SynapseException: Unexpected error during sending message
> out
> 2008-06-11 10:42:39,460 [192.168.0.84-PAVEL3] [HttpServerWorker-1] WARN
> MediatorFaultHandler Executing fault handler mediator : fault
> 2008-06-11 10:42:39,460 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SequenceMediator Start : Sequence <fault>
> 2008-06-11 10:42:39,476 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SequenceMediator Sequence <SequenceMediator> :: mediate()
> 2008-06-11 10:42:39,476 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> LogMediator Start : Log mediator
> 2008-06-11 10:42:39,523 [192.168.0.84-PAVEL3] [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:FBFE5786B51B3C8B4F1213173758175, Direction: request, MESSAGE =
> Executing default "fault" sequence, ERROR_CODE = 00000, ERROR_MESSAGE =
> Unexpected error during sending message out, 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-06-11 10:42:39,538 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> LogMediator End : Log mediator
> 2008-06-11 10:42:39,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> DropMediator Start : Drop mediator
> 2008-06-11 10:42:39,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> DropMediator End : Drop mediator
> 2008-06-11 10:42:39,554 [192.168.0.84-PAVEL3] [HttpServerWorker-1] DEBUG
> SequenceMediator End : Sequence <fault>
> 2008-06-11 10:43:08,023 [192.168.0.84-PAVEL3] [I/O dispatcher 7] ERROR
> ServerHandler HTTP connection [127.0.0.1/127.0.0.1:51337]: Stávající
> pøipojení bylo vynucenì ukonèeno vzdáleným hostitelem
> java.io.IOException: Stávající pøipojení bylo vynucenì ukonèeno vzdáleným
> hostitelem
> at sun.nio.ch.SocketDispatcher.read0(Native Method)
> at sun.nio.ch.SocketDispatcher.read(Unknown Source)
> at sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source)
> at sun.nio.ch.IOUtil.read(Unknown Source)
> at sun.nio.ch.SocketChannelImpl.read(Unknown Source)
> at
> org.apache.http.impl.nio.reactor.SessionInputBufferImpl.fill(SessionInputBufferImpl.java:85)
> at
> org.apache.http.impl.nio.codecs.AbstractMessageParser.fillBuffer(AbstractMessageParser.java:97)
> at
> org.apache.http.impl.nio.DefaultNHttpServerConnection.consumeInput(DefaultNHttpServerConnection.java:110)
> at
> org.apache.synapse.transport.nhttp.LoggingNHttpServerIOTarget.consumeInput(LoggingNHttpServerIOTarget.java:154)
> at
> org.apache.http.impl.nio.DefaultServerIOEventDispatch.inputReady(DefaultServerIOEventDispatch.java:98)
> at
> org.apache.http.impl.nio.reactor.BaseIOReactor.readable(BaseIOReactor.java:98)
> at
> org.apache.http.impl.nio.reactor.AbstractIOReactor.processEvent(AbstractIOReactor.java:195)
> at
> org.apache.http.impl.nio.reactor.AbstractIOReactor.processEvents(AbstractIOReactor.java:180)
> at
> org.apache.http.impl.nio.reactor.AbstractIOReactor.execute(AbstractIOReactor.java:142)
> at
> org.apache.http.impl.nio.reactor.BaseIOReactor.execute(BaseIOReactor.java:70)
> at
> org.apache.http.impl.nio.reactor.AbstractMultiworkerIOReactor$Worker.run(AbstractMultiworkerIOReactor.java:318)
> at java.lang.Thread.run(Unknown Source)
> 2008-06-11 10:43:08,023 [192.168.0.84-PAVEL3] [I/O dispatcher 7] ERROR
> ServerHandler I/O error: Stávající pøipojení bylo vynucenì ukonèeno
> vzdáleným hostitelem
> 2008-06-11 10:43:08,023 [192.168.0.84-PAVEL3] [I/O dispatcher 7] DEBUG
> ServerHandler HTTP connection [closed]: Closed
> 2008-06-11 10:43:15,273 [192.168.0.84-PAVEL3] [Thread-4] INFO
> SynapseServer Shutting down Apache Synapse ...
> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] INFO
> HttpCoreNIOListener HTTPS Listener Shutdown
> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [Thread-4] INFO
> MailTransportListener MAILTO Listener Shutdown
> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [HttpCoreNIOListener] INFO
> HttpCoreNIOListener HTTP Listener Shutdown
> 2008-06-11 10:43:15,288 [192.168.0.84-PAVEL3] [Thread-4] INFO
> VFSTransportListener VFS Listener Shutdown
> 2008-06-11 10:43:15,304 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] INFO
> HttpCoreNIOSender HTTPS Sender Shutdown
> 2008-06-11 10:43:15,304 [192.168.0.84-PAVEL3] [I/O dispatcher 3] DEBUG
> ClientHandler HTTP connection [closed]: Closed
> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [HttpCoreNIOSender] INFO
> HttpCoreNIOSender HTTP Sender Shutdown
> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] INFO JMSSender
> JMS Sender Shutdown
> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] INFO
> VFSTransportSender VFS Sender Shutdown
> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] DEBUG
> SynapseConfiguration Destroying the Synapse Configuration
> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] DEBUG
> SequenceMediator Destroying child mediators
> 2008-06-11 10:43:15,648 [192.168.0.84-PAVEL3] [Thread-4] DEBUG
> SequenceMediator Destroying child mediators
> 2008-06-11 10:43:15,663 [192.168.0.84-PAVEL3] [Thread-4] INFO
> SynapseServer Shutdown complete
> 2008-06-11 10:43:15,663 [192.168.0.84-PAVEL3] [Thread-4] INFO
> SynapseServer Halting JVM
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> SynapseCallbackReceiver Callback removed. Pending callbacks count : 0
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
> FaultHandler Fault handler - setting ERROR_MESSAGE : Abnormal connection
> close
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
> FaultHandler Fault handler - setting ERROR_DETAIL : java.lang.Exception:
> Abnormal connection close
> 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(Unknown
> Source)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown
> Source)
> at java.lang.Thread.run(Unknown Source)
>
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
> FaultHandler Fault handler - setting ERROR_EXCEPTION : java.lang.Exception:
> Abnormal connection close
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] WARN
> MediatorFaultHandler Executing fault handler mediator : fault
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> SequenceMediator Start : Sequence <fault>
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> SequenceMediator Sequence <SequenceMediator> :: mediate()
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> LogMediator Start : Log mediator
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] INFO
> LogMediator To: , WSAction: urn:getQuote, SOAPAction: urn:getQuote, ReplyTo:
> http://www.w3.org/2005/08/addressing/anonymous, MessageID:
> urn:uuid:FBFE5786B51B3C8B4F1213173758175, Direction: request, MESSAGE =
> Executing default "fault" sequence, ERROR_CODE = 00000, ERROR_MESSAGE =
> Abnormal connection close, 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-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> LogMediator End : Log mediator
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> DropMediator Start : Drop mediator
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> DropMediator End : Drop mediator
> 2008-06-11 10:43:15,710 [192.168.0.84-PAVEL3] [HttpClientWorker-1] DEBUG
> SequenceMediator End : Sequence <fault>
>
>
--
Ruwan Linton
http://www.wso2.org - "Oxygenating the Web Services Platform"