[Serusers] problem with IM b/w windows messenger 4.7 and 5.0

Daniel-Constantin Mierla daniel at iptel.org
Tue Mar 30 10:52:47 CEST 2004 

Hello Ralph,
calls are ok, I think the problem is somewhere to the instant messaging 
and presence extension. Actually I have played just a bit with versions 
5.x, then reverted back to 4.x, since one can't have both in the same 
time. As far as I know, MSN Messenger 5.x does not support SIP any 
longer, the Windows Messenger 5.x is the right one with some "residual" 
SIP.

.Daniel

On 03/30/04 10:03, Ralph.Wabel at swisscom.com wrote:

>Hi Daniel,
>I'm not 100% sure but I think I made calls between 4.7 and 5.0. I can't tell you the exact version number. Maybe I'm wrong, but as long as it is windows messenger and not msn messenger it should be ok.
>
>Ralph
>
>-----Original Message-----
>From: serusers-bounces at iptel.org [mailto:serusers-bounces at lists.iptel.org] On Behalf Of Daniel-Constantin Mierla
>Sent: Montag, 29. März 2004 12:10
>To: Rao Ser
>Cc: serusers at lists.iptel.org
>Subject: Re: [Serusers] problem with IM b/w windows messenger 4.7 and 5.0
>
>As you can see, 4.7 and 5.0 use different ways of sending instant 
>messages. 5.0 breaks the specifications and uses some M$ specific 
>extension. You can speak 5.0 to 5.0, 4.7 to 4.7 but not mixed.
>
>.Daniel
>
>On 03/27/04 23:32, Rao Ser wrote:
>
>  
>
>>Hi,
>>
>>I am unable to communicate b/w two systems running windows messeger 
>>4.7 and
>>5.0.
>>
>>129.146.85.163: is running 4.7 and can send IM messages but cannot 
>>recieve.
>>
>>129.150.32.27: is running 5.0, it can recieve messages but can send, I 
>>get an
>>error
>>"the following message could not be delivered to all reciepents"
>>
>>below I have included an output of ngrep and also debug output from ser.
>>It seems to me that the WM 5.0 adds a route header that ser is not 
>>interpretting
>>correctly and is trying to deliver the message to the local machine.
>>Also its not clear to me why WM 4.7 did not add a route header when we 
>>have
>>record route turned on, a later exchange (see at the end) shows that 
>>it adds
>>a Route headre but its format is different and its correctly interpreted
>>by ser. Does ser needs to be modified to check maddr parameter in the 
>>Route
>>header or is this a config problem on my part, as I have not yet setup 
>>DNS
>>records.
>>If I use WM 5.0 on both machines neither can send or recv IM messages. 
>>Don't
>>have WM 4.7 for win2K so cannot try with two 4.7 machines.
>> 
>>Thanks much for your help in resolving this.
>>
>>Rao.
>>
>>####
>>T 129.146.85.163:1427 -> 129.146.175.207:5060 [AP]
>>REGISTER sip:sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163:13395..Max
>>-Forwards: 70..From: ;tag=456f8505294f4203a2e0e048
>>d44422ed;epid=8bb54d4c1b..To: ..Call-ID: bbef84715
>>89a4080b173a320a864b7b5 at 129.146.85.163..CSeq: 1 REGISTER..Contact:
>>9.146.85.163:13395;transport=tcp>;methods="INVITE, MESSAGE, INFO, SUBSCRIB
>>E, OPTIONS, BYE, CANCEL, NOTIFY, ACK, REFER"..User-Agent: RTC/1.2.4949 (Me
>>ssenger 5.0.0482)..Supported: com.microsoft.msrtc.presence..Event: registr
>>ation..Allow-Events: presence..Content-Length: 0....
>>##
>>T 129.146.175.207:5060 -> 129.146.85.163:1427 [AP]
>>SIP/2.0 200 OK..Via: SIP/2.0/TCP 129.146.85.163:13395..From:
>>@sipserver>;tag=456f8505294f4203a2e0e048d44422ed;epid=8bb54d4c1b..To:
>>:rao-sony at sipserver>;tag=b27e1a1d33761e85846fc98f5f3a7e58.809c..Call-ID: b
>>bef8471589a4080b173a320a864b7b5 at 129.146.85.163..CSeq: 1 REGISTER..Contact:
>>;q=0.00;expires=3600..Server: Sip
>>EXpress router (0.8.12 (sparc64/solaris))..Content-Length: 0..Warning: 39
>>2 129.146.175.207:5060 "Noisy feedback tells: pid=19291 req_src_ip=129.14
>>6.85.163 req_src_port=1427 in_uri=sip:sipserver out_uri=sip:sipserver via_
>>cnt==1"....
>>#####
>>T 129.150.32.27:1064 -> 129.146.175.207:5060 [AP]
>>REGISTER sip:sipserver SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From:
>>;tag=ef57bcc5-d7d1-4527-ab39-6e68a8617246..To:
>>..Call-ID: 9ddfc882-0233-418c-adfb-0a760a1147e
>>4 at 129.150.32.27..CSeq: 1 REGISTER..Contact:
>>ort=tcp>;methods="INVITE, MESSAGE, INFO, SUBSCRIBE, OPTIONS, BYE, CANCEL,
>>NOTIFY, ACK"..User-Agent: Windows RTC/1.0..Expires: 1200..Event: registrat
>>ion..Allow-Events: presence..Content-Length: 0....
>>##
>>T 129.146.175.207:5060 -> 129.150.32.27:1064 [AP]
>>SIP/2.0 200 OK..Via: SIP/2.0/TCP 129.150.32.27:7685..From:
>>a at sipserver>;tag=ef57bcc5-d7d1-4527-ab39-6e68a8617246..To:
>>a at sipserver>;tag=b27e1a1d33761e85846fc98f5f3a7e58.7c4c..Call-ID: 9ddfc882-
>>0233-418c-adfb-0a760a1147e4 at 129.150.32.27..CSeq: 1 REGISTER..Contact:
>>:129.150.32.27:7685;transport=tcp>;q=0.00;expires=1200..Server: Sip EXpres
>>s router (0.8.12 (sparc64/solaris))..Content-Length: 0..Warning: 392 129.1
>>46.175.207:5060 "Noisy feedback tells: pid=19291 req_src_ip=129.150.32.27
>>req_src_port=1064 in_uri=sip:sipserver out_uri=sip:sipserver via_cnt==1".
>>...
>>##
>>T 129.146.85.163:1427 -> 129.146.175.207:5060 [AP]
>>INVITE sip:rao-toshiba at sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163:
>>13395..Max-Forwards: 70..From: "rao-sony at sipserver"
>>r>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To:
>>iba at sipserver>..Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163..
>>CSeq: 1 INVITE..Contact: 3;transport=tcp>..User-Agent: 
>>RTC/1.2..Roster-Manager: sip:rao-sony at sipser
>>ver..EndPoints: "rao-sony at sipserver" ,
>>shiba at sipserver>..Supported: com.microsoft.rtc-multiparty..Content-Type: a
>>pplication/sdp..Content-Length: 109....v=0..o=- 0 0 IN IP4 129.146.85.163.
>>.s=session..c=IN IP4 129.146.85.163..t=0 0..m=x-ms-message 5060 sip null..
>>##
>>T 129.146.175.207:5060 -> 129.146.85.163:1427 [AP]
>>SIP/2.0 100 trying -- your call is important to us..Via: SIP/2.0/TCP 129.1
>>46.85.163:13395..From: "rao-sony at sipserver" ;tag=3
>>b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To:
>>erver>..Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163..CSeq: 1
>>INVITE..Server: Sip EXpress router (0.8.12 (sparc64/solaris))..Content-Len
>>gth: 0..Warning: 392 129.146.175.207:5060 "Noisy feedback tells: pid=1929
>>1 req_src_ip=129.146.85.163 req_src_port=1427 in_uri=sip:rao-toshiba at sipse
>>rver out_uri=sip:129.150.32.27:7685;transport=tcp via_cnt==1"....
>>#
>>T 129.146.175.207:5060 -> 129.146.85.163:1427 [AP]
>>SIP/2.0 488 Not Acceptable Here..Via: SIP/2.0/TCP 129.146.85.163:13395..Fr
>>om: "rao-sony at sipserver" ;tag=3b3b0bff72424892aa8e
>>78d5bbf3a316;epid=8bb54d4c1b..To: ;tag=f3b15a35
>>-1c0a-4519-b2db-804cfb87fbb7..Call-ID: 951c963c0be44507aa2f39076c584129 at 12
>>9.146.85.163..CSeq: 1 INVITE..User-Agent: Windows RTC/1.0..Content-Length:
>>0....
>>##
>>T 129.146.85.163:1427 -> 129.146.175.207:5060 [AP]
>>ACK sip:rao-toshiba at sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163:133
>>95..Max-Forwards: 70..From: "rao-sony at sipserver" ;
>>tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To:
>>@sipserver>;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7..Call-ID: 951c963c0be
>>44507aa2f39076c584129 at 129.146.85.163..CSeq: 1 ACK..User-Agent: RTC/1.2..Co
>>ntent-Length: 0....
>>##
>>T 129.146.85.163:1427 -> 129.146.175.207:5060 [AP]
>>MESSAGE sip:rao-toshiba at sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163
>>:13395..Max-Forwards: 70..From: "rao-sony at sipserver"
>>er>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To:
>>hiba at sipserver>..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163.
>>.CSeq: 2 MESSAGE..Contact: 163;transport=tcp>..User-Agent: 
>>RTC/1.2..Content-Type: text/plain; charset
>>=UTF-8;msgr=WAAtAE0ATQBTAC0ASQBNAC0ARgBvAHIAbQBhAHQAOgAgAEYATgA9AE0AUwAlAD
>>IAMABTAGgAZQBsAGwAJQAyADAARABsAGcAJQAyADAAMgA7ACAARQBGAD0AOwAgAEMATwA9ADAA
>>OwAgAEMAUwA9ADEAOwAgAFAARgA9ADAADQAKAA0ACgA..Content-Length: 17....sending
>>from sony
>>##
>>T 129.146.175.207:5060 -> 129.146.85.163:1427 [AP]
>>SIP/2.0 200 OK..Via: SIP/2.0/TCP 129.146.85.163:13395..From: "rao-sony at sip
>>server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid
>>=8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5
>>cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSe
>>q: 2 MESSAGE..Record-Route: 
>>;ftag=3b3b0bff72424892aa8e78d5bbf3a316;lr=on>..Contact:
>>:7685;transport=tcp>..User-Agent: Windows RTC/1.0..Content-Length: 0....
>>##
>>T 129.150.32.27:1064 -> 129.146.175.207:5060 [AP]
>>INFO sip:rao-toshiba at 129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316
>>;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From:
>>a at sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at sipse
>>rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8
>>bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSeq:
>>1 INFO..Route: rt=tcp>..Contact: ..User-Agent: Wind
>>ows RTC/1.0..Content-Type: application/xml..Content-Length: 96.... 
>>rsion="1.0"?>. . .
>>oardActivity>.
>>##
>>T 129.146.175.207:5060 -> 129.150.32.27:1064 [AP]
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)..V
>>ia: SIP/2.0/TCP 129.150.32.27:7685..From: ;tag=
>>6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at sipserver"
>>ny at sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I
>>D: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSeq: 1 INFO..Server:
>>Sip EXpress router (0.8.12 (sparc64/solaris))..Content-Length: 0....
>>##
>>T 129.150.32.27:1064 -> 129.146.175.207:5060 [AP]
>>INFO sip:rao-toshiba at 129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316
>>;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From:
>>a at sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at sipse
>>rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8
>>bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSeq:
>>2 INFO..Route: rt=tcp>..Contact: ..User-Agent: Wind
>>ows RTC/1.0..Content-Type: application/xml..Content-Length: 96.... 
>>rsion="1.0"?>. . .
>>oardActivity>.
>>#
>>T 129.146.175.207:5060 -> 129.150.32.27:1064 [AP]
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)..V
>>ia: SIP/2.0/TCP 129.150.32.27:7685..From: ;tag=
>>6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at sipserver"
>>ny at sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I
>>D: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSeq: 2 INFO..Server:
>>Sip EXpress router (0.8.12 (sparc64/solaris))..Content-Length: 0....
>>##
>>T 129.150.32.27:1064 -> 129.146.175.207:5060 [AP]
>>INFO sip:rao-toshiba at 129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316
>>;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From:
>>a at sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at sipse
>>rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8
>>bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSeq:
>>3 INFO..Route: rt=tcp>..Contact: ..User-Agent: Wind
>>ows RTC/1.0..Content-Type: application/xml..Content-Length: 96.... 
>>rsion="1.0"?>. . .
>>oardActivity>.
>>#
>>T 129.146.175.207:5060 -> 129.150.32.27:1064 [AP]
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)..V
>>ia: SIP/2.0/TCP 129.150.32.27:7685..From: ;tag=
>>6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at sipserver"
>>ny at sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I
>>D: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSeq: 3 INFO..Server:
>>Sip EXpress router (0.8.12 (sparc64/solaris))..Content-Length: 0....
>>##
>>T 129.150.32.27:1064 -> 129.146.175.207:5060 [AP]
>>MESSAGE sip:rao-toshiba at 129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a
>>316;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From:
>>hiba at sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at si
>>pserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epi
>>d=8bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CS
>>eq: 4 MESSAGE..Route: ransport=tcp>..Contact: ..User-Agent
>>: Windows RTC/1.0..Content-Type: text/plain; charset=UTF-8;msgr=WAAtAE0ATQ
>>BTAC0ASQBNAC0ARgBvAHIAbQBhAHQAOgAgAEYATgA9AE0AUwAlADIAMABTAGgAZQBsAGwAJQAy
>>ADAARABsAGcAOwAgAEUARgA9ADsAIABDAE8APQAwADsAIABDAFMAPQAwADsAIABQAEYAPQAwAA
>>0ACgANAAoA..Content-Length: 20....sending from toshiba
>>#
>>T 129.146.175.207:5060 -> 129.150.32.27:1064 [AP]
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)..V
>>ia: SIP/2.0/TCP 129.150.32.27:7685..From: ;tag=
>>6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony at sipserver"
>>ny at sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I
>>D: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSeq: 4 MESSAGE..Serve
>>r: Sip EXpress router (0.8.12 (sparc64/solaris))..Content-Length: 0....
>>#
>>
>>
>><.......>
>>
>>I also happen to capture the following trace (the pot number is different
>>as I restarted ser), In this case toshiba running WM 4.7 adds a Route 
>>but its
>>not a symblic name for the proxy but the ip address of the peer and 
>>things work.
>>Now when do we add a Route header and when we dont ?
>>
>>#
>>T 129.146.85.163:1432 -> 129.146.175.207:5060 [AP]
>>INFO sip:rao-toshiba at 129.146.175.207;transport=tcp;ftag=3b3b0bff72424892aa
>>8e78d5bbf3a316;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163:13395..Max-F
>>orwards: 70..From: "rao-sony at sipserver" ;tag=3b3b0
>>bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To:
>>r>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..Call-ID: 72452f0ccee6437193a2
>>71e057d52725 at 129.146.85.163..CSeq: 7 INFO..Route:
>>transport=tcp>..Contact: 3;transport=tcp>..User-Agent: 
>>RTC/1.2..Content-Type: application/xml..Cont
>>ent-Length: 87......
>>="type" />..
>>##
>>T 129.146.175.207:5060 -> 129.146.85.163:1432 [AP]
>>SIP/2.0 200 OK..Via: SIP/2.0/TCP 129.146.85.163:13395..From: "rao-sony at sip
>>server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid
>>=8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5
>>cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSe
>>q: 7 INFO..Record-Route: 
>>ag=3b3b0bff72424892aa8e78d5bbf3a316;lr=on>..Contact:
>>85;transport=tcp>..User-Agent: Windows RTC/1.0..Content-Length: 0....
>>##
>>T 129.146.85.163:1432 -> 129.146.175.207:5060 [AP]
>>MESSAGE sip:rao-toshiba at 129.146.175.207;transport=tcp;ftag=3b3b0bff7242489
>>2aa8e78d5bbf3a316;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163:13395..Ma
>>x-Forwards: 70..From: "rao-sony at sipserver" ;tag=3b
>>3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To:
>>rver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..Call-ID: 72452f0ccee643719
>>3a271e057d52725 at 129.146.85.163..CSeq: 8 MESSAGE..Route:
>>:7685;transport=tcp>..Contact: .85.163;transport=tcp>..User-Agent: 
>>RTC/1.2..Content-Type: text/plain; cha
>>rset=UTF-8;msgr=WAAtAE0ATQBTAC0ASQBNAC0ARgBvAHIAbQBhAHQAOgAgAEYATgA9AE0AUw
>>AlADIAMABTAGgAZQBsAGwAJQAyADAARABsAGcAJQAyADAAMgA7ACAARQBGAD0AOwAgAEMATwA9
>>ADAAOwAgAEMAUwA9ADEAOwAgAFAARgA9ADAADQAKAA0ACgA..Content-Length: 5....hull
>>o
>>#
>>T 129.146.175.207:5060 -> 129.146.85.163:1432 [AP]
>>SIP/2.0 200 OK..Via: SIP/2.0/TCP 129.146.85.163:13395..From: "rao-sony at sip
>>server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid
>>=8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5
>>cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163..CSe
>>q: 8 MESSAGE..Record-Route: 
>>;ftag=3b3b0bff72424892aa8e78d5bbf3a316;lr=on>..Contact:
>>:7685;transport=tcp>..User-Agent: Windows RTC/1.0..Content-Length: 0....
>>
>>
>>/******************* Debug output from ser **********************/
>>
>>27(19299) tcpconn_new: new tcp connection: 129.146.85.163
>>27(19299) tcpconn_new: on port 1427, type 2
>>27(19299) tcpconn_add: hashes: 48, 9
>>27(19299) tcp_main_loop: new connection: fd1a8170 11
>>27(19299) send2child: to tcp child 0 19(19291), fd1a8170
>>19(19291) received n=4 con=fd1a8170, fd=11
>>19(19291) tcp_read_req: content-length= 0
>>19(19291) SIP Request:
>>19(19291) method:
>>19(19291) uri:
>>19(19291) version:
>>19(19291) parse_headers: flags=1
>>19(19291) end of header reached, state=5
>>19(19291) parse_headers: Via found, flags=1
>>19(19291) parse_headers: this is the first via
>>19(19291) After parse_msg...
>>19(19291) preparing to run routing scripts...
>>19(19291) DEBUG : is_maxfwd_present: searching for max_forwards header
>>19(19291) parse_headers: flags=128
>>19(19291) DEBUG: is_maxfwd_present: value = 70
>>19(19291) parse_headers: flags=8
>>19(19291) DEBUG: add_param: tag=456f8505294f4203a2e0e048d44422ed
>>19(19291) DEBUG: add_param: epid=8bb54d4c1b
>>19(19291) end of header reached, state=29
>>19(19291) parse_headers: flags=256
>>19(19291) end of header reached, state=9
>>19(19291) DEBUG: get_hdr_field: [26]; uri=[sip:rao-sony at sipserver]
>>19(19291) DEBUG: to body [
>>]
>>19(19291) get_hdr_field: cseq : <1>
>>19(19291) DEBUG: get_hdr_body : content_length=0
>>19(19291) found end of header
>>19(19291) find_first_route(): No Route headers found
>>19(19291) loose_route(): There is no Route HF
>>19(19291) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) parse_headers: flags=-1
>>19(19291) parse_headers: flags=-1
>>19(19291) check_via_address(129.146.85.163, 129.146.85.163, 0)
>>19(19291) tcp_send: tcp connection found (fd1a8170), acquiring fd
>>19(19291) tcp_send, c= fd1a8170, n=8
>>27(19299) tcp_main_loop: read response= fd1a8170, 1 from 19 (19291)
>>19(19291) tcp_send: after receive_fd: c= fd1a8170 n=4 fd=31
>>19(19291) tcp_send: sending...
>>19(19291) tcp_send: after write: c= fd1a8170 n=603 fd=31
>>19(19291) tcp_send: buf=
>>SIP/2.0 200 OK
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From:
>>;tag=456f8505294f4203a2e0e048d44422ed;epid=8bb54d4c1b
>>To: ;tag=b27e1a1d33761e85846fc98f5f3a7e58.809c
>>Call-ID: bbef8471589a4080b173a320a864b7b5 at 129.146.85.163
>>CSeq: 1 REGISTER
>>Contact: ;q=0.00;expires=3600
>>Server: Sip EXpress router (0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>Warning: 392 129.146.175.207:5060 "Noisy feedback tells: pid=19291
>>req_src_ip=129.146.85.163 req_src_port=1427 in_uri=sip:sipserver
>>out_uri=sip:sipserver via_cnt==1"
>>
>>
>>19(19291) receive_msg: cleaning up
>>19(19291) tcp_receive_loop: fd1a8170 expired (1789, 1790)
>>19(19291) releasing con fd1a8170, state 0, fd=11, id=9
>>19(19291) extra_data 0
>>27(19299) tcp_main_loop: reader response= fd1a8170, 0 from 0
>>27(19299) tcp_main_loop: CONN_RELEASE fd1a8170 refcnt= 0
>>27(19299) tcpconn_new: new tcp connection: 129.150.32.27
>>27(19299) tcpconn_new: on port 1064, type 2
>>27(19299) tcpconn_add: hashes: 51, 10
>>27(19299) tcp_main_loop: new connection: fd186390 29
>>27(19299) send2child: to tcp child 0 19(19291), fd186390
>>19(19291) received n=4 con=fd186390, fd=11
>>19(19291) tcp_read_req: content-length= 0
>>19(19291) SIP Request:
>>19(19291) method:
>>19(19291) uri:
>>19(19291) version:
>>19(19291) parse_headers: flags=1
>>19(19291) end of header reached, state=5
>>19(19291) parse_headers: Via found, flags=1
>>19(19291) parse_headers: this is the first via
>>19(19291) After parse_msg...
>>19(19291) preparing to run routing scripts...
>>19(19291) DEBUG : is_maxfwd_present: searching for max_forwards header
>>19(19291) parse_headers: flags=128
>>19(19291) end of header reached, state=9
>>19(19291) DEBUG: get_hdr_field: [29]; uri=[sip:rao-toshiba at sipserver]
>>19(19291) DEBUG: to body [
>>]
>>19(19291) get_hdr_field: cseq : <1>
>>19(19291) DEBUG: get_hdr_body : content_length=0
>>19(19291) found end of header
>>19(19291) DEBUG: is_maxfwd_present: max_forwards header not found!
>>19(19291) DEBUG: add_param: tag=ef57bcc5-d7d1-4527-ab39-6e68a8617246
>>19(19291) end of header reached, state=29
>>19(19291) parse_headers: flags=256
>>19(19291) find_first_route(): No Route headers found
>>19(19291) loose_route(): There is no Route HF
>>19(19291) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) parse_headers: flags=-1
>>19(19291) parse_headers: flags=-1
>>19(19291) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>19(19291) tcp_send: tcp connection found (fd186390), acquiring fd
>>19(19291) tcp_send, c= fd18639027(, n=192998)
>>tcp_main_loop: read response= fd186390, 1 from 19 (19291)
>>19(19291) tcp_send: after receive_fd: c= fd186390 n=4 fd=31
>>19(19291) tcp_send: sending...
>>19(19291) tcp_send: after write: c= fd186390 n=595 fd=31
>>19(19291) tcp_send: buf=
>>SIP/2.0 200 OK
>>Via: SIP/2.0/TCP 129.150.32.27:7685
>>From: ;tag=ef57bcc5-d7d1-4527-ab39-6e68a8617246
>>To: ;tag=b27e1a1d33761e85846fc98f5f3a7e58.7c4c
>>Call-ID: 9ddfc882-0233-418c-adfb-0a760a1147e4 at 129.150.32.27
>>CSeq: 1 REGISTER
>>Contact: ;q=0.00;expires=1200
>>Server: Sip EXpress router (0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>Warning: 392 129.146.175.207:5060 "Noisy feedback tells: pid=19291
>>req_src_ip=129.150.32.27 req_src_port=1064 in_uri=sip:sipserver
>>out_uri=sip:sipserver via_cnt==1"
>>
>>
>>19(19291) receive_msg: cleaning up
>>19(19291) tcp_receive_loop: fd186390 expired (1800, 1801)
>>19(19291) releasing con fd186390, state 0, fd=11, id=10
>>19(19291) extra_data 0
>>27(19299) tcp_main_loop: reader response= fd186390, 0 from 0
>>27(19299) tcp_main_loop: CONN_RELEASE fd186390 refcnt= 0
>>27(19299) tcp_main_loop: data available on fd1a8170 [h:48] 11
>>27(19299) send2child: to tcp child 0 19(19291), fd1a8170
>>19(19291) received n=4 con=fd1a8170, fd=11
>>19(19291) tcp_read_req: content-length= 109
>>19(19291) SIP Request:
>>19(19291) method:
>>19(19291) uri:
>>19(19291) version:
>>19(19291) parse_headers: flags=1
>>19(19291) end of header reached, state=5
>>19(19291) parse_headers: Via found, flags=1
>>19(19291) parse_headers: this is the first via
>>19(19291) After parse_msg...
>>19(19291) preparing to run routing scripts...
>>19(19291) DEBUG : is_maxfwd_present: searching for max_forwards header
>>19(19291) parse_headers: flags=128
>>19(19291) DEBUG: is_maxfwd_present: value = 70
>>19(19291) parse_headers: flags=8
>>19(19291) DEBUG: add_param: tag=3b3b0bff72424892aa8e78d5bbf3a316
>>19(19291) DEBUG: add_param: epid=8bb54d4c1b
>>19(19291) end of header reached, state=29
>>19(19291) parse_headers: flags=256
>>19(19291) end of header reached, state=9
>>19(19291) DEBUG: get_hdr_field: [29]; uri=[sip:rao-toshiba at sipserver]
>>19(19291) DEBUG: to body [
>>]
>>19(19291) get_hdr_field: cseq : <1>
>>19(19291) DEBUG: get_hdr_body : content_length=109
>>19(19291) found end of header
>>19(19291) find_first_route(): No Route headers found
>>19(19291) loose_route(): There is no Route HF
>>19(19291) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) rwrite(): Rewriting Request-URI with
>>'sip:129.150.32.27:7685;transport=tcp'
>>19(19291) DEBUG: t_addifnew: msg id=17 , global msg id=13 , T on
>>entrance=ffffffff
>>19(19291) parse_headers: flags=-1
>>19(19291) parse_headers: flags=60
>>19(19291) t_lookup_request: start searching: hash=8448, isACK=0
>>19(19291) DEBUG: proceeding to pre-RFC3261 transaction matching
>>19(19291) DEBUG: t_lookup_request: no transaction found
>>19(19291) SER: new INVITE
>>19(19291) parse_headers: flags=-1
>>19(19291) check_via_address(129.146.85.163, 129.146.85.163, 0)
>>19(19291) WARNING:vqm_resize: resize(0) called
>>19(19291) forward_request to: ip addr:129.146.85.163
>>19(19291) tcp_send: tcp connection found (fd1a8170), acquiring fd
>>19(19291) tcp_send, c= fd1a817027(, n=8
>>19299) tcp_main_loop: read response= fd1a8170, 1 from 19 (19291)
>>19(19291) tcp_send: after receive_fd: c= fd1a8170 n=4 fd=31
>>19(19291) tcp_send: sending...
>>19(19291) tcp_send: after write: c= fd1a8170 n=583 fd=31
>>19(19291) tcp_send: buf=
>>SIP/2.0 100 trying -- your call is important to us
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To:
>>Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163
>>CSeq: 1 INVITE
>>Server: Sip EXpress router (0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>Warning: 392 129.146.175.207:5060 "Noisy feedback tells: pid=19291
>>req_src_ip=129.146.85.163 req_src_port=1427 
>>in_uri=sip:rao-toshiba at sipserver
>>out_uri=sip:129.150.32.27:7685;transport=tcp via_cnt==1"
>>
>>
>>19(19291) DEBUG: reply sent out. buf=990b8: SIP/2.0 1..., shmem=fd1b8e68:
>>SIP/2.0 1
>>19(19291) DEBUG: t_reply: finished
>>19(19291) DEBUG: mk_proxy: doing DNS lookup...
>>19(19291) build_req_from_req: id added: <;i=9>, rcv proto=2
>>19(19291) build_req_from_req: checking for clen; proto=2, rcv->proto=2
>>19(19291) parse_headers: flags=2048
>>19(19291) check_via_address(129.146.85.163, 129.146.85.163, 0)
>>19(19291) forward_request to: ip addr:129.150.32.27
>>19(19291) tcp_send: tcp connection found (fd1b9ac8), acquiring fd
>>19(19291) tcp_send, c= fd1b9ac827(, n=8
>>19299) tcp_main_loop: read response= fd1b9ac8, 1 from 19 (19291)
>>19(19291) tcp_send: after receive_fd: c= fd1b9ac8 n=4 fd=31
>>19(19291) tcp_send: sending...
>>19(19291) tcp_send: after write: c= fd1b9ac8 n=922 fd=31
>>19(19291) tcp_send: buf=
>>INVITE sip:129.150.32.27:7685;transport=tcp SIP/2.0
>>Record-Route:
>>a316;lr=on>
>>Via: SIP/2.0/TCP 129.146.175.207;branch=z9hG4bK0012.a7d6.0;i=9
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>Max-Forwards: 69
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To:
>>Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163
>>CSeq: 1 INVITE
>>Contact:
>>User-Agent: RTC/1.2
>>Roster-Manager: sip:rao-sony at sipserver
>>EndPoints: "rao-sony at sipserver" ,
>>
>>Supported: com.microsoft.rtc-multiparty
>>Content-Type: application/sdp
>>Content-Length: 109
>>
>>v=0
>>o=- 0 0 IN IP4 129.146.85.163
>>s=session
>>c=IN IP4 129.146.85.163
>>t=0 0
>>m=x-ms-message 5060 sip null
>>
>>19(19291) DEBUG: add_to_tail_of_timer[0]: fd197fc0
>>19(19291) SER: new transaction fwd'ed
>>19(19291) receive_msg: cleaning up
>>27(19299) tcp_main_loop: data available on fd1b9ac8 [h:542] 48
>>27(19299) send2child: to tcp child 1 20(19292), fd1b9ac8
>>20(19292) received n=4 con=fd1b9ac8, fd=11
>>20(19292) tcp_read_req: content-length= 0
>>20(19292) SIP Reply (status):
>>20(19292) version:
>>20(19292) status: <488>
>>20(19292) reason:
>>20(19292) parse_headers: flags=1
>>20(19292) Found param type 232, = ; state=6
>>20(19292) Found param type 236, /= <9>; state=16
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=1
>>20(19292) parse_headers: this is the first via
>>20(19292) After parse_msg...
>>20(19292) forward_reply: found module tm, passing reply to it
>>20(19292) DEBUG: t_check: msg id=16 global id=15 T start=ffffffff
>>20(19292) parse_headers: flags=17
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=17
>>20(19292) parse_headers: this is the second via
>>20(19292) DEBUG: add_param: tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7
>>20(19292) end of header reached, state=29
>>20(19292) DEBUG: get_hdr_field: [70]; uri=[sip:rao-toshiba at sipserver]
>>20(19292) DEBUG: to body []
>>20(19292) get_hdr_field: cseq : <1>
>>20(19292) parse_headers: flags=4
>>20(19292) DEBUG: t_reply_matching: hash 8448 label 28026 branch 0
>>20(19292) DEBUG: t_reply_matching: reply matched (T=fd197e80)!
>>20(19292) DEBUG: t_check: msg id=16 global id=16 T end=fd197e80
>>20(19292) DEBUG: t_on_reply: org. status uas=100, uac[0]=0 local=0 
>>is_invite=1)
>>20(19292) parse_headers: flags=4
>>20(19292) forward_request to: ip addr:129.150.32.27
>>20(19292) tcp_send: tcp connection found (fd1b9ac8), acquiring fd
>>20(19292) 27(tcp_send, c= fd1b9ac8, n=819299)
>>tcp_main_loop: read response= fd1b9ac8, 1 from 20 (19292)
>>20(19292) tcp_send: after receive_fd: c= fd1b9ac8 n=4 fd=32
>>20(19292) tcp_send: sending...
>>20(19292) tcp_send: after write: c= fd1b9ac8 n=440 fd=32
>>20(19292) tcp_send: buf=
>>ACK sip:129.150.32.27:7685;transport=tcp SIP/2.0
>>Via: SIP/2.0/TCP 129.146.175.207;branch=z9hG4bK0012.a7d6.0
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163
>>To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7
>>CSeq: 1 ACK
>>User-Agent: Sip EXpress router(0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>
>>
>>20(19292) ->>>>>>>>> T_code=100, new_code=488
>>20(19292) DEBUG: relay_reply: branch=0, save=0, relay=0
>>20(19292) build_res_from_sip_res: checking content-length for
>>SIP/2.0 488 Not Acceptable Here
>>Via: SIP/2.0/TCP 129.146.175.207;branch=z9hG4bK0012.a7d6.0;i=9
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7
>>Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163
>>CSeq: 1 INVITE
>>User-Agent: Windows RTC/1.0
>>Content-Length: 0
>>
>>
>>20(19292) parse_headers: flags=2048
>>20(19292) DEBUG: get_hdr_body : content_length=0
>>20(19292) old size: 440, new size: 376
>>20(19292) build_res_from_sip_res: copied size: orig:97, new: 33, rest: 
>>343 msg=
>>SIP/2.0 488 Not Acceptable Here
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7
>>Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163
>>CSeq: 1 INVITE
>>User-Agent: Windows RTC/1.0
>>Content-Length: 0
>>
>>
>>20(19292) forward_request to: ip addr:129.146.85.163
>>20(19292) tcp_send: tcp connection found (fd1a8170), acquiring fd
>>20(19292) 27(tcp_send, c= fd1a8170, n=192998
>>) tcp_main_loop: read response= fd1a8170, 1 from 20 (19292)
>>20(19292) tcp_send: after receive_fd: c= fd1a8170 n=4 fd=32
>>20(19292) tcp_send: sending...
>>20(19292) tcp_send: after write: c= fd1a8170 n=376 fd=32
>>20(19292) tcp_send: buf=
>>SIP/2.0 488 Not Acceptable Here
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7
>>Call-ID: 951c963c0be44507aa2f39076c584129 at 129.146.85.163
>>CSeq: 1 INVITE
>>User-Agent: Windows RTC/1.0
>>Content-Length: 0
>>
>>
>>20(19292) DEBUG: reply relayed. buf=98e88: SIP/2.0 4..., 
>>shmem=fd1a7f60: SIP/2.0
>>4
>>20(19292) DEBUG: cleanup_uacs: RETR/FR timers reset
>>20(19292) DEBUG: add_to_tail_of_timer[0]: fd197f48
>>20(19292) receive_msg: cleaning up
>>19(19291) tcp_read_req: content-length= 0
>>19(19291) SIP Request:
>>19(19291) method:
>>19(19291) uri:
>>19(19291) version:
>>19(19291) parse_headers: flags=1
>>19(19291) end of header reached, state=5
>>19(19291) parse_headers: Via found, flags=1
>>19(19291) parse_headers: this is the first via
>>19(19291) After parse_msg...
>>19(19291) DEBUG : sl_filter_ACK: to late to be a local ACK!
>>19(19291) preparing to run routing scripts...
>>19(19291) DEBUG : is_maxfwd_present: searching for max_forwards header
>>19(19291) parse_headers: flags=128
>>19(19291) DEBUG: is_maxfwd_present: value = 70
>>19(19291) parse_headers: flags=8
>>19(19291) DEBUG: add_param: tag=3b3b0bff72424892aa8e78d5bbf3a316
>>19(19291) DEBUG: add_param: epid=8bb54d4c1b
>>19(19291) end of header reached, state=29
>>19(19291) parse_headers: flags=256
>>19(19291) DEBUG: add_param: tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7
>>19(19291) end of header reached, state=29
>>19(19291) DEBUG: get_hdr_field: [70]; uri=[sip:rao-toshiba at sipserver]
>>19(19291) DEBUG: to body []
>>19(19291) get_hdr_field: cseq : <1>
>>19(19291) DEBUG: get_hdr_body : content_length=0
>>19(19291) found end of header
>>19(19291) find_first_route(): No Route headers found
>>19(19291) loose_route(): There is no Route HF
>>19(19291) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) rwrite(): Rewriting Request-URI with
>>'sip:129.150.32.27:7685;transport=tcp'
>>19(19291) DEBUG: t_addifnew: msg id=18 , global msg id=17 , T on
>>entrance=ffffffff
>>19(19291) parse_headers: flags=-1
>>19(19291) parse_headers: flags=60
>>19(19291) t_lookup_request: start searching: hash=8448, isACK=1
>>19(19291) DEBUG: proceeding to pre-RFC3261 transaction matching
>>19(19291) DEBUG: non-2xx ACK matched
>>19(19291) DEBUG: t_lookup_request: transaction found (T=fd197e80)
>>19(19291) DEBUG: cleanup_uacs: RETR/FR timers reset
>>19(19291) DEBUG: add_to_tail_of_timer[2]: fd197ec8
>>19(19291) receive_msg: cleaning up
>>19(19291) tcp_read_req: content-length= 17
>>19(19291) SIP Request:
>>19(19291) method:
>>19(19291) uri:
>>19(19291) version:
>>19(19291) parse_headers: flags=1
>>19(19291) end of header reached, state=5
>>19(19291) parse_headers: Via found, flags=1
>>19(19291) parse_headers: this is the first via
>>19(19291) After parse_msg...
>>19(19291) preparing to run routing scripts...
>>19(19291) DEBUG : is_maxfwd_present: searching for max_forwards header
>>19(19291) parse_headers: flags=128
>>19(19291) DEBUG: is_maxfwd_present: value = 70
>>19(19291) parse_headers: flags=8
>>19(19291) DEBUG: add_param: tag=3b3b0bff72424892aa8e78d5bbf3a316
>>19(19291) DEBUG: add_param: epid=8bb54d4c1b
>>19(19291) end of header reached, state=29
>>19(19291) parse_headers: flags=256
>>19(19291) end of header reached, state=9
>>19(19291) DEBUG: get_hdr_field: [29]; uri=[sip:rao-toshiba at sipserver]
>>19(19291) DEBUG: to body [
>>]
>>19(19291) get_hdr_field: cseq : <2>
>>19(19291) DEBUG: get_hdr_body : content_length=17
>>19(19291) found end of header
>>19(19291) find_first_route(): No Route headers found
>>19(19291) loose_route(): There is no Route HF
>>19(19291) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>19(19291) check_self - checking if port 5060 matches port 5060
>>19(19291) rwrite(): Rewriting Request-URI with
>>'sip:129.150.32.27:7685;transport=tcp'
>>19(19291) DEBUG: t_addifnew: msg id=19 , global msg id=18 , T on
>>entrance=ffffffff
>>19(19291) parse_headers: flags=-1
>>19(19291) parse_headers: flags=60
>>19(19291) t_lookup_request: start searching: hash=43044, isACK=0
>>19(19291) DEBUG: proceeding to pre-RFC3261 transaction matching
>>19(19291) DEBUG: t_lookup_request: no transaction found
>>19(19291) DEBUG: mk_proxy: doing DNS lookup...
>>19(19291) build_req_from_req: id added: <;i=9>, rcv proto=2
>>19(19291) build_req_from_req: checking for clen; proto=2, rcv->proto=2
>>19(19291) parse_headers: flags=2048
>>19(19291) check_via_address(129.146.85.163, 129.146.85.163, 0)
>>19(19291) forward_request to: ip addr:129.150.32.27
>>19(19291) tcp_send: tcp connection found (fd1b9ac8), acquiring fd
>>19(19291) tcp_send, c= 27(fd1b9ac8, n=819299
>>) tcp_main_loop: read response= fd1b9ac8, 1 from 19 (19291)
>>19(19291) tcp_send: after receive_fd: c= fd1b9ac8 n=4 fd=31
>>19(19291) tcp_send: sending...
>>19(19291) tcp_send: after write: c= fd1b9ac8 n=857 fd=31
>>19(19291) tcp_send: buf=
>>MESSAGE sip:129.150.32.27:7685;transport=tcp SIP/2.0
>>Record-Route:
>>a316;lr=on>
>>Via: SIP/2.0/TCP 129.146.175.207;branch=z9hG4bK428a.aeb.0;i=9
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>Max-Forwards: 69
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To:
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 2 MESSAGE
>>Contact:
>>User-Agent: RTC/1.2
>>Content-Type: text/plain;
>>charset=UTF-8;msgr=WAAtAE0ATQBTAC0ASQBNAC0ARgBvAHIAbQBhAHQAOgAgAEYATgA9AE0AUwAlA
>>DIAMABTAGgAZQBsAGwAJQAyADAARABsAGcAJQAyADAAMgA7ACAARQBGAD0AOwAgAEMATwA9ADAAOwAgA
>>EMAUwA9ADEAOwAgAFAARgA9ADAADQAKAA0ACgA
>>Content-Length: 17
>>
>>sending from sony
>>19(19291) DEBUG: add_to_tail_of_timer[0]: fd1c9ce8
>>19(19291) SER: new transaction fwd'ed
>>19(19291) receive_msg: cleaning up
>>20(19292) tcp_read_req: content-length= 0
>>20(19292) SIP Reply (status):
>>20(19292) version:
>>20(19292) status: <200>
>>20(19292) reason:
>>20(19292) parse_headers: flags=1
>>20(19292) Found param type 232, = ; state=6
>>20(19292) Found param type 236, /= <9>; state=16
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=1
>>20(19292) parse_headers: this is the first via
>>20(19292) After parse_msg...
>>20(19292) forward_reply: found module tm, passing reply to it
>>20(19292) DEBUG: t_check: msg id=17 global id=16 T start=ffffffff
>>20(19292) parse_headers: flags=17
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=17
>>20(19292) parse_headers: this is the second via
>>20(19292) DEBUG: add_param: tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>20(19292) end of header reached, state=29
>>20(19292) DEBUG: get_hdr_field: [70]; uri=[sip:rao-toshiba at sipserver]
>>20(19292) DEBUG: to body []
>>20(19292) get_hdr_field: cseq : <2>
>>20(19292) DEBUG: t_reply_matching: hash 43044 label 3050 branch 0
>>20(19292) DEBUG: t_reply_matching: reply matched (T=fd1c9ba8)!
>>20(19292) DEBUG: t_check: msg id=17 global id=17 T end=fd1c9ba8
>>20(19292) DEBUG: t_on_reply: org. status uas=0, uac[0]=0 local=0 
>>is_invite=0)
>>20(19292) ->>>>>>>>> T_code=0, new_code=200
>>20(19292) DEBUG: relay_reply: branch=0, save=0, relay=0
>>20(19292) build_res_from_sip_res: checking content-length for
>>SIP/2.0 200 OK
>>Via: SIP/2.0/TCP 129.146.175.207;branch=z9hG4bK428a.aeb.0;i=9
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 2 MESSAGE
>>Record-Route:
>>a316;lr=on>
>>Contact:
>>User-Agent: Windows RTC/1.0
>>Content-Length: 0
>>
>>
>>20(19292) parse_headers: flags=2048
>>20(19292) DEBUG: get_hdr_body : content_length=0
>>20(19292) old size: 579, new size: 516
>>20(19292) build_res_from_sip_res: copied size: orig:79, new: 16, rest: 
>>500 msg=
>>SIP/2.0 200 OK
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 2 MESSAGE
>>Record-Route:
>>a316;lr=on>
>>Contact:
>>User-Agent: Windows RTC/1.0
>>Content-Length: 0
>>
>>
>>20(19292) WARNING:vqm_resize: resize(0) called
>>20(19292) forward_request to: ip addr:129.146.85.163
>>20(19292) tcp_send: tcp connection found (fd1a8170), acquiring fd
>>20(19292) tcp_send, c= fd1a817027(, n=8
>>19299) tcp_main_loop: read response= fd1a8170, 1 from 20 (19292)
>>20(19292) tcp_send: after receive_fd: c= fd1a8170 n=4 fd=32
>>20(19292) tcp_send: sending...
>>20(19292) tcp_send: after write: c= fd1a8170 n=516 fd=32
>>20(19292) tcp_send: buf=
>>SIP/2.0 200 OK
>>Via: SIP/2.0/TCP 129.146.85.163:13395
>>From: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 2 MESSAGE
>>Record-Route:
>>a316;lr=on>
>>Contact:
>>User-Agent: Windows RTC/1.0
>>Content-Length: 0
>>
>>
>>20(19292) DEBUG: reply relayed. buf=991a8: SIP/2.0 2..., 
>>shmem=fd197b50: SIP/2.0
>>2
>>20(19292) DEBUG: cleanup_uacs: RETR/FR timers reset
>>20(19292) DEBUG: add_to_tail_of_timer[2]: fd1c9bf0
>>20(19292) receive_msg: cleaning up
>>18(19290) DEBUG: timer routine:0,tl=fd197fc0 next=fd197f48
>>18(19290) DEBUG: timer routine:0,tl=fd197f48 next=fd1c9ce8
>>18(19290) DEBUG: timer routine:0,tl=fd1c9ce8 next=0
>>18(19290) DEBUG: timer routine:2,tl=fd197ec8 next=fd1c9bf0
>>18(19290) DEBUG: wait_handler : removing fd197e80 from table
>>18(19290) DEBUG: delete transaction fd197e80
>>18(19290) DEBUG: wait_handler : done
>>18(19290) DEBUG: timer routine:2,tl=fd1c9bf0 next=0
>>18(19290) DEBUG: wait_handler : removing fd1c9ba8 from table
>>18(19290) DEBUG: delete transaction fd1c9ba8
>>18(19290) DEBUG: wait_handler : done
>>20(19292) tcp_receive_loop: fd1b9ac8 expired (1840, 1841)
>>20(19292) releasing con fd1b9ac8, state 0, fd=11, id=4
>>20(19292) extra_data 0
>>27(19299) tcp_main_loop: reader response= fd1b9ac8, 0 from 1
>>27(19299) tcp_main_loop: CONN_RELEASE fd1b9ac8 refcnt= 0
>>27(19299) tcp_main_loop: data available on fd186390 [h:51] 29
>>27(19299) send2child: to tcp child 1 20(19292), fd186390
>>20(19292) received n=4 con=fd186390, fd=11
>>20(19292) tcp_read_req: content-length= 96
>>20(19292) SIP Request:
>>20(19292) method:
>>20(19292) uri:
>>
>>20(19292) version:
>>20(19292) parse_headers: flags=1
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=1
>>20(19292) parse_headers: this is the first via
>>20(19292) After parse_msg...
>>20(19292) preparing to run routing scripts...
>>20(19292) DEBUG : is_maxfwd_present: searching for max_forwards header
>>20(19292) parse_headers: flags=128
>>20(19292) DEBUG: add_param: tag=3b3b0bff72424892aa8e78d5bbf3a316
>>20(19292) DEBUG: add_param: epid=8bb54d4c1b
>>20(19292) end of header reached, state=29
>>20(19292) DEBUG: get_hdr_field: [100]; uri=[sip:rao-sony at sipserver]
>>20(19292) DEBUG: to body ["rao-sony at sipserver" ]
>>20(19292) get_hdr_field: cseq : <1>
>>20(19292) DEBUG: get_hdr_body : content_length=96
>>20(19292) found end of header
>>20(19292) DEBUG: is_maxfwd_present: max_forwards header not found!
>>20(19292) DEBUG: add_param: tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>20(19292) end of header reached, state=29
>>20(19292) parse_headers: flags=256
>>20(19292) check_self - checking if host==us: 15==9 && 
>>[129.146.175.207] ==
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 15==15 && 
>>[129.146.175.207] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self: host != me
>>20(19292) ras(): Next hop:
>>'sip:rao-sony at sipserver:13395;maddr=129.146.85.163;transport=tcp' is 
>>strict
>>router
>>20(19292) DEBUG: t_addifnew: msg id=18 , global msg id=17 , T on
>>entrance=ffffffff
>>20(19292) parse_headers: flags=-1
>>20(19292) parse_headers: flags=60
>>20(19292) t_lookup_request: start searching: hash=43047, isACK=0
>>20(19292) DEBUG: proceeding to pre-RFC3261 transaction matching
>>20(19292) DEBUG: t_lookup_request: no transaction found
>>20(19292) DEBUG: mk_proxy: doing DNS lookup...
>>20(19292) build_req_from_req: id added: <;i=a>, rcv proto=2
>>20(19292) build_req_from_req: checking for clen; proto=2, rcv->proto=2
>>20(19292) parse_headers: flags=2048
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) forward_request to: ip addr:129.146.175.207
>>20(19292) tcp_send: no open tcp connection found, opening new one
>>20(19292) ERROR: tcpconn_connect: connect: (146) Connection refused
>>20(19292) ERROR: tcp_send: connect failed
>>20(19292) msg_send: ERROR: Rao tcp_send failed
>>20(19292) ERROR: t_forward_nonack: sending request failed
>>20(19292) DEBUG: add_to_tail_of_timer[0]: fd1c9ce8
>>20(19292) SER:ERROR: t_forward
>>20(19292) parse_headers: flags=-1
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) ERROR: warning_builder: buffer size exceeded
>>20(19292) WARNING: warning skipped -- too big
>>20(19292) WARNING:vqm_resize: resize(0) called
>>20(19292) DEBUG: cleanup_uacs: RETR/FR timers reset
>>20(19292) DEBUG: add_to_tail_of_timer[2]: fd1c9bf0
>>20(19292) forward_request to: ip addr:129.150.32.27
>>20(19292) tcp_send: tcp connection found (fd186390), acquiring fd
>>20(19292) tcp_send, c= fd18639027(, n=8
>>19299) tcp_main_loop: read response= fd186390, 1 from 20 (19292)
>>20(19292) tcp_send: after receive_fd: c= fd186390 n=4 fd=32
>>20(19292) tcp_send: sending...
>>20(19292) tcp_send: after write: c= fd186390 n=438 fd=32
>>20(19292) tcp_send: buf=
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)
>>Via: SIP/2.0/TCP 129.150.32.27:7685
>>From: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>To: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 1 INFO
>>Server: Sip EXpress router (0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>
>>
>>20(19292) DEBUG: reply sent out. buf=991a8: SIP/2.0 4..., shmem=fd1b9228:
>>SIP/2.0 4
>>20(19292) DEBUG: t_reply: finished
>>20(19292) ERROR: generation of a stateful reply on error succeeded
>>20(19292) receive_msg: cleaning up
>>18(19290) DEBUG: timer routine:0,tl=fd1c9ce8 next=0
>>18(19290) DEBUG: timer routine:2,tl=fd1c9bf0 next=0
>>18(19290) DEBUG: wait_handler : removing fd1c9ba8 from table
>>18(19290) DEBUG: delete transaction fd1c9ba8
>>18(19290) DEBUG: wait_handler : done
>>20(19292) tcp_read_req: content-length= 96
>>20(19292) SIP Request:
>>20(19292) method:
>>20(19292) uri:
>>
>>20(19292) version:
>>20(19292) parse_headers: flags=1
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=1
>>20(19292) parse_headers: this is the first via
>>20(19292) After parse_msg...
>>20(19292) preparing to run routing scripts...
>>20(19292) DEBUG : is_maxfwd_present: searching for max_forwards header
>>20(19292) parse_headers: flags=128
>>20(19292) DEBUG: add_param: tag=3b3b0bff72424892aa8e78d5bbf3a316
>>20(19292) DEBUG: add_param: epid=8bb54d4c1b
>>20(19292) end of header reached, state=29
>>20(19292) DEBUG: get_hdr_field: [100]; uri=[sip:rao-sony at sipserver]
>>20(19292) DEBUG: to body ["rao-sony at sipserver" ]
>>20(19292) get_hdr_field: cseq : <2>
>>20(19292) DEBUG: get_hdr_body : content_length=96
>>20(19292) found end of header
>>20(19292) DEBUG: is_maxfwd_present: max_forwards header not found!
>>20(19292) DEBUG: add_param: tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>20(19292) end of header reached, state=29
>>20(19292) parse_headers: flags=256
>>20(19292) check_self - checking if host==us: 15==9 && 
>>[129.146.175.207] ==
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 15==15 && 
>>[129.146.175.207] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self: host != me
>>20(19292) ras(): Next hop:
>>'sip:rao-sony at sipserver:13395;maddr=129.146.85.163;transport=tcp' is 
>>strict
>>router
>>20(19292) DEBUG: t_addifnew: msg id=19 , global msg id=18 , T on
>>entrance=ffffffff
>>20(19292) parse_headers: flags=-1
>>20(19292) parse_headers: flags=60
>>20(19292) t_lookup_request: start searching: hash=43044, isACK=0
>>20(19292) DEBUG: proceeding to pre-RFC3261 transaction matching
>>20(19292) DEBUG: t_lookup_request: no transaction found
>>20(19292) DEBUG: mk_proxy: doing DNS lookup...
>>20(19292) build_req_from_req: id added: <;i=a>, rcv proto=2
>>20(19292) build_req_from_req: checking for clen; proto=2, rcv->proto=2
>>20(19292) parse_headers: flags=2048
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) forward_request to: ip addr:129.146.175.207
>>20(19292) tcp_send: no open tcp connection found, opening new one
>>20(19292) ERROR: tcpconn_connect: connect: (146) Connection refused
>>20(19292) ERROR: tcp_send: connect failed
>>20(19292) msg_send: ERROR: Rao tcp_send failed
>>20(19292) ERROR: t_forward_nonack: sending request failed
>>20(19292) DEBUG: add_to_tail_of_timer[0]: fd1c9ce8
>>20(19292) SER:ERROR: t_forward
>>20(19292) parse_headers: flags=-1
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) ERROR: warning_builder: buffer size exceeded
>>20(19292) WARNING: warning skipped -- too big
>>20(19292) WARNING:vqm_resize: resize(0) called
>>20(19292) DEBUG: cleanup_uacs: RETR/FR timers reset
>>20(19292) DEBUG: add_to_tail_of_timer[2]: fd1c9bf0
>>20(19292) forward_request to: ip addr:129.150.32.27
>>20(19292) tcp_send: tcp connection found (fd186390), acquiring fd
>>20(19292) tcp_send, c= fd186390, n=27(8
>>19299) tcp_main_loop: read response= fd186390, 1 from 20 (19292)
>>20(19292) tcp_send: after receive_fd: c= fd186390 n=4 fd=32
>>20(19292) tcp_send: sending...
>>20(19292) tcp_send: after write: c= fd186390 n=438 fd=32
>>20(19292) tcp_send: buf=
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)
>>Via: SIP/2.0/TCP 129.150.32.27:7685
>>From: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>To: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 2 INFO
>>Server: Sip EXpress router (0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>
>>
>>20(19292) DEBUG: reply sent out. buf=991a8: SIP/2.0 4..., shmem=fd1b9228:
>>SIP/2.0 4
>>20(19292) DEBUG: t_reply: finished
>>20(19292) ERROR: generation of a stateful reply on error succeeded
>>20(19292) receive_msg: cleaning up
>>18(19290) DEBUG: timer routine:0,tl=fd1c9ce8 next=0
>>18(19290) DEBUG: timer routine:2,tl=fd1c9bf0 next=0
>>18(19290) DEBUG: wait_handler : removing fd1c9ba8 from table
>>18(19290) DEBUG: delete transaction fd1c9ba8
>>18(19290) DEBUG: wait_handler : done
>>20(19292) tcp_read_req: content-length= 96
>>20(19292) SIP Request:
>>20(19292) method:
>>20(19292) uri:
>>
>>20(19292) version:
>>20(19292) parse_headers: flags=1
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=1
>>20(19292) parse_headers: this is the first via
>>20(19292) After parse_msg...
>>20(19292) preparing to run routing scripts...
>>20(19292) DEBUG : is_maxfwd_present: searching for max_forwards header
>>20(19292) parse_headers: flags=128
>>20(19292) DEBUG: add_param: tag=3b3b0bff72424892aa8e78d5bbf3a316
>>20(19292) DEBUG: add_param: epid=8bb54d4c1b
>>20(19292) end of header reached, state=29
>>20(19292) DEBUG: get_hdr_field: [100]; uri=[sip:rao-sony at sipserver]
>>20(19292) DEBUG: to body ["rao-sony at sipserver" ]
>>20(19292) get_hdr_field: cseq : <3>
>>20(19292) DEBUG: get_hdr_body : content_length=96
>>20(19292) found end of header
>>20(19292) DEBUG: is_maxfwd_present: max_forwards header not found!
>>20(19292) DEBUG: add_param: tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>20(19292) end of header reached, state=29
>>20(19292) parse_headers: flags=256
>>20(19292) check_self - checking if host==us: 15==9 && 
>>[129.146.175.207] ==
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 15==15 && 
>>[129.146.175.207] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self: host != me
>>20(19292) ras(): Next hop:
>>'sip:rao-sony at sipserver:13395;maddr=129.146.85.163;transport=tcp' is 
>>strict
>>router
>>20(19292) DEBUG: t_addifnew: msg id=20 , global msg id=19 , T on
>>entrance=ffffffff
>>20(19292) parse_headers: flags=-1
>>20(19292) parse_headers: flags=60
>>20(19292) t_lookup_request: start searching: hash=43045, isACK=0
>>20(19292) DEBUG: proceeding to pre-RFC3261 transaction matching
>>20(19292) DEBUG: t_lookup_request: no transaction found
>>20(19292) DEBUG: mk_proxy: doing DNS lookup...
>>20(19292) build_req_from_req: id added: <;i=a>, rcv proto=2
>>20(19292) build_req_from_req: checking for clen; proto=2, rcv->proto=2
>>20(19292) parse_headers: flags=2048
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) forward_request to: ip addr:129.146.175.207
>>20(19292) tcp_send: no open tcp connection found, opening new one
>>20(19292) ERROR: tcpconn_connect: connect: (146) Connection refused
>>20(19292) ERROR: tcp_send: connect failed
>>20(19292) msg_send: ERROR: Rao tcp_send failed
>>20(19292) ERROR: t_forward_nonack: sending request failed
>>20(19292) DEBUG: add_to_tail_of_timer[0]: fd1c9ce8
>>20(19292) SER:ERROR: t_forward
>>20(19292) parse_headers: flags=-1
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) ERROR: warning_builder: buffer size exceeded
>>20(19292) WARNING: warning skipped -- too big
>>20(19292) WARNING:vqm_resize: resize(0) called
>>20(19292) DEBUG: cleanup_uacs: RETR/FR timers reset
>>20(19292) DEBUG: add_to_tail_of_timer[2]: fd1c9bf0
>>20(19292) forward_request to: ip addr:129.150.32.27
>>20(19292) tcp_send: tcp connection found (fd186390), acquiring fd
>>20(19292) tcp_send, c= 27(fd186390, n=819299) tcp_main_loop: read 
>>response=
>>fd186390, 1 from 20 (19292)
>>20(19292) tcp_send: after receive_fd: c= fd186390 n=4 fd=32
>>20(19292) tcp_send: sending...
>>20(19292) tcp_send: after write: c= fd186390 n=438 fd=32
>>20(19292) tcp_send: buf=
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)
>>Via: SIP/2.0/TCP 129.150.32.27:7685
>>From: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>To: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 3 INFO
>>Server: Sip EXpress router (0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>
>>
>>20(19292) DEBUG: reply sent out. buf=991a8: SIP/2.0 4..., shmem=fd1b9228:
>>SIP/2.0 4
>>20(19292) DEBUG: t_reply: finished
>>20(19292) ERROR: generation of a stateful reply on error succeeded
>>20(19292) receive_msg: cleaning up
>>18(19290) DEBUG: timer routine:0,tl=fd1c9ce8 next=0
>>18(19290) DEBUG: timer routine:2,tl=fd1c9bf0 next=0
>>18(19290) DEBUG: wait_handler : removing fd1c9ba8 from table
>>18(19290) DEBUG: delete transaction fd1c9ba8
>>18(19290) DEBUG: wait_handler : done
>>20(19292) tcp_read_req: content-length= 20
>>20(19292) SIP Request:
>>20(19292) method:
>>20(19292) uri:
>>
>>20(19292) version:
>>20(19292) parse_headers: flags=1
>>20(19292) end of header reached, state=5
>>20(19292) parse_headers: Via found, flags=1
>>20(19292) parse_headers: this is the first via
>>20(19292) After parse_msg...
>>20(19292) preparing to run routing scripts...
>>20(19292) DEBUG : is_maxfwd_present: searching for max_forwards header
>>20(19292) parse_headers: flags=128
>>20(19292) DEBUG: add_param: tag=3b3b0bff72424892aa8e78d5bbf3a316
>>20(19292) DEBUG: add_param: epid=8bb54d4c1b
>>20(19292) end of header reached, state=29
>>20(19292) DEBUG: get_hdr_field: [100]; uri=[sip:rao-sony at sipserver]
>>20(19292) DEBUG: to body ["rao-sony at sipserver" ]
>>20(19292) get_hdr_field: cseq : <4>
>>20(19292) DEBUG: get_hdr_body : content_length=20
>>20(19292) found end of header
>>20(19292) DEBUG: is_maxfwd_present: max_forwards header not found!
>>20(19292) DEBUG: add_param: tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>20(19292) end of header reached, state=29
>>20(19292) parse_headers: flags=256
>>20(19292) check_self - checking if host==us: 15==9 && 
>>[129.146.175.207] ==
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 15==15 && 
>>[129.146.175.207] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 5060
>>20(19292) check_self - checking if host==us: 9==9 && [sipserver] == 
>>[127.0.0.1]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self - checking if host==us: 9==15 && [sipserver] ==
>>[129.146.175.207]
>>20(19292) check_self - checking if port 5060 matches port 13395
>>20(19292) check_self: host != me
>>20(19292) ras(): Next hop:
>>'sip:rao-sony at sipserver:13395;maddr=129.146.85.163;transport=tcp' is 
>>strict
>>router
>>20(19292) DEBUG: t_addifnew: msg id=21 , global msg id=20 , T on
>>entrance=ffffffff
>>20(19292) parse_headers: flags=-1
>>20(19292) parse_headers: flags=60
>>20(19292) t_lookup_request: start searching: hash=43042, isACK=0
>>20(19292) DEBUG: proceeding to pre-RFC3261 transaction matching
>>20(19292) DEBUG: t_lookup_request: no transaction found
>>20(19292) DEBUG: mk_proxy: doing DNS lookup...
>>20(19292) build_req_from_req: id added: <;i=a>, rcv proto=2
>>20(19292) build_req_from_req: checking for clen; proto=2, rcv->proto=2
>>20(19292) parse_headers: flags=2048
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) forward_request to: ip addr:129.146.175.207
>>20(19292) tcp_send: no open tcp connection found, opening new one
>>20(19292) ERROR: tcpconn_connect: connect: (146) Connection refused
>>20(19292) ERROR: tcp_send: connect failed
>>20(19292) msg_send: ERROR: Rao tcp_send failed
>>20(19292) ERROR: t_forward_nonack: sending request failed
>>20(19292) DEBUG: add_to_tail_of_timer[0]: fd1c9ce8
>>20(19292) SER:ERROR: t_forward
>>20(19292) parse_headers: flags=-1
>>20(19292) check_via_address(129.150.32.27, 129.150.32.27, 0)
>>20(19292) ERROR: warning_builder: buffer size exceeded
>>20(19292) WARNING: warning skipped -- too big
>>20(19292) WARNING:vqm_resize: resize(0) called
>>20(19292) DEBUG: cleanup_uacs: RETR/FR timers reset
>>20(19292) DEBUG: add_to_tail_of_timer[2]: fd1c9bf0
>>20(19292) forward_request to: ip addr:129.150.32.27
>>20(19292) tcp_send: tcp connection found (fd186390), acquiring fd
>>20(19292) tcp_send, c= fd18639027(, n=8
>>19299) tcp_main_loop: read response= fd186390, 1 from 20 (19292)
>>20(19292) tcp_send: after receive_fd: c= fd186390 n=4 fd=32
>>20(19292) tcp_send: sending...
>>20(19292) tcp_send: after write: c= fd186390 n=441 fd=32
>>20(19292) tcp_send: buf=
>>SIP/2.0 477 Unfortunately error on sending to next hop occured (477/TM)
>>Via: SIP/2.0/TCP 129.150.32.27:7685
>>From: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9
>>To: "rao-sony at sipserver"
>>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b
>>Call-ID: 72452f0ccee6437193a271e057d52725 at 129.146.85.163
>>CSeq: 4 MESSAGE
>>Server: Sip EXpress router (0.8.12 (sparc64/solaris))
>>Content-Length: 0
>>
>>
>>20(19292) DEBUG: reply sent out. buf=99530: SIP/2.0 4..., shmem=fd1b9228:
>>SIP/2.0 4
>>20(19292) DEBUG: t_reply: finished
>>20(19292) ERROR: generation of a stateful reply on error succeeded
>>20(19292) receive_msg: cleaning up
>>18(19290) DEBUG: timer routine:0,tl=fd1c9ce8 next=0
>>18(19290) DEBUG: timer routine:2,tl=fd1c9bf0 next=0
>>18(19290) DEBUG: wait_handler : removing fd1c9ba8 from table
>>18(19290) DEBUG: delete transaction fd1c9ba8
>>18(19290) DEBUG: wait_handler : done
>>20(19292) tcp_receive_loop: fd186390 expired (1866, 1867)
>>20(19292) releasing con fd186390, state 0, fd=11, id=10
>>20(19292) extra_data 0
>>27(19299) tcp_main_loop: reader response= fd186390, 0 from 1
>>27(19299) tcp_main_loop: CONN_RELEASE fd186390 refcnt= 0
>>
>>#
>>#
>>#
>>/******************* ser.cfg file *******************************/
>>#
>># $Id: ser.cfg,v 1.21.4.1 2003/11/10 15:35:15 andrei Exp $
>>#
>># simple quick-start config script
>>#
>>
>># ----------- global configuration parameters ------------------------
>>
>>alias=sipserver
>>#debug=3 # debug level (cmd line: -dddddddddd)
>>#fork=yes
>>#log_stderror=no # (cmd line: -E)
>>
>>/* Uncomment these lines to enter debugging mode */
>>debug=9
>>#fork=no
>>log_stderror=yes
>>
>>check_via=no # (cmd. line: -v)
>>dns=no # (cmd. line: -r)
>>rev_dns=no # (cmd. line: -R)
>>#port=5060
>>#children=4
>>fifo="/tmp/ser_fifo"
>>
>># ------------------ module loading ----------------------------------
>>
>># Uncomment this if you want to use SQL database
>>loadmodule "/usr/local/lib/ser/modules/mysql.so"
>>
>>loadmodule "/usr/local/lib/ser/modules/sl.so"
>>loadmodule "/usr/local/lib/ser/modules/tm.so"
>>loadmodule "/usr/local/lib/ser/modules/rr.so"
>>loadmodule "/usr/local/lib/ser/modules/maxfwd.so"
>>loadmodule "/usr/local/lib/ser/modules/usrloc.so"
>>loadmodule "/usr/local/lib/ser/modules/registrar.so"
>>
>># Uncomment this if you want digest authentication
>># mysql.so must be loaded !
>>#loadmodule "/usr/local/lib/ser/modules/auth.so"
>>#loadmodule "/usr/local/lib/ser/modules/auth_db.so"
>>
>># ----------------- setting module-specific parameters ---------------
>>
>># -- usrloc params --
>>
>>##modparam("usrloc", "db_mode", 0)
>>
>># Uncomment this if you want to use SQL database
>># for persistent storage and comment the previous line
>>modparam("usrloc", "db_mode", 2)
>>
>># -- auth params --
>># Uncomment if you are using auth module
>>#
>>#modparam("auth_db", "calculate_ha1", yes)
>>#
>># If you set "calculate_ha1" parameter to yes (which true in this 
>>config),
>># uncomment also the following parameter)
>>#
>>#modparam("auth_db", "password_column", "password")
>>
>># -- rr params --
>># add value to ;lr param to make some broken UAs happy
>>modparam("rr", "enable_full_lr", 1)
>>
>># ------------------------- request routing logic -------------------
>>
>># main routing logic
>>
>>route{
>>
>># initial sanity checks -- messages with
>># max_forwards==0, or excessively long requests
>>if (!mf_process_maxfwd_header("10")) {
>>sl_send_reply("483","Too Many Hops");
>>break;
>>};
>>if ( msg:len > max_len ) {
>>sl_send_reply("513", "Message too big");
>>break;
>>};
>>
>># we record-route all messages -- to make sure that
>># subsequent messages will go through our proxy; that's
>># particularly good if upstream and downstream entities
>># use different transport protocol
>>record_route();
>># loose-route processing
>>if (loose_route()) {
>>t_relay();
>>break;
>>};
>>
>># if the request is for other domain use UsrLoc
>># (in case, it does not work, use the following command
>># with proper names and addresses in it)
>>if (uri==myself) {
>>
>>if (method=="REGISTER") {
>>
>># Uncomment this if you want to use digest authentication
>># if (!www_authorize("iptel.org", "subscriber")) {
>># www_challenge("iptel.org", "0");
>># break;
>># };
>>
>>save("location");
>>break;
>>};
>>
>># native SIP destinations are handled using our USRLOC DB
>>if (!lookup("location")) {
>>sl_send_reply("404", "Not Found");
>>break;
>>};
>>};
>># forward to current uri now; use stateful forwarding; that
>># works reliably even if we forward from TCP to UDP
>>if (!t_relay()) {
>>sl_reply_error();
>>};
>>
>>}
>>
>>
>>//
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>
>>------------------------------------------------------------------------
>>Do you Yahoo!?
>>*Yahoo! Finance Tax Center* <http://taxes.yahoo.com/filing.html> - 
>>File online. File on time.
>>
>>
>>------------------------------------------------------------------------
>>
>>_______________________________________________
>>Serusers mailing list
>>serusers at lists.iptel.org
>>http://lists.iptel.org/mailman/listinfo/serusers
>>
>>    
>>
>
>_______________________________________________
>Serusers mailing list
>serusers at lists.iptel.org
>http://lists.iptel.org/mailman/listinfo/serusers
>
>  
>

More information about the sr-users mailing list