kamailio[3396]: DEBUG: db_mysql [km_dbase.c:358]: db_mysql_fetch_result(): converting row 0 of 1 count 1
kamailio[3396]: DEBUG: <core> [db_row.c:119]: db_allocate_row(): allocate 320 bytes for row values at 0x7fe87e48c0a0
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [2500-cti.sip.example.com]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [2500]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [cti.sip.example.com]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [2500]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [10.140.1.11]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [asterisk]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [2500]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [Qa31mwWv3MBdFYuVKY]
kamailio[3396]: DEBUG: <core> [db_val.c:117]: db_str2val(): converting STRING [sip:10.140.1.11]
kamailio[3396]: DEBUG: <core> [db_val.c:73]: db_str2val(): converting INT [10]
kamailio[3400]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (5): sqlops
kamailio[3398]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): sqlops
kamailio[3404]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-51f2284e-d4c-] (0 / 18)
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): registrar
kamailio[3404]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-51f2284e-d4c-] (0 / 18)
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): ctl
kamailio[3398]: DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_mysql [/usr/lib64/kamailio/modules/db_mysql.so]
kamailio[3398]: DEBUG: <core> [db.c:213]: db_bind_mod(): using db bind api for db_mysql
kamailio[3398]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49eff0 found in pool
kamailio[3398]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): dispatcher
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): mi_rpc
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): acc
kamailio[3400]: DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_mysql [/usr/lib64/kamailio/modules/db_mysql.so]
kamailio[3400]: DEBUG: <core> [db.c:213]: db_bind_mod(): using db bind api for db_mysql
kamailio[3400]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49eef0 found in pool
kamailio[3400]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (5): dispatcher
kamailio[3404]: DEBUG: <core> [db_id.c:292]: cmp_db_id(): identical DB URLs, but different DB connection pid [3404/3369]
kamailio[3404]: DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe87e437d20 not found in pool
kamailio[3404]: DEBUG: db_mysql [km_my_con.c:94]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/kamailio
kamailio[3396]: DEBUG: <core> [db_res.c:54]: db_free_rows(): freeing 1 rows
kamailio[3396]: DEBUG: <core> [db_row.c:97]: db_free_row(): freeing row values at 0x7fe87e48c0a0
kamailio[3396]: DEBUG: <core> [db_res.c:62]: db_free_rows(): freeing rows at 0x7fe87e48c070
kamailio[3396]: DEBUG: <core> [db_res.c:81]: db_free_columns(): freeing 10 columns
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[0] at 0x7fe87e48be90
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[1] at 0x7fe87e48bec0
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[2] at 0x7fe87e48bef0
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[3] at 0x7fe87e48bf20
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[4] at 0x7fe87e48bf50
kamailio[3400]: DEBUG: <core> [local_timer.c:74]: init_local_timer(): init_local_timer: timer_list between 0x894708 and 0x8d8708
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[5] at 0x7fe87e48bf80
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[6] at 0x7fe87e48bfb0
kamailio[3400]: DEBUG: <core> [io_wait.h:390]: io_watch_add(): DBG: io_watch_add(0x894540, 27, 1, (nil)), fd_no=0
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[7] at 0x7fe87e48bfe0
kamailio[3377]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): sqlops
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[8] at 0x7fe87e48c010
kamailio[3396]: DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[9] at 0x7fe87e48c040
kamailio[3396]: DEBUG: <core> [db_res.c:94]: db_free_columns(): freeing result names at 0x7fe87e48bdd0
kamailio[3396]: DEBUG: <core> [db_res.c:99]: db_free_columns(): freeing result types at 0x7fe87e48be40
kamailio[3396]: DEBUG: <core> [db_res.c:136]: db_free_result(): freeing result set at 0x7fe87e49f2e0
kamailio[3396]: DEBUG: tm [uac.c:243]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:10.140.1.11>
kamailio[3396]: DEBUG: tm [uac.c:182]: dlg2hash(): DEBUG: dlg2hash: 33416
kamailio[3377]: DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_mysql [/usr/lib64/kamailio/modules/db_mysql.so]
kamailio[3377]: DEBUG: <core> [db.c:213]: db_bind_mod(): using db bind api for db_mysql
kamailio[3377]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49fc20 found in pool
kamailio[3377]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): dispatcher
kamailio[3404]: DEBUG: db_mysql [km_my_con.c:121]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
kamailio[3404]: DEBUG: db_mysql [km_my_con.c:122]: db_mysql_new_connection(): protocol version is 10
kamailio[3404]: DEBUG: db_mysql [km_my_con.c:123]: db_mysql_new_connection(): server version is 5.1.67
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply  (status):
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version: <SIP/2.0>
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <401>
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:  <Unauthorized>
kamailio[3379]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8828.d5a9fe43.0>; state=6
kamailio[3379]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 234, <received> = <10.140.1.31>; state=16
kamailio[3379]: DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
kamailio[3379]: DEBUG: <core> [receive.c:149]: receive_msg(): After parse_msg...
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): mi_fifo
kamailio[3379]: DEBUG: tm [t_lookup.c:1095]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffffffffffff
kamailio[3379]: DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=as2ec7b125
kamailio[3379]: DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [37]; uri=[sip:2500@10.140.1.11]
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [sip:2500@10.140.1.11]
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <10> <REGISTER>
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
kamailio[3379]: DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
kamailio[3379]: DEBUG: tm [t_lookup.c:972]: t_reply_matching(): DEBUG: t_reply_matching: hash 33416 label 888117853 branch 0
kamailio[3379]: DEBUG: tm [t_lookup.c:1032]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe875ae8820)!
kamailio[3379]: DEBUG: tm [t_lookup.c:1164]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=1 T end=0x7fe875ae8820
kamailio[3379]: DEBUG: tm [t_reply.c:2085]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=2 is_invite=0)
kamailio[3379]: DEBUG: tm [t_reply.c:1207]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=401
kamailio[3379]: DEBUG: tm [t_reply.c:1965]: local_reply(): DEBUG: local_reply: branch=0, save=0, winner=0
kamailio[3379]: DEBUG: tm [t_reply.c:2002]: local_reply(): DEBUG: local transaction completed
kamailio[3379]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7fe875ae8820, callback type 1024, id 0 entered
kamailio[3379]: DEBUG: uac [uac_reg.c:465]: uac_reg_tm_callback(): completed with status 401 [uuid: 2500-cti.sip.example.com]
kamailio[3379]: DEBUG: uac [auth.c:225]: get_autenticate_hdr(): looking for header "WWW-Authenticate"
kamailio[3379]: DEBUG: uac [uac_reg.c:548]: uac_reg_tm_callback(): auth header body [Digest algorithm=MD5, realm="asterisk", nonce="6a2fb9b1"]
kamailio[3379]: DEBUG: uac [auth_hdr.c:210]: parse_authenticate_body(): <algorithm>="MD5" state=7
kamailio[3379]: DEBUG: uac [auth_hdr.c:210]: parse_authenticate_body(): <realm>="asterisk" state=2
kamailio[3379]: DEBUG: uac [auth_hdr.c:210]: parse_authenticate_body(): <nonce>="6a2fb9b1" state=3
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): kex
kamailio[3379]: DEBUG: uac [auth_hdr.c:409]: build_authorization_hdr(): hdr is <Authorization: Digest username="2500", realm="asterisk", nonce="6a2fb9b1", uri="sip:10.140.1.11", response="299f8b5fd0dec1df93cdf796fdbb6280", algorithm=MD5#015#012>
kamailio[3379]: DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=51b6a5d1a9ebf21332da43af5e84fad4-d180
kamailio[3379]: DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
kamailio[3379]: DEBUG: tm [uac.c:243]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:10.140.1.11>
kamailio[3406]: DEBUG: kex [kex_mod.c:156]: child_init(): rank is (7)
kamailio[3406]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-51f2284e-d4e-] (0 / 18)
kamailio[3379]: DEBUG: tm [uac.c:182]: dlg2hash(): DEBUG: dlg2hash: 33417
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): tm
kamailio[3379]: DEBUG: tm [t_reply.c:1547]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
kamailio[3379]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3379]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3379]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3379]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3379]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3379]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3379]: DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
kamailio[3379]: DEBUG: <core> [receive.c:293]: receive_msg(): receive_msg: cleaning up
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): auth_db
kamailio[3404]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e4981b0 found in pool
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): permissions
kamailio[3406]: DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '496dc8f41bad3cd2-3406@10.140.1.31'
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): domain
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply  (status):
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version: <SIP/2.0>
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <200>
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:  <OK>
kamailio[3378]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK9828.307c3916.0>; state=6
kamailio[3378]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 234, <received> = <10.140.1.31>; state=16
kamailio[3378]: DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
kamailio[3378]: DEBUG: <core> [receive.c:149]: receive_msg(): After parse_msg...
kamailio[3378]: DEBUG: tm [t_lookup.c:1095]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffffffffffff
kamailio[3378]: DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=as2ec7b125
kamailio[3378]: DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [37]; uri=[sip:2500@10.140.1.11]
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [sip:2500@10.140.1.11]
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): sl
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <11> <REGISTER>
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): usrloc
kamailio[3378]: DEBUG: tm [t_lookup.c:972]: t_reply_matching(): DEBUG: t_reply_matching: hash 33417 label 1637074691 branch 0
kamailio[3378]: DEBUG: tm [t_lookup.c:1032]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe875ae9d60)!
kamailio[3378]: DEBUG: tm [t_lookup.c:1164]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=1 T end=0x7fe875ae9d60
kamailio[3378]: DEBUG: tm [t_reply.c:2085]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=2 is_invite=0)
kamailio[3378]: DEBUG: tm [t_reply.c:1207]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
kamailio[3378]: DEBUG: tm [t_reply.c:1965]: local_reply(): DEBUG: local_reply: branch=0, save=0, winner=0
kamailio[3378]: DEBUG: tm [t_reply.c:2002]: local_reply(): DEBUG: local transaction completed
kamailio[3378]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7fe875ae9d60, callback type 1024, id 0 entered
kamailio[3406]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-51f2284e-d4e-] (0 / 18)
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): registrar
kamailio[3378]: DEBUG: uac [uac_reg.c:465]: uac_reg_tm_callback(): completed with status 200 [uuid: 2500-cti.sip.example.com]
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
kamailio[3378]: DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
kamailio[3406]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-51f2284e-d4e-] (0 / 18)
kamailio[3378]: DEBUG: tm [t_reply.c:1547]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
kamailio[3378]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3378]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3378]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3378]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3378]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3378]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3378]: DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
kamailio[3378]: DEBUG: <core> [receive.c:293]: receive_msg(): receive_msg: cleaning up
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): ctl
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): mi_rpc
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): acc
kamailio[3406]: DEBUG: <core> [db_id.c:292]: cmp_db_id(): identical DB URLs, but different DB connection pid [3406/3369]
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): presence
kamailio[3404]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e498350 found in pool
kamailio[3404]: DEBUG: presence [presence.c:474]: child_init(): child 6: Database connection opened successfully
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): presence_xml
kamailio[3404]: DEBUG: presence_xml [presence_xml.c:316]: child_init(): [6]  pid [3404]
kamailio[3404]: DEBUG: presence_xml [presence_xml.c:341]: child_init(): child 6: Database connection opened successfully
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): nathelper
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): rtpproxy
kamailio[3406]: DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe87e437d20 not found in pool
kamailio[3406]: DEBUG: db_mysql [km_my_con.c:94]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/kamailio
kamailio[3404]: INFO: rtpproxy [rtpproxy.c:1559]: rtpp_test(): rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): mi_fifo
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): debugger
kamailio[3404]: DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (6)
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): uac
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): siptrace
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): kex
kamailio[3407]: DEBUG: kex [kex_mod.c:156]: child_init(): rank is (8)
kamailio[3407]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-51f2284e-d4f-] (0 / 18)
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): tm
kamailio[3407]: DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '496dc8f41bad3cd2-3407@10.140.1.31'
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): sl
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): usrloc
kamailio[3407]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-51f2284e-d4f-] (0 / 18)
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): registrar
kamailio[3407]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-51f2284e-d4f-] (0 / 18)
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): ctl
kamailio[3404]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49ed30 found in pool
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): mi_datagram
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): rtimer
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): mi_rpc
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): acc
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): sqlops
kamailio[3407]: DEBUG: <core> [db_id.c:292]: cmp_db_id(): identical DB URLs, but different DB connection pid [3407/3369]
kamailio[3407]: DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe87e437d20 not found in pool
kamailio[3406]: DEBUG: db_mysql [km_my_con.c:121]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
kamailio[3406]: DEBUG: db_mysql [km_my_con.c:122]: db_mysql_new_connection(): protocol version is 10
kamailio[3406]: DEBUG: db_mysql [km_my_con.c:123]: db_mysql_new_connection(): server version is 5.1.67
kamailio[3404]: DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_mysql [/usr/lib64/kamailio/modules/db_mysql.so]
kamailio[3404]: DEBUG: <core> [db.c:213]: db_bind_mod(): using db bind api for db_mysql
kamailio[3404]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49eef0 found in pool
kamailio[3404]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (6): dispatcher
kamailio[3404]: DEBUG: <core> [local_timer.c:74]: init_local_timer(): init_local_timer: timer_list between 0x894708 and 0x8d8708
kamailio[3404]: DEBUG: <core> [io_wait.h:390]: io_watch_add(): DBG: io_watch_add(0x894540, 29, 1, (nil)), fd_no=0
kamailio[3407]: DEBUG: db_mysql [km_my_con.c:94]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@127.0.0.1/kamailio
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): auth_db
kamailio[3406]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e4981b0 found in pool
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): permissions
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): domain
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): presence
kamailio[3406]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e498350 found in pool
kamailio[3406]: DEBUG: presence [presence.c:474]: child_init(): child 7: Database connection opened successfully
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): presence_xml
kamailio[3406]: DEBUG: presence_xml [presence_xml.c:316]: child_init(): [7]  pid [3406]
kamailio[3406]: DEBUG: presence_xml [presence_xml.c:341]: child_init(): child 7: Database connection opened successfully
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): nathelper
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): rtpproxy
kamailio[3406]: INFO: rtpproxy [rtpproxy.c:1559]: rtpp_test(): rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): mi_fifo
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): kex
kamailio[3407]: DEBUG: db_mysql [km_my_con.c:121]: db_mysql_new_connection(): connection type is 127.0.0.1 via TCP/IP
kamailio[3407]: DEBUG: db_mysql [km_my_con.c:122]: db_mysql_new_connection(): protocol version is 10
kamailio[3407]: DEBUG: db_mysql [km_my_con.c:123]: db_mysql_new_connection(): server version is 5.1.67
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): auth_db
kamailio[3407]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e4981b0 found in pool
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): permissions
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): domain
kamailio[3409]: DEBUG: kex [kex_mod.c:156]: child_init(): rank is (-4)
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): presence
kamailio[3407]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e498350 found in pool
kamailio[3407]: DEBUG: presence [presence.c:474]: child_init(): child 8: Database connection opened successfully
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): presence_xml
kamailio[3407]: DEBUG: presence_xml [presence_xml.c:316]: child_init(): [8]  pid [3407]
kamailio[3407]: DEBUG: presence_xml [presence_xml.c:341]: child_init(): child 8: Database connection opened successfully
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): nathelper
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): rtpproxy
kamailio[3409]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-51f2284e-d51-] (0 / 18)
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): tm
kamailio[3409]: DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '496dc8f41bad3cd2-3409@10.140.1.31'
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): sl
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): usrloc
kamailio[3409]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-51f2284e-d51-] (0 / 18)
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): registrar
kamailio[3409]: DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-51f2284e-d51-] (0 / 18)
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): ctl
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): mi_rpc
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): acc
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): auth_db
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): permissions
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): domain
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): presence
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): presence_xml
kamailio[3409]: DEBUG: presence_xml [presence_xml.c:316]: child_init(): [-4]  pid [3409]
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): nathelper
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): rtpproxy
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): debugger
kamailio[3406]: DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (7)
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): uac
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): siptrace
kamailio[3406]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49ed30 found in pool
kamailio[3407]: INFO: rtpproxy [rtpproxy.c:1559]: rtpp_test(): rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): mi_datagram
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): rtimer
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): sqlops
kamailio[3409]: INFO: rtpproxy [rtpproxy.c:1559]: rtpp_test(): rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
kamailio[3406]: DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_mysql [/usr/lib64/kamailio/modules/db_mysql.so]
kamailio[3406]: DEBUG: <core> [db.c:213]: db_bind_mod(): using db bind api for db_mysql
kamailio[3406]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49eef0 found in pool
kamailio[3406]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (7): dispatcher
kamailio[3406]: DEBUG: <core> [local_timer.c:74]: init_local_timer(): init_local_timer: timer_list between 0x894708 and 0x8d8708
kamailio[3406]: DEBUG: <core> [io_wait.h:390]: io_watch_add(): DBG: io_watch_add(0x894540, 31, 1, (nil)), fd_no=0
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): debugger
kamailio[3407]: DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (8)
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): uac
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): siptrace
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): debugger
kamailio[3409]: DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (-4)
kamailio[3407]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49ed30 found in pool
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): uac
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): mi_datagram
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): siptrace
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): mi_datagram
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): rtimer
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): sqlops
kamailio[3407]: DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_mysql [/usr/lib64/kamailio/modules/db_mysql.so]
kamailio[3407]: DEBUG: <core> [db.c:213]: db_bind_mod(): using db bind api for db_mysql
kamailio[3407]: DEBUG: <core> [db.c:327]: db_do_init2(): connection 0x7fe87e49eef0 found in pool
kamailio[3407]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (8): dispatcher
kamailio[3407]: DEBUG: <core> [local_timer.c:74]: init_local_timer(): init_local_timer: timer_list between 0x894708 and 0x8d8708
kamailio[3407]: DEBUG: <core> [io_wait.h:390]: io_watch_add(): DBG: io_watch_add(0x894540, 33, 1, (nil)), fd_no=0
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): rtimer
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): sqlops
kamailio[3409]: DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-4): dispatcher
kamailio[3409]: DEBUG: <core> [local_timer.c:74]: init_local_timer(): init_local_timer: timer_list between 0x8504a8 and 0x8944a8
kamailio[3396]: DEBUG: tm [uac.c:243]: t_uac_prepare(): DEBUG:tm:t_uac: next_hop=<sip:10.140.1.11>
kamailio[3396]: DEBUG: tm [uac.c:182]: dlg2hash(): DEBUG: dlg2hash: 33413
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply  (status):
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version: <SIP/2.0>
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <401>
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:  <Unauthorized>
kamailio[3381]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK5828.83893957.0>; state=6
kamailio[3381]: DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 234, <received> = <10.140.1.31>; state=16
kamailio[3381]: DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
kamailio[3381]: DEBUG: <core> [receive.c:149]: receive_msg(): After parse_msg...
kamailio[3381]: DEBUG: tm [t_lookup.c:1095]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffffffffffff
kamailio[3381]: DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=as28816372
kamailio[3381]: DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [37]; uri=[sip:2500@10.140.1.11]
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [sip:2500@10.140.1.11]
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <10> <REGISTER>
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
kamailio[3381]: DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
kamailio[3381]: DEBUG: tm [t_lookup.c:972]: t_reply_matching(): DEBUG: t_reply_matching: hash 33413 label 1972607032 branch 0
kamailio[3381]: DEBUG: tm [t_lookup.c:1032]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe875ae9d60)!
kamailio[3381]: DEBUG: tm [t_lookup.c:1164]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=1 T end=0x7fe875ae9d60
kamailio[3381]: DEBUG: tm [t_reply.c:2085]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=2 is_invite=0)
kamailio[3381]: DEBUG: tm [t_reply.c:1207]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=401
kamailio[3381]: DEBUG: tm [t_reply.c:1965]: local_reply(): DEBUG: local_reply: branch=0, save=0, winner=0
kamailio[3381]: DEBUG: tm [t_reply.c:2002]: local_reply(): DEBUG: local transaction completed
kamailio[3381]: DEBUG: tm [t_hooks.c:288]: run_trans_callbacks_internal(): DBG: trans=0x7fe875ae9d60, callback type 1024, id 0 entered
kamailio[3381]: DEBUG: uac [uac_reg.c:465]: uac_reg_tm_callback(): completed with status 401 [uuid: 2500-cti.sip.example.com]
kamailio[3381]: ERROR: uac [uac_reg.c:537]: uac_reg_tm_callback(): authentication failed for <2500-cti.sip.example.com>
kamailio[3381]: DEBUG: tm [t_reply.c:1547]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
kamailio[3381]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3381]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3381]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3381]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3381]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3381]: DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
kamailio[3381]: DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
kamailio[3381]: DEBUG: <core> [receive.c:293]: receive_msg(): receive_msg: cleaning up