<div dir="ltr">Bumping this! Any comments? Or suggestions on what to check? I'm feeling it has to be something stupid but I can't see it :(<div><div><br></div></div></div><br><div class="gmail_quote"><div dir="ltr" class="gmail_attr">On Sun, Sep 11, 2022 at 12:56 AM Joel Serrano <<a href="mailto:joel@textplus.com">joel@textplus.com</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><div dir="ltr">Hello,<div><br></div><div>I have a cluster of 2 kamailios v5.5 on debian 9 working flawlessly.</div><div><br></div><div>We have added a third node, also on v5.5 but debian 11. Kamailio doesn't work correctly for some reason that I'm not seeing.</div><div><br></div><div>The symptoms are:</div><div><br></div><div>1- Kamailio receives INVITEs and starts to process them per routing script.</div><div>2- There is a specific place where something happens and the calls are dropped (Kamailio is not even replying to the source). Note that the config is exactly the same on all 3 servers, only one of the three is having the issue.</div><div><br></div><div><div>I enabled debug logs and I could see:</div><div><br></div><div>[...]</div><div>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.example.com" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.example.com</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[GET_OUTBOUND_API_DATA] c=[/etc/kamailio/sbc/api.cfg] l=61 a=5 n=route<br>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.example.com" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.example.com</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[APPLY_REWRITE_RULE] c=[/etc/kamailio/sbc/extras.cfg] l=211 a=26 n=xlog</div><div>Sep 10 12:30:48 sbc03 sbc[956340]: INFO: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <script>: Applying local to toll-free rewrite rules on callee number using dpid '20'<br>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[APPLY_REWRITE_RULE] c=[/etc/kamailio/sbc/extras.cfg] l=212 a=63 n=assign<br>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[APPLY_REWRITE_RULE] c=[/etc/kamailio/sbc/extras.cfg] l=219 a=16 n=if<br>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[APPLY_REWRITE_RULE] c=[/etc/kamailio/sbc/extras.cfg] l=213 a=26 n=dp_translate<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dialplan.c:237]: dp_get_ivalue(): searching 15<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dialplan.c:245]: dp_get_ivalue(): dpid is 20 from pv argument<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dialplan.c:254]: dp_get_svalue(): searching 15<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dialplan.c:350]: dp_translate_f(): input is 67339924<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:605]: dp_translate_helper(): regex operator testing over [67339924]<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dp_repl.c:676]: dp_translate_helper(): no matching rule<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialplan [dialplan.c:354]: dp_translate_f(): could not translate 67339924 with dpid 20<br>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[APPLY_REWRITE_RULE] c=[/etc/kamailio/sbc/extras.cfg] l=217 a=26 n=xlog<br>Sep 10 12:30:48 sbc03 sbc[956340]: INFO: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <script>: No matching rewrite rules for '67339924' found<br>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[APPLY_REWRITE_RULE] c=[/etc/kamailio/sbc/extras.cfg] l=218 a=2 n=return<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/receive.c:514]: receive_msg(): request-route executed in: 12245 usec<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:785]: dlg_lookup_mode(): ref dlg 0x7ff1b4884b48 with 1 -> 2<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:789]: dlg_lookup_mode(): dialog id=8614 found on entry 343<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_var.c:63]: dlg_cfg_cb(): new dialog with no transaction after config execution<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1091]: dlg_unref_helper(): unref op on 0x7ff1b4884b48 with 1 from dlg_hash.c:1109<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1095]: dlg_unref_helper(): unref dlg 0x7ff1b4884b48 with 1 -> 1<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1091]: dlg_unref_helper(): unref op on 0x7ff1b4884b48 with 1 from dlg_hash.c:1109<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1095]: dlg_unref_helper(): unref dlg 0x7ff1b4884b48 with 1 -> 0<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1095]: dlg_unref_helper(): ref <=0 for dialog 0x7ff1b4884b48<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:345]: destroy_dlg(): destroying dialog 0x7ff1b4884b48 (ref 0)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:356]: destroy_dlg(): removed timer for dlg 0x7ff1b4884b48 [343:8614] with clid '<a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>' and tags 'as5d31c96b' ''<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_db_handler.c:708]: remove_dialog_from_db(): trying to remove dialog [<a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>], update_flag is 1024<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [db_query.c:72]: db_do_submit_query(): submitted query: delete from `dialog` where `hash_entry`=343 AND `hash_id`=8614, result 0<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [db_query.c:72]: db_do_submit_query(): submitted query: delete from `dialog_vars` where `hash_entry`=343 AND `hash_id`=8614, result 0<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_db_handler.c:736]: remove_dialog_from_db(): callid was <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a><br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list 0x7ff1b4880370<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/receive.c:606]: receive_msg(): cleaning up<br></div></div><div><br></div><div>If I go to line 61 from route GET_OUTBOUND_API_DATA, I have this:</div><div><br></div><div>    if(route(APPLY_REWRITE_RULE)) {<br>        $rU = $var(dp_user);<br>        $avp(icx_real_number) = $var(dp_user);<br>    }<br></div><div><br></div><div>The very next thing is:</div><div><br></div><div>    # Query API<br>    $avp(apiUrl) = "{{ call_api_url }}/outbound/" + $avp(icx_src_number) + "/" + $avp(icx_real_number);<br>    http_client_query("$avp(apiUrl)", "$avp(apiResponseBody)");<br>    $avp(apiResponseCode) = $rc;<br></div><div><br></div><div>I see the GET_OUTBOUND_API_DATA route calling APPLY_REWRITE_RULE, and APPLY_REWRITE_RULE returning correctly then finished:</div><div><br></div><div>Sep 10 12:30:48 sbc03 sbc[956340]: exec: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} *** cfgtrace:dbg_cfg_trace(): request_route=[APPLY_REWRITE_RULE] c=[/etc/kamailio/sbc/extras.cfg] l=218 a=2 n=return<br></div><div><br></div><div>But then Kamailio is apparently just tearing down the call and ending it.<br></div><div><br></div><div>Can someone help me understand what this part of the logs means or what can be causing them?</div><div><br></div><div>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/receive.c:514]: receive_msg(): request-route executed in: 12245 usec</div><div>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:785]: dlg_lookup_mode(): ref dlg 0x7ff1b4884b48 with 1 -> 2<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:789]: dlg_lookup_mode(): dialog id=8614 found on entry 343<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_var.c:63]: dlg_cfg_cb(): new dialog with no transaction after config execution<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1091]: dlg_unref_helper(): unref op on 0x7ff1b4884b48 with 1 from dlg_hash.c:1109<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1095]: dlg_unref_helper(): unref dlg 0x7ff1b4884b48 with 1 -> 1<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1091]: dlg_unref_helper(): unref op on 0x7ff1b4884b48 with 1 from dlg_hash.c:1109<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1095]: dlg_unref_helper(): unref dlg 0x7ff1b4884b48 with 1 -> 0<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:1095]: dlg_unref_helper(): ref <=0 for dialog 0x7ff1b4884b48<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:345]: destroy_dlg(): destroying dialog 0x7ff1b4884b48 (ref 0)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_hash.c:356]: destroy_dlg(): removed timer for dlg 0x7ff1b4884b48 [343:8614] with clid '<a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>' and tags 'as5d31c96b' ''<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_db_handler.c:708]: remove_dialog_from_db(): trying to remove dialog [<a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>], update_flag is 1024<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [db_query.c:72]: db_do_submit_query(): submitted query: delete from `dialog` where `hash_entry`=343 AND `hash_id`=8614, result 0<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [db_query.c:72]: db_do_submit_query(): submitted query: delete from `dialog_vars` where `hash_entry`=343 AND `hash_id`=8614, result 0<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} dialog [dlg_db_handler.c:736]: remove_dialog_from_db(): callid was <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a><br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list 0x7ff1b4880370<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/usr_avp.c:637]: destroy_avp_list(): destroying list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil)<br>Sep 10 12:30:48 sbc03 sbc[956340]: DEBUG: {1 102 INVITE <a href="mailto:5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa" target="_blank">5111a7c71ec485e443099844491ef7d8@sip02.vozelia.com.pa</a>} <core> [core/receive.c:606]: receive_msg(): cleaning up<br></div><div><br></div><div>I can see it's related to the dialogs, but is this a cause or a consequence? I have checked the API access logs, and Kamailio doesn't get to send the request, I've also noticed that normally Kamailio replies with a 100-Trying almost instantly, in the case of this server I don't see a single 100-Trying from Kamailio.</div><div><br></div><div>I'm pretty lost here so any tips are more than welcome.</div><div><br></div><div>NOTE: I tried with debian11 + Kamailio 5.6 and the same issue happens too.</div><div><br></div><div><br></div><div>Thanks, </div><div>Joel.</div><div><br></div><div><br></div><div><br></div></div>
</blockquote></div>