[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#comment1…

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.