sr-users March 2004

sr-users@lists.kamailio.org

145 participants
286 discussions

RE: [Serusers] problem with IM b/w windows messenger 4.7 and 5.0
by Ralph.Wabel＠swisscom.com 30 Mar '04

30 Mar '04

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(a)iptel.org [mailto:serusers-bounces@lists.iptel.org] On Behalf Of Daniel-Constantin Mierla Sent: Montag, 29. März 2004 12:10 To: Rao Ser Cc: serusers(a)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@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@sipserver>;tag=b27e1a1d33761e85846fc98f5f3a7e58.809c..Call-ID: b > bef8471589a4080b173a320a864b7b5@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@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@sipserver>;tag=ef57bcc5-d7d1-4527-ab39-6e68a8617246..To: > a@sipserver>;tag=b27e1a1d33761e85846fc98f5f3a7e58.7c4c..Call-ID: 9ddfc882- > 0233-418c-adfb-0a760a1147e4@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@sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163: > 13395..Max-Forwards: 70..From: "rao-sony@sipserver" > r>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: > iba@sipserver>..Call-ID: 951c963c0be44507aa2f39076c584129(a)129.146.85.163.. > CSeq: 1 INVITE..Contact: 3;transport=tcp>..User-Agent: > RTC/1.2..Roster-Manager: sip:rao-sony@sipser > ver..EndPoints: "rao-sony@sipserver" , > shiba@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@sipserver" ;tag=3 > b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: > erver>..Call-ID: 951c963c0be44507aa2f39076c584129@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@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@sipserver" ;tag=3b3b0bff72424892aa8e > 78d5bbf3a316;epid=8bb54d4c1b..To: ;tag=f3b15a35 > -1c0a-4519-b2db-804cfb87fbb7..Call-ID: 951c963c0be44507aa2f39076c584129@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@sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163:133 > 95..Max-Forwards: 70..From: "rao-sony@sipserver" ; > tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: > @sipserver>;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7..Call-ID: 951c963c0be > 44507aa2f39076c584129@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@sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163 > :13395..Max-Forwards: 70..From: "rao-sony@sipserver" > er>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: > hiba@sipserver>..Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sip > server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid > =8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5 > cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316 > ;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: > a@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@sipse > rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8 > bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" > ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I > D: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316 > ;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: > a@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@sipse > rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8 > bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" > ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I > D: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316 > ;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: > a@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@sipse > rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8 > bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" > ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I > D: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a > 316;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: > hiba@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@si > pserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epi > d=8bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" > ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I > D: 72452f0ccee6437193a271e057d52725@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@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@sipserver" ;tag=3b3b0 > bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: > r>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..Call-ID: 72452f0ccee6437193a2 > 71e057d52725@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@sip > server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid > =8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5 > cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725@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@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@sipserver" ;tag=3b > 3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: > rver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..Call-ID: 72452f0ccee643719 > 3a271e057d52725@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@sip > server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid > =8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5 > cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725@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@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(a)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@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(a)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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: > Call-ID: 951c963c0be44507aa2f39076c584129(a)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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: > Call-ID: 951c963c0be44507aa2f39076c584129(a)129.146.85.163 > CSeq: 1 INVITE > Contact: > User-Agent: RTC/1.2 > Roster-Manager: sip:rao-sony@sipserver > EndPoints: "rao-sony@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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > Call-ID: 951c963c0be44507aa2f39076c584129(a)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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7 > Call-ID: 951c963c0be44507aa2f39076c584129(a)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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7 > Call-ID: 951c963c0be44507aa2f39076c584129(a)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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7 > Call-ID: 951c963c0be44507aa2f39076c584129(a)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@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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: > Call-ID: 72452f0ccee6437193a271e057d52725(a)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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9 > Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9 > Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9 > Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] > 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] > 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] > 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] > 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" > ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b > Call-ID: 72452f0ccee6437193a271e057d52725(a)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(a)lists.iptel.org >http://lists.iptel.org/mailman/listinfo/serusers > _______________________________________________ Serusers mailing list serusers(a)lists.iptel.org http://lists.iptel.org/mailman/listinfo/serusers

2 1

[Serusers] problem with IM b/w windows messenger 4.7 and 5.0
by Rao Ser 30 Mar '04

