<div dir="ltr"><div>Sorry for late question will it also be applied for the case when bye is receives in dlg state 2 results in Critical log ?<br><br></div>FYI: Its discussed here <a href="http://lists.opensips.org/pipermail/users/2012-March/020890.html">http://lists.opensips.org/pipermail/users/2012-March/020890.html</a><br></div><div class="gmail_extra"><br><div class="gmail_quote">On Mon, Aug 3, 2015 at 11:33 PM, Trevor Steyn <span dir="ltr"><<a href="mailto:trevor@webon.co.za" target="_blank">trevor@webon.co.za</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">Thanks for the Info Vlad I will update.<br>
<br>
Regards<br>
<span class="HOEnZb"><font color="#888888">Trevor Steyn<br>
</font></span><div class="HOEnZb"><div class="h5"><br>
<br>
On 03/08/2015 16:41, Vlad Paiu wrote:<br>
> Hello,<br>
><br>
> Please update your OpenSIPS sources. There was a recent bug fix which<br>
> addresses this issue - see<br>
> <a href="https://github.com/OpenSIPS/opensips/commit/d171b7aaa47a23481a1e374ca58305d1898511a6" rel="noreferrer" target="_blank">https://github.com/OpenSIPS/opensips/commit/d171b7aaa47a23481a1e374ca58305d1898511a6</a><br>
><br>
> Best Regards,<br>
><br>
> Vlad Paiu<br>
> OpenSIPS Developer<br>
> <a href="http://www.opensips-solutions.com" rel="noreferrer" target="_blank">http://www.opensips-solutions.com</a><br>
><br>
> On 03.08.2015 16:52, Trevor Steyn wrote:<br>
>> Anyone have any ideas?<br>
>><br>
>> On 28/07/2015 11:01, Trevor Steyn wrote:<br>
>>> Hi Guys,<br>
>>><br>
>>> I seem to be having some trouble with the new topology_hiding module in<br>
>>> opensips 2.1<br>
>>><br>
>>> here is the call scenario<br>
>>><br>
>>> UAC --> Opensips --> UAS<br>
>>><br>
>>> UAC Sends Invite to UAS with topology hiding module<br>
>>> UAS sends 180 with to-tag<br>
>>> UAC sends BYE<br>
>>><br>
>>> When the Bye is sent opensips loops the call till max forwards is<br>
>>> reached<br>
>>><br>
>>> from what i can see from the debugs the Bye from UAC is accepted and<br>
>>> matches the topology_hiding_match function but does not rewrite the<br>
>>> destination IP so when the message passes t_relay() its sending the Bye<br>
>>> to Itself from Itself.<br>
>>><br>
>>> I have tried this without topology hiding and the BYE is relayed as it<br>
>>> should<br>
>>> .<br>
>>><br>
>>> My route looks as follows<br>
>>><br>
>>><br>
>>> route{<br>
>>> script_trace( 3, "$rm from $si, ruri=$ru", "me");<br>
>>><br>
>>> if (!mf_process_maxfwd_header("10")) {<br>
>>> sl_send_reply("483","Too Many Hops");<br>
>>> exit;<br>
>>> }<br>
>>><br>
>>> if ( check_source_address("1","$avp(trunk_attrs)") ) {<br>
>>> # request comes from trunks<br>
>>> setflag(IS_TRUNK);<br>
>>> } else if ( is_from_gw() ) {<br>
>>> # request comes from GWs<br>
>>> } else {<br>
>>> # send_reply("403","Forbidden");<br>
>>> xlog("Message is not from Trunk or GW $si");<br>
>>> # exit;<br>
>>> }<br>
>>><br>
>>> if (has_totag()) {<br>
>>> # sequential request withing a dialog should<br>
>>> # take the path determined by record-routing<br>
>>> #if (loose_route()) {<br>
>>> if(topology_hiding_match()) {<br>
>>> # validate the sequential request against<br>
>>> dialog<br>
>>> if ( $DLG_status!=NULL && !validate_dialog() ) {<br>
>>> xlog("In-Dialog $rm from $si (callid=$ci) is not valid<br>
>>> according to dialog\n");<br>
>>> ## exit;<br>
>>> }<br>
>>> if (is_method("BYE")) {<br>
>>> setflag(ACC_DO); # do accounting ...<br>
>>> setflag(ACC_FAILED); # ... even if the transaction<br>
>>> fails<br>
>>> } else if (is_method("INVITE")) {<br>
>>> # even if in most of the cases is useless, do RR for<br>
>>> # re-INVITEs alos, as some buggy clients do change<br>
>>> route set<br>
>>> # during the dialog.<br>
>>> record_route();<br>
>>> }<br>
>>><br>
>>> # lets handle re-invites and offer proxy<br>
>>> if (has_body("application/sdp")) {<br>
>>> # Begin rtp session update gyrations<br>
>>> if (method == "INVITE") {<br>
>>> # INVITE w/ SDP, so early neg<br>
>>> # This is offer, reply is answer<br>
>>> rtpproxy_offer("iewlz20");<br>
>>> t_on_reply("1");<br>
>>> } else if (method == "ACK") {<br>
>>> # ACK w/ SDP, so late neg (done now)<br>
>>> # This is answer<br>
>>> rtpproxy_answer("iewlz20");<br>
>>> }<br>
>>> }<br>
>>><br>
>>> .............<br>
>>><br>
>>> if (is_method("INVITE")) {<br>
>>> force_send_socket(udp:XXX.XXX.XXX.XXX:5060);<br>
>>> #rtpproxy_engage('ierz20');<br>
>>> topology_hiding();<br>
>>> }<br>
>>><br>
>>> if (has_body("application/sdp")) {<br>
>>> if (rtpproxy_offer('iewz20')) {<br>
>>> t_on_reply("1");<br>
>>> } else {<br>
>>> t_on_reply("2");<br>
>>> }<br>
>>> }<br>
>>><br>
>>><br>
>>> route(RELAY);<br>
>>> }<br>
>>><br>
>>><br>
>>> route[RELAY] {<br>
>>> if (!t_relay()) {<br>
>>> sl_reply_error();<br>
>>> };<br>
>>> exit;<br>
>>> }<br>
>>><br>
>>><br>
>>><br>
>>><br>
>>><br>
>>><br>
>>> Debug below<br>
>>><br>
>>><br>
>>> [Script Trace][/etc/opensips/opensips.cfg:176][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:171][me][module<br>
>>> mf_process_maxfwd_header] -> (BYE from 10.10.16.1,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=100<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to_param: tag=6pou4pvn6xe2phuk.i<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: end of header reached,<br>
>>> state=29<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: display={},<br>
>>> ruri={sip:27111000626@10.10.16.1:5060;user=phone}<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: <To> [70];<br>
>>> uri=[sip:27111000626@10.10.16.1:5060;user=phone]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: to body<br>
>>> [<sip:27111000626@10.10.16.1:5060;user=phone>]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: cseq <CSeq>: <2> <BYE><br>
>>> Jul 23 16:01:16 [22780] DBG:maxfwd:is_maxfwd_present: value = 67<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:185][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:176][me][module<br>
>>> check_source_address] -> (BYE from 10.10.16.1,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:check_src_addr_3: Looking for :<br>
>>> <1, 10.10.16.1, 5060, 1> in tables<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:hash_match: no pattern to match<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:hash_match: match found in the<br>
>>> hash table<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:178][me][core setflag] -><br>
>>> (BYE<br>
>>> from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:248][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:187][me][module has_totag] -><br>
>>> (BYE from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:uri:has_totag: totag found<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:242][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:191][me][module<br>
>>> topology_hiding_match] -> (BYE from 10.10.16.1,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=200<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: content_length=0<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: found end of header<br>
>>> Jul 23 16:01:16 [22780] DBG:core:grep_sock_info: checking if host==us:<br>
>>> 13==13 && [10.10.16.2] == [10.10.16.2]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:grep_sock_info: checking if port 5060<br>
>>> matches port 5060<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:w_match_dialog: We found DID<br>
>>> param in<br>
>>> R-URI with value of dbf.d239f4f3<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:dlg_onroute: route param is<br>
>>> 'dbf.d239f4f3' (len=12)<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:lookup_dlg: ref dlg 0x7f81e1b3c2f8<br>
>>> with 1 -> 4<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:lookup_dlg: dialog id=1062179629<br>
>>> found on entry 4029<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=58<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to_param:<br>
>>> tag=SDl3i7c02-55B0F3A1-1DDB4E1-0ADE2C1B<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: end of header reached,<br>
>>> state=29<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: display={},<br>
>>> ruri={<a href="mailto:sip%3A27115138000@10.10.17.55">sip:27115138000@10.10.17.55</a>;user=phone}<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:next_state_dlg: dialog<br>
>>> 0x7f81e1b3c2f8<br>
>>> changed from state 2 to state 2, due event 7<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:199][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:194][me][module<br>
>>> validate_dialog] -> (BYE from 10.10.16.1,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:dlg_validate_dialog: CSEQ validation<br>
>>> passed<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:210][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:199][me][module is_method] -><br>
>>> (BYE from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:200][me][core setflag] -><br>
>>> (BYE<br>
>>> from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:201][me][core setflag] -><br>
>>> (BYE<br>
>>> from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:227][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:210][me][module has_body] -><br>
>>> (BYE from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:sipmsgops:has_body_f: content length is<br>
>>> zero<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:227][me][route RELAY] -> (BYE<br>
>>> from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:340][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:338][me][module t_relay] -><br>
>>> (BYE from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_newtran: transaction on<br>
>>> entrance=0xffffffffffffffff<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=ffffffffffffffff<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=78<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_lookup_request: start searching:<br>
>>> hash=40946, isACK=0<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:matching_3261: RFC3261 transaction<br>
>>> matching failed<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_lookup_request: no transaction found<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:run_reqin_callbacks:<br>
>>> trans=0x7f81e1b41548, callback type 1, id 1 entered<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=78<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:run_reqin_callbacks:<br>
>>> trans=0x7f81e1b41548, callback type 1, id 0 entered<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:ref_dlg: ref dlg 0x7f81e1b3c2f8 with<br>
>>> 1 -> 5<br>
>>> Jul 23 16:01:16 [22780] DBG:core:mk_proxy: doing DNS lookup...<br>
>>> Jul 23 16:01:16 [22780] DBG:core:check_ip_address: params 10.10.16.1,<br>
>>> 10.10.16.1, 0<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:set_timer: relative timeout is 500000<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:insert_timer_unsafe: [4]: 0x7f81e1b41768<br>
>>> (15800000)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:insert_timer_unsafe: [0]:<br>
>>> 0x7f81e1b41798 (20)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_relay_to: new transaction fwd'ed<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:341][me][core exit] -> (BYE<br>
>>> from 10.10.16.1, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_unref: UNREF_UNSAFE: [0x7f81e1b41548]<br>
>>> after is 0<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:unref_dlg: unref dlg 0x7f81e1b3c2f8<br>
>>> with 1 -> 4 in entry 0x7f81e1b35838<br>
>>> Jul 23 16:01:16 [22780] DBG:core:destroy_avp_list: destroying list<br>
>>> (nil)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:receive_msg: cleaning up<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: SIP Request:<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: method: <BYE><br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: uri:<br>
>>> <sip:10.10.16.2;did=dbf.d239f4f3><br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: version: <SIP/2.0><br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=2<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via_param: found param type 232,<br>
>>> <branch> = <z9hG4bK2ff9.7dfac551.0>; state=16<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via: end of header reached,<br>
>>> state=5<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: via found, flags=2<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: this is the first via<br>
>>> Jul 23 16:01:16 [22780] DBG:core:receive_msg: After parse_msg...<br>
>>> Jul 23 16:01:16 [22780] DBG:core:receive_msg: preparing to run routing<br>
>>> scripts...<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:176][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:171][me][module<br>
>>> mf_process_maxfwd_header] -> (BYE from 10.10.16.2,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=100<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via_param: found param type 232,<br>
>>> <branch> = <z9hG4bK53eneb00a89gqfc5m0o0.1>; state=16<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via: end of header reached,<br>
>>> state=5<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: via found, flags=100<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: parse_headers: this is<br>
>>> the second via<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to_param: tag=6pou4pvn6xe2phuk.i<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: end of header reached,<br>
>>> state=29<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: display={},<br>
>>> ruri={sip:27111000626@10.10.16.1:5060;user=phone}<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: <To> [70];<br>
>>> uri=[sip:27111000626@10.10.16.1:5060;user=phone]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: to body<br>
>>> [<sip:27111000626@10.10.16.1:5060;user=phone>]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: cseq <CSeq>: <2> <BYE><br>
>>> Jul 23 16:01:16 [22780] DBG:maxfwd:is_maxfwd_present: value = 66<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:185][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:176][me][module<br>
>>> check_source_address] -> (BYE from 10.10.16.2,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:check_src_addr_3: Looking for :<br>
>>> <1, 10.10.16.2, 5060, 1> in tables<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:hash_match: no match in the<br>
>>> hash<br>
>>> table<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:match_subnet_table: subnet<br>
>>> table<br>
>>> is empty<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:185][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:179][me][module<br>
>>> is_from_gw] -><br>
>>> (BYE from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:183][me][core xlog] -> (BYE<br>
>>> from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:buf_init: initializing...<br>
>>> Message is not from Trunk or GW 10.10.16.2[Script<br>
>>> Trace][/etc/opensips/opensips.cfg:248][me][core if] -> (BYE from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:187][me][module has_totag] -><br>
>>> (BYE from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:uri:has_totag: totag found<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:242][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:191][me][module<br>
>>> topology_hiding_match] -> (BYE from 10.10.16.2,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=200<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: content_length=0<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: found end of header<br>
>>> Jul 23 16:01:16 [22780] DBG:core:grep_sock_info: checking if host==us:<br>
>>> 13==13 && [10.10.16.2] == [10.10.16.2]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:grep_sock_info: checking if port 5060<br>
>>> matches port 5060<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:w_match_dialog: We found DID<br>
>>> param in<br>
>>> R-URI with value of dbf.d239f4f3<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:dlg_onroute: route param is<br>
>>> 'dbf.d239f4f3' (len=12)<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:lookup_dlg: ref dlg 0x7f81e1b3c2f8<br>
>>> with 1 -> 5<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:lookup_dlg: dialog id=1062179629<br>
>>> found on entry 4029<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=58<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to_param:<br>
>>> tag=SDl3i7c02-55B0F3A1-1DDB4E1-0ADE2C1B<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: end of header reached,<br>
>>> state=29<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: display={},<br>
>>> ruri={<a href="mailto:sip%3A27115138000@10.10.17.55">sip:27115138000@10.10.17.55</a>;user=phone}<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:next_state_dlg: dialog<br>
>>> 0x7f81e1b3c2f8<br>
>>> changed from state 2 to state 2, due event 7<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:199][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Trevor<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:194][me][module<br>
>>> validate_dialog] -> (BYE from 10.10.16.2,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:dlg_validate_dialog: CSEQ validation<br>
>>> passed<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:210][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:199][me][module is_method] -><br>
>>> (BYE from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:200][me][core setflag] -><br>
>>> (BYE<br>
>>> from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:201][me][core setflag] -><br>
>>> (BYE<br>
>>> from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:227][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:210][me][module has_body] -><br>
>>> (BYE from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:sipmsgops:has_body_f: content length is<br>
>>> zero<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:227][me][route RELAY] -> (BYE<br>
>>> from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:340][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:338][me][module t_relay] -><br>
>>> (BYE from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_newtran: transaction on<br>
>>> entrance=0xffffffffffffffff<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=ffffffffffffffff<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=78<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_lookup_request: start searching:<br>
>>> hash=40946, isACK=0<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:matching_3261: RFC3261 transaction<br>
>>> matching failed<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_lookup_request: no transaction found<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:run_reqin_callbacks:<br>
>>> trans=0x7f81e1b446a0, callback type 1, id 1 entered<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=78<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:run_reqin_callbacks:<br>
>>> trans=0x7f81e1b446a0, callback type 1, id 0 entered<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:ref_dlg: ref dlg 0x7f81e1b3c2f8 with<br>
>>> 1 -> 6<br>
>>> Jul 23 16:01:16 [22780] DBG:core:mk_proxy: doing DNS lookup...<br>
>>> Jul 23 16:01:16 [22780] DBG:core:check_ip_address: params 10.10.16.2,<br>
>>> 10.10.16.2, 0<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:set_timer: relative timeout is 500000<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:insert_timer_unsafe: [4]: 0x7f81e1b448c0<br>
>>> (15800000)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:insert_timer_unsafe: [0]:<br>
>>> 0x7f81e1b448f0 (20)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_relay_to: new transaction fwd'ed<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:341][me][core exit] -> (BYE<br>
>>> from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:tm:t_unref: UNREF_UNSAFE: [0x7f81e1b446a0]<br>
>>> after is 0<br>
>>> Jul 23 16:01:16 [22780] DBG:dialog:unref_dlg: unref dlg 0x7f81e1b3c2f8<br>
>>> with 1 -> 5 in entry 0x7f81e1b35838<br>
>>> Jul 23 16:01:16 [22780] DBG:core:destroy_avp_list: destroying list<br>
>>> (nil)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:receive_msg: cleaning up<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: SIP Request:<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: method: <BYE><br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: uri:<br>
>>> <sip:10.10.16.2;did=dbf.d239f4f3><br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_msg: version: <SIP/2.0><br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=2<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via_param: found param type 232,<br>
>>> <branch> = <z9hG4bK2ff9.8dfac551.0>; state=16<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via: end of header reached,<br>
>>> state=5<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: via found, flags=2<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: this is the first via<br>
>>> Jul 23 16:01:16 [22780] DBG:core:receive_msg: After parse_msg...<br>
>>> Jul 23 16:01:16 [22780] DBG:core:receive_msg: preparing to run routing<br>
>>> scripts...<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:176][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:171][me][module<br>
>>> mf_process_maxfwd_header] -> (BYE from 10.10.16.2,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: flags=100<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via_param: found param type 232,<br>
>>> <branch> = <z9hG4bK2ff9.7dfac551.0>; state=16<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via: end of header reached,<br>
>>> state=5<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: via found, flags=100<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: parse_headers: this is<br>
>>> the second via<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via_param: found param type 232,<br>
>>> <branch> = <z9hG4bK53eneb00a89gqfc5m0o0.1>; state=16<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_via: end of header reached,<br>
>>> state=5<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_headers: via found, flags=100<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to_param: tag=6pou4pvn6xe2phuk.i<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: end of header reached,<br>
>>> state=29<br>
>>> Jul 23 16:01:16 [22780] DBG:core:parse_to: display={},<br>
>>> ruri={sip:27111000626@10.10.16.1:5060;user=phone}<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: <To> [70];<br>
>>> uri=[sip:27111000626@10.10.16.1:5060;user=phone]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: to body<br>
>>> [<sip:27111000626@10.10.16.1:5060;user=phone>]<br>
>>> Jul 23 16:01:16 [22780] DBG:core:get_hdr_field: cseq <CSeq>: <2> <BYE><br>
>>> Jul 23 16:01:16 [22780] DBG:maxfwd:is_maxfwd_present: value = 65<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:185][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:176][me][module<br>
>>> check_source_address] -> (BYE from 10.10.16.2,<br>
>>> ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:check_src_addr_3: Looking for :<br>
>>> <1, 10.10.16.2, 5060, 1> in tables<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:hash_match: no match in the<br>
>>> hash<br>
>>> table<br>
>>> Jul 23 16:01:16 [22780] DBG:permissions:match_subnet_table: subnet<br>
>>> table<br>
>>> is empty<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:185][me][core if] -> (BYE<br>
>>> from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:179][me][module<br>
>>> is_from_gw] -><br>
>>> (BYE from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> [Script Trace][/etc/opensips/opensips.cfg:183][me][core xlog] -> (BYE<br>
>>> from 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>> Message is not from Trunk or GW 10.10.16.2[Script<br>
>>> Trace][/etc/opensips/opensips.cfg:248][me][core if] -> (BYE from<br>
>>> 10.10.16.2, ruri=sip:10.10.16.2;did=dbf.d239f4f3)<br>
>>><br>
>>><br>
>>> Regards<br>
>>> Trevor Steyn<br>
>>><br>
>><br>
>> _______________________________________________<br>
>> Devel mailing list<br>
>> <a href="mailto:Devel@lists.opensips.org">Devel@lists.opensips.org</a><br>
>> <a href="http://lists.opensips.org/cgi-bin/mailman/listinfo/devel" rel="noreferrer" target="_blank">http://lists.opensips.org/cgi-bin/mailman/listinfo/devel</a><br>
><br>
><br>
> _______________________________________________<br>
> Devel mailing list<br>
> <a href="mailto:Devel@lists.opensips.org">Devel@lists.opensips.org</a><br>
> <a href="http://lists.opensips.org/cgi-bin/mailman/listinfo/devel" rel="noreferrer" target="_blank">http://lists.opensips.org/cgi-bin/mailman/listinfo/devel</a><br>
<br>
<br>
_______________________________________________<br>
Devel mailing list<br>
<a href="mailto:Devel@lists.opensips.org">Devel@lists.opensips.org</a><br>
<a href="http://lists.opensips.org/cgi-bin/mailman/listinfo/devel" rel="noreferrer" target="_blank">http://lists.opensips.org/cgi-bin/mailman/listinfo/devel</a><br>
</div></div></blockquote></div><br></div>