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)
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 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 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 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 10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}property)
2008-06-11 10:41:48,804 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}log)
2008-06-11 10:41:48,819 [192.168.0.84-PAVEL3] [WrapperSimpleAppMain] DEBUG
MediatorFactoryFinder getMediator({http://ws.apache.org/ns/synapse}send)
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 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 ([EMAIL
PROTECTED])
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/SimpleStockQuoteService HTTP/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>