30 Mar '04

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@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@sipserver>;tag=b27e1a1d33761e85846fc98f5f3a7e58.809c..Call-ID: b bef8471589a4080b173a320a864b7b5@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@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@sipserver>;tag=ef57bcc5-d7d1-4527-ab39-6e68a8617246..To: a@sipserver>;tag=b27e1a1d33761e85846fc98f5f3a7e58.7c4c..Call-ID: 9ddfc882- 0233-418c-adfb-0a760a1147e4@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@sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163: 13395..Max-Forwards: 70..From: "rao-sony@sipserver" r>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: iba@sipserver>..Call-ID: 951c963c0be44507aa2f39076c584129(a)129.146.85.163.. CSeq: 1 INVITE..Contact: 3;transport=tcp>..User-Agent: RTC/1.2..Roster-Manager: sip:rao-sony@sipser ver..EndPoints: "rao-sony@sipserver" , shiba@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@sipserver" ;tag=3 b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: erver>..Call-ID: 951c963c0be44507aa2f39076c584129@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@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@sipserver" ;tag=3b3b0bff72424892aa8e 78d5bbf3a316;epid=8bb54d4c1b..To: ;tag=f3b15a35 -1c0a-4519-b2db-804cfb87fbb7..Call-ID: 951c963c0be44507aa2f39076c584129@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@sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163:133 95..Max-Forwards: 70..From: "rao-sony@sipserver" ; tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: @sipserver>;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7..Call-ID: 951c963c0be 44507aa2f39076c584129@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@sipserver SIP/2.0..Via: SIP/2.0/TCP 129.146.85.163 :13395..Max-Forwards: 70..From: "rao-sony@sipserver" er>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: hiba@sipserver>..Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sip server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid =8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5 cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316 ;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: a@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@sipse rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8 bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I D: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316 ;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: a@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@sipse rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8 bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I D: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a316 ;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: a@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@sipse rver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8 bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I D: 72452f0ccee6437193a271e057d52725@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@129.146.175.207;ftag=3b3b0bff72424892aa8e78d5bbf3a 316;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685..From: hiba@sipserver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..To: "rao-sony@si pserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epi d=8bb54d4c1b..Call-ID: 72452f0ccee6437193a271e057d52725@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@sipserver" ny@sipserver>;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..Call-I D: 72452f0ccee6437193a271e057d52725@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@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@sipserver" ;tag=3b3b0 bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: r>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..Call-ID: 72452f0ccee6437193a2 71e057d52725@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@sip server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid =8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5 cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725@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@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@sipserver" ;tag=3b 3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b..To: rver>;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9..Call-ID: 72452f0ccee643719 3a271e057d52725@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@sip server" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid =8bb54d4c1b..To: ;tag=6d5db818-3a2a-40b3-b919-5 cd06b18d8e9..Call-ID: 72452f0ccee6437193a271e057d52725@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@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(a)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@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(a)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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: Call-ID: 951c963c0be44507aa2f39076c584129(a)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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: Call-ID: 951c963c0be44507aa2f39076c584129(a)129.146.85.163 CSeq: 1 INVITE Contact: User-Agent: RTC/1.2 Roster-Manager: sip:rao-sony@sipserver EndPoints: "rao-sony@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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b Call-ID: 951c963c0be44507aa2f39076c584129(a)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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7 Call-ID: 951c963c0be44507aa2f39076c584129(a)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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7 Call-ID: 951c963c0be44507aa2f39076c584129(a)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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: ;tag=f3b15a35-1c0a-4519-b2db-804cfb87fbb7 Call-ID: 951c963c0be44507aa2f39076c584129(a)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@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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: Call-ID: 72452f0ccee6437193a271e057d52725(a)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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9 Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9 Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b To: ;tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9 Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b Call-ID: 72452f0ccee6437193a271e057d52725(a)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@sipserver] 20(19292) DEBUG: to body ["rao-sony@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@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@sipserver" ;tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b Call-ID: 72452f0ccee6437193a271e057d52725(a)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 - File online. File on time.

6 6

RE: [Serusers] problem with IM b/w windows messenger 4.7 and 5.0
by Ralph.Wabel＠swisscom.com 30 Mar '04

30 Mar '04

Hi Most versions of windows messenger are only for XP. Check http://www.meetingbywire.com/VersionWatch.htm They have pretty complete overview of the different messengers from MS. I heard once that MSN Messenger 5 Beta still had once SIP implementation. Or try also one of the older versions which support your OS. Good luck Ralph -----Original Message----- From: serusers-bounces(a)iptel.org [mailto:serusers-bounces@lists.iptel.org] On Behalf Of Rao Sent: Montag, 29. März 2004 22:33 To: Jan Janak Cc: serusers(a)lists.iptel.org Subject: Re: [Serusers] problem with IM b/w windows messenger 4.7 and 5.0 Jan, Ralph and Daniel thanks for your responses. Here is some more calrification. 1) I am using windows messaeger 5.0 and MSN messenger. 2) The link Ralph provided only works for wondows XP. On one of my machines I am running windows 2000 and would like to see IM work on win2K. I cannot find IM 4.7 for any version of windows except XP. 3) I can not get IM to work even when both machines are running messenger 5.0. Seems to be the same issue when one is running 4.7 i.e. maddr in route header is being ignored and ser tries to deliver it to the local machine and tcp connect fails. Has any one made IM to work b/w MM 5.0 Following is a trace of a SIP message request that has maddr in the Route request. Let me know if more traces are needed. So I have following questions 1) Can there be an maddr parameter in the Route header ? Cannot find an answer in RFC 3261. 2) In any case why can't we fix ser to interpret maddr. windows OS is probably the most important OS with which ser needs to support. 3) What is a better utility than ngrep to get traces which are better formatted. I have seen folks post very nicely formatted traces, what are they using. Let me know if I need to post these questions on the development list. Regards. Rao. ## T 129.150.32.27:1064 -> 129.146.175.207:5060 [AP] MESSAGE sip:rao-toshiba@129.146.175.207; ftag=3b3b0bff72424892aa8e78d5bbf3a316;lr=on SIP/2.0..Via: SIP/2.0/TCP 129.150.32.27:7685.. From: <sip:rao-toshiba@sipserver>; tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9.. To: "rao-sony@sipserver" <sip:rao-sony@sipserver>; tag=3b3b0bff72424892aa8e78d5bbf3a316; epid=8bb54d4c1b.. Call-ID: 72452f0ccee6437193a271e057d52725(a)129.146.85.163.. CSeq: 4 MESSAGE..Route:<sip:rao-sony@sipserver:13395; maddr=129.146.85.163;transport=tcp>.. Contact: <sip:129.150.32.27:7685;transport=tcp>..' User-Agent: Windows RTC/1.0..Content-Type: text/plain; charset=UTF-8;msgr=WAAtAE0ATQBTAC0ASQBNAC0ARgBvAHIAbQBhAHQAOgAgAEYATgA9AE0AUwA lADIAMABTAGgAZQBsAGwAJQAyADAARABsAGcAOwAgAEUARgA9ADsAIABDAE8APQAwADsAIABDAFMAP QAwADsAIABQAEYAPQAwAA0ACgANAAoA..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).. Via: SIP/2.0/TCP 129.150.32.27:7685.. From: <sip:rao-toshiba@sipserver>; tag=6d5db818-3a2a-40b3-b919-5cd06b18d8e9.. To: "rao-sony@sipserver" <sip:rao-sony@sipserver>; tag=3b3b0bff72424892aa8e78d5bbf3a316;epid=8bb54d4c1b.. Call-ID: 72452f0ccee6437193a271e057d52725(a)129.146.85.163.. CSeq: 4 MESSAGE..Server: Sip EXpress router (0.8.12 (sparc64/solaris)).. Content-Length: 0.... # __________________________________ Do you Yahoo!? Yahoo! Finance Tax Center - File online. File on time. http://taxes.yahoo.com/filing.html _______________________________________________ Serusers mailing list serusers(a)lists.iptel.org http://lists.iptel.org/mailman/listinfo/serusers

1 0

Re: [Serusers] outbound call access pin
by Jiri Kuthan 30 Mar '04

30 Mar '04

At 08:00 PM 3/29/2004, Jev wrote: >Because the phones that are being used are in common areas, used by employees, they work like traditional phones (using ata186's) so to use the phone they just pick it up and dial, these users don't log in. However the ATAs still do authenticate using preconfigured password. -jiri >I think this makes sense :) > >-Jev > >Jiri Kuthan wrote: > >>why dont you use digest authetnication to determine user identity? -jiri >>At 08:40 AM 3/29/2004, Jev wrote: >> >>>Hi all, >>> >>>I wish to block outgoing calls to certain numbers, specifically mobile phone numbers, and international numbers. Thats pretty straight forward. >>> >>>However, when a user attempts to ring one of these numbers I would like to have a IVR system to prompt them for a PIN number, upon entering a valid pin number the user would be forwarded to the dialled number, and the call logged. >>> >>>Has any one put together a setup similar to this? >>> >>>Thanks, >>>-Jev >>> >>>_______________________________________________ >>>Serusers mailing list >>>serusers(a)lists.iptel.org >>>http://lists.iptel.org/mailman/listinfo/serusers >> >>-- >>Jiri Kuthan http://iptel.org/~jiri/ -- Jiri Kuthan http://iptel.org/~jiri/

1 0

[Serusers] SER & SIP Clients ?
by Anton 30 Mar '04

30 Mar '04

Hello All, What is the best SIP Client used with SER ? Can SJphone be used with SER ? If yes, how to set up SJphone for authentification ? Thank you very much for any info. Regards, Anton

1 0

Re: [Serusers] authenticating BYE messages
by Jiri Kuthan 30 Mar '04

30 Mar '04

checking from against digest credentials takes first verifying the digest credentials with proxy_authenticate(). Note that this works for single domain. You can't really authenticate a BYE if the party that hangs up is from some other adminsitrative domain. -jiri At 11:17 PM 3/29/2004, Ticknor.Scott(a)ic.gc.ca wrote: >my lab partner & i have found that if we sniff an ACK message during call >setup and extract the call tag and id, then we can arbitrarily hang up the >call from our java attack generator. i thought about adding some logic to >ser.cfg to process BYEs. is there an easy way to authenticate the BYE? i >have something like the following in ser.cfg, but it seems to have no effect > >if (method=="BYE") { > if (!check_from()) { > ...etc > }; >}; > >thanks, >scott >DSi > >_______________________________________________ >Serusers mailing list >serusers(a)lists.iptel.org >http://lists.iptel.org/mailman/listinfo/serusers -- Jiri Kuthan http://iptel.org/~jiri/

1 0

[Serusers] acc timestamp problem
by Raymond Chen 29 Mar '04

29 Mar '04

Dear all, We’ve found that the timestamp in acc is UA(pstn as5300) time instead of SER server time. Is this intended act of a bug? Raymond

1 0

Re: [Serusers] RE: [Serdev] check_from
by Daniel-Constantin Mierla 29 Mar '04

29 Mar '04

What client do you use? It does not use the realm from challenge -- it is a MSN Messenger specific bug, but it might be present in other sip clients. You must set the realm from challenge as the host part of the sip id. .Daniel On 03/26/04 15:46, Raymond Chen wrote: >Danial, > >www_authorize fail to authorize without password > >0(3283) lookup(): '85234230599(a)218.20.229.53' Not found in usrloc > 0(3283) parse_headers: flags=4096 > 0(3283) pre_auth(): Credentials with given realm not found > 0(3283) build_auth_hf(): 'WWW-Authenticate: Digest realm="xxx.org", >nonce="40644192d74bf39b0ebb5d141cb2073a6c09daf8" >' > >Regards > >Raymond > >-----Original Message----- >From: Daniel-Constantin Mierla [mailto:daniel@iptel.org] >Sent: Friday, March 26, 2004 8:04 PM >To: Raymond Chen >Subject: Re: [Serusers] RE: [Serdev] check_from > > >Try something like this: > >if (method=="INVITE") >{ > if (!www_authorize("xxx.org", "subscriber")) { > www_challenge("xxx.org", "0"); > break; > }; > if (!check_from()) { > sl_send_reply("403", "Only registered users are allowed"); > break; > }; > >}; > >.Daniel > >On 03/26/04 12:48, Raymond Chen wrote: > > > >>Now we understand what the what the message means after reading the message >>a few times. We are trying to do PSTN(as5300) ---> ser -----> pstn >>(AS5300), and to authorize the calling number (callerid) in the INVITE >>message against URI table. But check_from command needs to call >>proxy_authorize, which it requires username and password. we setup the >>configuration like this >> >>if (method=="INVITE" & proxy_authorize("xxx.org", "subscriber") >> if (!check_from()) { >> sl_send_reply("403", "Only registered users are allowed"); >> break; >> } >>} >> >>Because cisco does not have sip password setting, so we have >> >>0(3173) check_username(): No authorized credentials found (error in >> >> >scripts) > > >>0(3173) check_username(): Call {www,proxy}_authorize before calling >> >> >check_* > > >>function ! >> >>Does anyone has a solution? >> >>Regards >> >> >>-----Original Message----- >>From: Daniel-Constantin Mierla [mailto:daniel@iptel.org] >>Sent: Friday, March 26, 2004 6:18 PM >>To: Raymond Chen >>Cc: serdev(a)lists.iptel.org >>Subject: Re: [Serdev] check_from >> >>Hello, >>the last error message is self explanatory. You need to call either >>www_authorize() or proxy_authorize() before calling check_from() because >>this method compares the data from From header with what is in >>credentials (response to a authentication challenge). >> >>.Daniel >> >>On 03/26/04 04:35, Raymond Chen wrote: >> >> >> >> >> >>>Dear all, >>> >>>We have configured Ser to check from username field to authorize user >>>“unknown” >>> >>>if (!check_from()) { >>> >>>sl_send_reply("403", "Only registered users are allowed"); >>> >>>break; >>> >>>}; >>> >>>We have error message >>> >>>0(2568) check_username(): No authorized credentials found (error in >>>scripts) >>> >>>0(2568) check_username(): Call {www,proxy}_authorize before calling >>>check_* function ! >>> >>>We have “unknown” username entry in uri table. >>> >>>Regards >>> >>>------------------------------------------------------------------------ >>> >>>_______________________________________________ >>>Serdev mailing list >>>serdev(a)lists.iptel.org >>>http://lists.iptel.org/mailman/listinfo/serdev >>> >>> >>> >>> >>> >>> >>_______________________________________________ >>Serusers mailing list >>serusers(a)lists.iptel.org >>http://lists.iptel.org/mailman/listinfo/serusers >> >> >> >> >> > >_______________________________________________ >Serdev mailing list >serdev(a)lists.iptel.org >http://lists.iptel.org/mailman/listinfo/serdev > > >

4 7

[Serusers] SER and multiple URIs for UA
by Andreas Granig 29 Mar '04

29 Mar '04

Hi, I'm trying to integrate small ISDN networks into a SIP network, so quite the opposite of the usual way (SIP -> PSTN). There exist some SOHO ISDN-to-SIP Gateways with two BRI out there, and I've successfully tested some of them by using the user id of the gateway as MSN on the phone. Now this is quite a waste of ISDN, cause you can in fact address up to eight devices on an S0 Bus. My current solution is to use the alias table of SER to configure the MSNs for the Gateway. The Gateway itself registers with e.a. sip:011000@my.domain and there are aliases from 0110001 to 0110008 pointing to that URI. But this still does not satisfy me because I think the usual way would be to configure one base number which the ISDN2SIP-Gateway uses to register with my SER, and let the user assign as much phone extensions as he likes without any interaction of mine. Here I'm thinking about a loop in the ser config where I lookup the location of the user part of the To-Header (if "^[0-9]+$"), and if not found, just cut off the last digit and repeat the loop until the user id under-runs a minimum length. If no appropriate user is found, I return 404. Otherwise I forward the original URI to the IP of the user I've found. So, generally said, I assign base numbers and perform some kind of wildcard matching on incoming URIs. What do you think about this solution? Would this be appropriate, or do there exist other approaches? I'm looking forward for some comments, Andy

3 3

[Serusers] Multiple SIP Domains into 1 SER
by Humberto Atristain 29 Mar '04

29 Mar '04

Hi, I´ll like to have runung several sip domains into 1 SER box, this is because I need to make for diferente sip users, diferent dial plan , (or tell me if there is a more easy way to do the following: I want so each domain has their dial plan for example userA1 dial 150 to call userD1 sipdomain1.com userA1 userB1 userC1 userD1 sipdomain2.com userA2 userB2 userC2 userD2 userE2 Sorry for the inconvenience I´m a newbie I´ll apreciate explcit instructions as possible. Regards HA

1 0

← Newer
1
2
3
4
5
6
7
8
...
29
Older →

Jump to page:

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

2005

2004

2003

2002

sr-users March 2004