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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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.
Unfortunately the dumps you attached do not show Route header fields (probably it was modified by your e-mail client). SER does not support maddr parameter and it will not use it if it is present in Route header fields.
Jan.
On 27-03 14: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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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. _______________________________________________ Serusers mailing list serusers@lists.iptel.org http://lists.iptel.org/mailman/listinfo/serusers
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@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@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
Hello-
I'm in the process of implementing my first SER server, and am running into some issues with the nathelper module. I'm using 0.8.12, compiled locally. I set the debug level to 7, and am seeing the following errors, any help is greatly appreciated:
Mar 29 16:06:49 east ser: set_mod_param_regex: parameter <ping_nated_only> not found in module <nathelper> Mar 29 16:06:49 east ser: parse error (88,43-44): Can't set module parameter Mar 29 16:06:49 east ser: parse error (113,22-23): unknown command, missing loadmodule? Mar 29 16:06:49 east ser: parse error (225,32-33): unknown command, missing loadmodule?
Relevant config file entries:
# ------------------ module loading ----------------------------------
# Uncomment this if you want to use SQL database loadmodule "/lib/ser/modules/mysql.so"
loadmodule "/lib/ser/modules/sl.so" loadmodule "/lib/ser/modules/tm.so" loadmodule "/lib/ser/modules/rr.so" loadmodule "/lib/ser/modules/maxfwd.so" loadmodule "/lib/ser/modules/usrloc.so" loadmodule "/lib/ser/modules/registrar.so" loadmodule "/lib/ser/modules/textops.so"
# Uncomment this if you want digest authentication # mysql.so must be loaded ! loadmodule "/lib/ser/modules/auth.so" loadmodule "/lib/ser/modules/auth_db.so"
# !! Nathelper loadmodule "/lib/ser/modules/nathelper.so"
# ----------------- setting module-specific parameters ---------------
Followed by lines 85-88:
# !! Nathelper modparam("registrar", "nat_flag", 6) modparam("nathelper", "natping_interval", 30) # Ping interval 30 s modparam("nathelper", "ping_nated_only", 1) # Ping only clients behind NAT
Section around line 113 (113 is the nat_uac_test):
# !! Nathelper # Special handling for NATed clients; first, NAT test is # executed: it looks for via!=received and RFC1918 addresses # in Contact (may fail if line-folding is used); also, # the received test should, if completed, should check all # vias for rpesence of received if (nat_uac_test("3")) { # Allow RR-ed requests, as these may indicate that # a NAT-enabled proxy takes care of it; unless it is # a REGISTER
if (method == "REGISTER" || ! search("^Record-Route:")) { log("LOG: Someone trying to register from private IP, rewriting\n");
Section around line 225 (failing on nat_ua_test again):
# !! Nathelper onreply_route[1] { # NATed transaction ? if (isflagset(6) && status =~ "(183)|2[0-9][0-9]") { fix_nated_contact(); force_rtp_proxy(); # otherwise, is it a transaction behind a NAT and we did not # know at time of request processing ? (RFC1918 contacts) } else if (nat_uac_test("1")) { fix_nated_contact(); }; }
maybe you are mixing old with new versions.
klaus
Steven Kokinos wrote:
Hello-
I'm in the process of implementing my first SER server, and am running into some issues with the nathelper module. I'm using 0.8.12, compiled locally. I set the debug level to 7, and am seeing the following errors, any help is greatly appreciated:
Mar 29 16:06:49 east ser: set_mod_param_regex: parameter <ping_nated_only> not found in module <nathelper> Mar 29 16:06:49 east ser: parse error (88,43-44): Can't set module parameter Mar 29 16:06:49 east ser: parse error (113,22-23): unknown command, missing loadmodule? Mar 29 16:06:49 east ser: parse error (225,32-33): unknown command, missing loadmodule?
Relevant config file entries:
# ------------------ module loading ----------------------------------
# Uncomment this if you want to use SQL database loadmodule "/lib/ser/modules/mysql.so"
loadmodule "/lib/ser/modules/sl.so" loadmodule "/lib/ser/modules/tm.so" loadmodule "/lib/ser/modules/rr.so" loadmodule "/lib/ser/modules/maxfwd.so" loadmodule "/lib/ser/modules/usrloc.so" loadmodule "/lib/ser/modules/registrar.so" loadmodule "/lib/ser/modules/textops.so"
# Uncomment this if you want digest authentication # mysql.so must be loaded ! loadmodule "/lib/ser/modules/auth.so" loadmodule "/lib/ser/modules/auth_db.so"
# !! Nathelper loadmodule "/lib/ser/modules/nathelper.so"
# ----------------- setting module-specific parameters ---------------
Followed by lines 85-88:
# !! Nathelper modparam("registrar", "nat_flag", 6) modparam("nathelper", "natping_interval", 30) # Ping interval 30 s modparam("nathelper", "ping_nated_only", 1) # Ping only clients behind NAT
Section around line 113 (113 is the nat_uac_test):
# !! Nathelper # Special handling for NATed clients; first, NAT test is # executed: it looks for via!=received and RFC1918 addresses # in Contact (may fail if line-folding is used); also, # the received test should, if completed, should check all # vias for rpesence of received if (nat_uac_test("3")) { # Allow RR-ed requests, as these may indicate that # a NAT-enabled proxy takes care of it; unless it is # a REGISTER if (method == "REGISTER" || ! search("^Record-Route:")) { log("LOG: Someone trying to register from private
IP, rewriting\n");
Section around line 225 (failing on nat_ua_test again):
# !! Nathelper onreply_route[1] { # NATed transaction ? if (isflagset(6) && status =~ "(183)|2[0-9][0-9]") { fix_nated_contact(); force_rtp_proxy(); # otherwise, is it a transaction behind a NAT and we did not # know at time of request processing ? (RFC1918 contacts) } else if (nat_uac_test("1")) { fix_nated_contact(); }; }
Serusers mailing list serusers@lists.iptel.org http://lists.iptel.org/mailman/listinfo/serusers
you can find download links at: http://www.ict.tuwien.ac.at/darilion/bookmarks-sip-rtp-ua.html
klaus
Rao wrote:
Jan, Ralph and Daniel thanks for your responses. Here is some more calrification.
I am using windows messaeger 5.0 and MSN messenger.
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.
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
Can there be an maddr parameter in the Route header ? Cannot find an answer in RFC 3261.
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.
- 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@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@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@lists.iptel.org http://lists.iptel.org/mailman/listinfo/serusers
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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@lists.iptel.org http://lists.iptel.org/mailman/listinfo/serusers