< Previous by Date Date Index Next by Date >
  Thread Index Next in Thread >

[reSIProcate-users] resip 1.6 routing issue with TLS in loadbalancing environment, please help


I have enabled flow token, also specifically bind the proxy server to IP and ports.

 

But I ran into problem when testing SIPS, we have a loadbalancer before proxy, here is the figure:

 

Client(UA, 113.128.245.176:4078) <->LoadBalancer(192.76.66.5:22361)<->Proxy(113.128.247.39:5060)<->EventPackage(UA, 113.128.245.176:13000)

---------------------------------TLS------------------------------------------------------------------------------------

                                                                                                                                                  -------------------------------------TCP------------------------------------------------

Note: Client and Event Package are on same box, all firewall rules have been set, no problem there.                                                                                                                                                                                                                                      

Registration works fine, subscription seems fine until intianl Notify is unable to be delivered from Proxy, log suggested proxy to look up tuple with client’s  IP address and port , instead of loadbalancer’s IP and port. Thus it couldn’t find the connection and try to create a new connection directly back to client, that fails because it has to go back through loadbalancer.  I think it could be something wrong on how we set the header of the initial notify(routes),  we did it based on vias from subscribe request. Your help is very appreciated.

 

 

Included Subscribe request, Initial Notify message and proxy error log.

 

Subscribe Request:

DEBUG | 20100907-172038.510 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | TcpConnection.cxx:83 | Writing SUBSCRIBE sip:profile@xxxxxxxxxxxxxxx:13000;rinstance=b6fb275a12611691;transport=TCP SIP/2.0

Via: SIP/2.0/TCP 113.128.247.39:5060;branch=z9hG4bK-d8754z-376ee92061489e40-1---d8754z-;rport

Via: SIP/2.0/TLS 113.128.245.176:4078;branch=z9hG4bK-d8754z-062fb435fa72a41e-1---d8754z-;rport=22361;received=192.76.66.5

Max-Forwards: 69

Record-Route: <sip:AAAAAAACyDJxgPWw@xxxxxxxxxxxxxx:5060;transport=TCP;lr>

Record-Route: <sip:7AMAAAABWVfATEIF@xxxxxxxxxxxxxx;lr;lp>

Identity: gY0RPzLO4j8WK8OTBwkEWu3/SOXaV3bwNsjWxr2EN41Ybqlxmb+R47QKncCBaMHbDtUbiIGzg6pPYzJO0QCwCGpB9gXqhk3rFeTInpZ0+W2toUOrywjS1rHkKXigfCrdLrEopaaxurDsSLlHPAQmXnf97RjWc8euiZoguSJOgEY=

Identity-Info: <http://.....:5080/cert?domain=ucc.vzb.com>

Contact: <sip:icp9116498@xxxxxxxxxxxxxxx:4078;transport=TLS>

To: <sip:profile@xxxxxxxxxxxxxxx>

From: <sip:icp9116498@xxxxxxxxxxx>;tag=7644ba66

Call-ID: OWE5MmEzZWE5MzI3Mjc3YmE2NWM5YWE3YzIxNDNmYTM.

CSeq: 2 SUBSCRIBE

Expires: 900

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, MESSAGE, SUBSCRIBE, PUBLISH, NOTIFY

Date: Tue, 07 Sep 2010 21:20:38 GMT

Proxy-Authorization: Digest username="icp9116498",realm="ucc.vzb.com",nonce="12928368038:56a3e645f35514635a213d9b9c852a2a",uri="sip:profile@xxxxxxxxxxxxxxx",response="9516fe58de2d21ed0f4330da311b45bb",cnonce="1dae3cc1627b08ad21636f17c4e24e69",nc=00000001,qop=auth-int,algorithm=MD5

Event: profile

ApplicationId: ProxyLBTestTool

ApplicationVersion: 2.0

Content-Length: 0

 

Initial Notify request:

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | Proxy.cxx:89 | Got: NOTIFY sip:icp9116498@xxxxxxxxxxxxxx:5060;branch=z9hG4bK-d8754z-376ee92061489e40-1---d8754z-;rport=1328;transport=TCP SIP/2.0

