Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
Hello,
how do you start kamailio, or better, said what are the command line parameters?
Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com mailto:miconda@gmail.com>:
Hello, can you get the output for kamctl ps and see the description for process with pid 94? I assume you run it in docker, given low pid value shown in the log message... Cheers, Daniel On 03.05.18 10:42, Enrico Bandiera wrote:Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem) The module seems to work but I'm getting these errors on the log: 0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store I get these errors for some time after running kamailio and then they stop appearing. Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to. Any idea? Thanks, Enrico. _______________________________________________ Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org <mailto:sr-users@lists.kamailio.org> https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users>-- Daniel-Constantin Mierla www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com <http://www.kamailioworld.com>
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
how do you start kamailio, or better, said what are the command line parameters? Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error?
The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com mailto:miconda@gmail.com>:
Hello, how do you start kamailio, or better, said what are the command line parameters? Cheers, Daniel On 03.05.18 12:26, Enrico Bandiera wrote:Hi Daniel, is kamcmd ps ok for this? 0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116main process - attendant Thanks, Enrico. 2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com <mailto:miconda@gmail.com>>: Hello, can you get the output for kamctl ps and see the description for process with pid 94? I assume you run it in docker, given low pid value shown in the log message... Cheers, Daniel On 03.05.18 10:42, Enrico Bandiera wrote:Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem) The module seems to work but I'm getting these errors on the log: 0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store I get these errors for some time after running kamailio and then they stop appearing. Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to. Any idea? Thanks, Enrico. _______________________________________________ Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org <mailto:sr-users@lists.kamailio.org> https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users>-- Daniel-Constantin Mierla www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com <http://www.kamailioworld.com>-- Daniel-Constantin Mierla www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com <http://www.kamailioworld.com>
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=< sip:notification_peer@127.0.0.1:5060> 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936.bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936.bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error? The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
how do you start kamailio, or better, said what are the command line parameters? Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
Hi Daniel, since it was just before the KWC I waited some time before pinging you :) Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera <enrico.bandiera@cloud.timenet.it
:
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=< sip:notification_peer@127.0.0.1:5060> 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936. bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936. bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error? The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
how do you start kamailio, or better, said what are the command line parameters? Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing Listsr-users@lists.kamailio.orghttps://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierlawww.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
I just pushed a patch to skip handling dmq requests, as done for local options (keepalive) and notify -- KDMQ is something internal to kamailio and was not considered when filtering out.
- https://github.com/kamailio/kamailio/commit/7ff8d4284f20763e8e47f9b24e460486...
Maybe you can give it a test just to be safe. I will backport it.
Cheers, Daniel
On 30.05.18 11:39, Enrico Bandiera wrote:
Hi Daniel, since it was just before the KWC I waited some time before pinging you :) Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera <enrico.bandiera@cloud.timenet.it mailto:enrico.bandiera@cloud.timenet.it>:
Hi Daniel, tell me if you need more portions of the log: 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=<sip:notification_peer@127.0.0.1:5060 <http://sip:notification_peer@127.0.0.1:5060>> 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: <sip:notification_peer@127.0.0.1:5060 <http://sip:notification_peer@127.0.0.1:5060>> 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936.bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060 <http://sip:notification_peer@127.0.0.1:5060>] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [<sip:notification_peer@127.0.0.1:5060 <http://sip:notification_peer@127.0.0.1:5060>> 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936.bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 <http://127.0.0.1:5060> 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 <http://172.22.3.7:5060> 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds Thanks, Enrico. 2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com <mailto:miconda@gmail.com>>: Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error? The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages. Cheers, Daniel On 03.05.18 13:45, Enrico Bandiera wrote:kamailio -f $PATH_KAMAILIO_CFG -DD -E -e Thanks, Enrico. 2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com <mailto:miconda@gmail.com>>: Hello, how do you start kamailio, or better, said what are the command line parameters? Cheers, Daniel On 03.05.18 12:26, Enrico Bandiera wrote:Hi Daniel, is kamcmd ps ok for this? 0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116main process - attendant Thanks, Enrico. 2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com <mailto:miconda@gmail.com>>: Hello, can you get the output for kamctl ps and see the description for process with pid 94? I assume you run it in docker, given low pid value shown in the log message... Cheers, Daniel On 03.05.18 10:42, Enrico Bandiera wrote:Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem) The module seems to work but I'm getting these errors on the log: 0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store I get these errors for some time after running kamailio and then they stop appearing. Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to. Any idea? Thanks, Enrico. _______________________________________________ Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org <mailto:sr-users@lists.kamailio.org> https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users <https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users>-- Daniel-Constantin Mierla www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com <http://www.kamailioworld.com>-- Daniel-Constantin Mierla www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com <http://www.kamailioworld.com>-- Daniel-Constantin Mierla www.twitter.com/miconda <http://www.twitter.com/miconda> -- www.linkedin.com/in/miconda <http://www.linkedin.com/in/miconda> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com <http://www.kamailioworld.com>
Hi Daniel,
We've tried today to build from sources and test the patch you've linked but unfortunately the errors ``` 0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store ```
are still present in the logs.
Kind regards, -- Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Wed, May 30, 2018 at 6:10 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
I just pushed a patch to skip handling dmq requests, as done for local options (keepalive) and notify -- KDMQ is something internal to kamailio and was not considered when filtering out.
Maybe you can give it a test just to be safe. I will backport it.
Cheers, Daniel
On 30.05.18 11:39, Enrico Bandiera wrote:
Hi Daniel, since it was just before the KWC I waited some time before pinging you :) Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera enrico.bandiera@cloud.timenet.it:
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936.bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936.bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error?
The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
how do you start kamailio, or better, said what are the command line parameters?
Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
Hello Alex & Daniel, the error specified is for database , but the warning for "tps_storage_record()" is also still here
0(1846) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(1846) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store and 25(1875) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests
Kind regards
*Paolo Visintin* *CTO* evosip.cloud [image: Risultati immagini per evosip]
2018-06-01 20:44 GMT+02:00 Aleksandar Sosic alex.sosic@evosip.cloud:
Hi Daniel,
We've tried today to build from sources and test the patch you've linked but unfortunately the errors
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to storeare still present in the logs.
Kind regards,
Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Wed, May 30, 2018 at 6:10 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
I just pushed a patch to skip handling dmq requests, as done for local
options (keepalive) and notify -- KDMQ is something internal to kamailio and was not considered when filtering out.
7ff8d4284f20763e8e47f9b24e4604867a242e4a
Maybe you can give it a test just to be safe. I will backport it.
Cheers, Daniel
On 30.05.18 11:39, Enrico Bandiera wrote:
Hi Daniel, since it was just before the KWC I waited some time before
pinging you :)
Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera <enrico.bandiera@cloud.
timenet.it>:
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info():
checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4]
0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info():
checking if port 5060 (advertise 0) matches port 5060
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info():
checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7]
0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info():
checking if port 5060 (advertise 0) matches port 5060
0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=<
sip:notification_peer@127.0.0.1:5060>
0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route():
executing event_route[topos:...] (3)
0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP
Request:
0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg():
method: <KDMQ>
0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg():
uri: sip:notification_peer@127.0.0.1:5060
0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg():
version: <SIP/2.0>
0(111) DEBUG: <core> [core/parser/parse_via.c:1303]:
parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936. bcc62347000000000000000000000000.0>; state=16
0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end
of header reached, state=5
0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers():
Via found, flags=2
0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers():
this is the first via
0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]:
parse_addr_spec(): end of header reached, state=10
0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field():
<To> [40]; uri=[sip:notification_peer@127.0.0.1:5060]
0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field():
to body [sip:notification_peer@127.0.0.1:5060
0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field():
cseq <CSeq>: <10> <KDMQ>
0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field():
content_length=69
0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field():
found end of header
0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]:
parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03
0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]:
parse_addr_spec(): end of header reached, state=29
0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling
outgoing request (0, 1)
0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted
headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936. bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46)
0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted
headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0)
0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted
headers - as_contact: [](0) - bs_contact: [](0)
0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps
lock get: 120
0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is
[tpsh-5aeb10fb-6f-1] (1 / 18)
0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no
local address - do record routing for all initial requests
0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No
database handle - misconfiguration?
0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed
to store
0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release():
tps lock release: 120
0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac:
0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060
0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required
parameters are not available - ignoring
0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping
node sip:172.22.3.7:5060
0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0
rank 0: htable [main]
0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0
rank 0: app_python [main]
0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0
rank 0: topos [main]
0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is
[tpsh-5aeb10fb-6f-] (0 / 17)
0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266
open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com
:
Can you run with higher debug level (e.g., add -ddd), then reproduce
and send here all DEBUG and the rest of log messages printed by the same PID showing this error?
The main attendant doesn't handle sip traffic in fork=yes, but -DD is
telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla <miconda@gmail.com
:
Hello,
how do you start kamailio, or better, said what are the command line
parameters?
Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No
database handle - misconfiguration?
0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record():
failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla <
miconda@gmail.com>:
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log
message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we
have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No
database handle - misconfiguration?
0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record():
failed to store
I get these errors for some time after running kamailio and then
they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure
what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
Hello,
can you give all the log messages with debug=3 in kamailio.cfg?
Cheers, Daniel
On 01.06.18 20:44, Aleksandar Sosic wrote:
Hi Daniel,
We've tried today to build from sources and test the patch you've linked but unfortunately the errors
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to storeare still present in the logs.
Kind regards,
Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Wed, May 30, 2018 at 6:10 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
I just pushed a patch to skip handling dmq requests, as done for local options (keepalive) and notify -- KDMQ is something internal to kamailio and was not considered when filtering out.
Maybe you can give it a test just to be safe. I will backport it.
Cheers, Daniel
On 30.05.18 11:39, Enrico Bandiera wrote:
Hi Daniel, since it was just before the KWC I waited some time before pinging you :) Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera enrico.bandiera@cloud.timenet.it:
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936.bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936.bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error?
The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
how do you start kamailio, or better, said what are the command line parameters?
Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
can you get the output for
kamctl ps
and see the description for process with pid 94?
I assume you run it in docker, given low pid value shown in the log message...
Cheers, Daniel
On 03.05.18 10:42, Enrico Bandiera wrote:
Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem)
The module seems to work but I'm getting these errors on the log:
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
I get these errors for some time after running kamailio and then they stop appearing.
Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to.
Any idea?
Thanks, Enrico.
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
Hi Daniel,
here you go:
0(578) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(578) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=73 22(600) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 22(600) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 22(600) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 0(578) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 24(602) DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f02cf32a830 24(602) DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 1 columns returned from the query 24(602) DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f02cf32a948 24(602) DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f02cf32a9b8 24(602) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f02cf32aa28 24(602) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf32aa28)[0]=[table_version] 24(602) DEBUG: db_mysql [km_res.c:99]: db_mysql_get_columns(): use DB1_INT result type 24(602) DEBUG: <core> [db_res.c:188]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f02cf341cf0 24(602) DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f02cf341d68 24(602) DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [6] 24(602) DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 1 columns 24(602) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f02cf32aa28 24(602) DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f02cf32a948 24(602) DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f02cf32a9b8 24(602) DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows 24(602) DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f02cf341d68 24(602) DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f02cf341cf0 24(602) DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f02cf32a830 24(602) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 24 rank 24: app_python [udp receiver child=7 sock=127.0.0.1:5060] 0(578) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=cc74da418311da4b8fe0787ffe42ae95-7b67 0(578) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(578) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(578) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0](77) - x_via2: [](0) - x_vbranch1: [z9hG4bKb5bc.cc5f1947000000000000000000000000.0](46) 0(578) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(578) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(578) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 61 0(578) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5b16c7df-242-1] (1 / 19) 0(578) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests BUSDELCUL 0(578) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 24(602) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 24 rank 24: topos [udp receiver child=7 sock=127.0.0.1:5060] 0(578) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(578) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 61 24(602) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5b16c7df-25a-] (0 / 18) 24(602) DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f02cf341df0 not found in pool 24(602) DEBUG: db_mysql [km_my_con.c:97]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@router-msgdb-service/kamailio 0(578) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f02a99815a8 branch: 0 to 172.22.5.101:5060 0(578) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 27(605) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 0(578) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.5.98:5060 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 27(605) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 27(605) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 13(591) DEBUG: <core> [core/udp_server.c:491]: udp_rcv_loop(): received on udp socket: (112/100/539) [[SIP/2.0 200 OK 0D 0A Via: SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0 0D 0A ]] 13(591) DEBUG: <core> [core/parser/msg_parser.c:614]: parse_msg(): SIP Reply (status): 13(591) DEBUG: <core> [core/parser/msg_parser.c:616]: parse_msg(): version: <SIP/2.0> 13(591) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_msg(): status: <200> 13(591) DEBUG: <core> [core/parser/msg_parser.c:620]: parse_msg(): reason: <OK> 13(591) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKb5bc.cc5f1947000000000000000000000000.0>; state=16 13(591) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 13(591) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 13(591) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=bd99401293f1d6eb474c42c4e35ace99.888f 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 13(591) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [85]; uri=[sip:notification_peer@172.22.5.101:5060] 13(591) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@172.22.5.101:5060], to tag [bd99401293f1d6eb474c42c4e35ace99.888f] 13(591) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 13(591) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=107 13(591) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=cc74da418311da4b8fe0787ffe42ae95-7b67 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 13(591) DEBUG: topos [tps_msg.c:868]: tps_response_received(): handling incoming response 13(591) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0](77) - x_via2: [](0) - x_vbranch1: [z9hG4bKb5bc.cc5f1947000000000000000000000000.0](46) 13(591) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 13(591) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 13(591) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 61 0(578) DEBUG: htable [htable.c:236]: child_init(): rank is (0) 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: permissions [main] 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 24(602) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 24(602) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 24(602) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(578) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5b16c7df-242-] (0 / 18) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: tm [tcp receiver (generic) child=0] 33(611) DEBUG: tm [callid.c:136]: child_init_callid(): callid: '374eb255510cbc96-611@31.193.99.6' 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: tmx [tcp receiver (generic) child=0] 33(611) DEBUG: tmx [tmx_mod.c:263]: child_init(): rank is (25) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: sl [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: dispatcher [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: ctl [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: jsonrpcs [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: dmq [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: htable [tcp receiver (generic) child=0] 33(611) DEBUG: htable [htable.c:236]: child_init(): rank is (25) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: permissions [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f02cf341a00 not found in pool 33(611) DEBUG: db_mysql [km_my_con.c:97]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@api-db-service/evox_crm 13(591) DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f02cf343318 13(591) DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 20 columns returned from the query 13(591) DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 160 bytes for result names at 0x7f02cf343430 13(591) DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 80 bytes for result types at 0x7f02cf343538 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f02cf3435f0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3435f0)[0]=[rectime] 13(591) DEBUG: db_mysql [km_res.c:115]: db_mysql_get_columns(): use DB1_DATETIME result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7f02cf343668 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343668)[1]=[a_callid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[2] at 0x7f02cf3436e0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3436e0)[2]=[a_uuid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[3] at 0x7f02cf343758 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343758)[3]=[b_uuid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[4] at 0x7f02cf3437d0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3437d0)[4]=[direction] 13(591) DEBUG: db_mysql [km_res.c:99]: db_mysql_get_columns(): use DB1_INT result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[5] at 0x7f02cf343848 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343848)[5]=[x_via] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[6] at 0x7f02cf3438c0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3438c0)[6]=[x_vbranch] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[7] at 0x7f02cf343938 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343938)[7]=[x_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[8] at 0x7f02cf3439b0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3439b0)[8]=[y_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[9] at 0x7f02cf343a28 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343a28)[9]=[s_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[10] at 0x7f02cf343aa0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343aa0)[10]=[x_uri] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[11] at 0x7f02cf343b18 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343b18)[11]=[x_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[12] at 0x7f02cf343b90 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343b90)[12]=[s_method] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[13] at 0x7f02cf343c08 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343c08)[13]=[s_cseq] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[14] at 0x7f02cf343c80 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343c80)[14]=[a_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[15] at 0x7f02cf343cf8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343cf8)[15]=[b_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[16] at 0x7f02cf343d70 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343d70)[16]=[as_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[17] at 0x7f02cf343de8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343de8)[17]=[bs_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[18] at 0x7f02cf343e60 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343e60)[18]=[a_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[19] at 0x7f02cf343ed8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343ed8)[19]=[b_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:168]: db_mysql_convert_rows(): no rows returned from the query 13(591) DEBUG: topos [tps_storage.c:967]: tps_db_load_branch(): no stored record for <z9hG4bKb5bc.cc5f1947000000000000000000000000.0> 13(591) DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 20 columns 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f02cf3435f0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7f02cf343668 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[2] at 0x7f02cf3436e0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[3] at 0x7f02cf343758 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[4] at 0x7f02cf3437d0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[5] at 0x7f02cf343848 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[6] at 0x7f02cf3438c0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[7] at 0x7f02cf343938 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[8] at 0x7f02cf3439b0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[9] at 0x7f02cf343a28 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[10] at 0x7f02cf343aa0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[11] at 0x7f02cf343b18 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[12] at 0x7f02cf343b90 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[13] at 0x7f02cf343c08 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[14] at 0x7f02cf343c80 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[15] at 0x7f02cf343cf8 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[16] at 0x7f02cf343d70 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[17] at 0x7f02cf343de8 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[18] at 0x7f02cf343e60 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[19] at 0x7f02cf343ed8 13(591) DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f02cf343430 13(591) DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f02cf343538 13(591) DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f02cf343318 13(591) DEBUG: topos [tps_msg.c:889]: tps_response_received(): loaded dialog a_uuid [] 13(591) DEBUG: topos [tps_storage.c:1043]: tps_db_load_dialog(): no dlg uuid provided 13(591) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 61
Let me know if you need a wider log range.
Thanks, -- Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Tue, Jun 5, 2018 at 3:24 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
Hello,
can you give all the log messages with debug=3 in kamailio.cfg?
Cheers, Daniel
On 01.06.18 20:44, Aleksandar Sosic wrote:
Hi Daniel,
We've tried today to build from sources and test the patch you've linked but unfortunately the errors
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to storeare still present in the logs.
Kind regards,
Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Wed, May 30, 2018 at 6:10 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
I just pushed a patch to skip handling dmq requests, as done for local options (keepalive) and notify -- KDMQ is something internal to kamailio and was not considered when filtering out.
Maybe you can give it a test just to be safe. I will backport it.
Cheers, Daniel
On 30.05.18 11:39, Enrico Bandiera wrote:
Hi Daniel, since it was just before the KWC I waited some time before pinging you :) Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera enrico.bandiera@cloud.timenet.it:
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936.bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936.bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error?
The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Hello,
how do you start kamailio, or better, said what are the command line parameters?
Cheers, Daniel
On 03.05.18 12:26, Enrico Bandiera wrote:
Hi Daniel, is kamcmd ps ok for this?
0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store
root@proxy-bbd996f48-mjzr4:/# kamcmd ps 116 main process - attendant
Thanks, Enrico.
2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com: > Hello, > > can you get the output for > > kamctl ps > > and see the description for process with pid 94? > > I assume you run it in docker, given low pid value shown in the log message... > > Cheers, > Daniel > > > On 03.05.18 10:42, Enrico Bandiera wrote: > > Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem) > > The module seems to work but I'm getting these errors on the log: > > 0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? > 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store > > I get these errors for some time after running kamailio and then they stop appearing. > > Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to. > > Any idea? > > Thanks, > Enrico. > > > _______________________________________________ > Kamailio (SER) - Users Mailing List > sr-users@lists.kamailio.org > https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users > > > -- > Daniel-Constantin Mierla > www.twitter.com/miconda -- www.linkedin.com/in/miconda > Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Hello,
it was a mistake in matching KDMQ, I just pushed a fix for it in master branch.
Cheers, Daniel
On 05.06.18 19:29, Aleksandar Sosic wrote:
Hi Daniel,
here you go:
0(578) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(578) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=73 22(600) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 22(600) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 22(600) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 0(578) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 24(602) DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f02cf32a830 24(602) DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 1 columns returned from the query 24(602) DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f02cf32a948 24(602) DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f02cf32a9b8 24(602) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f02cf32aa28 24(602) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf32aa28)[0]=[table_version] 24(602) DEBUG: db_mysql [km_res.c:99]: db_mysql_get_columns(): use DB1_INT result type 24(602) DEBUG: <core> [db_res.c:188]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f02cf341cf0 24(602) DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f02cf341d68 24(602) DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [6] 24(602) DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 1 columns 24(602) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f02cf32aa28 24(602) DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f02cf32a948 24(602) DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f02cf32a9b8 24(602) DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows 24(602) DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f02cf341d68 24(602) DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f02cf341cf0 24(602) DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f02cf32a830 24(602) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 24 rank 24: app_python [udp receiver child=7 sock=127.0.0.1:5060] 0(578) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=cc74da418311da4b8fe0787ffe42ae95-7b67 0(578) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(578) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(578) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0](77)
- x_via2: [](0) - x_vbranch1:
[z9hG4bKb5bc.cc5f1947000000000000000000000000.0](46) 0(578) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(578) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(578) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 61 0(578) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5b16c7df-242-1] (1 / 19) 0(578) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests BUSDELCUL 0(578) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 24(602) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 24 rank 24: topos [udp receiver child=7 sock=127.0.0.1:5060] 0(578) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(578) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 61 24(602) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5b16c7df-25a-] (0 / 18) 24(602) DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f02cf341df0 not found in pool 24(602) DEBUG: db_mysql [km_my_con.c:97]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@router-msgdb-service/kamailio 0(578) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f02a99815a8 branch: 0 to 172.22.5.101:5060 0(578) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 27(605) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 0(578) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.5.98:5060 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 27(605) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 27(605) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 13(591) DEBUG: <core> [core/udp_server.c:491]: udp_rcv_loop(): received on udp socket: (112/100/539) [[SIP/2.0 200 OK 0D 0A Via: SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0 0D 0A ]] 13(591) DEBUG: <core> [core/parser/msg_parser.c:614]: parse_msg(): SIP Reply (status): 13(591) DEBUG: <core> [core/parser/msg_parser.c:616]: parse_msg(): version: <SIP/2.0> 13(591) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_msg(): status: <200> 13(591) DEBUG: <core> [core/parser/msg_parser.c:620]: parse_msg(): reason: <OK> 13(591) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKb5bc.cc5f1947000000000000000000000000.0>; state=16 13(591) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 13(591) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 13(591) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=bd99401293f1d6eb474c42c4e35ace99.888f 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 13(591) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [85]; uri=[sip:notification_peer@172.22.5.101:5060] 13(591) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@172.22.5.101:5060], to tag [bd99401293f1d6eb474c42c4e35ace99.888f] 13(591) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 13(591) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=107 13(591) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=cc74da418311da4b8fe0787ffe42ae95-7b67 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 13(591) DEBUG: topos [tps_msg.c:868]: tps_response_received(): handling incoming response 13(591) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0](77)
- x_via2: [](0) - x_vbranch1:
[z9hG4bKb5bc.cc5f1947000000000000000000000000.0](46) 13(591) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 13(591) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 13(591) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 61 0(578) DEBUG: htable [htable.c:236]: child_init(): rank is (0) 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: permissions [main] 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 24(602) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 24(602) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 24(602) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(578) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5b16c7df-242-] (0 / 18) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: tm [tcp receiver (generic) child=0] 33(611) DEBUG: tm [callid.c:136]: child_init_callid(): callid: '374eb255510cbc96-611@31.193.99.6' 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: tmx [tcp receiver (generic) child=0] 33(611) DEBUG: tmx [tmx_mod.c:263]: child_init(): rank is (25) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: sl [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: dispatcher [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: ctl [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: jsonrpcs [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: dmq [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: htable [tcp receiver (generic) child=0] 33(611) DEBUG: htable [htable.c:236]: child_init(): rank is (25) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: permissions [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f02cf341a00 not found in pool 33(611) DEBUG: db_mysql [km_my_con.c:97]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@api-db-service/evox_crm 13(591) DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f02cf343318 13(591) DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 20 columns returned from the query 13(591) DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 160 bytes for result names at 0x7f02cf343430 13(591) DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 80 bytes for result types at 0x7f02cf343538 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f02cf3435f0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3435f0)[0]=[rectime] 13(591) DEBUG: db_mysql [km_res.c:115]: db_mysql_get_columns(): use DB1_DATETIME result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7f02cf343668 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343668)[1]=[a_callid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[2] at 0x7f02cf3436e0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3436e0)[2]=[a_uuid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[3] at 0x7f02cf343758 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343758)[3]=[b_uuid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[4] at 0x7f02cf3437d0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3437d0)[4]=[direction] 13(591) DEBUG: db_mysql [km_res.c:99]: db_mysql_get_columns(): use DB1_INT result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[5] at 0x7f02cf343848 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343848)[5]=[x_via] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[6] at 0x7f02cf3438c0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3438c0)[6]=[x_vbranch] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[7] at 0x7f02cf343938 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343938)[7]=[x_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[8] at 0x7f02cf3439b0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3439b0)[8]=[y_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[9] at 0x7f02cf343a28 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343a28)[9]=[s_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[10] at 0x7f02cf343aa0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343aa0)[10]=[x_uri] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[11] at 0x7f02cf343b18 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343b18)[11]=[x_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[12] at 0x7f02cf343b90 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343b90)[12]=[s_method] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[13] at 0x7f02cf343c08 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343c08)[13]=[s_cseq] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[14] at 0x7f02cf343c80 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343c80)[14]=[a_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[15] at 0x7f02cf343cf8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343cf8)[15]=[b_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[16] at 0x7f02cf343d70 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343d70)[16]=[as_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[17] at 0x7f02cf343de8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343de8)[17]=[bs_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[18] at 0x7f02cf343e60 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343e60)[18]=[a_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[19] at 0x7f02cf343ed8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343ed8)[19]=[b_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:168]: db_mysql_convert_rows(): no rows returned from the query 13(591) DEBUG: topos [tps_storage.c:967]: tps_db_load_branch(): no stored record for <z9hG4bKb5bc.cc5f1947000000000000000000000000.0> 13(591) DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 20 columns 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f02cf3435f0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7f02cf343668 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[2] at 0x7f02cf3436e0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[3] at 0x7f02cf343758 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[4] at 0x7f02cf3437d0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[5] at 0x7f02cf343848 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[6] at 0x7f02cf3438c0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[7] at 0x7f02cf343938 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[8] at 0x7f02cf3439b0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[9] at 0x7f02cf343a28 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[10] at 0x7f02cf343aa0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[11] at 0x7f02cf343b18 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[12] at 0x7f02cf343b90 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[13] at 0x7f02cf343c08 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[14] at 0x7f02cf343c80 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[15] at 0x7f02cf343cf8 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[16] at 0x7f02cf343d70 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[17] at 0x7f02cf343de8 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[18] at 0x7f02cf343e60 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[19] at 0x7f02cf343ed8 13(591) DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f02cf343430 13(591) DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f02cf343538 13(591) DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f02cf343318 13(591) DEBUG: topos [tps_msg.c:889]: tps_response_received(): loaded dialog a_uuid [] 13(591) DEBUG: topos [tps_storage.c:1043]: tps_db_load_dialog(): no dlg uuid provided 13(591) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 61
Let me know if you need a wider log range.
Thanks,
Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Tue, Jun 5, 2018 at 3:24 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
Hello,
can you give all the log messages with debug=3 in kamailio.cfg?
Cheers, Daniel
On 01.06.18 20:44, Aleksandar Sosic wrote:
Hi Daniel,
We've tried today to build from sources and test the patch you've linked but unfortunately the errors
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to storeare still present in the logs.
Kind regards,
Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Wed, May 30, 2018 at 6:10 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
I just pushed a patch to skip handling dmq requests, as done for local options (keepalive) and notify -- KDMQ is something internal to kamailio and was not considered when filtering out.
Maybe you can give it a test just to be safe. I will backport it.
Cheers, Daniel
On 30.05.18 11:39, Enrico Bandiera wrote:
Hi Daniel, since it was just before the KWC I waited some time before pinging you :) Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera enrico.bandiera@cloud.timenet.it:
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936.bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936.bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com:
Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error?
The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages.
Cheers, Daniel
On 03.05.18 13:45, Enrico Bandiera wrote:
kamailio -f $PATH_KAMAILIO_CFG -DD -E -e
Thanks, Enrico.
2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com: > Hello, > > how do you start kamailio, or better, said what are the command line parameters? > > Cheers, > Daniel > > > On 03.05.18 12:26, Enrico Bandiera wrote: > > Hi Daniel, is kamcmd ps ok for this? > > 0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? > 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store > > root@proxy-bbd996f48-mjzr4:/# kamcmd ps > 116 main process - attendant > > Thanks, > Enrico. > > > 2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com: >> Hello, >> >> can you get the output for >> >> kamctl ps >> >> and see the description for process with pid 94? >> >> I assume you run it in docker, given low pid value shown in the log message... >> >> Cheers, >> Daniel >> >> >> On 03.05.18 10:42, Enrico Bandiera wrote: >> >> Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem) >> >> The module seems to work but I'm getting these errors on the log: >> >> 0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? >> 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store >> >> I get these errors for some time after running kamailio and then they stop appearing. >> >> Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to. >> >> Any idea? >> >> Thanks, >> Enrico. >> >> >> _______________________________________________ >> Kamailio (SER) - Users Mailing List >> sr-users@lists.kamailio.org >> https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users >> >> >> -- >> Daniel-Constantin Mierla >> www.twitter.com/miconda -- www.linkedin.com/in/miconda >> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com > > -- > Daniel-Constantin Mierla > www.twitter.com/miconda -- www.linkedin.com/in/miconda > Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Ok Daniel,
thank you very much! -- Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud
On Wed, Jun 6, 2018 at 8:23 AM Daniel-Constantin Mierla miconda@gmail.com wrote:
Hello,
it was a mistake in matching KDMQ, I just pushed a fix for it in master branch.
Cheers, Daniel
On 05.06.18 19:29, Aleksandar Sosic wrote:
Hi Daniel,
here you go:
0(578) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(578) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=73 22(600) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 22(600) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 22(600) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 0(578) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 24(602) DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f02cf32a830 24(602) DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 1 columns returned from the query 24(602) DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 8 bytes for result names at 0x7f02cf32a948 24(602) DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 4 bytes for result types at 0x7f02cf32a9b8 24(602) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f02cf32aa28 24(602) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf32aa28)[0]=[table_version] 24(602) DEBUG: db_mysql [km_res.c:99]: db_mysql_get_columns(): use DB1_INT result type 24(602) DEBUG: <core> [db_res.c:188]: db_allocate_rows(): allocate 16 bytes for rows at 0x7f02cf341cf0 24(602) DEBUG: <core> [db_row.c:117]: db_allocate_row(): allocate 32 bytes for row values at 0x7f02cf341d68 24(602) DEBUG: <core> [db_val.c:74]: db_str2val(): converting INT [6] 24(602) DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 1 columns 24(602) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f02cf32aa28 24(602) DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f02cf32a948 24(602) DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f02cf32a9b8 24(602) DEBUG: <core> [db_res.c:52]: db_free_rows(): freeing 1 rows 24(602) DEBUG: <core> [db_row.c:95]: db_free_row(): freeing row values at 0x7f02cf341d68 24(602) DEBUG: <core> [db_res.c:60]: db_free_rows(): freeing rows at 0x7f02cf341cf0 24(602) DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f02cf32a830 24(602) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 24 rank 24: app_python [udp receiver child=7 sock=127.0.0.1:5060] 0(578) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=cc74da418311da4b8fe0787ffe42ae95-7b67 0(578) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(578) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(578) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0](77)
- x_via2: [](0) - x_vbranch1:
[z9hG4bKb5bc.cc5f1947000000000000000000000000.0](46) 0(578) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(578) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(578) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 61 0(578) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5b16c7df-242-1] (1 / 19) 0(578) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests BUSDELCUL 0(578) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 24(602) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 24 rank 24: topos [udp receiver child=7 sock=127.0.0.1:5060] 0(578) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(578) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 61 24(602) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5b16c7df-25a-] (0 / 18) 24(602) DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f02cf341df0 not found in pool 24(602) DEBUG: db_mysql [km_my_con.c:97]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@router-msgdb-service/kamailio 0(578) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f02a99815a8 branch: 0 to 172.22.5.101:5060 0(578) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 27(605) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 0(578) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.5.98:5060 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 27(605) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 27(605) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 13(591) DEBUG: <core> [core/udp_server.c:491]: udp_rcv_loop(): received on udp socket: (112/100/539) [[SIP/2.0 200 OK 0D 0A Via: SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0 0D 0A ]] 13(591) DEBUG: <core> [core/parser/msg_parser.c:614]: parse_msg(): SIP Reply (status): 13(591) DEBUG: <core> [core/parser/msg_parser.c:616]: parse_msg(): version: <SIP/2.0> 13(591) DEBUG: <core> [core/parser/msg_parser.c:618]: parse_msg(): status: <200> 13(591) DEBUG: <core> [core/parser/msg_parser.c:620]: parse_msg(): reason: <OK> 13(591) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKb5bc.cc5f1947000000000000000000000000.0>; state=16 13(591) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 13(591) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 13(591) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=bd99401293f1d6eb474c42c4e35ace99.888f 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 13(591) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [85]; uri=[sip:notification_peer@172.22.5.101:5060] 13(591) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@172.22.5.101:5060], to tag [bd99401293f1d6eb474c42c4e35ace99.888f] 13(591) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 13(591) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=107 13(591) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=cc74da418311da4b8fe0787ffe42ae95-7b67 13(591) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 13(591) DEBUG: topos [tps_msg.c:868]: tps_response_received(): handling incoming response 13(591) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.5.98;branch=z9hG4bKb5bc.cc5f1947000000000000000000000000.0](77)
- x_via2: [](0) - x_vbranch1:
[z9hG4bKb5bc.cc5f1947000000000000000000000000.0](46) 13(591) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 13(591) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 13(591) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 61 0(578) DEBUG: htable [htable.c:236]: child_init(): rank is (0) 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: permissions [main] 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 24(602) DEBUG: db_mysql [km_my_con.c:136]: db_mysql_new_connection(): connection type is router-msgdb-service via TCP/IP 24(602) DEBUG: db_mysql [km_my_con.c:137]: db_mysql_new_connection(): protocol version is 10 24(602) DEBUG: db_mysql [km_my_con.c:138]: db_mysql_new_connection(): server version is 5.7.18 0(578) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(578) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5b16c7df-242-] (0 / 18) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: tm [tcp receiver (generic) child=0] 33(611) DEBUG: tm [callid.c:136]: child_init_callid(): callid: '374eb255510cbc96-611@31.193.99.6' 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: tmx [tcp receiver (generic) child=0] 33(611) DEBUG: tmx [tmx_mod.c:263]: child_init(): rank is (25) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: sl [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: dispatcher [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: ctl [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: jsonrpcs [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: dmq [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: htable [tcp receiver (generic) child=0] 33(611) DEBUG: htable [htable.c:236]: child_init(): rank is (25) 33(611) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 33 rank 25: permissions [tcp receiver (generic) child=0] 33(611) DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f02cf341a00 not found in pool 33(611) DEBUG: db_mysql [km_my_con.c:97]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@api-db-service/evox_crm 13(591) DEBUG: <core> [db_res.c:119]: db_new_result(): allocate 56 bytes for result set at 0x7f02cf343318 13(591) DEBUG: db_mysql [km_res.c:66]: db_mysql_get_columns(): 20 columns returned from the query 13(591) DEBUG: <core> [db_res.c:156]: db_allocate_columns(): allocate 160 bytes for result names at 0x7f02cf343430 13(591) DEBUG: <core> [db_res.c:167]: db_allocate_columns(): allocate 80 bytes for result types at 0x7f02cf343538 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7f02cf3435f0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3435f0)[0]=[rectime] 13(591) DEBUG: db_mysql [km_res.c:115]: db_mysql_get_columns(): use DB1_DATETIME result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7f02cf343668 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343668)[1]=[a_callid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[2] at 0x7f02cf3436e0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3436e0)[2]=[a_uuid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[3] at 0x7f02cf343758 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343758)[3]=[b_uuid] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[4] at 0x7f02cf3437d0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3437d0)[4]=[direction] 13(591) DEBUG: db_mysql [km_res.c:99]: db_mysql_get_columns(): use DB1_INT result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[5] at 0x7f02cf343848 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343848)[5]=[x_via] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[6] at 0x7f02cf3438c0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3438c0)[6]=[x_vbranch] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[7] at 0x7f02cf343938 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343938)[7]=[x_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[8] at 0x7f02cf3439b0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf3439b0)[8]=[y_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[9] at 0x7f02cf343a28 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343a28)[9]=[s_rr] 13(591) DEBUG: db_mysql [km_res.c:120]: db_mysql_get_columns(): use DB1_BLOB result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[10] at 0x7f02cf343aa0 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343aa0)[10]=[x_uri] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[11] at 0x7f02cf343b18 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343b18)[11]=[x_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[12] at 0x7f02cf343b90 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343b90)[12]=[s_method] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[13] at 0x7f02cf343c08 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343c08)[13]=[s_cseq] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[14] at 0x7f02cf343c80 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343c80)[14]=[a_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[15] at 0x7f02cf343cf8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343cf8)[15]=[b_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[16] at 0x7f02cf343d70 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343d70)[16]=[as_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[17] at 0x7f02cf343de8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343de8)[17]=[bs_contact] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[18] at 0x7f02cf343e60 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343e60)[18]=[a_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:83]: db_mysql_get_columns(): allocate 16 bytes for RES_NAMES[19] at 0x7f02cf343ed8 13(591) DEBUG: db_mysql [km_res.c:90]: db_mysql_get_columns(): RES_NAMES(0x7f02cf343ed8)[19]=[b_tag] 13(591) DEBUG: db_mysql [km_res.c:135]: db_mysql_get_columns(): use DB1_STRING result type 13(591) DEBUG: db_mysql [km_res.c:168]: db_mysql_convert_rows(): no rows returned from the query 13(591) DEBUG: topos [tps_storage.c:967]: tps_db_load_branch(): no stored record for <z9hG4bKb5bc.cc5f1947000000000000000000000000.0> 13(591) DEBUG: <core> [db_res.c:79]: db_free_columns(): freeing 20 columns 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[0] at 0x7f02cf3435f0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[1] at 0x7f02cf343668 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[2] at 0x7f02cf3436e0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[3] at 0x7f02cf343758 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[4] at 0x7f02cf3437d0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[5] at 0x7f02cf343848 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[6] at 0x7f02cf3438c0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[7] at 0x7f02cf343938 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[8] at 0x7f02cf3439b0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[9] at 0x7f02cf343a28 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[10] at 0x7f02cf343aa0 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[11] at 0x7f02cf343b18 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[12] at 0x7f02cf343b90 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[13] at 0x7f02cf343c08 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[14] at 0x7f02cf343c80 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[15] at 0x7f02cf343cf8 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[16] at 0x7f02cf343d70 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[17] at 0x7f02cf343de8 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[18] at 0x7f02cf343e60 13(591) DEBUG: <core> [db_res.c:83]: db_free_columns(): freeing RES_NAMES[19] at 0x7f02cf343ed8 13(591) DEBUG: <core> [db_res.c:96]: db_free_columns(): freeing result names at 0x7f02cf343430 13(591) DEBUG: <core> [db_res.c:101]: db_free_columns(): freeing result types at 0x7f02cf343538 13(591) DEBUG: <core> [db_res.c:138]: db_free_result(): freeing result set at 0x7f02cf343318 13(591) DEBUG: topos [tps_msg.c:889]: tps_response_received(): loaded dialog a_uuid [] 13(591) DEBUG: topos [tps_storage.c:1043]: tps_db_load_dialog(): no dlg uuid provided 13(591) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 61
Let me know if you need a wider log range.
Thanks,
Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Tue, Jun 5, 2018 at 3:24 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
Hello,
can you give all the log messages with debug=3 in kamailio.cfg?
Cheers, Daniel
On 01.06.18 20:44, Aleksandar Sosic wrote:
Hi Daniel,
We've tried today to build from sources and test the patch you've linked but unfortunately the errors
0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to storeare still present in the logs.
Kind regards,
Aleksandar Sosic linkedin.com/in/alexsosic/ mail: alex.sosic@evosip.cloud On Wed, May 30, 2018 at 6:10 PM Daniel-Constantin Mierla miconda@gmail.com wrote:
I just pushed a patch to skip handling dmq requests, as done for local options (keepalive) and notify -- KDMQ is something internal to kamailio and was not considered when filtering out.
Maybe you can give it a test just to be safe. I will backport it.
Cheers, Daniel
On 30.05.18 11:39, Enrico Bandiera wrote:
Hi Daniel, since it was just before the KWC I waited some time before pinging you :) Any news on this issue?
Thanks, Enrico.
2018-05-03 15:42 GMT+02:00 Enrico Bandiera enrico.bandiera@cloud.timenet.it:
Hi Daniel, tell me if you need more portions of the log:
0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==11 && [172.22.3.7] == [31.193.99.4] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: <core> [core/socket_info.c:559]: grep_sock_info(): checking if host==us: 10==10 && [172.22.3.7] == [172.22.3.7] 0(111) DEBUG: <core> [core/socket_info.c:566]: grep_sock_info(): checking if port 5060 (advertise 0) matches port 5060 0(111) DEBUG: tm [uac.c:426]: t_uac_prepare(): next_hop=sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: tm [uac.c:152]: dlg2hash(): hashid 25495 0(111) DEBUG: topos [topos_mod.c:468]: tps_execute_event_route(): executing event_route[topos:...] (3) 0(111) ERROR: <script>: HIDING TOPOLOGY: 1:5060:127.0.0.1 0(111) DEBUG: <core> [core/parser/msg_parser.c:604]: parse_msg(): SIP Request: 0(111) DEBUG: <core> [core/parser/msg_parser.c:606]: parse_msg(): method: <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:608]: parse_msg(): uri: sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): version: <SIP/2.0> 0(111) DEBUG: <core> [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK7936.bcc62347000000000000000000000000.0>; state=16 0(111) DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 0(111) DEBUG: <core> [core/parser/msg_parser.c:492]: parse_headers(): Via found, flags=2 0(111) DEBUG: <core> [core/parser/msg_parser.c:494]: parse_headers(): this is the first via 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 0(111) DEBUG: <core> [core/parser/msg_parser.c:171]: get_hdr_field(): <To> [40]; uri=[sip:notification_peer@127.0.0.1:5060] 0(111) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body [sip:notification_peer@127.0.0.1:5060 0(111) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <10> <KDMQ> 0(111) DEBUG: <core> [core/parser/msg_parser.c:183]: get_hdr_field(): content_length=69 0(111) DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=2a154ea4a528648fbfaaac82505be461-4e03 0(111) DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 0(111) DEBUG: topos [tps_msg.c:934]: tps_request_sent(): handling outgoing request (0, 1) 0(111) DEBUG: topos [tps_msg.c:376]: tps_pack_message(): compacted headers - x_via1: [SIP/2.0/UDP 172.22.3.7;branch=z9hG4bK7936.bcc62347000000000000000000000000.0](76) - x_via2: [](0) - x_vbranch1: [z9hG4bK7936.bcc62347000000000000000000000000.0](46) 0(111) DEBUG: topos [tps_msg.c:485]: tps_pack_message(): compacted headers - a_rr: [](0) - b_rr: [](0) - s_rr: [](0) 0(111) DEBUG: topos [tps_msg.c:490]: tps_pack_message(): compacted headers - as_contact: [](0) - bs_contact: [](0) 0(111) DEBUG: topos [tps_storage.c:124]: tps_storage_lock_get(): tps lock get: 120 0(111) DEBUG: <core> [sruid.c:182]: sruid_next(): new sruid is [tpsh-5aeb10fb-6f-1] (1 / 18) 0(111) WARNING: topos [tps_storage.c:400]: tps_storage_record(): no local address - do record routing for all initial requests 0(111) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? 0(111) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store 0(111) DEBUG: topos [tps_storage.c:136]: tps_storage_lock_release(): tps lock release: 120 0(111) DEBUG: tm [uac.c:645]: send_prepared_request_impl(): uac: 0x7f88fdc0b468 branch: 0 to 127.0.0.1:5060 0(111) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring 0(111) DEBUG: dmq [dmq_funcs.c:165]: bcast_dmq_message(): skipping node sip:172.22.3.7:5060 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: htable [main] 0(111) DEBUG: htable [htable.c:232]: child_init(): rank is (0) 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: app_python [main] 0(111) DEBUG: <core> [core/sr_module.c:941]: init_mod_child(): idx 0 rank 0: topos [main] 0(111) DEBUG: <core> [sruid.c:105]: sruid_init(): root for sruid is [tpsh-5aeb10fb-6f-] (0 / 17) 0(111) DEBUG: <core> [main.c:1751]: main_loop(): Expect maximum 2266 open fds
Thanks, Enrico.
2018-05-03 15:33 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com: > Can you run with higher debug level (e.g., add -ddd), then reproduce and send here all DEBUG and the rest of log messages printed by the same PID showing this error? > > The main attendant doesn't handle sip traffic in fork=yes, but -DD is telling not to daemonize the main attendant, so I need to see what that process does before throwing the error messages. > > Cheers, > Daniel > > > On 03.05.18 13:45, Enrico Bandiera wrote: > > kamailio -f $PATH_KAMAILIO_CFG -DD -E -e > > Thanks, > Enrico. > > 2018-05-03 12:52 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com: >> Hello, >> >> how do you start kamailio, or better, said what are the command line parameters? >> >> Cheers, >> Daniel >> >> >> On 03.05.18 12:26, Enrico Bandiera wrote: >> >> Hi Daniel, is kamcmd ps ok for this? >> >> 0(116) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? >> 0(116) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store >> >> root@proxy-bbd996f48-mjzr4:/# kamcmd ps >> 116 main process - attendant >> >> Thanks, >> Enrico. >> >> >> 2018-05-03 11:48 GMT+02:00 Daniel-Constantin Mierla miconda@gmail.com: >>> Hello, >>> >>> can you get the output for >>> >>> kamctl ps >>> >>> and see the description for process with pid 94? >>> >>> I assume you run it in docker, given low pid value shown in the log message... >>> >>> Cheers, >>> Daniel >>> >>> >>> On 03.05.18 10:42, Enrico Bandiera wrote: >>> >>> Hi, I'm trying to use the TOPOS module to solve some MTU issues we have (we have multiple kamailio servers in our architecture and route/via headers are becoming a problem) >>> >>> The module seems to work but I'm getting these errors on the log: >>> >>> 0(94) ERROR: topos [tps_storage.c:480]: tps_db_insert_dialog(): No database handle - misconfiguration? >>> 0(94) ERROR: topos [tps_storage.c:412]: tps_storage_record(): failed to store >>> >>> I get these errors for some time after running kamailio and then they stop appearing. >>> >>> Data is inserted on topos_d and topos_t on the db, so I'm not sure what the error is referring to. >>> >>> Any idea? >>> >>> Thanks, >>> Enrico. >>> >>> >>> _______________________________________________ >>> Kamailio (SER) - Users Mailing List >>> sr-users@lists.kamailio.org >>> https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users >>> >>> >>> -- >>> Daniel-Constantin Mierla >>> www.twitter.com/miconda -- www.linkedin.com/in/miconda >>> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com >> >> -- >> Daniel-Constantin Mierla >> www.twitter.com/miconda -- www.linkedin.com/in/miconda >> Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com > > -- > Daniel-Constantin Mierla > www.twitter.com/miconda -- www.linkedin.com/in/miconda > Kamailio World Conference - May 14-16, 2018 - www.kamailioworld.com
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
-- Daniel-Constantin Mierla -- www.asipto.com www.twitter.com/miconda -- www.linkedin.com/in/miconda Kamailio World Conference -- www.kamailioworld.com
-
Aleksandar Sosic -
Daniel-Constantin Mierla -
Enrico Bandiera -
Paolo Visintin - evosip.cloud