[SR-Users] Kamailio 5.1.x - Dialog event route not fired

[Loic Chabert]

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 at sip.testinfratrunk.vox.fr]
 1(7) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to
body [<sip:+33975181257 at 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 at 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 at 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 at 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.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.kamailio.org/pipermail/sr-users/attachments/20180528/24a2e2f7/attachment.html>