Via: SIP/2.0/TCP 113.128.245.176:13000;branch=z9hG4bK-d8754z-55695b3f4601b974-1---d8754z-;rport=4037

Max-Forwards: 70

Route: <sip:icp9116498@xxxxxxxxxxxxxxx:4078;branch=z9hG4bK-d8754z-062fb435fa72a41e-1---d8754z-;rport=22361;received=192.76.66.5;transport=TLS>

Route: <sip:icp9116498@xxxxxxxxxxxxxxx:4078;transport=TLS>

Contact: <sip:profile@xxxxxxxxxxxxxxx:13000;rinstance=b6fb275a12611691;transport=TCP>

To: <sip:icp9116498@xxxxxxxxxxx>;tag=7644ba66

From: <sip:profile@xxxxxxxxxxxxxxx>;tag=0d21807f

Call-ID: OWE5MmEzZWE5MzI3Mjc3YmE2NWM5YWE3YzIxNDNmYTM.

CSeq: 2 NOTIFY

Content-Type: application/xml

Subscription-State: active

Event: profile

Content-Length: 1148

<content>……………………………….

 

Proxy error:

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | ResponseContext.cxx:626 | Set tuple dest: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]

INFO | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | RequestContext.cxx:621 | Updating timer C.

INFO | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | Proxy.cxx:458 | Posting timer C

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSACTION | 2956 | TimerQueue.cxx:105 | Adding application timer: TimerCMessage(55695b3f4601b974)

INFO | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | Proxy.cxx:444 | add client transaction tid=32619727ef6b597a 00BE4F80

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | RESIP | 2956 | SipStack.cxx:319 | SEND: SipReq:  NOTIFY icp9116498@xxxxxxxxxxxxxxx:4078 tid=32619727ef6b597a cseq=NOTIFY contact=profile@xxxxxxxxxxxxxxx:13000 / 2 from(wire)

INFO | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | ResponseContext.cxx:279 | Creating new client transaction 32619727ef6b597a -> sip:icp9116498@xxxxxxxxxxxxxx:5060;branch=z9hG4bK-d8754z-376ee92061489e40-1---d8754z-;rport=1328;transport=TCP

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | ProcessorChain.cxx:80 | Monkey aborted all chains: SimpleTargetHandler baboon

 

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | ProcessorChain.cxx:80 | Monkey aborted all chains: Monkey Chain![00BB44E8]

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | Proxy.cxx:89 | Got: ServerTransactionTerminated 062fb435fa72a41e

INFO | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | RequestContext.cxx:70 | RequestContext::process(TransactionTerminated) 062fb435fa72a41e : RequestContext:  identity=icp9116498 count=1 final=1 orig requri=SipReq:  SUBSCRIBE profile@xxxxxxxxxxxxxxxxxxxxxxxxxxx tid=062fb435fa72a41e cseq=SUBSCRIBE contact=icp9116498@xxxxxxxxxxxxxxx:4078 / 2 from(wire) tlsd=ucc.vzb.com

DEBUG | 20100907-172038.557 | Vz.Nexus.Proxy.R3.1.exe | REPRO:APP | 2956 | RequestContext.cxx:53 | RequestContext::~RequestContext() 00BC5378

STACK | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSACTION | 5260 | TransactionState.cxx:389 | No matching transaction for SipReq:  NOTIFY icp9116498@xxxxxxxxxxxxxxx:4078 tid=32619727ef6b597a cseq=NOTIFY contact=profile@xxxxxxxxxxxxxxx:13000 / 2 from(tu)

STACK | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSACTION | 5260 | TransactionState.cxx:56 | Creating new TransactionState: tid=32619727ef6b597a [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]

STACK | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSACTION | 5260 | TransactionState.cxx:661 | TransactionState::processClientNonInvite: SipReq:  NOTIFY icp9116498@xxxxxxxxxxxxxxx:4078 tid=32619727ef6b597a cseq=NOTIFY contact=profile@xxxxxxxxxxxxxxx:13000 / 2 from(tu)

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSACTION | 5260 | TimerQueue.cxx:85 | Adding timer: Timer F tid=32619727ef6b597a ms=32000

