Hi list,

I'm facing a strange behaviour with dialog module and stop event route.

When a BYE request has been receved by Kamailio, no stop event route has been fired but dialog state go to 5.

My config is following this guide: http://kb.asipto.com/kamailio:presence:k43-blf
Using dialog_manage on RELAY route, as describe on this guide.

If i set more debug information, i can see dialog has been found and dialog status incremented :

 1(7) DEBUG: <core> [core/udp_server.c:491]: udp_rcv_loop(): received on udp socket: (106/100/818) [[BYE sip:172.16.74.201:5060;prxroute=1 SIP/2.0 0D  0A Record-Route: <sip:172.16.74.24;r2=on;lr=on;ftag=1;ng]]
 1(7) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request:
 1(7) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg():  method:  <BYE>
 1(7) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg():  uri:     <sip:172.16.74.201:5060;prxroute=1>
 1(7) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg():  version: <SIP/2.0>
 1(7) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKc2c.939fa953ea648059586a7f684fd56b5a.0>; state=16
 1(7) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5
 1(7) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2
 1(7) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via
 1(7) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, <rport> = <5060>; state=6
 1(7) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK-69-1-10>; state=16
 1(7) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5
 1(7) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=78
 1(7) DEBUG: <core> [core/parser/msg_parser.c:505]: parse_headers(): this is the second via
 1(7) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=f651e13a-0aac-40d5-b877-1c24497ff87c
 1(7) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29
 1(7) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [87]; uri=[sip:+33975181257@sip.testinfratrunk.vox.fr]
 1(7) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:+33975181257@sip.testinfratrunk.vox.fr>]
 1(7) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <3> <BYE>
 1(7) DEBUG: <core> [core/receive.c:232]: receive_msg(): --- received sip message - request - call-id: [1-69@60.0.0.30] - cseq: [3 BYE]
 1(7) DEBUG: <core> [core/receive.c:287]: receive_msg(): preparing to run routing scripts...
 1(7) DEBUG: maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 69
 1(7) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=1
 1(7) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29
 1(7) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=0
 1(7) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header
 1(7) DEBUG: sanity [sanity_mod.c:256]: w_sanity_check(): sanity checks result: 1
 1(7) NOTICE: <script>: {1 3 BYE 1-69@60.0.0.30} Initialisation des variables.
 1(7) DEBUG: tm [t_lookup.c:1018]: t_check_msg(): msg (0x7ffa420727d8) id=2 global id=1 T start=(nil)
 1(7) DEBUG: tm [t_lookup.c:476]: t_lookup_request(): start searching: hash=3116, isACK=0
 1(7) DEBUG: tm [t_lookup.c:435]: matching_3261(): RFC3261 transaction matching failed
 1(7) DEBUG: tm [t_lookup.c:659]: t_lookup_request(): no transaction found
 1(7) DEBUG: tm [t_lookup.c:1087]: t_check_msg(): msg (0x7ffa420727d8) id=2 global id=2 T end=(nil)
 1(7) DEBUG: siputils [checks.c:123]: has_totag(): totag found
 1(7) DEBUG: rr [loose.c:113]: find_first_route(): No Route headers found
 1(7) DEBUG: rr [loose.c:960]: loose_route(): There is no Route HF
 1(7) DEBUG: tm [t_lookup.c:1018]: t_check_msg(): msg (0x7ffa420727d8) id=2 global id=2 T start=(nil)
 1(7) DEBUG: tm [t_lookup.c:1094]: t_check_msg(): T previously sought and not found for msg (0x7ffa420727d8)
 1(7) DEBUG: dialog [dlg_hash.c:768]: internal_get_dlg(): ref dlg 0x7ffa327dd588 with 1 -> 3
 1(7) DEBUG: dialog [dlg_hash.c:770]: internal_get_dlg(): dialog callid='1-69@60.0.0.30' found on entry 3063, dir=1
 1(7) DEBUG: dialog [dlg_profile.c:536]: set_current_dialog(): setting current dialog [3063:9173]
 1(7) DEBUG: dialog [dlg_hash.c:1151]: next_state_dlg(): dialog 0x7ffa327dd588 changed from state 4 to state 5, due event 7 (ref 3)
 1(7) DEBUG: dialog [dlg_hash.c:932]: dlg_ref_helper(): ref op on 0x7ffa327dd588 with 1 from dlg_handlers.c:1389
 1(7) DEBUG: dialog [dlg_hash.c:936]: dlg_ref_helper(): ref dlg 0x7ffa327dd588 with 1 -> 4
 1(7) DEBUG: dialog [dlg_handlers.c:1400]: dlg_onroute(): BYE successfully processed


Do you have any hints concerning this issue ? Is it a bug or a config part is missing ?

Thanks in advance,
Best regards.