[Serusers] problem with messenge 5.0 connect ser-0.8.14+rtpproxy+NAT

Sun Oct 31 02:54:24 CET 2004

i am a problem, is configuration(ser.cfg)?or problems with server.

alias=cwafrica.com.pe
port=9000 

(clients: xlite/msn5.0)<----------->(server
nat)<----internet----->(server: ser+rtpproxy)

the messenger 5.0 work with TCP o UDP?

could somebody inform me better?

# ngrep walter@ port 9000
interface: eth0 (200.60.219.112/255.255.255.248)
filter: ip and ( port 9000 )
match: walter@
      #
T 200.60.219.114:1695 -> 200.60.219.116:9000 [AP]
  INVITE sip:rbolivar at chiclayo.org SIP/2.0..Via: SIP/2.0/TCP
192.168.0.82:7141..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafrica.com.p
  e>;tag=843790d812224e8a8586407bf5c66b72;epid=b28758a49a..To:
<sip:rbolivar at chiclayo.org>..Call-ID:
3b47572fde074cedb17c29004abcef57 at 192.168.0.82..CSeq: 1
   INVITE..Contact:
<sip:walter at cwafrica.com.pe:7141;maddr=192.168.0.82;transport=tcp>..User-Agent:
RTC/1.2..Roster-Manager: sip:walter at cwafrica.com.pe..En
  dPoints: "walter at cwafrica.com.pe" <sip:walter at cwafrica.com.pe>,
<sip:rbolivar at chiclayo.org>..Supported:
com.microsoft.rtc-multiparty..Content-Type: appli
  cation/sdp..Content-Length: 105....v=0..o=- 0 0 IN IP4
192.168.0.82..s=session..c=IN IP4 192.168.0.82..t=0 0..m=x-ms-message
5060 sip null..
##
T 200.60.219.116:9000 -> 200.60.219.114:1695 [AP]
  SIP/2.0 100 trying -- your call is important to us..Via: SIP/2.0/TCP
192.168.0.82:7141;rport=1695;received=200.60.219.114..From:
"walter at cwafrica.com.pe"
   <sip:walter at cwafrica.com.pe>;tag=843790d812224e8a8586407bf5c66b72;epid=b28758a49a..To:
<sip:rbolivar at chiclayo.org>..Call-ID: 3b47572fde074cedb17c29004ab
  cef57 at 192.168.0.82..CSeq: 1 INVITE..Server: Sip EXpress router
(0.8.14 (i386/linux))..Content-Length: 0..Warning: 392
200.60.219.116:9000 "Noisy feedback
   tells:  pid=1681 req_src_ip=200.60.219.114 req_src_port=1695
in_uri=sip:rbolivar at chiclayo.org
out_uri=sip:192.168.0.186:15806;transport=tcp via_cnt==1".
  ...
##
T 200.60.219.116:9000 -> 200.60.219.114:1695 [AP]
  SIP/2.0 477 Unfortunately error on sending to next hop occured
(477/TM)..Via: SIP/2.0/TCP
192.168.0.82:7141;rport=1695;received=200.60.219.114..From: "wa
  lter at cwafrica.com.pe"
<sip:walter at cwafrica.com.pe>;tag=843790d812224e8a8586407bf5c66b72;epid=b28758a49a..To:
<sip:rbolivar at chiclayo.org>;tag=f9bfb6381e9d
  8b262ae0b2278491991b-afac..Call-ID:
3b47572fde074cedb17c29004abcef57 at 192.168.0.82..CSeq: 1 INVITE..Server:
Sip EXpress router (0.8.14 (i386/linux))..Cont
  ent-Length: 0..Warning: 392 200.60.219.116:9000 "Noisy feedback
tells:  pid=1681 req_src_ip=200.60.219.114 req_src_port=1695
in_uri=sip:rbolivar at chiclayo
  .org out_uri=sip:192.168.0.186:15806;transport=tcp via_cnt==1"....
