Hi Folks,
As per the suggestion by Asankha I did some testing and debugging using two
different machines. I have attached my Synapse configuration herewith
(synapse_sample_57.xml). I have included some additional log mediators in
the config. I see the requests getting logged but not the responses (see
attachment - synapse.txt). But the tcpdump output (see attachment -
tcpdump.txt) says that Axis2 actually does send a response (this was
confirmed by previous results from TCPMon).
However I also noticed this. Requests sent from the service client contain
addressing headers. But when Synapse forwards the messages to Axis2 the
addressing headers are lost. Can this have any effect on this strange
behavior? Or am I doing something wrong?
Thanks
Best Regards,
Hiranya
On Fri, Nov 14, 2008 at 12:55 PM, Asankha C. Perera <[EMAIL PROTECTED]>wrote:
> Hi Hiranya
>
>> Thanks for the tip. I'll try it out and will let the team know. Anyway
>> even in the previous case the service client didn't receive any response
>> back. However according to the TCPMon trace the Axis2 server instance has
>> sent a response back. Could it be because Synapse failed to route the
>> response back properly?
>>
> Well, yes it could.. the easiest way to find that out is to put a verbose
> log mediator in the out sequence
>
>
> asankha
>
> --
> Asankha C. Perera
> http://adroitlogic.org
>
> http://esbmagic.blogspot.com
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [EMAIL PROTECTED]
> For additional commands, e-mail: [EMAIL PROTECTED]
>
>
13:10:58.633276 IP 10.8.108.114.39386 > 10.8.108.66.9001: S
3056958362:3056958362(0) win 5840 <mss 1460,sackOK,timestamp 947865
0,nop,wscale 7>
0x0000: 4500 003c 0d03 4000 4006 40f5 0a08 6c72 E..<[EMAIL
PROTECTED]@[EMAIL PROTECTED]
0x0010: 0a08 6c42 99da 2329 b635 7b9a 0000 0000 ..lB..#).5{.....
0x0020: a002 16d0 def0 0000 0204 05b4 0402 080a ................
0x0030: 000e 7699 0000 0000 0103 0307 ..v.........
13:10:58.633593 IP 10.8.108.66.9001 > 10.8.108.114.39386: S
481015035:481015035(0) ack 3056958363 win 5792 <mss 1460,sackOK,timestamp
259404 947865,nop,wscale 7>
0x0000: 4500 003c 0000 4000 4006 4df8 0a08 6c42 E..<[EMAIL
PROTECTED]@.M...lB
0x0010: 0a08 6c72 2329 99da 1cab b4fb b635 7b9b ..lr#).......5{.
0x0020: a012 16a0 1819 0000 0204 05b4 0402 080a ................
0x0030: 0003 f54c 000e 7699 0103 0307 ...L..v.....
13:10:58.634659 IP 10.8.108.114.39386 > 10.8.108.66.9001: . ack 1 win 46
<nop,nop,timestamp 947866 259404>
0x0000: 4500 0034 0d04 4000 4006 40fc 0a08 6c72 [EMAIL
PROTECTED]@[EMAIL PROTECTED]
0x0010: 0a08 6c42 99da 2329 b635 7b9b 1cab b4fc ..lB..#).5{.....
0x0020: 8010 002e 5d56 0000 0101 080a 000e 769a ....]V........v.
0x0030: 0003 f54c ...L
13:10:58.643517 IP 10.8.108.114.39386 > 10.8.108.66.9001: P 1:250(249) ack 1
win 46 <nop,nop,timestamp 947868 259404>
0x0000: 4500 012d 0d05 4000 4006 4002 0a08 6c72 [EMAIL
PROTECTED]@[EMAIL PROTECTED]
0x0010: 0a08 6c42 99da 2329 b635 7b9b 1cab b4fc ..lB..#).5{.....
0x0020: 8018 002e 1964 0000 0101 080a 000e 769c .....d........v.
0x0030: 0003 f54c 504f 5354 2068 7474 703a 2f2f ...LPOST.http://
0x0040: 3130 2e38 2e31 3038 2e36 363a 3930 3031 10.8.108.66:9001
0x0050: 2f73 6572 7669 6365 732f 4c42 5365 7276 /services/LBServ
0x0060: 6963 6531 2048 5454 502f 312e 310d 0a53 ice1.HTTP/1.1..S
0x0070: 4f41 5041 6374 696f 6e3a 2075 726e 3a73 OAPAction:.urn:s
0x0080: 616d 706c 654f 7065 7261 7469 6f6e 0d0a ampleOperation..
0x0090: 436f 6e74 656e 742d 5479 7065 3a20 7465 Content-Type:.te
0x00a0: 7874 2f78 6d6c 3b20 6368 6172 7365 743d xt/xml;.charset=
0x00b0: 5554 462d 380d 0a54 7261 6e73 6665 722d UTF-8..Transfer-
0x00c0: 456e 636f 6469 6e67 3a20 6368 756e 6b65 Encoding:.chunke
0x00d0: 640d 0a48 6f73 743a 2031 302e 382e 3130 d..Host:.10.8.10
0x00e0: 382e 3636 3a39 3030 310d 0a43 6f6e 6e65 8.66:9001..Conne
0x00f0: 6374 696f 6e3a 204b 6565 702d 416c 6976 ction:.Keep-Aliv
0x0100: 650d 0a55 7365 722d 4167 656e 743a 2053 e..User-Agent:.S
0x0110: 796e 6170 7365 2d48 7474 7043 6f6d 706f ynapse-HttpCompo
0x0120: 6e65 6e74 732d 4e49 4f0d 0a0d 0a nents-NIO....
13:10:58.643560 IP 10.8.108.66.9001 > 10.8.108.114.39386: . ack 250 win 54
<nop,nop,timestamp 259407 947868>
0x0000: 4500 0034 41b0 4000 4006 0c50 0a08 6c42 [EMAIL
PROTECTED]@..P..lB
0x0010: 0a08 6c72 2329 99da 1cab b4fc b635 7c94 ..lr#).......5|.
0x0020: 8010 0036 5c50 0000 0101 080a 0003 f54f ...6\P.........O
0x0030: 000e 769c ..v.
13:10:58.646931 IP 10.8.108.114.39386 > 10.8.108.66.9001: P 250:451(201) ack 1
win 46 <nop,nop,timestamp 947869 259407>
0x0000: 4500 00fd 0d06 4000 4006 4031 0a08 6c72 [EMAIL
PROTECTED]@[EMAIL PROTECTED]
0x0010: 0a08 6c42 99da 2329 b635 7c94 1cab b4fc ..lB..#).5|.....
0x0020: 8018 002e 5da5 0000 0101 080a 000e 769d ....].........v.
0x0030: 0003 f54f 6265 0d0a 3c3f 786d 6c20 7665 ...Obe..<?xml.ve
0x0040: 7273 696f 6e3d 2731 2e30 2720 656e 636f rsion='1.0'.enco
0x0050: 6469 6e67 3d27 5554 462d 3827 3f3e 3c73 ding='UTF-8'?><s
0x0060: 6f61 7065 6e76 3a45 6e76 656c 6f70 6520 oapenv:Envelope.
0x0070: 786d 6c6e 733a 736f 6170 656e 763d 2268 xmlns:soapenv="h
0x0080: 7474 703a 2f2f 7363 6865 6d61 732e 786d ttp://schemas.xm
0x0090: 6c73 6f61 702e 6f72 672f 736f 6170 2f65 lsoap.org/soap/e
0x00a0: 6e76 656c 6f70 652f 223e 3c73 6f61 7065 nvelope/"><soape
0x00b0: 6e76 3a42 6f64 793e 3c56 616c 7565 3e53 nv:Body><Value>S
0x00c0: 616d 706c 6520 7374 7269 6e67 3c2f 5661 ample.string</Va
0x00d0: 6c75 653e 3c2f 736f 6170 656e 763a 426f lue></soapenv:Bo
0x00e0: 6479 3e3c 2f73 6f61 7065 6e76 3a45 6e76 dy></soapenv:Env
0x00f0: 656c 6f70 653e 0d0a 300d 0a0d 0a elope>..0....
13:10:58.646972 IP 10.8.108.66.9001 > 10.8.108.114.39386: . ack 451 win 62
<nop,nop,timestamp 259408 947869>
0x0000: 4500 0034 41b1 4000 4006 0c4f 0a08 6c42 [EMAIL
PROTECTED]@..O..lB
0x0010: 0a08 6c72 2329 99da 1cab b4fc b635 7d5d ..lr#).......5}]
0x0020: 8010 003e 5b7d 0000 0101 080a 0003 f550 ...>[}.........P
0x0030: 000e 769d ..v.
13:10:58.762750 IP 10.8.108.66.9001 > 10.8.108.114.39386: P 1:184(183) ack 451
win 62 <nop,nop,timestamp 259436 947869>
0x0000: 4500 00eb 41b2 4000 4006 0b97 0a08 6c42 [EMAIL
PROTECTED]@.....lB
0x0010: 0a08 6c72 2329 99da 1cab b4fc b635 7d5d ..lr#).......5}]
0x0020: 8018 003e 74cd 0000 0101 080a 0003 f56c ...>t..........l
0x0030: 000e 769d 4854 5450 2f31 2e31 2032 3030 ..v.HTTP/1.1.200
0x0040: 204f 4b0d 0a43 6f6e 7465 6e74 2d54 7970 .OK..Content-Typ
0x0050: 653a 2074 6578 742f 786d 6c3b 2063 6861 e:.text/xml;.cha
0x0060: 7273 6574 3d55 5446 2d38 0d0a 4461 7465 rset=UTF-8..Date
0x0070: 3a20 4672 692c 2031 3420 4e6f 7620 3230 :.Fri,.14.Nov.20
0x0080: 3038 2030 373a 3430 3a35 3820 474d 540d 08.07:40:58.GMT.
0x0090: 0a53 6572 7665 723a 2053 796e 6170 7365 .Server:.Synapse
0x00a0: 2d48 7474 7043 6f6d 706f 6e65 6e74 732d -HttpComponents-
0x00b0: 4e49 4f0d 0a54 7261 6e73 6665 722d 456e NIO..Transfer-En
0x00c0: 636f 6469 6e67 3a20 6368 756e 6b65 640d coding:.chunked.
0x00d0: 0a43 6f6e 6e65 6374 696f 6e3a 204b 6565 .Connection:.Kee
0x00e0: 702d 416c 6976 650d 0a0d 0a p-Alive....
13:10:58.764273 IP 10.8.108.114.39386 > 10.8.108.66.9001: . ack 184 win 54
<nop,nop,timestamp 947898 259436>
0x0000: 4500 0034 0d07 4000 4006 40f9 0a08 6c72 [EMAIL
PROTECTED]@[EMAIL PROTECTED]
0x0010: 0a08 6c42 99da 2329 b635 7d5d 1cab b5b3 ..lB..#).5}]....
0x0020: 8010 0036 5a95 0000 0101 080a 000e 76ba ...6Z.........v.
0x0030: 0003 f56c ...l
13:10:58.795992 IP 10.8.108.66.9001 > 10.8.108.114.39386: P 184:399(215) ack
451 win 62 <nop,nop,timestamp 259445 947898>
0x0000: 4500 010b 41b3 4000 4006 0b76 0a08 6c42 [EMAIL
PROTECTED]@..v..lB
0x0010: 0a08 6c72 2329 99da 1cab b5b3 b635 7d5d ..lr#).......5}]
0x0020: 8018 003e 0347 0000 0101 080a 0003 f575 ...>.G.........u
0x0030: 000e 76ba 6363 0d0a 3c3f 786d 6c20 7665 ..v.cc..<?xml.ve
0x0040: 7273 696f 6e3d 2731 2e30 2720 656e 636f rsion='1.0'.enco
0x0050: 6469 6e67 3d27 5554 462d 3827 3f3e 3c73 ding='UTF-8'?><s
0x0060: 6f61 7065 6e76 3a45 6e76 656c 6f70 6520 oapenv:Envelope.
0x0070: 786d 6c6e 733a 736f 6170 656e 763d 2268 xmlns:soapenv="h
0x0080: 7474 703a 2f2f 7363 6865 6d61 732e 786d ttp://schemas.xm
0x0090: 6c73 6f61 702e 6f72 672f 736f 6170 2f65 lsoap.org/soap/e
0x00a0: 6e76 656c 6f70 652f 223e 3c73 6f61 7065 nvelope/"><soape
0x00b0: 6e76 3a42 6f64 793e 3c56 616c 7565 3e52 nv:Body><Value>R
0x00c0: 6573 706f 6e73 6520 6672 6f6d 2073 6572 esponse.from.ser
0x00d0: 7665 723a 204e 6f64 6531 3c2f 5661 6c75 ver:.Node1</Valu
0x00e0: 653e 3c2f 736f 6170 656e 763a 426f 6479 e></soapenv:Body
0x00f0: 3e3c 2f73 6f61 7065 6e76 3a45 6e76 656c ></soapenv:Envel
0x0100: 6f70 653e 0d0a 300d 0a0d 0a ope>..0....
13:10:58.797632 IP 10.8.108.114.39386 > 10.8.108.66.9001: . ack 399 win 63
<nop,nop,timestamp 947906 259445>
0x0000: 4500 0034 0d08 4000 4006 40f8 0a08 6c72 [EMAIL
PROTECTED]@[EMAIL PROTECTED]
0x0010: 0a08 6c42 99da 2329 b635 7d5d 1cab b68a ..lB..#).5}]....
0x0020: 8010 003f 59a4 0000 0101 080a 000e 76c2 ...?Y.........v.
0x0030: 0003 f575 ...u
<!--
~ Licensed to the Apache Software Foundation (ASF) under one
~ or more contributor license agreements. See the NOTICE file
~ distributed with this work for additional information
~ regarding copyright ownership. The ASF licenses this file
~ to you under the Apache License, Version 2.0 (the
~ "License"); you may not use this file except in compliance
~ with the License. You may obtain a copy of the License at
~
~ http://www.apache.org/licenses/LICENSE-2.0
~
~ Unless required by applicable law or agreed to in writing,
~ software distributed under the License is distributed on an
~ * "AS IS" BASIS, WITHOUT WARRANTIES OR CONDITIONS OF ANY
~ KIND, either express or implied. See the License for the
~ specific language governing permissions and limitations
~ under the License.
-->
<!-- Session less load balancing between 3 endpoints -->
<definitions xmlns="http://ws.apache.org/ns/synapse";>
<sequence name="main" onError="errorHandler">
<in>
<log level="full"/>
<send>
<endpoint name="dynamicLB">
<dynamicLoadbalance failover="true"
algorithm="org.apache.synapse.endpoints.algorithms.RoundRobin">
<membershipHandler
class="org.apache.synapse.core.axis2.Axis2LoadBalanceMembershipHandler">
<property name="applicationDomain" value="apache.axis2.app.domain"/>
</membershipHandler>
</dynamicLoadbalance>
</endpoint>
</send>
<drop/>
</in>
<out>
<!-- Send the messages where they have been sent (i.e. implicit To EPR) -->
<log level="full"/>
<send/>
</out>
</sequence>
<sequence name="errorHandler">
<makefault response="true">
<code value="tns:Receiver" xmlns:tns="http://www.w3.org/2003/05/soap-envelope"/>
<reason value="COULDN'T SEND THE MESSAGE TO THE SERVER."/>
</makefault>
<send/>
</sequence>
</definitions>
2008-11-14 13:10:28,846 [-] [main] INFO ServerManager Using Synapse home as :
/home/hiranya/synapse-SNAPSHOT
2008-11-14 13:10:28,849 [-] [main] INFO ServerManager Using the Axis2
Repository : /home/hiranya/synapse-SNAPSHOT/repository
2008-11-14 13:10:28,849 [-] [main] INFO ServerManager Using the axis2.xml :
/home/hiranya/synapse-SNAPSHOT/repository/conf/axis2.xml
2008-11-14 13:10:28,849 [-] [main] INFO ServerManager Using server name :
localhost
2008-11-14 13:10:28,849 [-] [main] INFO ServerManager The timeout handler will
run every : 15s
2008-11-14 13:10:30,067 [-] [main] INFO SynapseInitializationModule
Initializing Synapse at : Fri Nov 14 13:10:30 IST 2008
2008-11-14 13:10:30,073 [127.0.1.1-hiranya-laptop] [main] INFO
SynapseInitializationModule Loading mediator extensions...
2008-11-14 13:10:30,073 [127.0.1.1-hiranya-laptop] [main] INFO
SynapseInitializationModule Initializing the Synapse configuration ...
2008-11-14 13:10:30,082 [127.0.1.1-hiranya-laptop] [main] INFO
XMLConfigurationBuilder Generating the Synapse configuration model by parsing
the XML configuration
2008-11-14 13:10:30,248 [127.0.1.1-hiranya-laptop] [main] INFO
Axis2LoadBalanceMembershipHandler *********apache.axis2.app.domain
2008-11-14 13:10:30,316 [127.0.1.1-hiranya-laptop] [main] INFO
SynapseConfigurationBuilder Loaded Synapse configuration from :
/home/hiranya/synapse-SNAPSHOT/repository/conf/sample/synapse_sample_57.xml
2008-11-14 13:10:30,327 [127.0.1.1-hiranya-laptop] [main] INFO
SynapseInitializationModule Deploying the Synapse service..
2008-11-14 13:10:30,361 [127.0.1.1-hiranya-laptop] [main] INFO
SynapseInitializationModule Synapse server name : localhost
2008-11-14 13:10:30,361 [127.0.1.1-hiranya-laptop] [main] INFO
SynapseInitializationModule Deploying Proxy services...
2008-11-14 13:10:30,365 [127.0.1.1-hiranya-laptop] [main] INFO
DynamicLoadbalanceEndpoint Dynamic load balance endpoint initialized
2008-11-14 13:10:30,366 [127.0.1.1-hiranya-laptop] [main] INFO
SynapseInitializationModule Synapse initialized successfully...!
2008-11-14 13:10:30,368 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOSSLSender Loading Identity Keystore from : lib/identity.jks
2008-11-14 13:10:30,399 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOSSLSender Loading Trust Keystore from : lib/trust.jks
2008-11-14 13:10:30,516 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOSender HTTPS Sender starting
2008-11-14 13:10:30,527 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOSender HTTP Sender starting
2008-11-14 13:10:30,542 [127.0.1.1-hiranya-laptop] [main] INFO
VFSTransportSender VFS Sender started
2008-11-14 13:10:31,018 [127.0.1.1-hiranya-laptop] [main] INFO JMSSender JMS
Sender started
Nov 14, 2008 1:10:31 PM org.apache.catalina.tribes.transport.ReceiverBase bind
INFO: Receiver Server Socket bound to:/10.8.108.114:4000
Nov 14, 2008 1:10:31 PM org.apache.catalina.tribes.membership.McastServiceImpl
setupSocket
INFO: Setting cluster mcast soTimeout to 500
Nov 14, 2008 1:10:31 PM org.apache.catalina.tribes.membership.McastServiceImpl
waitForMembers
INFO: Sleeping for 1000 milliseconds to establish cluster membership, start
level:4
Nov 14, 2008 1:10:32 PM org.apache.catalina.tribes.membership.McastServiceImpl
waitForMembers
INFO: Done sleeping, membership established, start level:4
Nov 14, 2008 1:10:32 PM org.apache.catalina.tribes.membership.McastServiceImpl
waitForMembers
INFO: Sleeping for 1000 milliseconds to establish cluster membership, start
level:8
Nov 14, 2008 1:10:33 PM org.apache.catalina.tribes.membership.McastServiceImpl
waitForMembers
INFO: Done sleeping, membership established, start level:8
2008-11-14 13:10:33,192 [127.0.1.1-hiranya-laptop] [main] INFO ServerManager
Starting transport https on port 8243
2008-11-14 13:10:33,193 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOSSLListener Loading Identity Keystore from : lib/identity.jks
2008-11-14 13:10:33,196 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOSSLListener Loading Trust Keystore from : lib/trust.jks
2008-11-14 13:10:33,324 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOListener HTTPS Listener started on port : 8243
2008-11-14 13:10:33,324 [127.0.1.1-hiranya-laptop] [main] INFO ServerManager
Starting transport http on port 8280
2008-11-14 13:10:33,330 [127.0.1.1-hiranya-laptop] [main] INFO
HttpCoreNIOListener HTTP Listener started on port : 8280
2008-11-14 13:10:33,330 [127.0.1.1-hiranya-laptop] [main] INFO ServerManager
Starting transport vfs
2008-11-14 13:10:33,380 [127.0.1.1-hiranya-laptop] [main] INFO
VFSTransportListener VFS Listener started
2008-11-14 13:10:33,381 [127.0.1.1-hiranya-laptop] [main] INFO ServerManager
Starting transport mailto
2008-11-14 13:10:33,383 [127.0.1.1-hiranya-laptop] [main] INFO
MailTransportListener MAILTO Listener started
2008-11-14 13:10:33,383 [127.0.1.1-hiranya-laptop] [main] INFO ServerManager
Ready for processing
Nov 14, 2008 1:10:36 PM org.apache.catalina.tribes.io.BufferPool getBufferPool
INFO: Created a buffer pool with max size:104857600 bytes of
type:org.apache.catalina.tribes.io.BufferPool15Impl
2008-11-14 13:10:44,359 [127.0.1.1-hiranya-laptop] [HttpServerWorker-1] INFO
LogMediator To: http://localhost:8280/services/LBService1, WSAction:
urn:sampleOperation, SOAPAction: urn:sampleOperation, ReplyTo:
http://www.w3.org/2005/08/addressing/anonymous, MessageID:
urn:uuid:EF2135AF46D11E96541226648443905, 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:8280/services/LBService1</wsa:To><wsa:MessageID>urn:uuid:EF2135AF46D11E96541226648443905</wsa:MessageID><wsa:Action>urn:sampleOperation</wsa:Action></soapenv:Header><soapenv:Body><Value>Sample
string</Value></soapenv:Body></soapenv:Envelope>
---------------------------------------------------------------------
To unsubscribe, e-mail: [EMAIL PROTECTED]
For additional commands, e-mail: [EMAIL PROTECTED]
