<div>Hello,</div><div><br></div>We found out the problem, it was out fault. The ACK was dropped because
the was a previous (and incorrect) evaluation of $rU. Being $null for
these calls, the ACK wasn't relayed. We already fixed it; thanks for the
assistance. <div><br></div><div>Regards,</div><div>David.<br><br><div class="gmail_quote">2012/7/30 Daniel-Constantin Mierla <span dir="ltr"><<a href="mailto:miconda@gmail.com" target="_blank">miconda@gmail.com</a>></span><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
<div bgcolor="#FFFFFF" text="#000000">
Hello,<br>
<br>
add $si, $ru and $ci in the xlog(...) from the config to compare
with ngrep trace, maybe there are different ACKs.<br>
<br>
Cheers,<br>
Daniel<div><div class="h5"><br>
<br>
<div>On 7/30/12 3:55 PM, David Notivol
wrote:<br>
</div>
<blockquote type="cite">
<div>Hello,</div>
<div><br>
</div>
It looks like the r-uri in the trace is different from the one
kamailio considers. From the ACK message captured with ngrep:
<br>
ACK <a>sip:200.87.137.150:5060;user=phone</a> SIP/2.0
<br>
<br>
<div>But form the logs:
<br>
Jul 30 09:15:00 theseus-test
/usr/local/kamailio/sbin/kamailio[1577]: DEBUG: <core>
[parser/msg_parser.c:630]: method: <ACK>
<br>
Jul 30 09:15:00 theseus-test
/usr/local/kamailio/sbin/kamailio[1577]: DEBUG: <core>
[parser/msg_parser.c:632]: uri: <a href="mailto:sip:59146938249@79.170.68.157:5060;user=phone" target="_blank"><sip:59146938249@79.170.68.157:5060;user=phone></a>
<br>
<br>
<br>
Regards,
<div>David.</div>
<div><br>
<div class="gmail_quote">2012/7/30 Daniel-Constantin Mierla <span dir="ltr"><<a href="mailto:miconda@gmail.com" target="_blank">miconda@gmail.com</a>></span><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
<div bgcolor="#FFFFFF" text="#000000"> Hello,<br>
<br>
the log message shows that the transaction is not found.
Is the ACK coming late after 200ok ? There is a tm
module parameter that you can adjust to prolong the time
a transaction is kept after completion, if that is the
case.<br>
<br>
Also, be sure the INVITE is sent using tm functions, not
stateless via core function.<br>
<br>
Then, instead of dropping, you can just try forwarding
it if the uri si not myself, like adding:<br>
<br>
if(uri!=myself) t_relay();<br>
<br>
before the exit.<br>
<br>
Cheers,<br>
Daniel
<div>
<div><br>
<br>
<div>On 7/30/12 11:10 AM, David Notivol wrote:<br>
</div>
<blockquote type="cite">This is the code that's
being executed:
<div><br>
route[WITHINDLG] { <br>
if (has_totag()) { <br>
# sequential request withing a dialog
should <br>
# take the path determined by
record-routing <br>
xlog("ESTAMOS EN WITHIN\n"); <br>
if (loose_route()){ <br>
xlog("LOOSE ROUTE DETECTED\n"); <br>
if (is_method("ACK")) { <br>
xlog("ES UN ACK\n"); <br>
} <br>
route(BYE); <br>
route(RELAY); <br>
} else { <br>
xlog("NO LOOSE ROUTE\n"); <br>
if ( is_method("ACK") ) { <br>
if ( t_check_trans() ) { <br>
xlog("NO LOOSE AND
TRANSACTION FOUND\n"); <br>
# no loose-route, but
stateful ACK; <br>
# must be an ACK after a 487
<br>
# or e.g. 404 from upstream
server <br>
t_relay(); <br>
exit; <br>
} else { <br>
xlog("NO LOOSE AND
TRANSACTION NOT FOUND\n"); <br>
# ACK without matching
transaction ... ignore and discard <br>
exit; <br>
} <br>
} <br>
sl_send_reply("404","Not here"); <br>
} <br>
exit; <br>
} <br>
} <br>
<br>
<br>
And the call log with cfgtrace. It seems to be
clear that the transaction is not matched, but
we can't see why.<br>
<br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=335 a=26 n=xlog <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
<script>: ESTAMOS EN WITHIN <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=360 a=17 n=if <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=336 a=25 n=loose_route <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
rr [loose.c:108]: No Route headers found <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
rr [loose.c:838]: There is no Route HF <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=344 a=26 n=xlog <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
<script>: NO LOOSE ROUTE <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=359 a=17 n=if <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=345 a=26 n=is_method <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=357 a=17 n=if <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=346 a=25 n=t_check_trans <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg
id=12 global id=10 T start=(nil) <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
tm [t_lookup.c:527]: t_lookup_request: start
searching: hash=58737, isACK=1 <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
tm [t_lookup.c:485]: DEBUG: RFC3261 transaction
matching failed <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
tm [t_lookup.c:709]: DEBUG: t_lookup_request: no
transaction found <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg
id=12 global id=12 T end=(nil) <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=354 a=26 n=xlog <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
<script>: NO LOOSE AND TRANSACTION NOT
FOUND <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: ERROR:
*** cfgtrace:
c=[/usr/local/kamailio/etc/kamailio/kamailio.cfg]
l=356 a=3 n=exit <br>
Jul 30 04:58:08 theseus-test
/usr/local/kamailio/sbin/kamailio[577]: DEBUG:
<core> [usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying list (nil) <br>
<br>
<div class="gmail_quote">2012/7/30
Daniel-Constantin Mierla <span dir="ltr"><<a href="mailto:miconda@gmail.com" target="_blank">miconda@gmail.com</a>></span><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
<div bgcolor="#FFFFFF" text="#000000">
Hello,<br>
<br>
if your config is based on the default
one, there is a check for associated
INVITE transaction and if that does not
exist, then the ACK is droppend.<br>
<br>
You can use debugger module with cfgtrace
set on in order to see what actions in the
config file are executed. That will help
to see if it gets to t_check_trans() and
then exit.<br>
<br>
Cheers,<br>
Daniel
<div>
<div><br>
<br>
<div>On 7/30/12 10:35 AM, David
Notivol wrote:<br>
</div>
<blockquote type="cite">Hi Daniel,
<div><br>
</div>
<div>This is the ACK message:</div>
<div><br>
</div>
<div>
<div>U 2012/07/30 04:23:31.604721
<a href="http://79.170.68.151:5060" target="_blank">79.170.68.151:5060</a>
-> <a href="http://79.170.68.157:5060" target="_blank">79.170.68.157:5060</a></div>
<div>ACK <a>sip:200.87.137.150:5060;user=phone</a>
SIP/2.0.</div>
<div>Via: SIP/2.0/UDP
79.170.68.151:5060;branch=z9hG4bK334faa4497ll114a52eACK450932302031.</div>
<div>Max-Forwards: 70.</div>
<div>Route: <a><sip:79.170.68.157;lr;ftag=45093230-co8241-INS033></a>.</div>
<div>To: <<a href="mailto:sip%3A59146292331@79.170.68.157" target="_blank">sip:59146292331@79.170.68.157</a>;user=phone;noa=international>;tag=ldb0cbn6-CC-23.</div>
<div>From: "Javi Gallart"<<a href="mailto:sip%3A34917019888@79.170.68.151" target="_blank">sip:34917019888@79.170.68.151</a>;user=phone;noa=national>;tag=45093230-co8241-INS033.</div>
<div>Call-ID: <a href="mailto:071ae17301d3eb470f5cdde00cd9b24b@ens.com" target="_blank">071ae17301d3eb470f5cdde00cd9b24b@ens.com</a>.</div>
<div>CSeq: 824101 ACK.</div>
<div>User-Agent:
ENSR3.0.66.34-IS33-RMRG106382-RG105488-CPO13110.</div>
<div>Content-Length: 0.</div>
<div><br>
</div>
<br>
<div class="gmail_quote">2012/7/30
Daniel-Constantin Mierla <span dir="ltr"><<a href="mailto:miconda@gmail.com" target="_blank">miconda@gmail.com</a>></span><br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
<div bgcolor="#FFFFFF" text="#000000"> Hello,<br>
<br>
can you add a log message to
print the source ip, call id
and r-uri?<br>
<br>
It may happen that the ACK
is looping back if r-uri is
pointing to itself.<br>
<br>
Also, try to get the ngrep
on all devices, like:<br>
<br>
ngrep -d any -qt -W byline
port 5060<br>
<br>
Pasting the ACK request here
will help to see if
something is wrong with it.<br>
<br>
Cheers,<br>
Daniel
<div>
<div><br>
<br>
<div>On 7/30/12 8:53 AM,
David Notivol wrote:<br>
</div>
</div>
</div>
<blockquote type="cite">
<div>
<div>In a
UAC-Kamailio-UAS
scenario, we've found
a case where the ACK
coming from uac is not
relayed by our proxy
to the uas. This is
the log for the ACK
message: <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:624]:
SIP Request: <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:626]:
method: <ACK> <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:628]:
uri: <a href="mailto:sip:59138553189@79.170.68.157" target="_blank"><sip:59138553189@79.170.68.157></a>
<br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:630]:
version:
<SIP/2.0> <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/parse_via.c:1286]:
Found param type 235,
<rport> =
<n/a>; state=6 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/parse_via.c:1286]:
Found param type 232,
<branch> =
<z9hG4bKKc4gKXyemS9HD>;
state=16 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/parse_via.c:2561]:
end of header reached,
state=5 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:511]:
parse_headers: Via
found, flags=2 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:513]:
parse_headers: this is
the first via <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[receive.c:149]: After
parse_msg... <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[receive.c:190]:
preparing to run
routing scripts... <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: sl
[sl_funcs.c:396]:
DEBUG : sl_filter_ACK:
to late to be a local
ACK! <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: maxfwd
[mf_funcs.c:85]: value
= 65 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/parse_to.c:178]:
DEBUG: add_param:
tag=6ne1x6d6-CC-23 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/parse_to.c:802]:
end of header reached,
state=29 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:188]:
DEBUG: get_hdr_field:
<To> [52]; uri=[<a href="mailto:sip:59138553189@79.170.68.157" target="_blank">sip:59138553189@79.170.68.157</a>]
<br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:190]:
DEBUG: to body [<a href="mailto:sip:59138553189@79.170.68.157" target="_blank"><sip:59138553189@79.170.68.157></a>]
<br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:168]:
get_hdr_field: cseq
<CSeq>:
<31362871>
<ACK> <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:202]:
DEBUG: get_hdr_body :
content_length=0 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/msg_parser.c:104]:
found end of header <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/parse_to.c:178]:
DEBUG: add_param:
tag=0cS8trtaF196F <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: <core>
[parser/parse_to.c:802]:
end of header reached,
state=29 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: sanity
[mod_sanity.c:255]:
sanity checks result:
1 <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: siputils
[checks.c:106]: totag
found <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
ERROR: <script>:
ESTAMOS EN WITHIN <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: rr
[loose.c:108]: No
Route headers found <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
DEBUG: rr
[loose.c:829]: There
is no Route HF <br>
Jul 27 10:04:59
theseus-test
/usr/local/kamailio/sbin/kamailio[17358]:
ERROR: <script>:
NO LOOSE ROUTE
<div><br>
However, in a pcap
trace I clearly see
the Route header: <br>
This is the
Record-Route in the
200 message <br>
Record-Route: <a><sip:79.170.68.157;lr;ftag=330cdf48-co1022-INS033></a>
<br>
And this is the
Route header in the
ACk: <br>
Route: <a><sip:79.170.68.157;lr;ftag=330cdf48-co1022-INS033></a>
<br>
<br>
</div>
<div>I haven't been
able to figure out
what's going on. We
have plenty of
traffic working in
that proxy. What am
I missing? <br>
<br>
</div>
<div>Thanks in advance
<br>
David. </div>
<br>
<fieldset></fieldset>
<br>
</div>
</div>
<pre>_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
<a href="mailto:sr-users@lists.sip-router.org" target="_blank">sr-users@lists.sip-router.org</a>
<a href="http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users" target="_blank">http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users</a><span><font color="#888888">
</font></span></pre>
<span><font color="#888888"> </font></span></blockquote>
<span><font color="#888888">
<br>
<pre cols="72">--
Daniel-Constantin Mierla - <a href="http://www.asipto.com" target="_blank">http://www.asipto.com</a>
<a href="http://twitter.com/#%21/miconda" target="_blank">http://twitter.com/#!/miconda</a> - <a href="http://www.linkedin.com/in/miconda" target="_blank">http://www.linkedin.com/in/miconda</a>
Kamailio Advanced Training, Seattle, USA, Sep 23-26, 2012 - <a href="http://asipto.com/u/katu" target="_blank">http://asipto.com/u/katu</a>
Kamailio Practical Workshop, Netherlands, Sep 10-12, 2012 - <a href="http://asipto.com/u/kpw" target="_blank">http://asipto.com/u/kpw</a></pre>
</font></span></div>
</blockquote>
</div>
<br>
<br>
</div>
</blockquote>
<br>
<pre cols="72">--
Daniel-Constantin Mierla - <a href="http://www.asipto.com" target="_blank">http://www.asipto.com</a>
<a href="http://twitter.com/#%21/miconda" target="_blank">http://twitter.com/#!/miconda</a> - <a href="http://www.linkedin.com/in/miconda" target="_blank">http://www.linkedin.com/in/miconda</a>
Kamailio Advanced Training, Seattle, USA, Sep 23-26, 2012 - <a href="http://asipto.com/u/katu" target="_blank">http://asipto.com/u/katu</a>
Kamailio Practical Workshop, Netherlands, Sep 10-12, 2012 - <a href="http://asipto.com/u/kpw" target="_blank">http://asipto.com/u/kpw</a></pre>
</div>
</div>
</div>
</blockquote>
</div>
<br>
<br clear="all">
<div><br>
</div>
-- <br>
Saludos,<br>
David Notivol<br>
<a href="mailto:dnotivol@gmail.com" target="_blank">dnotivol@gmail.com</a><br>
<br>
</div>
</blockquote>
<br>
<pre cols="72">--
Daniel-Constantin Mierla - <a href="http://www.asipto.com" target="_blank">http://www.asipto.com</a>
<a href="http://twitter.com/#%21/miconda" target="_blank">http://twitter.com/#!/miconda</a> - <a href="http://www.linkedin.com/in/miconda" target="_blank">http://www.linkedin.com/in/miconda</a>
Kamailio Advanced Training, Seattle, USA, Sep 23-26, 2012 - <a href="http://asipto.com/u/katu" target="_blank">http://asipto.com/u/katu</a>
Kamailio Practical Workshop, Netherlands, Sep 10-12, 2012 - <a href="http://asipto.com/u/kpw" target="_blank">http://asipto.com/u/kpw</a></pre>
</div>
</div>
</div>
</blockquote>
</div>
<br>
</div>
</div>
</blockquote>
<br>
<pre cols="72">--
Daniel-Constantin Mierla - <a href="http://www.asipto.com" target="_blank">http://www.asipto.com</a>
<a href="http://twitter.com/#!/miconda" target="_blank">http://twitter.com/#!/miconda</a> - <a href="http://www.linkedin.com/in/miconda" target="_blank">http://www.linkedin.com/in/miconda</a>
Kamailio Advanced Training, Seattle, USA, Sep 23-26, 2012 - <a href="http://asipto.com/u/katu" target="_blank">http://asipto.com/u/katu</a>
Kamailio Practical Workshop, Netherlands, Sep 10-12, 2012 - <a href="http://asipto.com/u/kpw" target="_blank">http://asipto.com/u/kpw</a></pre></div></div></div></blockquote></div>
</div>