#
T 200.60.219.114:1695 -> 200.60.219.116:9000 [AP]
  ACK sip:rbolivar at chiclayo.org SIP/2.0..Via: SIP/2.0/TCP
192.168.0.82:7141..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafrica.com.pe>;
  tag=843790d812224e8a8586407bf5c66b72;epid=b28758a49a..To:
<sip:rbolivar at chiclayo.org>;tag=f9bfb6381e9d8b262ae0b2278491991b-afac..Call-ID:
3b47572fde074ce
  db17c29004abcef57 at 192.168.0.82..CSeq: 1 ACK..User-Agent:
RTC/1.2..Content-Length: 0....
#

localhost root # ngrep walter@ port 9000
interface: eth0 (200.60.219.112/255.255.255.248)
filter: ip and ( port 9000 )
match: walter@
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  INVITE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafrica.c
  om.pe>;tag=0473ba9ac9344acdb9edc1352dde2c97;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
ef288d03fa8741199d37f284b800a629 at 192.168.0.82..
  CSeq: 1 INVITE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Content-Type: application/sdp..Content-Length:
105....v=0..o=- 0 0 IN IP4 192.168
  .0.82..s=session..c=IN IP4 192.168.0.82..t=0 0..m=x-ms-message 5060 sip null..
#
U 200.60.219.116:9000 -> 200.60.219.114:1902
  SIP/2.0 100 trying -- your call is important to us..Via: SIP/2.0/UDP
192.168.0.82:13451;rport=1902;received=200.60.219.114..From:
"walter at cwafrica.com.pe
  " <sip:walter at cwafrica.com.pe>;tag=0473ba9ac9344acdb9edc1352dde2c97;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID: ef288d03fa8741199d37f28
  4b800a629 at 192.168.0.82..CSeq: 1 INVITE..Server: Sip EXpress router
(0.8.14 (i386/linux))..Content-Length: 0..Warning: 392
200.60.219.116:9000 "Noisy feed
  back tells:  pid=1650 req_src_ip=200.60.219.114 req_src_port=1902
in_uri=sip:rbolivar at cwafrica.com.pe
out_uri=sip:rbolvar at cwafrica.com.pe via_cnt==1"....
#
U 200.60.219.116:9000 -> 200.60.219.114:1902
  SIP/2.0 408 Request Timeout..Via: SIP/2.0/UDP
192.168.0.82:13451;rport=1902;received=200.60.219.114..From:
"walter at cwafrica.com.pe" <sip:walter at cwafrica.
  com.pe>;tag=0473ba9ac9344acdb9edc1352dde2c97;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>;tag=f9bfb6381e9d8b262ae0b2278491991b-3ede..Call-ID:
ef28
  8d03fa8741199d37f284b800a629 at 192.168.0.82..CSeq: 1 INVITE..Server:
Sip EXpress router (0.8.14 (i386/linux))..Content-Length: 0..Warning:
392 200.60.219.1
  16:9000 "Noisy feedback tells:  pid=1680 req_src_ip=200.60.219.114
req_src_port=1902 in_uri=sip:rbolivar at cwafrica.com.pe
out_uri=sip:rbolvar at cwafrica.com
  .pe via_cnt==0"....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  ACK sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafrica.com.
  pe>;tag=0473ba9ac9344acdb9edc1352dde2c97;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>;tag=f9bfb6381e9d8b262ae0b2278491991b-3ede..Call-ID:
ef288d03
  fa8741199d37f284b800a629 at 192.168.0.82..CSeq: 1 ACK..User-Agent:
RTC/1.2..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=5ad2a07b1ea149ccac8980cdb57a5a05;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
bff043932d244181aae79a9c102d128b at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=5ad2a07b1ea149ccac8980cdb57a5a05;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
bff043932d244181aae79a9c102d128b at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=5ad2a07b1ea149ccac8980cdb57a5a05;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
bff043932d244181aae79a9c102d128b at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=5ad2a07b1ea149ccac8980cdb57a5a05;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
bff043932d244181aae79a9c102d128b at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=5ad2a07b1ea149ccac8980cdb57a5a05;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
bff043932d244181aae79a9c102d128b at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.116:9000 -> 200.60.219.114:1902
  SIP/2.0 408 Request Timeout..Via: SIP/2.0/UDP
