Hi Ruwan,
You can find the debug log below:
There is one major difference between my earlier posted configuration and
the
one which I used to create this DEBUG log and that is the use of an XSLT
mediator in the outSequence to transform the content of the MyWebservice I
am
calling. And this XSLT transformation is going wrong, because of a
deliberately
created error for testing purposes. As you will see in the debug log this
error
will throw a NPE. So the actual outSequence looks like this:
<outSequence>
<xslt key="wrong-xslt" />
<send />
</outSequence>
2009-08-14 11:05:41,511 [127.0.1.1-LAPTOP] [main] INFO ServerManager Ready
for
processing
2009-08-14 11:05:48,386 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG
ServerHandler HTTP connection [/127.0.0.1:45916]: Connected
2009-08-14 11:05:48,411 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG
ServerHandler HTTP connection [/127.0.0.1:45916]: POST /soap/Proxy1 HTTP/1.1
2009-08-14 11:05:48,436 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG headers
>>
POST /soap/Proxy1 HTTP/1.1
2009-08-14 11:05:48,436 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG headers
>>
User-Agent: Jakarta Commons-HttpClient/3.1
2009-08-14 11:05:48,436 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG headers
>>
Host: localhost:8280
2009-08-14 11:05:48,436 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG headers
>>
Content-Length: 5961
2009-08-14 11:05:48,436 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG headers
>>
Content-Type: soap/xml; charset=utf-8
2009-08-14 11:05:48,436 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG
ServerHandler HTTP connection [localhost/127.0.0.1:45916]: Input ready
2009-08-14 11:05:48,437 [127.0.1.1-LAPTOP] [I/O dispatcher 8] DEBUG
ServerHandler HTTP connection [localhost/127.0.0.1:45916]: Content decoder
[content length: 5961; pos: 5961; completed: true]
2009-08-14 11:05:48,449 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
TransportUtils Could not find a Builder for type (soap/xml). Using SOAP.
2009-08-14 11:05:48,472 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
ProxyServiceMessageReceiver Proxy Service Proxy1 received a new message from
:
127.0.0.1
2009-08-14 11:05:48,472 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
ProxyServiceMessageReceiver Message To: /soap/Proxy1
2009-08-14 11:05:48,472 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
ProxyServiceMessageReceiver SOAPAction: null
2009-08-14 11:05:48,472 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
ProxyServiceMessageReceiver WSA-Action: null
2009-08-14 11:05:48,475 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
ProxyServiceMessageReceiver Setting specified anonymous fault-sequence for
proxy
2009-08-14 11:05:48,475 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
ProxyServiceMessageReceiver Using the anonymous in-sequence of the proxy
service
for mediation
2009-08-14 11:05:48,475 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SequenceMediator Start : Sequence <anonymous>
2009-08-14 11:05:48,475 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SequenceMediator Sequence <SequenceMediator> :: mediate()
2009-08-14 11:05:48,475 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SendMediator Start : Send mediator
2009-08-14 11:05:48,475 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
AddressEndpoint Start : Address Endpoint
2009-08-14 11:05:48,475 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
AddressEndpoint Sending message to endpoint : AnonymousEndpoint resolves to
address = http://localhost:8300/soap/MyWebService
2009-08-14 11:05:48,476 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
AddressEndpoint SOAPAction: null
2009-08-14 11:05:48,476 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
AddressEndpoint WSA-Action: null
2009-08-14 11:05:48,494 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
Axis2FlexibleMEPClient Sending [add = false] [sec = false] [rm = false]
[mtom =
false] [swa = false] [format = soap11] [force soap11=true] [force
soap12=false]
[pox=false] [get=false] [encoding=null] [to Address: /soap/Proxy1]
2009-08-14 11:05:48,520 [127.0.1.1-LAPTOP] [HttpServerWorker-1] INFO
TimeoutHandler This engine will expire all callbacks after : 86400 seconds,
irrespective of the timeout action, after the specified or optional timeout
2009-08-14 11:05:48,527 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SynapseCallbackReceiver Callback added. Total callbacks waiting for : 1
2009-08-14 11:05:48,534 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
ConnectionPool No connections available for reuse
2009-08-14 11:05:48,540 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Connected
(org.apache.synapse.transport.nhttp.axis2httprequ...@1e46a68)
2009-08-14 11:05:48,541 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler ClientHandler connected : [localhost/127.0.0.1:8300]
2009-08-14 11:05:48,541 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
SOAPMessageFormatter contentType from the OMOutputFormat =text/xml
2009-08-14 11:05:48,542 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
SOAPMessageFormatter contentType returned =text/xml; charset=utf-8
2009-08-14 11:05:48,545 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
>>
POST http://localhost:8300/soap/MyWebService HTTP/1.1
2009-08-14 11:05:48,545 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
>>
Host: localhost:8280
2009-08-14 11:05:48,545 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
>>
Content-Type: text/xml; charset=utf-8
2009-08-14 11:05:48,545 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
>>
Transfer-Encoding: chunked
2009-08-14 11:05:48,545 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
>>
Connection: Keep-Alive
2009-08-14 11:05:48,545 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
>>
User-Agent: Synapse-HttpComponents-NIO
2009-08-14 11:05:48,546 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Output ready
2009-08-14 11:05:48,546 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Content encoder
[chunk-coded; completed: false]
2009-08-14 11:05:48,546 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
HttpCoreNIOSender A new connection established to : localhost:8300
2009-08-14 11:05:48,546 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
Axis2HttpRequest start streaming outgoing http request
2009-08-14 11:05:48,546 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SOAPMessageFormatter start writeTo()
2009-08-14 11:05:48,547 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SOAPMessageFormatter preserve=false
2009-08-14 11:05:48,547 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SOAPMessageFormatter isOptimized=false
2009-08-14 11:05:48,547 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SOAPMessageFormatter isDoingSWA=false
2009-08-14 11:05:48,616 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SOAPMessageFormatter end writeTo()
2009-08-14 11:05:48,617 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Output ready
2009-08-14 11:05:48,617 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SendMediator End : Send mediator
2009-08-14 11:05:48,617 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Content encoder
[chunk-coded; completed: true]
2009-08-14 11:05:48,617 [127.0.1.1-LAPTOP] [HttpServerWorker-1] DEBUG
SequenceMediator End : Sequence <anonymous>
2009-08-14 11:05:48,617 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Request ready
2009-08-14 11:05:48,704 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: HTTP/1.1 500
Internal
Server Error
2009-08-14 11:05:48,704 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler Received an internal server error with a SOAP payload
2009-08-14 11:05:48,708 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
<<
HTTP/1.1 500 Internal Server Error
2009-08-14 11:05:48,708 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
<<
Transfer-encoding: chunked
2009-08-14 11:05:48,708 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
<<
Content-type: text/xml;charset="utf-8"
2009-08-14 11:05:48,708 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG headers
<<
Accept: text/xml, text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
2009-08-14 11:05:48,708 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Input ready
2009-08-14 11:05:48,729 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
TransportUtils createSOAPEnvelope using Builder (class
org.apache.axis2.builder.SOAPBuilder) selected from type (text/xml)
2009-08-14 11:05:48,729 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ConnectionPool Released a connection to host: localhost on port : 8300 to
the
connection pool of current size : 1
2009-08-14 11:05:48,730 [127.0.1.1-LAPTOP] [I/O dispatcher 3] DEBUG
ClientHandler HTTP connection [localhost/127.0.0.1:8300]: Content decoder
[chunk-coded; completed: true]
2009-08-14 11:05:48,731 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SynapseCallbackReceiver Callback removed. Pending callbacks count : 0
2009-08-14 11:05:48,731 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SynapseCallbackReceiver Synapse received an asynchronous response message
2009-08-14 11:05:48,731 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SynapseCallbackReceiver Received To: null
2009-08-14 11:05:48,731 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SynapseCallbackReceiver SOAPAction:
2009-08-14 11:05:48,731 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SynapseCallbackReceiver WSA-Action:
2009-08-14 11:05:48,739 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SynapseCallbackReceiver Body :
<?xml version='1.0' encoding='utf-8'?><SOAP-ENV:Envelope
xmlns:SOAP-ENV="http://
schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Body><SOAP-ENV:Fault><faultcod
e>SO
AP-ENV:Client</faultcode><faultstring>Format
type can not be determined as the following root element namespace is
unknown: '
http://www.egem.nl/StUF/sector/bg/0204'</faultstring></SOAP-ENV:Fault></SOAP
-ENV
:Body></SOAP-ENV:Envelope>
2009-08-14 11:05:48,741 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
Axis2SynapseEnvironment Injecting MessageContext
2009-08-14 11:05:48,741 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
Axis2SynapseEnvironment Using the anonymous out-sequence specified in the
proxy
service Proxy1 for outgoing message mediation
2009-08-14 11:05:48,741 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SequenceMediator Start : Sequence <anonymous>
2009-08-14 11:05:48,741 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SequenceMediator Sequence <SequenceMediator> :: mediate()
2009-08-14 11:05:48,742 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
XSLTMediator Start : XSLT mediator
2009-08-14 11:05:48,754 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
XSLTMediator Using byte array serialization for transformation
[Fatal Error] :11:62: The element type "wrongelement" must be terminated by
the
matching end-tag "</wrongelement>".
SystemId Unknown; Line #11; Column #62; org.xml.sax.SAXParseException: The
element type "wrongelement" must be terminated by the matching end-tag
"</wrongelement>".
2009-08-14 11:05:49,077 [127.0.1.1-LAPTOP] [HttpClientWorker-1] ERROR
XSLTMediator Unable to perform XSLT transformation using : wrong-xslt
against
source XPath : s11:Body/child::*[position()=1] |
s12:Body/child::*[position()=1]
java.lang.NullPointerException
at
org.apache.xalan.transformer.TransformerImpl.createSerializationHandler(Tran
sformerImpl.java:1178)
at
org.apache.xalan.transformer.TransformerImpl.createSerializationHandler(Tran
sformerImpl.java:1060)
at
org.apache.xalan.transformer.TransformerImpl.transform(TransformerImpl.java:
1279)
at
org.apache.xalan.transformer.TransformerImpl.transform(TransformerImpl.java:
1262)
at
org.apache.synapse.mediators.transform.XSLTMediator.performXSLT(XSLTMediator
.java:319)
at
org.apache.synapse.mediators.transform.XSLTMediator.mediate(XSLTMediator.jav
a:177)
at
org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediat
or.java:58)
at
org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.
java:125)
at
org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2Syn
apseEnvironment.java:162)
at
org.apache.synapse.core.axis2.SynapseCallbackReceiver.handleMessage(SynapseC
allbackReceiver.java:301)
at
org.apache.synapse.core.axis2.SynapseCallbackReceiver.receive(SynapseCallbac
kReceiver.java:131)
at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
at
org.apache.synapse.transport.nhttp.ClientWorker.run(ClientWorker.java:200)
at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.ja
va:886)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:9
08)
at java.lang.Thread.run(Thread.java:619)
2009-08-14 11:05:49,079 [127.0.1.1-LAPTOP] [HttpClientWorker-1] WARN
FaultHandler Fault handler - setting ERROR_MESSAGE : Unable to perform XSLT
transformation using : wrong-xslt against source XPath :
s11:Body/child::*[position()=1] | s12:Body/child::*[position()=1]
2009-08-14 11:05:49,079 [127.0.1.1-LAPTOP] [HttpClientWorker-1] WARN
FaultHandler Fault handler - setting ERROR_DETAIL :
org.apache.synapse.SynapseException: Unable to perform XSLT transformation
using
: wrong-xslt against source XPath : s11:Body/child::*[position()=1] |
s12:Body/child::*[position()=1]
at
org.apache.synapse.mediators.AbstractMediator.handleException(AbstractMediat
or.java:220)
at
org.apache.synapse.mediators.transform.XSLTMediator.mediate(XSLTMediator.jav
a:180)
at
org.apache.synapse.mediators.AbstractListMediator.mediate(AbstractListMediat
or.java:58)
at
org.apache.synapse.mediators.base.SequenceMediator.mediate(SequenceMediator.
java:125)
at
org.apache.synapse.core.axis2.Axis2SynapseEnvironment.injectMessage(Axis2Syn
apseEnvironment.java:162)
at
org.apache.synapse.core.axis2.SynapseCallbackReceiver.handleMessage(SynapseC
allbackReceiver.java:301)
at
org.apache.synapse.core.axis2.SynapseCallbackReceiver.receive(SynapseCallbac
kReceiver.java:131)
at org.apache.axis2.engine.AxisEngine.receive(AxisEngine.java:176)
at
org.apache.synapse.transport.nhttp.ClientWorker.run(ClientWorker.java:200)
at
java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.ja
va:886)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:9
08)
at java.lang.Thread.run(Thread.java:619)
Caused by: java.lang.NullPointerException
at
org.apache.xalan.transformer.TransformerImpl.createSerializationHandler(Tran
sformerImpl.java:1178)
at
org.apache.xalan.transformer.TransformerImpl.createSerializationHandler(Tran
sformerImpl.java:1060)
at
org.apache.xalan.transformer.TransformerImpl.transform(TransformerImpl.java:
1279)
at
org.apache.xalan.transformer.TransformerImpl.transform(TransformerImpl.java:
1262)
at
org.apache.synapse.mediators.transform.XSLTMediator.performXSLT(XSLTMediator
.java:319)
at
org.apache.synapse.mediators.transform.XSLTMediator.mediate(XSLTMediator.jav
a:177)
... 10 more
2009-08-14 11:05:49,079 [127.0.1.1-LAPTOP] [HttpClientWorker-1] WARN
FaultHandler Fault handler - setting ERROR_EXCEPTION :
org.apache.synapse.SynapseException: Unable to perform XSLT transformation
using
: wrong-xslt against source XPath : s11:Body/child::*[position()=1] |
s12:Body/child::*[position()=1]
2009-08-14 11:05:49,080 [127.0.1.1-LAPTOP] [HttpClientWorker-1] WARN
MediatorFaultHandler Executing fault handler mediator : fault
2009-08-14 11:05:49,080 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SequenceMediator Start : Sequence <fault>
2009-08-14 11:05:49,080 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SequenceMediator Sequence <SequenceMediator> :: mediate()
2009-08-14 11:05:49,080 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
LogMediator Start : Log mediator
2009-08-14 11:05:49,082 [127.0.1.1-LAPTOP] [HttpClientWorker-1] INFO
LogMediator To: http://www.w3.org/2005/08/addressing/anonymous, WSAction: ,
SOAPAction: , MessageID: urn:uuid:4E542411E5661A7E9F98859849617194975985799,
Direction: response, MESSAGE = Executing default "fault" sequence,
ERROR_CODE =
00000, ERROR_MESSAGE = Unable to perform XSLT transformation using :
wrong-xslt
against source XPath : s11:Body/child::*[position()=1] |
s12:Body/child::*[position()=1], Envelope: <?xml version='1.0'
encoding='utf-8'?><SOAP-ENV:Envelope
xmlns:SOAP-ENV="http://schemas.xmlsoap.org/
soap/envelope/"><SOAP-ENV:Body><SOAP-ENV:Fault><faultcode>SOAP-ENV:Client</f
ault
code><faultstring>Something
went
wrong</faultstring></SOAP-ENV:Fault></SOAP-ENV:Body></SOAP-ENV:Envelope>
2009-08-14 11:05:49,082 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
LogMediator End : Log mediator
2009-08-14 11:05:49,082 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
DropMediator Start : Drop mediator
2009-08-14 11:05:49,082 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
DropMediator End : Drop mediator
2009-08-14 11:05:49,082 [127.0.1.1-LAPTOP] [HttpClientWorker-1] DEBUG
SequenceMediator End : Sequence <fault>
