Added some debug to find out what is going on:
From: Victor Seva vseva@sipwise.com Date: Fri, 12 Jun 2020 16:32:41 +0200 Subject: dialog: more debug
src/modules/dialog/dlg_db_handler.c | 8 +++++--- 1 file changed, 5 insertions(+), 3 deletions(-)
diff --git a/src/modules/dialog/dlg_db_handler.c b/src/modules/dialog/dlg_db_handler.c index df43c7e..90e526c 100644 --- a/src/modules/dialog/dlg_db_handler.c +++ b/src/modules/dialog/dlg_db_handler.c @@ -837,7 +837,8 @@ int update_dialog_dbinfo_unsafe(struct dlg_cell * cell)
if(use_dialog_table()!=0) return -1;
LM_DBG("state:%d dflags:%d dlg [%d:%d]\n", cell->state, cell->dflags, cell->h_entry, cell->h_id);
srjson_InitDoc(&jdoc, NULL);
if((cell->dflags & DLG_FLAG_NEW) != 0){
@@ -909,7 +910,7 @@ int update_dialog_dbinfo_unsafe(struct dlg_cell * cell)
VAL_NULL(values+22) = 0; VAL_INT(values+22) = cell->iflags;
if((dialog_dbf.insert(dialog_db_handle, insert_keys, values, DIALOG_TABLE_COL_NO)) !=0){ LM_ERR("could not add another dialog to db\n");LM_DBG("insert dlg [%d:%d]\n", cell->h_entry, cell->h_id);
@@ -941,7 +942,7 @@ int update_dialog_dbinfo_unsafe(struct dlg_cell * cell) VAL_NULL(values+10) = VAL_NULL(values+11) = VAL_NULL(values+12) = VAL_NULL(values+13) = VAL_NULL(values+14) = VAL_NULL(values+15) = 0;
if((dialog_dbf.update(dialog_db_handle, (insert_keys), 0, (values), (insert_keys+10), (values+10), 2, 6)) !=0){ LM_ERR("could not update database info\n");LM_DBG("update dlg [%d:%d]\n", cell->h_entry, cell->h_id);
@@ -949,6 +950,7 @@ int update_dialog_dbinfo_unsafe(struct dlg_cell * cell) } cell->dflags &= ~(DLG_FLAG_CHANGED); } else {
return 0; }LM_DBG("skip dlg [%d:%d]\n", cell->h_entry, cell->h_id);
Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_hash.c:955]: dlg_search(): dialog with callid='aimoskeqqzmpizv@debrock.torreviejawireless.org' not found Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_hash.c:480]: build_new_dlg(): new dialog on hash 1705 Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_handlers.c:259]: populate_leg_info(): leg(0) route_set [sip:127.0.0.1;r2=on;lr=on;ftag=ejsmo;ngcplb=yes;nat=yes;socket=udp:192.168.1.102:5060,sip:192.168.1.102;r2=on;lr=on;ftag=ejsm o;ngcplb=yes;nat=yes;socket=udp:192.168.1.102:5060], contact [sip:43993003@192.168.1.123:5068;alias=192.168.1.123~5068~1], cseq [487] and bind_addr [udp:127.0.0.1:5062] Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_hash.c:1018]: link_dlg(): linking dialog [1705:12197] ... Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_hash.c:789]: dlg_lookup_mode(): dialog id=12197 found on entry 1705 Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_db_handler.c:835]: update_dialog_dbinfo_unsafe(): updated 5 vars for dlg [1705:12197] Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_db_handler.c:840]: update_dialog_dbinfo_unsafe(): state:1 dflags:0 dlg [1705:12197] Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_db_handler.c:953]: update_dialog_dbinfo_unsafe(): skip dlg [1705:12197]