24 Sep
2015
24 Sep
'15
4:24 p.m.
Hello there,
I'm using dialog module to send keep alive options to callee and caller in
order to detect if dialog still up, but when kamailio generates the OPTION
and parse it, gives the following errors:
[root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog [dlg_hash.c:614]:
dlg_lookup(): ref dlg 0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup(): dialog id=2210 found
on entry 2350
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to caller
37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<
sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [dns_cache.c:3293]: dns_srv_sip_resolve():
(62.28.197.25, 0, 0), ip, ret=0
37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 14692
37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <
sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
*37(1587) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field(): ERROR:
get_hdr_field: bad header*
37(1587) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
*37(1587) INFO: <core> [parser/msg_parser.c:338]: parse_headers(): ERROR:
bad header field [VC*
*]*
37(1587) DEBUG: topoh [topoh_mod.c:232]: th_prepare_msg(): parsing headers
failed37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <
sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=e34d43e164ad4b7392dc9c6e1058a983
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking cseq
updates
37(1587) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7f89134c1d40 with 1 -> 4
37(1587) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='13208317017c42128fbf00e1685ee1b3' found on entry 2350, dir=2
37(1587) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request not
going downstream (2)
37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_req_within.c:452]: dlg_send_ka(): keep-alive
sent to caller
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to callee
I'm using the following dialog configuration:
modparam("dialog","db_url", DBURL)
modparam("dialog","db_mode", 0)
modparam("dialog","enable_stats", 1)
modparam("dialog","dlg_flag", DLG_FLAG)
modparam("dialog","profiles_with_value","concurrentcalls")
modparam("dialog", "detect_spirals", 1)
modparam("dialog","dlg_match_mode", 2)
modparam("dialog", "initial_cbs_inscript", 0)
modparam("dialog", "default_timeout", 3600)
modparam("dialog", "send_bye", 1)
modparam("dialog", "timeout_avp", "$avp(s:timeout_dlg)")
modparam("dialog", "track_cseq_updates", 1)
modparam("dialog", "ka_timer", 10)
modparam("dialog", "ka_interval", 30)
I'm also using topoh module.
Anyone can help me to identify if I'm doing the proper configuration?
Thank you for your support
Best Regards
José Seabra
24 Sep
24 Sep
8:47 p.m.
New subject: Dialog keep alive options are with error parsing headers
Adding more information to my issue, these errors only happens when I'm
using topoh module, seems that dialog keep alive options is not working
with topoh module, maybe its something related with topoh callid encription.
Best regards
José Seabra
2015-09-24 15:24 GMT+01:00 José Seabra <joseseabra4(a)gmail.com>om>:
Hello there,
I'm using dialog module to send keep alive options to callee and caller in
order to detect if dialog still up, but when kamailio generates the OPTION
and parse it, gives the following errors:
[root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog
[dlg_hash.c:614]: dlg_lookup(): ref dlg 0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup(): dialog id=2210
found on entry 2350
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to caller
37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<
sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [dns_cache.c:3293]: dns_srv_sip_resolve():
(62.28.197.25, 0, 0), ip, ret=0
37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 14692
37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <
sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
*37(1587) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field(): ERROR:
get_hdr_field: bad header*
37(1587) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
*37(1587) INFO: <core> [parser/msg_parser.c:338]: parse_headers(): ERROR:
bad header field [VC*
*]*
37(1587) DEBUG: topoh [topoh_mod.c:232]: th_prepare_msg(): parsing headers
failed37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <
sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=e34d43e164ad4b7392dc9c6e1058a983
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking cseq
updates
37(1587) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7f89134c1d40 with 1 -> 4
37(1587) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='13208317017c42128fbf00e1685ee1b3' found on entry 2350, dir=2
37(1587) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request not
going downstream (2)
37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_req_within.c:452]: dlg_send_ka(): keep-alive
sent to caller
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to callee
I'm using the following dialog configuration:
modparam("dialog","db_url", DBURL)
modparam("dialog","db_mode", 0)
modparam("dialog","enable_stats", 1)
modparam("dialog","dlg_flag", DLG_FLAG)
modparam("dialog","profiles_with_value","concurrentcalls")
modparam("dialog", "detect_spirals", 1)
modparam("dialog","dlg_match_mode", 2)
modparam("dialog", "initial_cbs_inscript", 0)
modparam("dialog", "default_timeout", 3600)
modparam("dialog", "send_bye", 1)
modparam("dialog", "timeout_avp", "$avp(s:timeout_dlg)")
modparam("dialog", "track_cseq_updates", 1)
modparam("dialog", "ka_timer", 10)
modparam("dialog", "ka_interval", 30)
I'm also using topoh module.
Anyone can help me to identify if I'm doing the proper configuration?
Thank you for your support
Best Regards
José Seabra
--
Cumprimentos
José Seabra
9:49 p.m.
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d473…
and compile in your version to see the full message content.
Cheers,
Daniel
On 24/09/15 20:47, José Seabra wrote:
Adding more information to my issue, these errors only
happens when
I'm using topoh module, seems that dialog keep alive options is not
working with topoh module, maybe its something related with topoh
callid encription.
Best regards
José Seabra
2015-09-24 15:24 GMT+01:00 José Seabra <joseseabra4(a)gmail.com
<mailto:joseseabra4@gmail.com>>:
Hello there,
I'm using dialog module to send keep alive options to callee and
caller in order to detect if dialog still up, but when kamailio
generates the OPTION and parse it, gives the following errors:
[root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog
[dlg_hash.c:614]: dlg_lookup(): ref dlg 0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup(): dialog
id=2210 found on entry 2350
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka():
sending OPTIONS to caller
37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac:
next_hop=<sip:unknown@62.28.197.25:5060
<http://sip:unknown@62.28.197.25:5060>>
37(1587) DEBUG: <core> [dns_cache.c:3293]: dns_srv_sip_resolve():
(62.28.197.25, 0, 0), ip, ret=0
37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 14692
37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:unknown@62.28.197.25:5060
<http://sip:unknown@62.28.197.25:5060>>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end
of header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6
<mailto:sip%3Aunknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@62.28.197.6
<mailto:sip%3Aunknown@62.28.197.6>>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
*37(1587) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field():
ERROR: get_hdr_field: bad header*
37(1587) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
*37(1587) INFO: <core> [parser/msg_parser.c:338]: parse_headers():
ERROR: bad header field [VC*
*
*
*]*
37(1587) DEBUG: topoh [topoh_mod.c:232]: th_prepare_msg(): parsing
headers failed37(1587) DEBUG: <core> [parser/msg_parser.c:606]:
parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:unknown@62.28.197.25:5060
<http://sip:unknown@62.28.197.25:5060>>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end
of header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6
<mailto:sip%3Aunknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@62.28.197.6
<mailto:sip%3Aunknown@62.28.197.6>>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=e34d43e164ad4b7392dc9c6e1058a983
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent():
traking cseq updates
37(1587) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref
dlg 0x7f89134c1d40 with 1 -> 4
37(1587) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg():
dialog callid='13208317017c42128fbf00e1685ee1b3' found on entry
2350, dir=2
37(1587) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent():
request not going downstream (2)
37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_req_within.c:452]: dlg_send_ka():
keep-alive sent to caller
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka():
sending OPTIONS to callee
I'm using the following dialog configuration:
modparam("dialog","db_url", DBURL)
modparam("dialog","db_mode", 0)
modparam("dialog","enable_stats", 1)
modparam("dialog","dlg_flag", DLG_FLAG)
modparam("dialog","profiles_with_value","concurrentcalls")
modparam("dialog", "detect_spirals", 1)
modparam("dialog","dlg_match_mode", 2)
modparam("dialog", "initial_cbs_inscript", 0)
modparam("dialog", "default_timeout", 3600)
modparam("dialog", "send_bye", 1)
modparam("dialog", "timeout_avp",
"$avp(s:timeout_dlg)")
modparam("dialog", "track_cseq_updates", 1)
modparam("dialog", "ka_timer", 10)
modparam("dialog", "ka_interval", 30)
I'm also using topoh module.
Anyone can help me to identify if I'm doing the proper configuration?
Thank you for your support
Best Regards
José Seabra
--
Cumprimentos
José Seabra
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Daniel-Constantin Mierla
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Kamailio Advanced Training, Sep 28-30, 2015, in Berlin - http://asipto.com/u/kat
25 Sep
25 Sep
10:37 a.m.
New subject: Dialog keep alive options are with error parsing headers
Hello Daniel,
Once again thank you for your support.
Please check below the kamailio logs with your patch:
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking cseq
updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request not
going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is [xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking cseq
updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=1
34(22654) DEBUG: dialog [dlg_cseq.c:241]: dlg_cseq_msg_sent(): no
transaction for request
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is [xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking cseq
updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request not
going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is [dlh]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking cseq
updates
34(22654) DEBUG: dialog [dlg_hash.c:680]: internal_get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_hash.c:711]: get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_handlers.c:1085]: dlg_lookup_msg_dialog(): dlg
with callid '!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' not found
34(22654) DEBUG: dialog [dlg_cseq.c:227]: dlg_cseq_msg_sent(): no dialog
for this request
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param(): Found
param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field(): ERROR:
get_hdr_field: bad header
34(22654) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
34(22654) INFO: <core> [parser/msg_parser.c:338]: parse_headers(): ERROR:
bad header field [Voicis
]
34(22654) DEBUG: topoh [topoh_mod.c:233]: th_prepare_msg(): parsing headers
failed [[OPTIONS sip:unknown@XX.XX.XX.25:5060 SIP/2.0
Via: SIP/2.0/UDP
XX.XX.XX.6;branch=z9hG4bKa135.f3b51855000000000000000000000000.0
To: <sip:unknown@XX.XX.XX.6>;tag=ee01f902aad54d95a6ebe96869cb8c99
From: <sip:0035912832114@XX.XX.XX.6>;tag=af3be7f0ca0e408fac220f5febc9e509
CSeq: 0 OPTIONS
Call-ID: 3178f921d05c4a05a03b77ffecf17e96
Max-Forwards: 70
Content-Length: 0
Voicis
]]
Best Regads
José Seabra
2015-09-24 20:49 GMT+01:00 Daniel-Constantin Mierla <miconda(a)gmail.com>om>:
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d473…
and compile in your version to see the full message content.
Cheers,
Daniel
On 24/09/15 20:47, José Seabra wrote:
Adding more information to my issue, these errors only happens when I'm
using topoh module, seems that dialog keep alive options is not working
with topoh module, maybe its something related with topoh callid
encription.
Best regards
José Seabra
2015-09-24 15:24 GMT+01:00 José Seabra <joseseabra4(a)gmail.com>om>:
--
Cumprimentos
José Seabra
Hello there,
I'm using dialog module to send keep alive options to callee and caller
in order to detect if dialog still up, but when kamailio generates the
OPTION and parse it, gives the following errors:
[root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog
[dlg_hash.c:614]: dlg_lookup(): ref dlg 0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup(): dialog id=2210
found on entry 2350
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to caller
37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac:
next_hop=<sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [dns_cache.c:3293]: dns_srv_sip_resolve():
(62.28.197.25, 0, 0), ip, ret=0
37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 14692
37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <
<http://sip:unknown@62.28.197.25:5060>sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[ <sip%3Aunknown(a)62.28.197.6>
sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
*37(1587) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field(): ERROR:
get_hdr_field: bad header*
37(1587) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
*37(1587) INFO: <core> [parser/msg_parser.c:338]: parse_headers(): ERROR:
bad header field [VC*
*]*
37(1587) DEBUG: topoh [topoh_mod.c:232]: th_prepare_msg(): parsing
headers failed37(1587) DEBUG: <core> [parser/msg_parser.c:606]:
parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri: <
<http://sip:unknown@62.28.197.25:5060>sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[ <sip%3Aunknown(a)62.28.197.6>
sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=e34d43e164ad4b7392dc9c6e1058a983
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
37(1587) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
37(1587) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7f89134c1d40 with 1 -> 4
37(1587) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='13208317017c42128fbf00e1685ee1b3' found on entry 2350, dir=2
37(1587) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request not
going downstream (2)
37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_req_within.c:452]: dlg_send_ka(): keep-alive
sent to caller
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to callee
I'm using the following dialog configuration:
modparam("dialog","db_url", DBURL)
modparam("dialog","db_mode", 0)
modparam("dialog","enable_stats", 1)
modparam("dialog","dlg_flag", DLG_FLAG)
modparam("dialog","profiles_with_value","concurrentcalls")
modparam("dialog", "detect_spirals", 1)
modparam("dialog","dlg_match_mode", 2)
modparam("dialog", "initial_cbs_inscript", 0)
modparam("dialog", "default_timeout", 3600)
modparam("dialog", "send_bye", 1)
modparam("dialog", "timeout_avp", "$avp(s:timeout_dlg)")
modparam("dialog", "track_cseq_updates", 1)
modparam("dialog", "ka_timer", 10)
modparam("dialog", "ka_interval", 30)
I'm also using topoh module.
Anyone can help me to identify if I'm doing the proper configuration?
Thank you for your support
Best Regards
José Seabra
--
Cumprimentos
José Seabra
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing
listsr-users@lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Daniel-Constantin Mierlahttp://twitter.com/#!/miconda -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Kamailio Advanced Training, Sep 28-30, 2015, in Berlin - http://asipto.com/u/kat
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
7 Oct
7 Oct
6:23 p.m.
New subject: Dialog keep alive options are with error parsing headers
Hello Daniel,
Do you have any suggestion how to resolve this issue?
Best Regards
José Seabra
2015-09-25 9:37 GMT+01:00 José Seabra <joseseabra4(a)gmail.com>om>:
Hello Daniel,
Once again thank you for your support.
Please check below the kamailio logs with your patch:
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request not
going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=1
34(22654) DEBUG: dialog [dlg_cseq.c:241]: dlg_cseq_msg_sent(): no
transaction for request
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request not
going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[dlh]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:680]: internal_get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_hash.c:711]: get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_handlers.c:1085]: dlg_lookup_msg_dialog():
dlg with callid '!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' not found
34(22654) DEBUG: dialog [dlg_cseq.c:227]: dlg_cseq_msg_sent(): no dialog
for this request
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]: parse_addr_spec():
end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field(): DEBUG:
get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field(): DEBUG:
to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field(): DEBUG:
get_hdr_body : content_length=0
34(22654) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field(): ERROR:
get_hdr_field: bad header
34(22654) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
34(22654) INFO: <core> [parser/msg_parser.c:338]: parse_headers(): ERROR:
bad header field [Voicis
]
34(22654) DEBUG: topoh [topoh_mod.c:233]: th_prepare_msg(): parsing
headers failed [[OPTIONS sip:unknown@XX.XX.XX.25:5060 SIP/2.0
Via: SIP/2.0/UDP
XX.XX.XX.6;branch=z9hG4bKa135.f3b51855000000000000000000000000.0
To: <sip:unknown@XX.XX.XX.6>;tag=ee01f902aad54d95a6ebe96869cb8c99
From: <sip:0035912832114@XX.XX.XX.6>;tag=af3be7f0ca0e408fac220f5febc9e509
CSeq: 0 OPTIONS
Call-ID: 3178f921d05c4a05a03b77ffecf17e96
Max-Forwards: 70
Content-Length: 0
Voicis
]]
Best Regads
José Seabra
2015-09-24 20:49 GMT+01:00 Daniel-Constantin Mierla <miconda(a)gmail.com>om>:
--
Cumprimentos
José Seabra
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d473…
and compile in your version to see the full message content.
Cheers,
Daniel
On 24/09/15 20:47, José Seabra wrote:
Adding more information to my issue, these errors only happens when I'm
using topoh module, seems that dialog keep alive options is not working
with topoh module, maybe its something related with topoh callid
encription.
Best regards
José Seabra
2015-09-24 15:24 GMT+01:00 José Seabra <joseseabra4(a)gmail.com>om>:
--
Cumprimentos
José Seabra
Hello there,
I'm using dialog module to send keep alive options to callee and caller
in order to detect if dialog still up, but when kamailio generates the
OPTION and parse it, gives the following errors:
[root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog
[dlg_hash.c:614]: dlg_lookup(): ref dlg 0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup(): dialog id=2210
found on entry 2350
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to caller
37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac:
next_hop=<sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [dns_cache.c:3293]: dns_srv_sip_resolve():
(62.28.197.25, 0, 0), ip, ret=0
37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 14692
37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
< <http://sip:unknown@62.28.197.25:5060>sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[ <sip%3Aunknown(a)62.28.197.6>
sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
*37(1587) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field():
ERROR: get_hdr_field: bad header*
37(1587) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
*37(1587) INFO: <core> [parser/msg_parser.c:338]: parse_headers():
ERROR: bad header field [VC*
*]*
37(1587) DEBUG: topoh [topoh_mod.c:232]: th_prepare_msg(): parsing
headers failed37(1587) DEBUG: <core> [parser/msg_parser.c:606]:
parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
< <http://sip:unknown@62.28.197.25:5060>sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[ <sip%3Aunknown(a)62.28.197.6>
sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=e34d43e164ad4b7392dc9c6e1058a983
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
37(1587) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7f89134c1d40 with 1 -> 4
37(1587) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='13208317017c42128fbf00e1685ee1b3' found on entry 2350, dir=2
37(1587) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request
not going downstream (2)
37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_req_within.c:452]: dlg_send_ka(): keep-alive
sent to caller
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to callee
I'm using the following dialog configuration:
modparam("dialog","db_url", DBURL)
modparam("dialog","db_mode", 0)
modparam("dialog","enable_stats", 1)
modparam("dialog","dlg_flag", DLG_FLAG)
modparam("dialog","profiles_with_value","concurrentcalls")
modparam("dialog", "detect_spirals", 1)
modparam("dialog","dlg_match_mode", 2)
modparam("dialog", "initial_cbs_inscript", 0)
modparam("dialog", "default_timeout", 3600)
modparam("dialog", "send_bye", 1)
modparam("dialog", "timeout_avp", "$avp(s:timeout_dlg)")
modparam("dialog", "track_cseq_updates", 1)
modparam("dialog", "ka_timer", 10)
modparam("dialog", "ka_interval", 30)
I'm also using topoh module.
Anyone can help me to identify if I'm doing the proper configuration?
Thank you for your support
Best Regards
José Seabra
--
Cumprimentos
José Seabra
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing
listsr-users@lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Daniel-Constantin Mierlahttp://twitter.com/#!/miconda -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Kamailio Advanced Training, Sep 28-30, 2015, in Berlin - http://asipto.com/u/kat
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
12 Oct
12 Oct
5:28 p.m.
Hello,
do you set extra headers parameter?
http://kamailio.org/docs/modules/stable/modules/dialog.html#idp83584
There is a Voicis at the end of the OPTIONS headers, which makes the
request invalid -- we need to track how it got there...
Cheers,
Daniel
On 07/10/15 18:23, José Seabra wrote:
Hello Daniel,
Do you have any suggestion how to resolve this issue?
Best Regards
José Seabra
2015-09-25 9:37 GMT+01:00 José Seabra <joseseabra4(a)gmail.com
<mailto:joseseabra4@gmail.com>>:
Hello Daniel,
Once again thank you for your support.
Please check below the kamailio logs with your patch:
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [64];
uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent():
traking cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref
dlg 0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg():
dialog callid='3178f921d05c4a05a03b77ffecf17e96' found on entry
740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent():
request not going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:10.92.30.20:5060 <http://10.92.30.20:5060>>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [70];
uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]:
get_hdr_field(): DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field():
found end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no
second via in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the
COOKIE is [xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:10.92.30.20:5060 <http://10.92.30.20:5060>>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [70];
uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent():
traking cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref
dlg 0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg():
dialog callid='3178f921d05c4a05a03b77ffecf17e96' found on entry
740, dir=1
34(22654) DEBUG: dialog [dlg_cseq.c:241]: dlg_cseq_msg_sent(): no
transaction for request
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [64];
uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]:
get_hdr_field(): DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field():
found end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no
second via in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the
COOKIE is [xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [64];
uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent():
traking cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref
dlg 0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg():
dialog callid='3178f921d05c4a05a03b77ffecf17e96' found on entry
740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent():
request not going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:10.92.30.20:5060 <http://10.92.30.20:5060>>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [70];
uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]:
get_hdr_field(): DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field():
found end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no
second via in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the
COOKIE is [dlh]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:10.92.30.20:5060 <http://10.92.30.20:5060>>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [70];
uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent():
traking cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:680]: internal_get_dlg(): no
dialog callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_hash.c:711]: get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_handlers.c:1085]:
dlg_lookup_msg_dialog(): dlg with callid
'!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' not found
34(22654) DEBUG: dialog [dlg_cseq.c:227]: dlg_cseq_msg_sent(): no
dialog for this request
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg():
SIP Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
method: <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():
uri: <sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via():
end of header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [64];
uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]:
get_hdr_field(): DEBUG: get_hdr_body : content_length=0
34(22654) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field():
ERROR: get_hdr_field: bad header
34(22654) DEBUG: <core> [parser/msg_parser.c:280]:
get_hdr_field(): get_hdr_field: error exit
34(22654) INFO: <core> [parser/msg_parser.c:338]: parse_headers():
ERROR: bad header field [Voicis
]
34(22654) DEBUG: topoh [topoh_mod.c:233]: th_prepare_msg():
parsing headers failed [[OPTIONS sip:unknown@XX.XX.XX.25:5060 SIP/2.0
Via: SIP/2.0/UDP
XX.XX.XX.6;branch=z9hG4bKa135.f3b51855000000000000000000000000.0
To: <sip:unknown@XX.XX.XX.6>;tag=ee01f902aad54d95a6ebe96869cb8c99
From:
<sip:0035912832114@XX.XX.XX.6>;tag=af3be7f0ca0e408fac220f5febc9e509
CSeq: 0 OPTIONS
Call-ID: 3178f921d05c4a05a03b77ffecf17e96
Max-Forwards: 70
Content-Length: 0
Voicis
]]
Best Regads
José Seabra
2015-09-24 20:49 GMT+01:00 Daniel-Constantin Mierla
<miconda(a)gmail.com <mailto:miconda@gmail.com>>:
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d473…
and compile in your version to see the full message content.
Cheers,
Daniel
On 24/09/15 20:47, José Seabra wrote:
--
Daniel-Constantin Mierla
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Adding more information to my issue,
these errors only
happens when I'm using topoh module, seems that dialog keep
alive options is not working with topoh module, maybe its
something related with topoh callid encription.
Best regards
José Seabra
2015-09-24 15:24 GMT+01:00 José Seabra <joseseabra4(a)gmail.com
<mailto:joseseabra4@gmail.com>>:
Hello there,
I'm using dialog module to send keep alive options to
callee and caller in order to detect if dialog still up,
but when kamailio generates the OPTION and parse it,
gives the following errors:
[root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog
[dlg_hash.c:614]: dlg_lookup(): ref dlg 0x7f89134c1d40
with 1 -> 3
37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup():
dialog id=2210 found on entry 2350
37(1587) DEBUG: dialog [dlg_req_within.c:427]:
dlg_send_ka(): sending OPTIONS to caller
37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare():
DEBUG:tm:t_uac: next_hop=<sip:unknown@62.28.197.25:5060
<http://sip:unknown@62.28.197.25:5060>>
37(1587) DEBUG: <core> [dns_cache.c:3293]:
dns_srv_sip_resolve(): (62.28.197.25, 0, 0), ip, ret=0
37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG:
dlg2hash: 14692
37(1587) DEBUG: <core> [parser/msg_parser.c:606]:
parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]:
parse_msg(): method: <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]:
parse_msg(): uri: <sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]:
parse_msg(): version: <SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]:
parse_via(): end of header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [64];
uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:unknown@62.28.197.6
<mailto:sip%3Aunknown@62.28.197.6>>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:187]:
get_hdr_field(): DEBUG: get_hdr_body : content_length=0
*37(1587) ERROR: <core> [parser/msg_parser.c:96]:
get_hdr_field(): ERROR: get_hdr_field: bad header*
37(1587) DEBUG: <core> [parser/msg_parser.c:280]:
get_hdr_field(): get_hdr_field: error exit
*37(1587) INFO: <core> [parser/msg_parser.c:338]:
parse_headers(): ERROR: bad header field [VC*
*
*
*]*
37(1587) DEBUG: topoh [topoh_mod.c:232]:
th_prepare_msg(): parsing headers failed37(1587) DEBUG:
<core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]:
parse_msg(): method: <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]:
parse_msg(): uri: <sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]:
parse_msg(): version: <SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]:
parse_via_param(): Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]:
parse_via(): end of header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]:
parse_headers(): parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]:
parse_headers(): parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]:
get_hdr_field(): DEBUG: get_hdr_field: <To> [64];
uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]:
get_hdr_field(): DEBUG: to body [<sip:unknown@62.28.197.6
<mailto:sip%3Aunknown@62.28.197.6>>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]:
get_hdr_field(): get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param:
tag=e34d43e164ad4b7392dc9c6e1058a983
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: dialog [dlg_cseq.c:215]:
dlg_cseq_msg_sent(): traking cseq updates
37(1587) DEBUG: dialog [dlg_hash.c:671]:
internal_get_dlg(): ref dlg 0x7f89134c1d40 with 1 -> 4
37(1587) DEBUG: dialog [dlg_hash.c:674]:
internal_get_dlg(): dialog
callid='13208317017c42128fbf00e1685ee1b3' found on entry
2350, dir=2
37(1587) DEBUG: dialog [dlg_cseq.c:233]:
dlg_cseq_msg_sent(): request not going downstream (2)
37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref():
unref dlg 0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_req_within.c:452]:
dlg_send_ka(): keep-alive sent to caller
37(1587) DEBUG: dialog [dlg_req_within.c:427]:
dlg_send_ka(): sending OPTIONS to callee
I'm using the following dialog configuration:
modparam("dialog","db_url", DBURL)
modparam("dialog","db_mode", 0)
modparam("dialog","enable_stats", 1)
modparam("dialog","dlg_flag", DLG_FLAG)
modparam("dialog","profiles_with_value","concurrentcalls")
modparam("dialog", "detect_spirals", 1)
modparam("dialog","dlg_match_mode", 2)
modparam("dialog", "initial_cbs_inscript", 0)
modparam("dialog", "default_timeout", 3600)
modparam("dialog", "send_bye", 1)
modparam("dialog", "timeout_avp",
"$avp(s:timeout_dlg)")
modparam("dialog", "track_cseq_updates", 1)
modparam("dialog", "ka_timer", 10)
modparam("dialog", "ka_interval", 30)
I'm also using topoh module.
Anyone can help me to identify if I'm doing the proper
configuration?
Thank you for your support
Best Regards
José Seabra
--
Cumprimentos
José Seabra
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
<mailto:sr-users@lists.sip-router.org>
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Daniel-Constantin Mierla
http://twitter.com/#!/miconda <http://twitter.com/#%21/miconda> -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Kamailio Advanced Training, Sep 28-30, 2015, in Berlin - http://asipto.com/u/kat
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users
mailing list
sr-users(a)lists.sip-router.org
<mailto:sr-users@lists.sip-router.org>
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Cumprimentos
José Seabra
--
Cumprimentos
José Seabra 
5:38 p.m.
New subject: Dialog keep alive options are with error parsing headers
Hello Daniel,
Rigth now the only thing that we are adding extra to dialog module is the
following:
#!ifdef WITH_TOPOH
modparam("dialog", "lreq_callee_headers", "TH: dlh\r\n")
#!endif
We are also adding the following configuration:
user_agent_header="Voicis"
Best Regards
José Seabra
2015-10-12 16:28 GMT+01:00 Daniel-Constantin Mierla <miconda(a)gmail.com>om>:
Hello,
do you set extra headers parameter?
http://kamailio.org/docs/modules/stable/modules/dialog.html#idp83584
There is a Voicis at the end of the OPTIONS headers, which makes the
request invalid -- we need to track how it got there...
Cheers,
Daniel
On 07/10/15 18:23, José Seabra wrote:
Hello Daniel,
Do you have any suggestion how to resolve this issue?
Best Regards
José Seabra
2015-09-25 9:37 GMT+01:00 José Seabra <joseseabra4(a)gmail.com>om>:
--
José SeabraResearch | DevelopmentPhone: +351 256370980Email:
jose.seabra(a)itcenter.com.pt <http://www.itcenter.com.pt/>
<http://www.itcenter.com.pt/> <http://www.itcenter.com.pt/>
www.itcenter.com.pt[image: ITCENTER Store]
<http://store.itcenter.com.pt/>[image:
ITCENTER Helpdesk] <http://support.itcenter.com.pt/otrs/customer.pl>[image:
ITCENTER Facebook] <https://www.facebook.com/itcenterpt>[image: ITCENTER
Linkedin] <https://www.linkedin.com/company/itcenter>[image: ITCENTER
Twitter] <https://twitter.com/ITCenter_PT>
Hello Daniel,
Once again thank you for your support.
Please check below the kamailio logs with your patch:
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request
not going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=1
34(22654) DEBUG: dialog [dlg_cseq.c:241]: dlg_cseq_msg_sent(): no
transaction for request
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request
not going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second via
in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[dlh]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:680]: internal_get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_hash.c:711]: get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_handlers.c:1085]: dlg_lookup_msg_dialog():
dlg with callid '!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' not found
34(22654) DEBUG: dialog [dlg_cseq.c:227]: dlg_cseq_msg_sent(): no dialog
for this request
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg(): version:
<SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field(): ERROR:
get_hdr_field: bad header
34(22654) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
34(22654) INFO: <core> [parser/msg_parser.c:338]: parse_headers(): ERROR:
bad header field [Voicis
]
34(22654) DEBUG: topoh [topoh_mod.c:233]: th_prepare_msg(): parsing
headers failed [[OPTIONS sip:unknown@XX.XX.XX.25:5060 SIP/2.0
Via: SIP/2.0/UDP
XX.XX.XX.6;branch=z9hG4bKa135.f3b51855000000000000000000000000.0
To: <sip:unknown@XX.XX.XX.6>;tag=ee01f902aad54d95a6ebe96869cb8c99
From: <sip:0035912832114@XX.XX.XX.6>;tag=af3be7f0ca0e408fac220f5febc9e509
CSeq: 0 OPTIONS
Call-ID: 3178f921d05c4a05a03b77ffecf17e96
Max-Forwards: 70
Content-Length: 0
Voicis
]]
Best Regads
José Seabra
2015-09-24 20:49 GMT+01:00 Daniel-Constantin Mierla < <miconda(a)gmail.com>
miconda(a)gmail.com>gt;:
--
Cumprimentos
José Seabra
--
Daniel-Constantin Mierlahttp://twitter.com/#!/miconda -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d473…
and compile in your version to see the full message content.
Cheers,
Daniel
On 24/09/15 20:47, José Seabra wrote:
Adding more information to my issue, these errors only happens when I'm
using topoh module, seems that dialog keep alive options is not working
with topoh module, maybe its something related with topoh callid
encription.
Best regards
José Seabra
2015-09-24 15:24 GMT+01:00 José Seabra < <joseseabra4(a)gmail.com>
joseseabra4(a)gmail.com>gt;:
--
Cumprimentos
José Seabra
Hello there,
I'm using dialog module to send keep alive options to callee and caller
in order to detect if dialog still up, but when kamailio generates the
OPTION and parse it, gives the following errors:
[root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog
[dlg_hash.c:614]: dlg_lookup(): ref dlg 0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup(): dialog id=2210
found on entry 2350
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to caller
37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac:
next_hop=< <http://sip:unknown@62.28.197.25:5060>
sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [dns_cache.c:3293]: dns_srv_sip_resolve():
(62.28.197.25, 0, 0), ip, ret=0
37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 14692
37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [< <sip%3Aunknown@62.28.197.6>sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
*37(1587) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field():
ERROR: get_hdr_field: bad header*
37(1587) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
*37(1587) INFO: <core> [parser/msg_parser.c:338]: parse_headers():
ERROR: bad header field [VC*
*]*
37(1587) DEBUG: topoh [topoh_mod.c:232]: th_prepare_msg(): parsing
headers failed37(1587) DEBUG: <core> [parser/msg_parser.c:606]:
parse_msg(): SIP Request:
37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@62.28.197.25:5060>
37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [< <sip%3Aunknown@62.28.197.6>sip:unknown@62.28.197.6>]
37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=e34d43e164ad4b7392dc9c6e1058a983
37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
37(1587) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
37(1587) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7f89134c1d40 with 1 -> 4
37(1587) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='13208317017c42128fbf00e1685ee1b3' found on entry 2350, dir=2
37(1587) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request
not going downstream (2)
37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7f89134c1d40 with 1 -> 3
37(1587) DEBUG: dialog [dlg_req_within.c:452]: dlg_send_ka():
keep-alive sent to caller
37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
OPTIONS to callee
I'm using the following dialog configuration:
modparam("dialog","db_url", DBURL)
modparam("dialog","db_mode", 0)
modparam("dialog","enable_stats", 1)
modparam("dialog","dlg_flag", DLG_FLAG)
modparam("dialog","profiles_with_value","concurrentcalls")
modparam("dialog", "detect_spirals", 1)
modparam("dialog","dlg_match_mode", 2)
modparam("dialog", "initial_cbs_inscript", 0)
modparam("dialog", "default_timeout", 3600)
modparam("dialog", "send_bye", 1)
modparam("dialog", "timeout_avp", "$avp(s:timeout_dlg)")
modparam("dialog", "track_cseq_updates", 1)
modparam("dialog", "ka_timer", 10)
modparam("dialog", "ka_interval", 30)
I'm also using topoh module.
Anyone can help me to identify if I'm doing the proper configuration?
Thank you for your support
Best Regards
José Seabra
--
Cumprimentos
José Seabra
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing
listsr-users@lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Daniel-Constantin Mierlahttp://twitter.com/#!/miconda -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Kamailio Advanced Training, Sep 28-30, 2015, in Berlin - http://asipto.com/u/kat
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
5:40 p.m.
New subject: Dialog keep alive options are with error parsing headers
Hello Daniel,
Rigth now the only thing that we are adding extra to dialog module is the
following:
#!ifdef WITH_TOPOH
modparam("dialog", "lreq_callee_headers", "TH: dlh\r\n")
#!endif
We are also adding the following configuration:
user_agent_header="Voicis"
Best Regards
2015-10-12 16:38 GMT+01:00 José seabra <jose.seabra(a)itcenter.com.pt>pt>:
Hello Daniel,
Rigth now the only thing that we are adding extra to dialog module is the
following:
#!ifdef WITH_TOPOH
modparam("dialog", "lreq_callee_headers", "TH: dlh\r\n")
#!endif
We are also adding the following configuration:
user_agent_header="Voicis"
Best Regards
José Seabra
2015-10-12 16:28 GMT+01:00 Daniel-Constantin Mierla <miconda(a)gmail.com>om>:
--
Cumprimentos
José Seabra
Hello,
do you set extra headers parameter?
http://kamailio.org/docs/modules/stable/modules/dialog.html#idp83584
There is a Voicis at the end of the OPTIONS headers, which makes the
request invalid -- we need to track how it got there...
Cheers,
Daniel
On 07/10/15 18:23, José Seabra wrote:
Hello Daniel,
Do you have any suggestion how to resolve this issue?
Best Regards
José Seabra
2015-09-25 9:37 GMT+01:00 José Seabra <joseseabra4(a)gmail.com>om>:
--
José SeabraResearch | DevelopmentPhone: +351 256370980Email:
jose.seabra(a)itcenter.com.pt <http://www.itcenter.com.pt/>
<http://www.itcenter.com.pt/> <http://www.itcenter.com.pt/>
www.itcenter.com.pt[image: ITCENTER Store] <http://store.itcenter.com.pt/>[image:
ITCENTER Helpdesk] <http://support.itcenter.com.pt/otrs/customer.pl>[image:
ITCENTER Facebook] <https://www.facebook.com/itcenterpt>[image: ITCENTER
Linkedin] <https://www.linkedin.com/company/itcenter>[image: ITCENTER
Twitter] <https://twitter.com/ITCenter_PT>
Hello Daniel,
Once again thank you for your support.
Please check below the kamailio logs with your patch:
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request
not going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second
via in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK475c.719b3903000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28937> <OPTIONS>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=1
34(22654) DEBUG: dialog [dlg_cseq.c:241]: dlg_cseq_msg_sent(): no
transaction for request
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second
via in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[xxx]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKb135.7d842b44000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <1> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
0x7fc69f8f0f28 with 1 -> 2
34(22654) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
callid='3178f921d05c4a05a03b77ffecf17e96' found on entry 740, dir=2
34(22654) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request
not going downstream (2)
34(22654) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
0x7fc69f8f0f28 with 1 -> 1
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
end of header
34(22654) DEBUG: topoh [topoh_mod.c:241]: th_prepare_msg(): no second
via in this message
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: topoh [topoh_mod.c:398]: th_msg_sent(): the COOKIE is
[dlh]
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<BYE>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:10.92.30.20:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bK185c.846af8c0000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=af3be7f0ca0e408fac220f5febc9e509
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [70]; uri=[sip:0035912832114@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:0035912832114@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <28939> <BYE>
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
cseq updates
34(22654) DEBUG: dialog [dlg_hash.c:680]: internal_get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_hash.c:711]: get_dlg(): no dialog
callid='!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' found
34(22654) DEBUG: dialog [dlg_handlers.c:1085]: dlg_lookup_msg_dialog():
dlg with callid '!!:NIxmJglQN-vTNDR-Hgx5HPx5N42mH4spsPHpNAreJAl*' not found
34(22654) DEBUG: dialog [dlg_cseq.c:227]: dlg_cseq_msg_sent(): no dialog
for this request
34(22654) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
Request:
34(22654) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): method:
<OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
<sip:unknown@XX.XX.XX.25:5060>
34(22654) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
version: <SIP/2.0>
34(22654) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
Found param type 232, <branch> =
<z9hG4bKa135.f3b51855000000000000000000000000.0>; state=16
34(22654) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
header reached, state=5
34(22654) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
parse_headers: Via found, flags=2
34(22654) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
parse_headers: this is the first via
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:172]:
parse_to_param(): DEBUG: add_param: tag=ee01f902aad54d95a6ebe96869cb8c99
34(22654) DEBUG: <core> [parser/parse_addr_spec.c:894]:
parse_addr_spec(): end of header reached, state=29
34(22654) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@XX.XX.XX.6]
34(22654) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
DEBUG: to body [<sip:unknown@XX.XX.XX.6>]
34(22654) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
34(22654) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
DEBUG: get_hdr_body : content_length=0
34(22654) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field():
ERROR: get_hdr_field: bad header
34(22654) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
get_hdr_field: error exit
34(22654) INFO: <core> [parser/msg_parser.c:338]: parse_headers():
ERROR: bad header field [Voicis
]
34(22654) DEBUG: topoh [topoh_mod.c:233]: th_prepare_msg(): parsing
headers failed [[OPTIONS sip:unknown@XX.XX.XX.25:5060 SIP/2.0
Via: SIP/2.0/UDP
XX.XX.XX.6;branch=z9hG4bKa135.f3b51855000000000000000000000000.0
To: <sip:unknown@XX.XX.XX.6>;tag=ee01f902aad54d95a6ebe96869cb8c99
From: <sip:0035912832114@XX.XX.XX.6>
;tag=af3be7f0ca0e408fac220f5febc9e509
CSeq: 0 OPTIONS
Call-ID: 3178f921d05c4a05a03b77ffecf17e96
Max-Forwards: 70
Content-Length: 0
Voicis
]]
Best Regads
José Seabra
2015-09-24 20:49 GMT+01:00 Daniel-Constantin Mierla <
<miconda@gmail.com>miconda@gmail.com>:
--
Cumprimentos
José Seabra
--
Daniel-Constantin Mierlahttp://twitter.com/#!/miconda -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d473…
and compile in your version to see the full message content.
Cheers,
Daniel
On 24/09/15 20:47, José Seabra wrote:
Adding more information to my issue, these errors only happens when I'm
using topoh module, seems that dialog keep alive options is not working
with topoh module, maybe its something related with topoh callid
encription.
Best regards
José Seabra
2015-09-24 15:24 GMT+01:00 José Seabra < <joseseabra4(a)gmail.com>
joseseabra4(a)gmail.com>gt;:
> Hello there,
>
> I'm using dialog module to send keep alive options to callee and
> caller in order to detect if dialog still up, but when kamailio generates
> the OPTION and parse it, gives the following errors:
>
> [root@c4-compute02-srv1 kamailio]# 37(1587) DEBUG: dialog
> [dlg_hash.c:614]: dlg_lookup(): ref dlg 0x7f89134c1d40 with 1 -> 3
> 37(1587) DEBUG: dialog [dlg_hash.c:616]: dlg_lookup(): dialog id=2210
> found on entry 2350
> 37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
> OPTIONS to caller
> 37(1587) DEBUG: tm [uac.c:249]: t_uac_prepare(): DEBUG:tm:t_uac:
> next_hop=< <http://sip:unknown@62.28.197.25:5060>
> sip:unknown@62.28.197.25:5060>
> 37(1587) DEBUG: <core> [dns_cache.c:3293]: dns_srv_sip_resolve():
> (62.28.197.25, 0, 0), ip, ret=0
> 37(1587) DEBUG: tm [uac.c:150]: dlg2hash(): DEBUG: dlg2hash: 14692
> 37(1587) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP
> Request:
> 37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
> method: <OPTIONS>
> 37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
> <sip:unknown@62.28.197.25:5060>
> 37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
> version: <SIP/2.0>
> 37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
> Found param type 232, <branch> =
> <z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
> 37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
> header reached, state=5
> 37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
> parse_headers: Via found, flags=2
> 37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
> parse_headers: this is the first via
> 37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
> parse_to_param(): DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
> 37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
> parse_addr_spec(): end of header reached, state=29
> 37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
> DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
> 37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
> DEBUG: to body [< <sip%3Aunknown@62.28.197.6>sip:unknown@62.28.197.6>]
> 37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
> get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
> 37(1587) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
> DEBUG: get_hdr_body : content_length=0
> *37(1587) ERROR: <core> [parser/msg_parser.c:96]: get_hdr_field():
> ERROR: get_hdr_field: bad header*
> 37(1587) DEBUG: <core> [parser/msg_parser.c:280]: get_hdr_field():
> get_hdr_field: error exit
> *37(1587) INFO: <core> [parser/msg_parser.c:338]: parse_headers():
> ERROR: bad header field [VC*
>
> *]*
> 37(1587) DEBUG: topoh [topoh_mod.c:232]: th_prepare_msg(): parsing
> headers failed37(1587) DEBUG: <core> [parser/msg_parser.c:606]:
> parse_msg(): SIP Request:
> 37(1587) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():
> method: <OPTIONS>
> 37(1587) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg(): uri:
> <sip:unknown@62.28.197.25:5060>
> 37(1587) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():
> version: <SIP/2.0>
> 37(1587) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
> Found param type 232, <branch> =
> <z9hG4bK4693.2af96e95000000000000000000000000.0>; state=16
> 37(1587) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
> header reached, state=5
> 37(1587) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
> parse_headers: Via found, flags=2
> 37(1587) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
> parse_headers: this is the first via
> 37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
> parse_to_param(): DEBUG: add_param: tag=90d40279ee5745d8851aa989e6fadfc2
> 37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
> parse_addr_spec(): end of header reached, state=29
> 37(1587) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
> DEBUG: get_hdr_field: <To> [64]; uri=[sip:unknown@62.28.197.6]
> 37(1587) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
> DEBUG: to body [< <sip%3Aunknown@62.28.197.6>sip:unknown@62.28.197.6>]
> 37(1587) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
> get_hdr_field: cseq <CSeq>: <0> <OPTIONS>
> 37(1587) DEBUG: <core> [parser/parse_addr_spec.c:172]:
> parse_to_param(): DEBUG: add_param: tag=e34d43e164ad4b7392dc9c6e1058a983
> 37(1587) DEBUG: <core> [parser/parse_addr_spec.c:894]:
> parse_addr_spec(): end of header reached, state=29
> 37(1587) DEBUG: dialog [dlg_cseq.c:215]: dlg_cseq_msg_sent(): traking
> cseq updates
> 37(1587) DEBUG: dialog [dlg_hash.c:671]: internal_get_dlg(): ref dlg
> 0x7f89134c1d40 with 1 -> 4
> 37(1587) DEBUG: dialog [dlg_hash.c:674]: internal_get_dlg(): dialog
> callid='13208317017c42128fbf00e1685ee1b3' found on entry 2350, dir=2
> 37(1587) DEBUG: dialog [dlg_cseq.c:233]: dlg_cseq_msg_sent(): request
> not going downstream (2)
> 37(1587) DEBUG: dialog [dlg_hash.c:846]: dlg_unref(): unref dlg
> 0x7f89134c1d40 with 1 -> 3
> 37(1587) DEBUG: dialog [dlg_req_within.c:452]: dlg_send_ka():
> keep-alive sent to caller
> 37(1587) DEBUG: dialog [dlg_req_within.c:427]: dlg_send_ka(): sending
> OPTIONS to callee
>
> I'm using the following dialog configuration:
>
> modparam("dialog","db_url", DBURL)
> modparam("dialog","db_mode", 0)
> modparam("dialog","enable_stats", 1)
> modparam("dialog","dlg_flag", DLG_FLAG)
>
modparam("dialog","profiles_with_value","concurrentcalls")
> modparam("dialog", "detect_spirals", 1)
> modparam("dialog","dlg_match_mode", 2)
> modparam("dialog", "initial_cbs_inscript", 0)
> modparam("dialog", "default_timeout", 3600)
> modparam("dialog", "send_bye", 1)
> modparam("dialog", "timeout_avp",
"$avp(s:timeout_dlg)")
> modparam("dialog", "track_cseq_updates", 1)
> modparam("dialog", "ka_timer", 10)
> modparam("dialog", "ka_interval", 30)
>
>
> I'm also using topoh module.
>
> Anyone can help me to identify if I'm doing the proper configuration?
>
> Thank you for your support
>
> Best Regards
>
> José Seabra
>
--
Cumprimentos
José Seabra
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing
listsr-users@lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Daniel-Constantin Mierlahttp://twitter.com/#!/miconda -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
Kamailio Advanced Training, Sep 28-30, 2015, in Berlin - http://asipto.com/u/kat
_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users(a)lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
--
Cumprimentos
José Seabra
5:47 p.m.
The value for user agent header is invalid, must be with header name, see:
https://www.kamailio.org/wiki/cookbooks/4.3.x/core#user_agent_header
Cheers,
Daniel
On 12/10/15 17:40, José Seabra wrote:
Hello Daniel,
Rigth now the only thing that we are adding extra to dialog module is
the following:
#!ifdef WITH_TOPOH
modparam("dialog", "lreq_callee_headers", "TH: dlh\r\n")
#!endif
We are also adding the following configuration:
user_agent_header="Voicis"
Best Regards
--
Daniel-Constantin Mierla
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
6 p.m.
New subject: Dialog keep alive options are with error parsing headers
Ok, my bad.
Thank you.
BR
José Seabra
2015-10-12 16:47 GMT+01:00 Daniel-Constantin Mierla <miconda(a)gmail.com>om>:
The value for user agent header is invalid, must be with header name, see:
https://www.kamailio.org/wiki/cookbooks/4.3.x/core#user_agent_header
Cheers,
Daniel
On 12/10/15 17:40, José Seabra wrote:
Hello Daniel,
Rigth now the only thing that we are adding extra to dialog module is the
following:
#!ifdef WITH_TOPOH
modparam("dialog", "lreq_callee_headers", "TH: dlh\r\n")
#!endif
We are also adding the following configuration:
user_agent_header="Voicis"
Best Regards
--
Daniel-Constantin Mierlahttp://twitter.com/#!/miconda -
http://www.linkedin.com/in/miconda
Book: SIP Routing With Kamailio - http://www.asipto.com
--
Cumprimentos
José Seabra
3128
days inactive
3146
days old
9 comments
3 participants
participants (3)
-
Daniel-Constantin Mierla -
José Seabra -
José seabra