192.168.0.82:13451;rport=1902;received=200.60.219.114..From:
"walter at cwafrica.com.pe" <sip:walter at cwafrica.
  com.pe>;tag=5ad2a07b1ea149ccac8980cdb57a5a05;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>;tag=f9bfb6381e9d8b262ae0b2278491991b-3ede..Call-ID:
bff0
  43932d244181aae79a9c102d128b at 192.168.0.82..CSeq: 1
SUBSCRIBE..Server: Sip EXpress router (0.8.14
(i386/linux))..Content-Length: 0..Warning: 392 200.60.21
  9.116:9000 "Noisy feedback tells:  pid=1680
req_src_ip=200.60.219.114 req_src_port=1902
in_uri=sip:rbolivar at cwafrica.com.pe out_uri=sip:rbolivar at cwafrica
  .com.pe via_cnt==0"....
#
T 200.60.219.114:1069 -> 200.60.219.116:9000 [AP]
  SUBSCRIBE sip:walter at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/TCP
192.168.0.186:9122..Max-Forwards: 70..From: "rbolivar at cwafrica.com.pe"
<sip:rbolivar at cwafr
  ica.com.pe>;tag=8557f40059d64ca6b03e4cdfa7362158;epid=959e31b43f..To:
<sip:walter at cwafrica.com.pe>..Call-ID:
621db8b5cf4f47d792dba9cbae4fb9da at 192.168.0.1
  86..CSeq: 1 SUBSCRIBE..Contact:
<sip:rbolivar at cwafrica.com.pe:9122;maddr=192.168.0.186;transport=tcp>..User-Agent:
RTC/1.2..Event: presence..Accept: appl
  ication/xpidf+xml, text/xml+msrtc.pidf..Supported:
com.microsoft.autoextend..Content-Length: 0....
##
T 200.60.219.116:9000 -> 200.60.219.114:1069 [AP]
  SIP/2.0 408 Request Timeout..Via: SIP/2.0/TCP
192.168.0.186:9122;rport=1069;received=200.60.219.114..From:
"rbolivar at cwafrica.com.pe" <sip:rbolivar at cwafr
  ica.com.pe>;tag=8557f40059d64ca6b03e4cdfa7362158;epid=959e31b43f..To:
<sip:walter at cwafrica.com.pe>;tag=f9bfb6381e9d8b262ae0b2278491991b-88f2..Call-ID:
62
  1db8b5cf4f47d792dba9cbae4fb9da at 192.168.0.186..CSeq: 1
SUBSCRIBE..Server: Sip EXpress router (0.8.14
(i386/linux))..Content-Length: 0..Warning: 392 200.60
  .219.116:9000 "Noisy feedback tells:  pid=1680
req_src_ip=200.60.219.114 req_src_port=1069
in_uri=sip:walter at cwafrica.com.pe out_uri=sip:walter at cwafrica.
  com.pe via_cnt==0"....
##
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=cf702e23dc8345aa92d47e6cd437d0bd;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
ec97a8cce37e432c9bf27a53c9889d07 at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=cf702e23dc8345aa92d47e6cd437d0bd;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
ec97a8cce37e432c9bf27a53c9889d07 at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=cf702e23dc8345aa92d47e6cd437d0bd;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
ec97a8cce37e432c9bf27a53c9889d07 at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=cf702e23dc8345aa92d47e6cd437d0bd;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
ec97a8cce37e432c9bf27a53c9889d07 at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....
#
U 200.60.219.114:1902 -> 200.60.219.116:9000
  SUBSCRIBE sip:rbolivar at cwafrica.com.pe SIP/2.0..Via: SIP/2.0/UDP
