<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 sip:200.87.137.150:5060;user=phone 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 class="moz-txt-link-rfc2396E" href="mailto:sip:59146938249@79.170.68.157:5060;user=phone"><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 class="h5"><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/#!/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>