STACK | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSACTION | 5260 | TransactionState.cxx:2038 | sendToWire with no dns result: tid=32619727ef6b597a [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:DNS | 5260 | DnsResult.cxx:207 | DnsResult::lookup sip:icp9116498@xxxxxxxxxxxxxxx:4078;branch=z9hG4bK-d8754z-062fb435fa72a41e-1---d8754z-;rport=22361;received=192.76.66.5;transport=TLS

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:DNS | 5260 | DnsResult.cxx:249 | Found immediate result: [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

STACK | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSACTION | 5260 | TransactionState.cxx:1865 | tid=32619727ef6b597a [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]] got DNS result: 113.128.245.176 --> [[ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]]

STACK | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:DNS | 5260 | DnsResult.cxx:187 | Returning next dns entry: [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:70 | ** SEARCHING FOR TUPLE, [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:74 | ** ITEM, [ V4 192.76.66.5:22361 TLS target domain=unspecified mFlowKey=1004 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:74 | ** ITEM, [ V4 192.76.66.6:35888 TLS target domain=unspecified mFlowKey=1172 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:74 | ** ITEM, [ V4 192.76.66.6:17904 TLS target domain=unspecified mFlowKey=1192 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:85 | Could not find a connection for [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | TransportSelector.cxx:1245 | Searching for TLS transport for domain='' have 1

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | TransportSelector.cxx:1254 | Found a default transport.

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | TransportSelector.cxx:945 | Transmitting to [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ] tlsDomain= via [ V4 113.128.247.39:5061 TLS target domain=unspecified mFlowKey=0 ]

 

NOTIFY sip:icp9116498@xxxxxxxxxxxxxxx:4078;branch=z9hG4bK-d8754z-062fb435fa72a41e-1---d8754z-;rport=22361;received=192.76.66.5;transport=TLS SIP/2.0

Via: SIP/2.0/TLS 113.128.247.39:5061;branch=z9hG4bK-d8754z-32619727ef6b597a-1---d8754z-;rport

Via: SIP/2.0/TCP 113.128.245.176:13000;branch=z9hG4bK-d8754z-55695b3f4601b974-1---d8754z-;rport=4037

Max-Forwards: 69

Route: <sip:icp9116498@xxxxxxxxxxxxxxx:4078;transport=TLS>

Route: <sip:icp9116498@xxxxxxxxxxxxxx:5060;branch=z9hG4bK-d8754z-376ee92061489e40-1---d8754z-;rport=1328;transport=TCP>

Contact: <sip:profile@xxxxxxxxxxxxxxx:13000;rinstance=b6fb275a12611691;transport=TCP>

To: <sip:icp9116498@xxxxxxxxxxx>;tag=7644ba66

From: <sip:profile@xxxxxxxxxxxxxxx>;tag=0d21807f

Call-ID: OWE5MmEzZWE5MzI3Mjc3YmE2NWM5YWE3YzIxNDNmYTM.

CSeq: 2 NOTIFY

Content-Type: application/xml

Subscription-State: active

Event: profile

Content-Length: 1148

<content>………………………..

sigcomp id=

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | Transport.cxx:213 | Adding message to tx buffer to: [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | TcpBaseTransport.cxx:141 | Processing write for [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:70 | ** SEARCHING FOR TUPLE, [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:74 | ** ITEM, [ V4 192.76.66.5:22361 TLS target domain=unspecified mFlowKey=1004 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:74 | ** ITEM, [ V4 192.76.66.6:35888 TLS target domain=unspecified mFlowKey=1172 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:74 | ** ITEM, [ V4 192.76.66.6:17904 TLS target domain=unspecified mFlowKey=1192 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | ConnectionManager.cxx:85 | Could not find a connection for [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | InternalTransport.cxx:86 | Creating fd=1376 V4/TCP

DEBUG | 20100907-172038.588 | Vz.Nexus.Proxy.R3.1.exe | RESIP:TRANSPORT | 5260 | TcpBaseTransport.cxx:177 | Opening new connection to [ V4 113.128.245.176:4078 TLS target domain=113.128.245.176 mFlowKey=0 ]

DEBUG |

 

It should use fd 1004, instead of creating a new one at 1376

 

 

Wei Li

ICP Client Team, Verizon Business