Added some debug to find out what is going on:
From: Victor Seva <vseva(a)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;
-
+ LM_DBG("insert dlg [%d:%d]\n", cell->h_entry, cell->h_id);
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");
@@ -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;
-
+ LM_DBG("update dlg [%d:%d]\n", cell->h_entry, cell->h_id);
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");
@@ -949,6 +950,7 @@ int update_dialog_dbinfo_unsafe(struct dlg_cell * cell)
}
cell->dflags &= ~(DLG_FLAG_CHANGED);
} else {
+ LM_DBG("skip dlg [%d:%d]\n", cell->h_entry, cell->h_id);
return 0;
}
Jun 12 16:46:05 sp1 proxy[6037]: DEBUG: ROUTE_DLG_MANAGE dialog [dlg_hash.c:955]:
dlg_search(): dialog with callid='aimoskeqqzmpizv(a)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]