192.168.0.82:13451..Max-Forwards: 70..From: "walter at cwafrica.com.pe"
<sip:walter at cwafric
  a.com.pe>;tag=cf702e23dc8345aa92d47e6cd437d0bd;epid=af936a9682..To:
<sip:rbolivar at cwafrica.com.pe>..Call-ID:
ec97a8cce37e432c9bf27a53c9889d07 at 192.168.0.8
  2..CSeq: 1 SUBSCRIBE..Contact: <sip:192.168.0.82:13451>..User-Agent:
RTC/1.2..Event: presence..Accept: application/xpidf+xml,
text/xml+msrtc.pidf..Suppor
  ted: com.microsoft.autoextend..Content-Length: 0....

with serweb sending message ("send IM"); sending to msn 5.0 view is un log:

9(1667) fifo_get_method: method: 'MESSAGE'
 9(1667) DEBUG: fifo_get_ruri: 'sip:rbolivar at cwafrica.com.pe'
 9(1667) DEBUG: fifo_get_nexthop: next hop empty
 9(1667) fifo_get_headers: headers: From: sip:rbolivar at cwafrica.com.pe
To: <sip:rbolivar at cwafrica.com.pe>
p-version: Web_interface_Karel_Kozlik-0.9
Contact: <sip:daemon at cwafrica.com.pe>
Content-Type: text/plain; charset=UTF-8

 9(1667) parse_headers: flags=-1
 9(1667) end of header reached, state=9
 9(1667) DEBUG: get_hdr_field: <To> [32]; uri=[sip:rbolivar at cwafrica.com.pe]
 9(1667) DEBUG: to body [<sip:rbolivar at cwafrica.com.pe>
]
 9(1667) DEBUG: fifo_uac: parse_headers succeeded
 9(1667) fifo_get_body: body: prueba

 9(1667) end of header reached, state=8
 9(1667) DEBUG: get_hf_block: one more hf processed
 9(1667) DEBUG: get_hf_block: one more hf processed
 9(1667) DEBUG: get_hf_block: one more hf processed
 9(1667) DEBUG: fifo_uac: EoL -- proceeding to transaction creation
 9(1667) DEBUG: mk_proxy: doing DNS lookup...
 9(1667) get_record: lookup(_sip._udp.cwafrica.com.pe, 33) failed
 9(1667) sip_resolvehost: no SRV record found for cwafrica.com.pe,
trying 'normal' lookup...
 9(1667) DEBUG: dlg2hash: 57679
 9(1667) DEBUG: add_to_tail_of_timer[4]: 0x2ad738e0
 9(1667) DEBUG: add_to_tail_of_timer[0]: 0x2ad738f4
 9(1667) INFO: fifo_server: command empty
10(1680) DEBUG: timer routine:4,tl=0x2ad738e0 next=(nil)
10(1680) DEBUG: retransmission_handler : request resending
(t=0x2ad737d0, MESSAGE s ... )
10(1680) DEBUG: add_to_tail_of_timer[5]: 0x2ad738e0
10(1680) DEBUG: retransmission_handler : done
10(1680) DEBUG: timer routine:5,tl=0x2ad738e0 next=(nil)
10(1680) DEBUG: retransmission_handler : request resending
(t=0x2ad737d0, MESSAGE s ... )
10(1680) DEBUG: add_to_tail_of_timer[6]: 0x2ad738e0
10(1680) DEBUG: retransmission_handler : done
10(1680) DEBUG: timer routine:6,tl=0x2ad738e0 next=(nil)
10(1680) DEBUG: retransmission_handler : request resending
(t=0x2ad737d0, MESSAGE s ... )
10(1680) DEBUG: add_to_tail_of_timer[7]: 0x2ad738e0
10(1680) DEBUG: retransmission_handler : done
10(1680) DEBUG: timer routine:7,tl=0x2ad738e0 next=(nil)
10(1680) DEBUG: retransmission_handler : request resending
(t=0x2ad737d0, MESSAGE s ... )
10(1680) DEBUG: add_to_tail_of_timer[7]: 0x2ad738e0
10(1680) DEBUG: retransmission_handler : done
10(1680) DEBUG: timer routine:0,tl=0x2ad738f4 next=(nil)
10(1680) DEBUG: FR_handler:stop retr. and send CANCEL (0x2ad737d0)
10(1680) ->>>>>>>>> T_code=0, new_code=408
10(1680) DBG: callback type 6, id 4 entered
10(1680) DBG: acc: on_missed: no uas.request, local t; skipping
10(1680) DEBUG: local_reply: branch=0, save=0, winner=0
10(1680) DEBUG: local transaction completed
10(1680) !!!!! ref_counter: 0
10(1680) DEBUG: fifo UAC completed with status 408
10(1680) DEBUG: fifo_callback sucesssfuly completed
10(1680) DEBUG: add_to_tail_of_timer[2]: 0x2ad73818
10(1680) DEBUG: final_response_handler : done
10(1680) DEBUG: timer routine:7,tl=0x2ad738e0 next=(nil)
10(1680) DEBUG: timer routine:2,tl=0x2ad73818 next=(nil)
10(1680) DEBUG: wait_handler : removing 0x2ad737d0 from table
10(1680) DEBUG: delete transaction 0x2ad737d0
10(1680) DEBUG: wait_handler : done

