28 May
2018
28 May
'18
6:01 a.m.
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(a)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(a)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(a)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.