[sr-dev] [tracker] Comment added: zombie dialogs in state 5
sip-router
bugtracker at sip-router.org
Fri Sep 19 23:58:57 CEST 2014
THIS IS AN AUTOMATED MESSAGE, DO NOT REPLY.
The following task has a new comment added:
FS#424 - zombie dialogs in state 5
User who did this - Ovidiu Sas (osas)
----------
<code>
$ git diff
diff --git a/modules/dialog/dlg_handlers.c b/modules/dialog/dlg_handlers.c
index 16c711f..11559ea 100644
--- a/modules/dialog/dlg_handlers.c
+++ b/modules/dialog/dlg_handlers.c
@@ -713,11 +713,16 @@ void dlg_onreq(struct cell* t, int type, struct tmcb_params *param)
sip_msg_t *req = param->req;
dlg_cell_t *dlg = NULL;
+ LM_NOTICE("MICONDA: method_value=[%d]\n", req->first_line.u.request.method_value);
+
if(req->first_line.u.request.method_value == METHOD_BYE) {
_dlg_ctx.t = 1;
+ LM_NOTICE("MICONDA: method is METHOD_BYE\n");
return;
}
+ LM_NOTICE("MICONDA: method is NOT METHOD_BYE\n");
+
if(req->first_line.u.request.method_value != METHOD_INVITE)
return;
diff --git a/modules/dialog/dlg_hash.c b/modules/dialog/dlg_hash.c
index 937f006..27d25e6 100644
--- a/modules/dialog/dlg_hash.c
+++ b/modules/dialog/dlg_hash.c
@@ -677,6 +677,7 @@ dlg_cell_t* dlg_get_by_iuid(dlg_iuid_t *diuid)
{
if(diuid==NULL)
return NULL;
+ LM_NOTICE("MICONDA: diuid=[%p] diuid->h_entry=[%d] diuid->h_id=[%d]\n", diuid, diuid->h_entry, diuid->h_id);
if(diuid->h_id==0)
return NULL;
/* dlg ref counter is increased by next line */
diff --git a/modules/dialog/dlg_var.c b/modules/dialog/dlg_var.c
index c61cb7f..369e5d6 100644
--- a/modules/dialog/dlg_var.c
+++ b/modules/dialog/dlg_var.c
@@ -44,9 +44,14 @@ int dlg_cfg_cb(sip_msg_t *msg, unsigned int flags, void *cbp)
dlg_cell_t *dlg;
if(flags&POST_SCRIPT_CB) {
dlg = dlg_get_ctx_dialog();
+ LM_NOTICE("MICONDA: dlg=[%p]\n", dlg);
if(dlg!=NULL) {
+ LM_NOTICE("MICONDA: _dlg_ctx.t=[%d] dlg->state=[%d] _dlg_ctx.expect_t=[%d]\n",
+ _dlg_ctx.t, dlg->state, _dlg_ctx.expect_t);
if(_dlg_ctx.t==0 && (dlg->state==DLG_STATE_UNCONFIRMED
|| _dlg_ctx.expect_t==1)) {
+ LM_NOTICE("MICONDA: _dlg_ctx.cpid=[%d] _dlg_ctx.cpid=[%d] my_pid=[%d]\n",
+ _dlg_ctx.cpid, _dlg_ctx.cpid, my_pid());
if(_dlg_ctx.cpid!=0 && _dlg_ctx.cpid==my_pid()) {
/* release to destroy dialog if created by this process
* and request was not forwarded */
</code>
<code>
Sep 19 17:56:19 centos k[26594]: INFO: <script>: [2] BYE sip:4165552222 at 192.168.2.97:5064 From:sip:1234 at 192.168.2.62,tag=as3bbf6251 To:sip:4165552222 at 192.168.2.62,tag=dcb1d5e18ed8d93eo4 Call-ID:c69950dd-39713a72 at 192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060>
Sep 19 17:56:19 centos k[26594]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0]
Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is 'dfb.8302' (len=8)
Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 3
Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069
Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [3069:8248]
Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:1067]: next_state_dlg(): dialog 0x7f97a94ca070 changed from state 4 to state 5, due event 7 (ref 3)
Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_hash.c:855]: dlg_ref(): ref dlg 0x7f97a94ca070 with 1 -> 4
Sep 19 17:56:19 centos k[26594]: DEBUG: dialog [dlg_handlers.c:1557]: dlg_run_event_route(): executing event_route 1 on state 5
Sep 19 17:56:19 centos k[26594]: INFO: <script>: [2] entering event_route[dialog:end]
Sep 19 17:56:19 centos k[26592]: INFO: <script>: [3] BYE sip:4165552222 at 192.168.2.97:5064 From:sip:1234 at 192.168.2.62,tag=as3bbf6251 To:sip:4165552222 at 192.168.2.62,tag=dcb1d5e18ed8d93eo4 Call-ID:c69950dd-39713a72 at 192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060>
Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0]
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_handlers.c:1168]: dlg_onroute(): route param is 'dfb.8302' (len=8)
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 5
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [3069:8248]
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:1067]: next_state_dlg(): dialog 0x7f97a94ca070 changed from state 5 to state 5, due event 7 (ref 5)
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 4
Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=3 , global msg id=2 , T on entrance=0xffffffffffffffff
Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=60109, isACK=0
Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failed
Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7f97a94ca320, callback type 1, id 0 entered
Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7f97a94ca320, callback type 1, id 0 entered
Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_handlers.c:716]: dlg_onreq(): MICONDA: method_value=[8]
Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_handlers.c:720]: dlg_onreq(): MICONDA: method is METHOD_BYE
Sep 19 17:56:19 centos k[26592]: DEBUG: tm [t_funcs.c:394]: t_relay_to(): SER: new transaction fwd'ed
Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[3069] diuid->h_id=[8248]
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 5
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069
Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[0x7f97a94ca070]
Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_var.c:50]: dlg_cfg_cb(): MICONDA: _dlg_ctx.t=[1] dlg->state=[5] _dlg_ctx.expect_t=[0]
Sep 19 17:56:19 centos k[26592]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 4
Sep 19 17:56:19 centos k[26592]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0]
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=2 T start=(nil)
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 60109 label 0 branch 0
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f97a94ca320)!
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7f97a94ca320, callback type 2, id 0 entered
Sep 19 17:56:19 centos k[26590]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a94ccd68] diuid->h_entry=[3069] diuid->h_id=[8248]
Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f97a94ca070 with 1 -> 5
Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=8248 found on entry 3069
Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_profile.c:511]: set_current_dialog(): setting current dialog [3069:8248]
Sep 19 17:56:19 centos k[26590]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 4
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=3 global id=3 T end=0x7f97a94ca320
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:2210]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
Sep 19 17:56:19 centos k[26590]: INFO: <script>: [3] entering onreply_route[BYE] for 200 OK Call-ID:c69950dd-39713a72 at 192.168.2.97 Cseq:102
Sep 19 17:56:19 centos k[26590]: INFO: <script>: [3] exiting onreply_route[BYE] for 200 OK Call-ID:c69950dd-39713a72 at 192.168.2.97 Cseq:102
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0
Sep 19 17:56:19 centos k[26590]: DEBUG: tm [t_reply.c:1663]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 19 17:56:20 centos k[26594]: INFO: <script>: [2] exiting event_route[dialog:end]
Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 3
Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:855]: dlg_ref(): ref dlg 0x7f97a94ca070 with 1 -> 4
Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_handlers.c:1290]: dlg_onroute(): BYE successfully processed
Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 2 -> 2
Sep 19 17:56:20 centos k[26594]: DEBUG: dialog [dlg_hash.c:873]: dlg_unref(): unref dlg 0x7f97a94ca070 with 1 -> 1
Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=2 , global msg id=1 , T on entrance=0xffffffffffffffff
Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=60109, isACK=0
Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:470]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=62033d3c
Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_lookup.c:726]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f97a94ca320)
Sep 19 17:56:20 centos k[26594]: DEBUG: tm [t_reply.c:1623]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f97a428a340: SIP/2.0 2..., shmem=0x7f97a94cda68: SIP/2.0 2
Sep 19 17:56:20 centos k[26594]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0]
Sep 19 17:56:20 centos k[26594]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[(nil)]
Sep 19 17:56:20 centos k[26594]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0]
Sep 19 17:56:24 centos k[26597]: DEBUG: dialog [dlg_handlers.c:324]: dlg_iuid_sfree(): freeing dlg iuid [3069:8248] (0x7f97a94ccd68)
Sep 19 17:56:26 centos k[26591]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0]
Sep 19 17:56:26 centos k[26591]: NOTICE: dialog [dlg_var.c:47]: dlg_cfg_cb(): MICONDA: dlg=[(nil)]
Sep 19 17:56:26 centos k[26591]: NOTICE: dialog [dlg_hash.c:680]: dlg_get_by_iuid(): MICONDA: diuid=[0x7f97a25c6c20] diuid->h_entry=[0] diuid->h_id=[0]
</code>
----------
More information can be found at the following URL:
http://sip-router.org/tracker/index.php?do=details&task_id=424#comment1642
You are receiving this message because you have requested it from the Flyspray bugtracking system. If you did not expect this message or don't want to receive mails in future, you can change your notification settings at the URL shown above.
More information about the sr-dev
mailing list