me loggeo en el msn 5.0, pero no puedo enviar mensages, ni ver a
usuarios conectados.

4(2432) SIP Request:
 4(2432)  method:  <INVITE>
 4(2432)  uri:     <sip:rbolivar at cwafrica.com.pe>
 4(2432)  version: <SIP/2.0>
 4(2432) parse_headers: flags=1
 4(2432) end of header reached, state=5
 4(2432) parse_headers: Via found, flags=1
 4(2432) parse_headers: this is the first via
 4(2432) After parse_msg...
 4(2432) preparing to run routing scripts...
 4(2432) parse_headers: flags=256
 4(2432) end of header reached, state=9
 4(2432) DEBUG: get_hdr_field: <To> [32]; uri=[sip:rbolivar at cwafrica.com.pe]
 4(2432) DEBUG: to body [<sip:rbolivar at cwafrica.com.pe>
]
 4(2432) get_hdr_field: cseq <CSeq>: <1> <INVITE>
 4(2432) DEBUG: get_hdr_body : content_length=105
 4(2432) found end of header
 4(2432) find_first_route(): No Route headers found
 4(2432) loose_route(): There is no Route HF
 4(2432) parse_headers: flags=64
 4(2432) DEBUG: add_param: tag=1a6c14eb07b64f1a88a13771bba4b71b
 4(2432) DEBUG: add_param: epid=4f4128eff7
 4(2432) end of header reached, state=29
 4(2432) parse_headers: flags=-1
 4(2432) DEBUG:check_content_type: type <application/sdp> found valid
 4(2432) ERROR: extract_mediaip: no `c=' in SDP
 4(2432) parse_headers: flags=-1
 4(2432) DEBUG:check_content_type: type <application/sdp> found valid
 4(2432) ERROR: extract_mediaip: no `c=' in SDP
 4(2432) ERROR: extract_mediaport: can't parse `m=' in SDP
 4(2432) ERROR: force_rtp_proxy2: can't extract media port from the message
 4(2432) lookup(): 'rbolivar' Not found in usrloc
 4(2432) parse_headers: flags=-1
 4(2432) check_via_address(200.60.219.114, 192.168.0.82, 0)
 4(2432) DEBUG:destroy_avp_list: destroing list (nil)
 4(2432) receive_msg: cleaning up
 1(2427) SIP Request:
 1(2427)  method:  <ACK>
 1(2427)  uri:     <sip:rbolivar at cwafrica.com.pe>
 1(2427)  version: <SIP/2.0>
 1(2427) parse_headers: flags=1
 1(2427) end of header reached, state=5
 1(2427) parse_headers: Via found, flags=1
 1(2427) parse_headers: this is the first via
 1(2427) After parse_msg...
 1(2427) preparing to run routing scripts...
 1(2427) parse_headers: flags=4
 1(2427) DEBUG: add_param: tag=154f6989ca6960534d4c9126252550ea.3ede
 1(2427) end of header reached, state=29
 1(2427) DEBUG: get_hdr_field: <To> [74]; uri=[sip:rbolivar at cwafrica.com.pe]
 1(2427) DEBUG: to body [<sip:rbolivar at cwafrica.com.pe>]
 1(2427) DEBUG: sl_filter_ACK : local ACK found -> dropping it!
 1(2427) DEBUG:destroy_avp_list: destroing list (nil)
 1(2427) receive_msg: cleaning up
