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 class="h5"><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/#!/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">dnotivol@gmail.com</a><br><br>
</div>