<html>
<head>
<meta content="text/html; charset=UTF-8" http-equiv="Content-Type">
</head>
<body text="#000000" bgcolor="#FFFFFF">
<div class="moz-cite-prefix"><tt>George, you can get all that info
by using ngrep or tcpdump to see the actual traffic.<br>
<br>
Regards,<br>
</tt>
<pre class="moz-signature" cols="72">Bogdan-Andrei Iancu
OpenSIPS Founder and Developer
<a class="moz-txt-link-freetext" href="http://www.opensips-solutions.com">http://www.opensips-solutions.com</a></pre>
On 15.10.2014 14:25, george wu wrote:<br>
</div>
<blockquote
cite="mid:289d70c8.fe4b.149138e294d.Coremail.aihuawu2012@163.com"
type="cite">
<div
style="line-height:1.7;color:#000000;font-size:14px;font-family:Arial"><br>
<div>Hi, Bogdan:<br>
1)<br>
I can find the ip from the pinging message, but can't get the
port.<br>
Can you tell me the script to print port?<br>
2)<br>
Also how to check the relay for invite in the log message?<br>
I can't see the ip/port for the invite relay message in the
log.<br>
It simply Cancel sent out, sending 408 (0x7f9858507f28) <br>
see below<br>
<br>
///////////////////////////////<br>
<br>
Oct 15 19:04:51 [15160] DBG:core:udp_rcv_loop: probing packet
received len = 4<br>
Oct 15 19:04:54 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
</div>
<div>......<br>
Oct 15 19:04:29 [15164] DBG:tm:timer_routine: timer
routine:0,tl=0x7f9858508178 next=0x7f9858508378, timeout=873<br>
Oct 15 19:04:29 [15164] DBG:tm:final_response_handler: Cancel
sent out, sending 408 (0x7f9858507f28)<br>
</div>
<br>
<br>
<div>/////The following is opensips invite log messages<br>
<br>
<br>
<br>
<br>
Oct 15 19:04:24 [15159] DBG:core:parse_msg: SIP Request:<br>
Oct 15 19:04:24 [15159] DBG:core:parse_msg: method:
<INVITE><br>
Oct 15 19:04:24 [15159] DBG:core:parse_msg: uri:
<a class="moz-txt-link-rfc2396E" href="mailto:sip:test2@103.24.228.158"><sip:test2@103.24.228.158></a><br>
Oct 15 19:04:24 [15159] DBG:core:parse_msg: version:
<SIP/2.0><br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=2<br>
Oct 15 19:04:24 [15159] DBG:core:parse_via_param: found param
type 232, <branch> = <z9hG4bK.1tcd1QMAc>; state=6<br>
Oct 15 19:04:24 [15159] DBG:core:parse_via_param: found param
type 235, <rport> = <n/a>; state=17<br>
Oct 15 19:04:24 [15159] DBG:core:parse_via: end of header
reached, state=5<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: via found,
flags=2<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: this is the
first via<br>
Oct 15 19:04:24 [15159] DBG:core:receive_msg: After
parse_msg...<br>
Oct 15 19:04:24 [15159] DBG:core:receive_msg: preparing to run
routing scripts...<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:24 [15159] DBG:core:parse_to: end of header
reached, state=9<br>
Oct 15 19:04:24 [15159] DBG:core:parse_to: display={},
ruri={<a class="moz-txt-link-abbreviated" href="mailto:sip:test2@103.24.228.158">sip:test2@103.24.228.158</a>}<br>
Oct 15 19:04:24 [15159] DBG:core:get_hdr_field: <To>
[26]; uri=[<a class="moz-txt-link-abbreviated" href="mailto:sip:test2@103.24.228.158">sip:test2@103.24.228.158</a>] <br>
Oct 15 19:04:24 [15159] DBG:core:get_hdr_field: to body
[<a class="moz-txt-link-abbreviated" href="mailto:sip:test2@103.24.228.158">sip:test2@103.24.228.158</a><br>
]<br>
Oct 15 19:04:24 [15159] DBG:core:get_hdr_field: cseq
<CSeq>: <20> <INVITE><br>
Oct 15 19:04:24 [15159] DBG:core:get_hdr_field:
content_length=729<br>
Oct 15 19:04:24 [15159] DBG:core:get_hdr_field: found end of
header<br>
Oct 15 19:04:24 [15159] DBG:core:parse_params: Parsing params
for:[+sip.instance="<urn:uuid:866598bd-4f5e-47f9-b7fa-8be6c6883a57>"]<br>
Oct 15 19:04:24 [15159] DBG:maxfwd:is_maxfwd_present: value =
70 <br>
Oct 15 19:04:24 [15159] DBG:uri:has_totag: no totag<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=78<br>
Oct 15 19:04:24 [15159] DBG:tm:t_lookup_request: start
searching: hash=53427, isACK=0<br>
Oct 15 19:04:24 [15159] DBG:tm:matching_3261: RFC3261
transaction matching failed<br>
Oct 15 19:04:24 [15159] DBG:tm:t_lookup_request: no
transaction found<br>
Oct 15 19:04:24 [15159] DBG:core:parse_to_param: tag=eC6yy-cLm<br>
Oct 15 19:04:24 [15159] DBG:core:parse_to: end of header
reached, state=29<br>
Oct 15 19:04:24 [15159] DBG:core:parse_to: display={},
ruri={<a class="moz-txt-link-abbreviated" href="mailto:sip:test1@103.24.228.158">sip:test1@103.24.228.158</a>}<br>
Oct 15 19:04:24 [15159] DBG:core:grep_sock_info: checking if
host==us: 14==14 && [103.24.228.158] ==
[103.24.228.158]<br>
Oct 15 19:04:24 [15159] DBG:core:grep_sock_info: checking if
port 5060 matches port 5060<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=200<br>
Oct 15 19:04:24 [15159] DBG:rr:find_first_route: No Route
headers found<br>
Oct 15 19:04:24 [15159] DBG:rr:loose_route: There is no Route
HF<br>
Oct 15 19:04:24 [15159] DBG:core:grep_sock_info: checking if
host==us: 14==14 && [103.24.228.158] ==
[103.24.228.158]<br>
Oct 15 19:04:24 [15159] DBG:core:grep_sock_info: checking if
port 5060 matches port 5060<br>
Oct 15 19:04:24 [15159] DBG:registrar:lookup: found a complete
match<br>
Oct 15 19:04:24 [15159] DBG:registrar:lookup: setting as ruri
<a class="moz-txt-link-rfc2396E" href="mailto:sip:test2@192.168.1.3:5080"><sip:test2@192.168.1.3:5080></a><br>
Oct 15 19:04:24 [15159] DBG:registrar:lookup: looking for
branches<br>
Oct 15 19:04:24 [15159] DBG:registrar:lookup: setting branch
<a class="moz-txt-link-rfc2396E" href="mailto:sip:test2@124.193.138.210:5299"><sip:test2@124.193.138.210:5299></a><br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=8<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=8<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=8000000<br>
Oct 15 19:04:24 [15159] DBG:rr:add_rr_param: adding (;nat=yes)
0x7f985bfe1250<br>
Oct 15 19:04:24 [15159] DBG:tm:t_newtran: transaction on
entrance=(nil)<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=78<br>
Oct 15 19:04:24 [15159] DBG:tm:t_lookup_request: start
searching: hash=53427, isACK=0<br>
Oct 15 19:04:24 [15159] DBG:tm:matching_3261: RFC3261
transaction matching failed<br>
Oct 15 19:04:24 [15159] DBG:tm:t_lookup_request: no
transaction found<br>
Oct 15 19:04:24 [15159] DBG:tm:run_reqin_callbacks:
trans=0x7f9858507f28, callback type 1, id 0 entered<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=78<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:24 [15159] DBG:core:_shm_resize: resize(0) called<br>
Oct 15 19:04:24 [15159] DBG:tm:_reply_light: reply sent out.
buf=0x7f985bfe1d78: SIP/2.0 1..., shmem=0x7f985850bb48:
SIP/2.0 1<br>
Oct 15 19:04:24 [15159] DBG:tm:_reply_light: finished<br>
Oct 15 19:04:24 [15159] DBG:core:buf_init: initializing...<br>
new branch at <a class="moz-txt-link-abbreviated" href="mailto:sip:test2@192.168.1.3:5080">sip:test2@192.168.1.3:5080</a><br>
Oct 15 19:04:24 [15159] DBG:core:_shm_resize: resize(0) called<br>
Oct 15 19:04:24 [15159] DBG:core:mk_proxy: doing DNS lookup...<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=2000<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:24 [15159] DBG:core:clen_builder: content-length:
871 (871)<br>
new branch at <a class="moz-txt-link-abbreviated" href="mailto:sip:test2@124.193.138.210:5299">sip:test2@124.193.138.210:5299</a><br>
Oct 15 19:04:24 [15159] DBG:core:_shm_resize: resize(0) called<br>
Oct 15 19:04:24 [15159] DBG:core:mk_proxy: doing DNS lookup...<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers: flags=2000<br>
Oct 15 19:04:24 [15159] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:24 [15159] DBG:core:clen_builder: content-length:
871 (871)<br>
Oct 15 19:04:24 [15159] DBG:tm:set_timer: relative timeout is
500000<br>
Oct 15 19:04:24 [15159] DBG:tm:insert_timer_unsafe: [4]:
0x7f9858508148 (869000000)<br>
Oct 15 19:04:24 [15159] DBG:tm:insert_timer_unsafe: [0]:
0x7f9858508178 (873)<br>
Oct 15 19:04:24 [15159] DBG:tm:set_timer: relative timeout is
500000<br>
Oct 15 19:04:24 [15159] DBG:tm:insert_timer_unsafe: [4]:
0x7f9858508348 (869000000)<br>
Oct 15 19:04:24 [15159] DBG:tm:insert_timer_unsafe: [0]:
0x7f9858508378 (873)<br>
Oct 15 19:04:24 [15159] DBG:tm:t_relay_to: new transaction
fwd'ed<br>
Oct 15 19:04:24 [15159] DBG:tm:t_unref: UNREF_UNSAFE:
[0x7f9858507f28] after is 0<br>
Oct 15 19:04:24 [15159] DBG:core:destroy_avp_list: destroying
list (nil)<br>
Oct 15 19:04:24 [15159] DBG:core:receive_msg: cleaning up<br>
Oct 15 19:04:24 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:24 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:24 [15164] DBG:tm:utimer_routine: timer
routine:4,tl=0x7f9858508148 next=0x7f9858508348,
timeout=869000000<br>
Oct 15 19:04:24 [15164] DBG:tm:retransmission_handler:
retransmission_handler : request resending (t=0x7f9858507f28,
INVITE si ... )<br>
Oct 15 19:04:24 [15164] DBG:tm:set_timer: relative timeout is
1000000<br>
Oct 15 19:04:24 [15164] DBG:tm:insert_timer_unsafe: [5]:
0x7f9858508148 (870000000)<br>
Oct 15 19:04:24 [15164] DBG:tm:retransmission_handler:
retransmission_handler : done<br>
Oct 15 19:04:24 [15164] DBG:tm:utimer_routine: timer
routine:4,tl=0x7f9858508348 next=(nil), timeout=869000000<br>
Oct 15 19:04:24 [15164] DBG:tm:retransmission_handler:
retransmission_handler : request resending (t=0x7f9858507f28,
INVITE si ... )<br>
Oct 15 19:04:24 [15164] DBG:tm:set_timer: relative timeout is
1000000<br>
Oct 15 19:04:24 [15164] DBG:tm:insert_timer_unsafe: [5]:
0x7f9858508348 (870000000)<br>
Oct 15 19:04:24 [15164] DBG:tm:retransmission_handler:
retransmission_handler : done<br>
Oct 15 19:04:25 [15164] DBG:tm:utimer_routine: timer
routine:5,tl=0x7f9858508148 next=0x7f9858508348,
timeout=870000000<br>
Oct 15 19:04:25 [15164] DBG:tm:retransmission_handler:
retransmission_handler : request resending (t=0x7f9858507f28,
INVITE si ... )<br>
Oct 15 19:04:25 [15164] DBG:tm:set_timer: relative timeout is
2000000<br>
Oct 15 19:04:25 [15164] DBG:tm:insert_timer_unsafe: [6]:
0x7f9858508148 (872000000)<br>
Oct 15 19:04:25 [15164] DBG:tm:retransmission_handler:
retransmission_handler : done<br>
Oct 15 19:04:25 [15164] DBG:tm:utimer_routine: timer
routine:5,tl=0x7f9858508348 next=(nil), timeout=870000000<br>
Oct 15 19:04:25 [15164] DBG:tm:retransmission_handler:
retransmission_handler : request resending (t=0x7f9858507f28,
INVITE si ... )<br>
Oct 15 19:04:25 [15164] DBG:tm:set_timer: relative timeout is
2000000<br>
Oct 15 19:04:25 [15164] DBG:tm:insert_timer_unsafe: [6]:
0x7f9858508348 (872000000)<br>
Oct 15 19:04:25 [15164] DBG:tm:retransmission_handler:
retransmission_handler : done<br>
Oct 15 19:04:28 [15164] DBG:tm:utimer_routine: timer
routine:6,tl=0x7f9858508148 next=0x7f9858508348,
timeout=872000000<br>
Oct 15 19:04:28 [15164] DBG:tm:retransmission_handler:
retransmission_handler : request resending (t=0x7f9858507f28,
INVITE si ... )<br>
Oct 15 19:04:28 [15164] DBG:tm:set_timer: relative timeout is
4000000<br>
Oct 15 19:04:28 [15164] DBG:tm:insert_timer_unsafe: [7]:
0x7f9858508148 (876000000)<br>
Oct 15 19:04:28 [15164] DBG:tm:retransmission_handler:
retransmission_handler : done<br>
Oct 15 19:04:28 [15164] DBG:tm:utimer_routine: timer
routine:6,tl=0x7f9858508348 next=(nil), timeout=872000000<br>
Oct 15 19:04:28 [15164] DBG:tm:retransmission_handler:
retransmission_handler : request resending (t=0x7f9858507f28,
INVITE si ... )<br>
Oct 15 19:04:28 [15164] DBG:tm:set_timer: relative timeout is
4000000<br>
Oct 15 19:04:28 [15164] DBG:tm:insert_timer_unsafe: [7]:
0x7f9858508348 (876000000)<br>
Oct 15 19:04:28 [15164] DBG:tm:retransmission_handler:
retransmission_handler : done<br>
Oct 15 19:04:29 [15164] DBG:tm:timer_routine: timer
routine:0,tl=0x7f9858508178 next=0x7f9858508378, timeout=873<br>
Oct 15 19:04:29 [15164] DBG:tm:final_response_handler: Cancel
sent out, sending 408 (0x7f9858507f28)<br>
Oct 15 19:04:29 [15164] DBG:tm:t_should_relay_response:
T_code=100, new_code=408<br>
Oct 15 19:04:29 [15164] DBG:tm:relay_reply: branch=0, save=1,
relay=-1<br>
Oct 15 19:04:29 [15164] DBG:tm:final_response_handler: done<br>
Oct 15 19:04:29 [15164] DBG:tm:timer_routine: timer
routine:0,tl=0x7f9858508378 next=(nil), timeout=873<br>
Oct 15 19:04:29 [15164] DBG:tm:final_response_handler: Cancel
sent out, sending 408 (0x7f9858507f28)<br>
Oct 15 19:04:29 [15164] DBG:tm:t_should_relay_response:
T_code=100, new_code=408<br>
Oct 15 19:04:29 [15164] DBG:tm:t_pick_branch: picked branch 0,
code 408 (prio=800)<br>
Oct 15 19:04:29 [15164] DBG:tm:is_3263_failure: dns-failover
test: branch=0, last_recv=408, flags=1<br>
Oct 15 19:04:29 [15164] DBG:tm:t_should_relay_response: trying
DNS-based failover<br>
Oct 15 19:04:29 [15164] DBG:tm:run_trans_callbacks:
trans=0x7f9858507f28, callback type 32, id 0 entered<br>
ACC: call missed:
timestamp=1413371069;method=INVITE;from_tag=eC6yy-cLm;to_tag=;call_id=OHtW9D~WiH;code=408;reason=Request
Timeout<br>
Oct 15 19:04:29 [15164] DBG:tm:relay_reply: branch=1, save=0,
relay=0<br>
Oct 15 19:04:29 [15164] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:29 [15164] DBG:tm:set_timer: relative timeout is
500000<br>
Oct 15 19:04:29 [15164] DBG:tm:insert_timer_unsafe: [4]:
0x7f9858508070 (873400000)<br>
Oct 15 19:04:29 [15164] DBG:tm:insert_timer_unsafe: [0]:
0x7f98585080a0 (878)<br>
Oct 15 19:04:29 [15164] DBG:tm:relay_reply: sent
buf=0x7f985bfdd030: SIP/2.0 4..., shmem=0x7f985850bb48:
SIP/2.0 4<br>
Oct 15 19:04:29 [15164] DBG:tm:run_trans_callbacks:
trans=0x7f9858507f28, callback type 128, id 0 entered<br>
Oct 15 19:04:29 [15164] DBG:tm:final_response_handler: done<br>
Oct 15 19:04:29 [15161] DBG:core:parse_msg: SIP Request:<br>
Oct 15 19:04:29 [15161] DBG:core:parse_msg: method:
<ACK><br>
Oct 15 19:04:29 [15161] DBG:core:parse_msg: uri:
<a class="moz-txt-link-rfc2396E" href="mailto:sip:test2@103.24.228.158"><sip:test2@103.24.228.158></a><br>
Oct 15 19:04:29 [15161] DBG:core:parse_msg: version:
<SIP/2.0><br>
Oct 15 19:04:29 [15161] DBG:core:parse_headers: flags=2<br>
Oct 15 19:04:29 [15161] DBG:core:parse_via_param: found param
type 232, <branch> = <z9hG4bK.1tcd1QMAc>; state=6<br>
Oct 15 19:04:29 [15161] DBG:core:parse_via_param: found param
type 235, <rport> = <n/a>; state=17<br>
Oct 15 19:04:29 [15161] DBG:core:parse_via: end of header
reached, state=5<br>
Oct 15 19:04:29 [15161] DBG:core:parse_headers: via found,
flags=2<br>
Oct 15 19:04:29 [15161] DBG:core:parse_headers: this is the
first via<br>
Oct 15 19:04:29 [15161] DBG:core:receive_msg: After
parse_msg...<br>
Oct 15 19:04:29 [15161] DBG:core:receive_msg: preparing to run
routing scripts...<br>
Oct 15 19:04:29 [15161] DBG:sl:sl_filter_ACK: to late to be a
local ACK!<br>
Oct 15 19:04:29 [15161] DBG:core:parse_headers:
flags=ffffffffffffffff<br>
Oct 15 19:04:29 [15161] DBG:core:parse_to_param:
tag=d8bd1b57441363f641ce73441337eed0-d2b8<br>
Oct 15 19:04:29 [15161] DBG:core:parse_to: end of header
reached, state=29<br>
Oct 15 19:04:29 [15161] DBG:core:parse_to: display={},
ruri={<a class="moz-txt-link-abbreviated" href="mailto:sip:test2@103.24.228.158">sip:test2@103.24.228.158</a>}<br>
Oct 15 19:04:29 [15161] DBG:core:get_hdr_field: <To>
[70]; uri=[<a class="moz-txt-link-abbreviated" href="mailto:sip:test2@103.24.228.158">sip:test2@103.24.228.158</a>] <br>
Oct 15 19:04:29 [15161] DBG:core:get_hdr_field: to body
[<a class="moz-txt-link-rfc2396E" href="mailto:sip:test2@103.24.228.158"><sip:test2@103.24.228.158></a>]<br>
Oct 15 19:04:29 [15161] DBG:core:get_hdr_field: cseq
<CSeq>: <20> <ACK><br>
Oct 15 19:04:29 [15161] DBG:core:get_hdr_field: found end of
header<br>
Oct 15 19:04:29 [15161] DBG:core:parse_params: Parsing params
for:[+sip.instance="<urn:uuid:866598bd-4f5e-47f9-b7fa-8be6c6883a57>"]<br>
Oct 15 19:04:29 [15161] DBG:maxfwd:is_maxfwd_present: value =
70 <br>
Oct 15 19:04:29 [15161] DBG:uri:has_totag: totag found<br>
Oct 15 19:04:29 [15161] DBG:core:parse_headers: flags=200<br>
Oct 15 19:04:29 [15161] DBG:rr:find_first_route: No Route
headers found<br>
Oct 15 19:04:29 [15161] DBG:rr:loose_route: There is no Route
HF<br>
Oct 15 19:04:29 [15161] DBG:core:parse_headers: flags=78<br>
Oct 15 19:04:29 [15161] DBG:tm:t_lookup_request: start
searching: hash=53427, isACK=1<br>
Oct 15 19:04:29 [15161] DBG:tm:matching_3261: RFC3261
transaction matched, tid=.1tcd1QMAc<br>
Oct 15 19:04:29 [15161] DBG:tm:t_lookup_request:
REF_UNSAFE:[0x7f9858507f28] after is 1<br>
Oct 15 19:04:29 [15161] DBG:tm:t_lookup_request: transaction
found (T=0x7f9858507f28)<br>
Oct 15 19:04:29 [15161] DBG:tm:cleanup_uac_timers: RETR/FR
timers reset<br>
Oct 15 19:04:29 [15161] DBG:tm:insert_timer_unsafe: [2]:
0x7f9858507fa8 (878)<br>
Oct 15 19:04:29 [15161] DBG:tm:t_unref: UNREF_UNSAFE:
[0x7f9858507f28] after is 0<br>
Oct 15 19:04:29 [15161] DBG:core:destroy_avp_list: destroying
list (nil)<br>
Oct 15 19:04:29 [15161] DBG:core:receive_msg: cleaning up<br>
Oct 15 19:04:29 [15164] DBG:tm:utimer_routine: timer
routine:4,tl=0x7f9858508070 next=(nil), timeout=873400000<br>
Oct 15 19:04:31 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:31 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:31 [15160] DBG:core:udp_rcv_loop: probing packet
received len = 4<br>
Oct 15 19:04:32 [15164] DBG:tm:utimer_routine: timer
routine:7,tl=0x7f9858508148 next=0x7f9858508348,
timeout=876000000<br>
Oct 15 19:04:32 [15164] DBG:tm:utimer_routine: timer
routine:7,tl=0x7f9858508348 next=(nil), timeout=876000000<br>
Oct 15 19:04:33 [15162] DBG:core:udp_rcv_loop: probing packet
received len = 4<br>
Oct 15 19:04:35 [15164] DBG:tm:timer_routine: timer
routine:0,tl=0x7f98585080a0 next=(nil), timeout=878<br>
Oct 15 19:04:35 [15164] DBG:tm:timer_routine: timer
routine:2,tl=0x7f9858507fa8 next=(nil), timeout=878<br>
Oct 15 19:04:35 [15164] DBG:tm:wait_handler: removing
0x7f9858507f28 from table <br>
Oct 15 19:04:35 [15164] DBG:tm:delete_cell: delete transaction
0x7f9858507f28<br>
Oct 15 19:04:35 [15164] DBG:tm:wait_handler: done<br>
Oct 15 19:04:35 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:35 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:41 [15159] DBG:core:udp_rcv_loop: probing packet
received len = 4<br>
Oct 15 19:04:43 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:43 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:43 [15161] DBG:core:udp_rcv_loop: probing packet
received len = 4<br>
Oct 15 19:04:46 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:46 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:51 [15160] DBG:core:udp_rcv_loop: probing packet
received len = 4<br>
Oct 15 19:04:54 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:54 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:57 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
Oct 15 19:04:57 [15165] DBG:nathelper:nh_timer: resolving next
hop: '124.193.138.210'<br>
<br>
<br>
<br>
</div>
At 2014-10-15 15:13:00, "Bogdan-Andrei Iancu"
<a class="moz-txt-link-rfc2396E" href="mailto:bogdan@opensips.org"><bogdan@opensips.org></a> wrote:<br>
<blockquote id="isReplyContent" style="PADDING-LEFT: 1ex;
MARGIN: 0px 0px 0px 0.8ex; BORDER-LEFT: #ccc 1px solid">
<div class="moz-cite-prefix"><tt>Hi George,<br>
<br>
If your OpenSIPS fails to reach the UAC is because of two
reasons:<br>
- NAT pinhole is closed - but if pinging is done, it
shouldn't be<br>
- opensips is trying to contact UAC via wrong IP:port
- can you confirm that when calling the UAC, OpenSIPS
sends the INVITE to same IP and port as where the pingings
are coming from ?<br>
<br>
TCP works as this part is "automatically" resolved because
of the connection (where the other pipe is known).<br>
<br>
Regards,<br>
</tt>
<pre class="moz-signature" cols="72">Bogdan-Andrei Iancu
OpenSIPS Founder and Developer
<a moz-do-not-send="true" class="moz-txt-link-freetext" href="http://www.opensips-solutions.com">http://www.opensips-solutions.com</a></pre>
On 15.10.2014 03:24, george wu wrote:<br>
</div>
<blockquote
cite="mid:4dd40a9b.e54.14911311a6a.Coremail.aihuawu2012@163.com"
type="cite">
<div
style="line-height:1.7;color:#000000;font-size:14px;font-family:Arial"><br>
<div>Hi, Bogdan-Andrei:<br>
<br>
For udp, it fails when <tt>reaching the UAC even though
the UAC keeps pinging the server all the time.<br>
<br>
For tcp, although it works. I find something
interesting.<br>
Only when the client pings the server, the invite
message is sent to the UAC.<br>
In my understanding, the server should be able to send
message to the UAC since the <br>
tcp connection is open. Actually the sip server is
unable to send message to the UAC.<br>
<br>
About the firewall type, I use opensipsctl ul show/rm
to check.<br>
I find every time when it register, i get the same
ip/port</tt><tt><tt> most of time</tt>. <br>
But occasionally it might get different ip/port.<br>
I believe it is nat within a cone.<br>
<br>
I am using ice, the ice only work after the first
invite message is delivered to the peer.<br>
My ice with mediaproxy works perfectly.<br>
<br>
</tt></div>
<br>
George Wu<br>
<br>
At 2014-10-15 00:22:46, "Bogdan-Andrei Iancu" <a
moz-do-not-send="true" class="moz-txt-link-rfc2396E"
href="mailto:bogdan@opensips.org"><bogdan@opensips.org></a>
wrote:<br>
<blockquote id="isReplyContent" style="PADDING-LEFT: 1ex;
MARGIN: 0px 0px 0px 0.8ex; BORDER-LEFT: #ccc 1px solid">
<div class="moz-cite-prefix"><tt>Hi George,<br>
<br>
NAT traversal is not only about pinging, but also
about mangling/correcting the SIP traffic (from
private IPs perspective) and ensuring the RTP flow.<br>
<br>
So you need to be sure that all 3 points are
addressed.<br>
<br>
TCP versus UDP - there is only a difference at IP
transport level...like datagram versus connection,
and their implications at NAT level (being able to
reach the device behind the nat). Otherwise it;s the
same.<br>
<br>
For UDP, can you see what fails ? the registration?
reaching the UAC ? <br>
<br>
Regards,<br>
</tt>
<pre class="moz-signature" cols="72">Bogdan-Andrei Iancu
OpenSIPS Founder and Developer
<a moz-do-not-send="true" class="moz-txt-link-freetext" href="http://www.opensips-solutions.com">http://www.opensips-solutions.com</a></pre>
On 14.10.2014 18:37, george wu wrote:<br>
</div>
<blockquote
cite="mid:9f50cdb.2658d.1490f4e26ee.Coremail.aihuawu2012@163.com"
type="cite">
<div
style="line-height:1.7;color:#000000;font-size:14px;font-family:Arial">
<div>My experience is for two uac (linphone) behind
a firewall,<br>
tcp/tls will always work.<br>
udp will never work.<br>
<br>
for both tcp/udp, my uac will send keep alive
every 10 seconds.<br>
I don't understand what makes those difference.<br>
Can any one share your experience?<br>
<br>
George Wu<br>
<br>
<br>
</div>
</div>
<br>
<br>
<span title="neteasefooter"><span
id="netease_mail_footer"></span></span><br>
<fieldset class="mimeAttachmentHeader"></fieldset>
<br>
<pre wrap="">_______________________________________________
Users mailing list
<a moz-do-not-send="true" class="moz-txt-link-abbreviated" href="mailto:Users@lists.opensips.org">Users@lists.opensips.org</a>
<a moz-do-not-send="true" class="moz-txt-link-freetext" href="http://lists.opensips.org/cgi-bin/mailman/listinfo/users">http://lists.opensips.org/cgi-bin/mailman/listinfo/users</a>
</pre>
</blockquote>
<br>
</blockquote>
</div>
<br>
<br>
<span title="neteasefooter"><span id="netease_mail_footer"></span></span>
</blockquote>
<br>
</blockquote>
</div>
<br>
<br>
<span title="neteasefooter"><span id="netease_mail_footer"></span></span>
</blockquote>
<br>
</body>
</html>