15(2468) tcpconn_new: new tcp connection: 200.60.219.114
15(2468) tcpconn_new: on port 1129, type 2
15(2468) tcpconn_add: hashes: 161, 1
15(2468) tcp_main_loop: new connection: 0x2ad61e00 28
15(2468) send2child: to tcp child 0 11(2462), 0x2ad61e00
11(2462) received n=4 con=0x2ad61e00, fd=24
11(2462) tcp_read_req: content-length= 0
11(2462) SIP Request:
11(2462)  method:  <SUBSCRIBE>
11(2462)  uri:     <sip:walter at cwafrica.com.pe>
11(2462)  version: <SIP/2.0>
11(2462) parse_headers: flags=1
11(2462) end of header reached, state=5
11(2462) parse_headers: Via found, flags=1
11(2462) parse_headers: this is the first via
11(2462) After parse_msg...
11(2462) preparing to run routing scripts...
11(2462) parse_headers: flags=256
11(2462) end of header reached, state=9
11(2462) DEBUG: get_hdr_field: <To> [30]; uri=[sip:walter at cwafrica.com.pe]
11(2462) DEBUG: to body [<sip:walter at cwafrica.com.pe>
]
11(2462) get_hdr_field: cseq <CSeq>: <1> <SUBSCRIBE>
11(2462) DEBUG: get_hdr_body : content_length=0
11(2462) found end of header
11(2462) find_first_route(): No Route headers found
11(2462) loose_route(): There is no Route HF
11(2462) parse_headers: flags=64
11(2462) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on
entrance=0xffffffff
11(2462) parse_headers: flags=-1
11(2462) parse_headers: flags=60
11(2462) t_lookup_request: start searching: hash=34536, isACK=0
11(2462) DEBUG: proceeding to pre-RFC3261 transaction matching
11(2462) DEBUG: t_lookup_request: no transaction found
11(2462) DBG: callback type 2, id 3 entered
11(2462) DEBUG: mk_proxy: doing DNS lookup...
11(2462) get_record: lookup(_sip._udp.cwafrica.com.pe, 33) failed
11(2462) sip_resolvehost: no SRV record found for cwafrica.com.pe,
trying 'normal' lookup...
11(2462) build_req_from_req: id added: <;i=1>, rcv proto=2
11(2462) check_via_address(200.60.219.114, 192.168.0.186, 0)
11(2462) DEBUG: add_to_tail_of_timer[4]: 0x2ad60820
11(2462) DEBUG: add_to_tail_of_timer[0]: 0x2ad60834
11(2462) SER: new transaction fwd'ed
11(2462) DEBUG:destroy_avp_list: destroing list (nil)
11(2462) receive_msg: cleaning up
10(2461) DEBUG: timer routine:4,tl=0x2ad60820 next=(nil)
10(2461) DEBUG: retransmission_handler : request resending
(t=0x2ad60710, SUBSCRIBE ... )
10(2461) DEBUG: add_to_tail_of_timer[5]: 0x2ad60820
10(2461) DEBUG: retransmission_handler : done
10(2461) DEBUG: timer routine:5,tl=0x2ad60820 next=(nil)
10(2461) DEBUG: retransmission_handler : request resending
(t=0x2ad60710, SUBSCRIBE ... )
10(2461) DEBUG: add_to_tail_of_timer[6]: 0x2ad60820
10(2461) DEBUG: retransmission_handler : done
11(2462) tcp_receive_loop: 0x2ad61e00 expired (75, 76)
11(2462) releasing con 0x2ad61e00, state 0, fd=24, id=1
11(2462)  extra_data (nil)
15(2468) tcp_main_loop: reader response= 2ad61e00, 0 from 0
15(2468) tcp_main_loop: CONN_RELEASE  0x2ad61e00 refcnt= 0
10(2461) DEBUG: timer routine:6,tl=0x2ad60820 next=(nil)
10(2461) DEBUG: retransmission_handler : request resending
(t=0x2ad60710, SUBSCRIBE ... )
10(2461) DEBUG: add_to_tail_of_timer[7]: 0x2ad60820
10(2461) DEBUG: retransmission_handler : done
10(2461) DEBUG: timer routine:7,tl=0x2ad60820 next=(nil)
10(2461) DEBUG: retransmission_handler : request resending
(t=0x2ad60710, SUBSCRIBE ... )
10(2461) DEBUG: add_to_tail_of_timer[7]: 0x2ad60820
10(2461) DEBUG: retransmission_handler : done
10(2461) DEBUG: timer routine:0,tl=0x2ad60834 next=(nil)
10(2461) DEBUG: FR_handler:stop retr. and send CANCEL (0x2ad60710)
10(2461) ->>>>>>>>> T_code=0, new_code=408
10(2461) DBG: callback type 6, id 4 entered
10(2461) DEBUG: relay_reply: branch=0, save=0, relay=0
10(2461) parse_headers: flags=-1
10(2461) check_via_address(200.60.219.114, 192.168.0.186, 0)
10(2461) WARNING:vqm_resize: resize(0) called
10(2461) tcp_send: tcp connection found (0x2ad61e00), acquiring fd
10(2461) tcp_send, c= 0x2ad61e00, n=8
15(2468) tcp_main_loop: read response= 2ad61e00, 1 from 10 (2461)
10(2461) tcp_send: after receive_fd: c= 0x2ad61e00 n=4 fd=20
10(2461) tcp_send: sending...
10(2461) tcp_send: after write: c= 0x2ad61e00 n=634 fd=20
10(2461) tcp_send: buf=
SIP/2.0 408 Request Timeout
Via: SIP/2.0/TCP 192.168.0.186:9122;rport=1129;received=200.60.219.114
From: "rbolivar at cwafrica.com.pe"
<sip:rbolivar at cwafrica.com.pe>;tag=21762bcf4955464391162d5ff261ba4a;epid=959e31b43f
To: <sip:walter at cwafrica.com.pe>;tag=f9bfb6381e9d8b262ae0b2278491991b-88f2
Call-ID: 1ad3d764b508414ebf6ff0ea505bfd8d at 192.168.0.186
CSeq: 1 SUBSCRIBE
Server: Sip EXpress router (0.8.14 (i386/linux))
Content-Length: 0
Warning: 392 200.60.219.116:9000 "Noisy feedback tells:  pid=2461
req_src_ip=200.60.219.114 req_src_port=1129
in_uri=sip:walter at cwafrica.com.pe out_uri=sip:walter at cwafrica.com.pe
via_cnt==0"

10(2461) DEBUG: reply relayed. buf=0x80b8bb0: SIP/2.0 4...,
shmem=0x2ad61b78: SIP/2.0 4
10(2461) DBG: callback type 7, id 1 entered
10(2461) DEBUG: add_to_tail_of_timer[2]: 0x2ad60758
10(2461) DEBUG: final_response_handler : done
10(2461) DEBUG: timer routine:7,tl=0x2ad60820 next=(nil)
10(2461) DEBUG: timer routine:2,tl=0x2ad60758 next=(nil)
10(2461) DEBUG: wait_handler : removing 0x2ad60710 from table
10(2461) DEBUG: delete transaction 0x2ad60710
10(2461) DEBUG: wait_handler : done