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
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@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> 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
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d4736...
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@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@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
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:
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d4736...
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@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 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@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@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@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
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@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> 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:
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d4736...
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@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 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@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@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@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
-- 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@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@gmail.com <mailto:miconda@gmail.com>>: Hello, the patch from next commit: https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d47366abf42c9 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@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@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@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
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@gmail.com:
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@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> 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>:
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d4736...
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@gmail.com 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=< 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@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
-- Cumprimentos José Seabra
-- 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 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@itcenter.com.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@gmail.com:
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@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> 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.commiconda@gmail.com>:
Hello,
the patch from next commit:
https://github.com/kamailio/kamailio/commit/551a562eb799c903cda09599ff5d4736...
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@gmail.com 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=< 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.6sip: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.6sip: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@lists.sip-router.org http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
-- Cumprimentos José Seabra
-- Cumprimentos José Seabra
-- Daniel-Constantin Mierlahttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda Book: SIP Routing With Kamailio - http://www.asipto.com
-- José SeabraResearch | DevelopmentPhone: +351 256370980Email: jose.seabra@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
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
Ok, my bad.
Thank you. BR José Seabra
2015-10-12 16:47 GMT+01:00 Daniel-Constantin Mierla miconda@gmail.com:
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