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

Mon Mar 29 12:10:03 CEST 2004

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
>