[tracker] Comment added: zombie dialogs in state 5 - sr-dev

15 Sep 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)
----------
Using the latest master, if a transaction is created before handling the BYE, then the dialog is properly terminated.
If there's no manual creation of a transaction, then the zombie dialog still exists:
<FIRST BYE HANDLING>
Sep 15 11:48:20 centos k[26769]: INFO: <script>: [4] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as684b0b1a To:sip:4165552222@192.168.2.62,tag=3364246b1869c93eo4 Call-ID:387521af-e5594cca@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060>
Sep 15 11:48:20 centos k[26769]: DEBUG: dialog [dlg_handlers.c:1158]: dlg_onroute(): route param is '3d7.9cc' (len=7)
Sep 15 11:48:20 centos k[26769]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f891503c118 with 1 -> 3
Sep 15 11:48:20 centos k[26769]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=3273 found on entry 2003
Sep 15 11:48:20 centos k[26769]: DEBUG: dialog [dlg_profile.c:514]: set_current_dialog(): setting current dialog [2003:3273]
Sep 15 11:48:20 centos k[26769]: DEBUG: dialog [dlg_hash.c:1066]: next_state_dlg(): dialog 0x7f891503c118 changed from state 4 to state 5, due event 7 (ref 3)
Sep 15 11:48:20 centos k[26769]: DEBUG: dialog [dlg_hash.c:854]: dlg_ref(): ref dlg 0x7f891503c118 with 1 -> 4
Sep 15 11:48:20 centos k[26769]: DEBUG: dialog [dlg_handlers.c:1544]: dlg_run_event_route(): executing event_route 1 on state 5
Sep 15 11:48:20 centos k[26769]: INFO: <script>: [4] entering event_route[dialog:end]
</FIRST BYE HANDLING>
<SECOND BYE HANDLING>
Sep 15 11:48:21 centos k[26768]: INFO: <script>: [5] BYE sip:4165552222@192.168.2.97:5064 From:sip:1234@192.168.2.62,tag=as684b0b1a To:sip:4165552222@192.168.2.62,tag=3364246b1869c93eo4 Call-ID:387521af-e5594cca@192.168.2.97 Contact:<null> <192.168.2.2:5060->udp:192.168.2.62:5060>
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_handlers.c:1158]: dlg_onroute(): route param is '3d7.9cc' (len=7)
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f891503c118 with 1 -> 5
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=3273 found on entry 2003
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_profile.c:514]: set_current_dialog(): setting current dialog [2003:3273]
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:1066]: next_state_dlg(): dialog 0x7f891503c118 changed from state 5 to state 5, due event 7 (ref 5)
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7f891503c118 with 1 -> 4
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=5 , global msg id=3 , T on entrance=0xffffffffffffffff
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=18082, isACK=0
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:485]: matching_3261(): DEBUG: RFC3261 transaction matching failed
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:709]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7f891503c3c8, callback type 1, id 0 entered
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7f891503c3c8, callback type 1, id 0 entered
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_funcs.c:394]: t_relay_to(): SER: new transaction fwd'ed
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f891503c118 with 1 -> 5
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=3273 found on entry 2003
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7f891503c118 with 1 -> 4
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:1072]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=5 T start=0xffffffffffffffff
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:949]: t_reply_matching(): DEBUG: t_reply_matching: hash 18082 label 0 branch 0
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:1004]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7f891503c3c8)!
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7f891503c3c8, callback type 2, id 0 entered
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:654]: dlg_lookup(): ref dlg 0x7f891503c118 with 1 -> 5
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:656]: dlg_lookup(): dialog id=3273 found on entry 2003
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_profile.c:514]: set_current_dialog(): setting current dialog [2003:3273]
Sep 15 11:48:21 centos k[26768]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7f891503c118 with 1 -> 4
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_lookup.c:1141]: t_check_msg(): DEBUG: t_check_msg: msg id=6 global id=6 T end=0x7f891503c3c8
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_reply.c:2210]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_reply.c:1304]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_reply.c:1822]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0 icode=0
Sep 15 11:48:21 centos k[26768]: DEBUG: tm [t_reply.c:1663]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
Sep 15 11:48:21 centos k[26769]: INFO: <script>: [4] exiting event_route[dialog:end]
Sep 15 11:48:21 centos k[26769]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7f891503c118 with 1 -> 3
Sep 15 11:48:21 centos k[26769]: DEBUG: dialog [dlg_hash.c:854]: dlg_ref(): ref dlg 0x7f891503c118 with 1 -> 4
Sep 15 11:48:21 centos k[26769]: DEBUG: dialog [dlg_handlers.c:1280]: dlg_onroute(): BYE successfully processed
Sep 15 11:48:21 centos k[26769]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7f891503c118 with 2 -> 2
Sep 15 11:48:21 centos k[26769]: DEBUG: dialog [dlg_hash.c:872]: dlg_unref(): unref dlg 0x7f891503c118 with 1 -> 1
Sep 15 11:48:21 centos k[26769]: DEBUG: tm [t_lookup.c:1373]: t_newtran(): DEBUG: t_newtran: msg id=4 , global msg id=3 , T on entrance=(nil)
Sep 15 11:48:21 centos k[26769]: DEBUG: tm [t_lookup.c:527]: t_lookup_request(): t_lookup_request: start searching: hash=18082, isACK=0
Sep 15 11:48:21 centos k[26769]: DEBUG: tm [t_lookup.c:470]: matching_3261(): DEBUG: RFC3261 transaction matched, tid=1f8b3c1c
Sep 15 11:48:21 centos k[26769]: DEBUG: tm [t_lookup.c:726]: t_lookup_request(): DEBUG: t_lookup_request: transaction found (T=0x7f891503c3c8)
Sep 15 11:48:21 centos k[26769]: DEBUG: tm [t_reply.c:1623]: t_retransmit_reply(): DEBUG: reply retransmitted. buf=0x7f890fefb840: SIP/2.0 2..., shmem=0x7f891503fb10: SIP/2.0 2
</SECOND BYE HANDLING>
Sep 15 11:48:26 centos k[26772]: DEBUG: dialog [dlg_handlers.c:324]: dlg_iuid_sfree(): freeing dlg iuid [2003:3273] (0x7f891503ee10)
----------
More information can be found at the following URL:
http://sip-router.org/tracker/index.php?do=details&task_id=424#comment16...
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.