2018-02-23 14:33:44.956  DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): SIP Request:
2018-02-23 14:33:44.957  DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():  method:  <GET>
2018-02-23 14:33:44.957  DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  uri:     </api/htable>
2018-02-23 14:33:44.957  DEBUG: <core> [parser/msg_parser.c:614]: parse_msg():  version: <HTTP/1.1>
2018-02-23 14:33:44.957  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 64) called from core: parser/msg_parser.c: parse_headers(326)
2018-02-23 14:33:44.957  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 64) returns address 0x7f76808fb648 
2018-02-23 14:33:44.957  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 232) called from core: parser/msg_parser.c: get_hdr_field(116)
2018-02-23 14:33:44.958  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 232) returns address 0x7f76808fb800 
2018-02-23 14:33:44.958  DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=5
2018-02-23 14:33:44.958  DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2
2018-02-23 14:33:44.958  DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via
2018-02-23 14:33:44.958  DEBUG: pv [pv_trans.c:549]: tr_eval_string(): i=4 j=20
2018-02-23 14:33:44.958  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 8) called from pv: pv_svar.c: set_var_value(128)
2018-02-23 14:33:44.959  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 8) returns address 0x7f7680522270 
2018-02-23 14:33:44.959  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 936) called from core: rvalue.c: rval_new_empty(224)
2018-02-23 14:33:44.959  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 936) returns address 0x7f76808eeec8 
2018-02-23 14:33:44.959  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808eeec8), called from core: rvalue.c: rval_destroy(129)
2018-02-23 14:33:44.959  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: rvalue.c: rval_new_empty(224)
2018-02-23 14:33:44.959  INFO: <script>: [HTTP_RELOAD_CONFIGS] Reloading htables .... 
2018-02-23 14:33:44.959  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 75) called from pv: pv_svar.c: set_var_value(128)
2018-02-23 14:33:44.960  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 80) returns address 0x7f76808eeec8 
2018-02-23 14:33:44.960  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 75) called from core: rvalue.c: rval_get_str(1256)
2018-02-23 14:33:44.960  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 80) returns address 0x7f76808eef60 
2018-02-23 14:33:44.960  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 56) called from core: sr_module.c: fix_param(1139)
2018-02-23 14:33:44.960  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 56) returns address 0x7f76808eeff8 
2018-02-23 14:33:44.961  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 32) called from core: pvapi.c: pv_parse_format(1075)
2018-02-23 14:33:44.961  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 32) returns address 0x7f76808ef078 
2018-02-23 14:33:44.961  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808ef078), called from core: pvapi.c: pv_elem_free_all(1506)
2018-02-23 14:33:44.961  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: pvapi.c: pv_parse_format(1075)
2018-02-23 14:33:44.961  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808eeff8), called from core: sr_module.c: fparam_free_restore(1346)
2018-02-23 14:33:44.961  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: sr_module.c: fix_param(1139)
2018-02-23 14:33:44.962  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 56) called from core: sr_module.c: fix_param(1139)
2018-02-23 14:33:44.962  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 56) returns address 0x7f76808eeff8 
2018-02-23 14:33:44.962  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 56) called from core: db.c: db_bind_mod(172)
2018-02-23 14:33:44.962  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 56) returns address 0x7f76808ef0e0 
2018-02-23 14:33:44.962  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 15) called from core: db.c: db_bind_mod(189)
2018-02-23 14:33:44.962  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 16) returns address 0x7f76808fb3c0 
2018-02-23 14:33:44.962  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808ef0e0), called from core: db.c: db_bind_mod(197)
2018-02-23 14:33:44.963  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db.c: db_bind_mod(172)
2018-02-23 14:33:44.963  DEBUG: <core> [sr_module.c:694]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_postgres [/usr/lib64/kamailio/modules/db_postgres.so]
2018-02-23 14:33:44.963  DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_postgres
2018-02-23 14:33:44.963  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808fb3c0), called from core: db.c: db_bind_mod(254)
2018-02-23 14:33:44.963  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db.c: db_bind_mod(189)
2018-02-23 14:33:44.964  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 32) called from core: db.c: db_do_init2(298)
2018-02-23 14:33:44.964  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 32) returns address 0x7f76808ef078 
2018-02-23 14:33:44.964  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 125) called from core: db_id.c: new_db_id(272)
2018-02-23 14:33:44.964  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 128) returns address 0x7f76808ef160 
2018-02-23 14:33:44.964  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 9) called from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.964  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 16) returns address 0x7f76808fb3c0 
2018-02-23 14:33:44.964  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 8) called from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.965  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 8) returns address 0x7f7680527880 
2018-02-23 14:33:44.965  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 6) called from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.965  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 8) returns address 0x7f76808d5fa8 
2018-02-23 14:33:44.965  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 14) called from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.965  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 16) returns address 0x7f76808fb368 
2018-02-23 14:33:44.965  DEBUG: <core> [mem/f_malloc.c:444]: fm_malloc(): fm_malloc(0x7f768049a010, 9) called from core: db_id.c: dupl_string_name(72)
2018-02-23 14:33:44.965  DEBUG: <core> [mem/f_malloc.c:527]: fm_malloc(): fm_malloc(0x7f768049a010, 16) returns address 0x7f76808fb418 
2018-02-23 14:33:44.966  DEBUG: <core> [db.c:323]: db_do_init2(): connection 0x7f76808ef160 found in pool
2018-02-23 14:33:44.966  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808fb3c0), called from core: db_id.c: free_db_id(346)
2018-02-23 14:33:44.966  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.966  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f7680527880), called from core: db_id.c: free_db_id(347)
2018-02-23 14:33:44.966  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.966  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808d5fa8), called from core: db_id.c: free_db_id(348)
2018-02-23 14:33:44.967  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.967  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808fb368), called from core: db_id.c: free_db_id(349)
2018-02-23 14:33:44.967  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db_id.c: dupl_string(48)
2018-02-23 14:33:44.967  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808fb418), called from core: db_id.c: free_db_id(350)
2018-02-23 14:33:44.967  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db_id.c: dupl_string_name(72)
2018-02-23 14:33:44.968  DEBUG: <core> [mem/f_malloc.c:588]: fm_free(): fm_free(0x7f768049a010, 0x7f76808ef160), called from core: db_id.c: free_db_id(351)
2018-02-23 14:33:44.968  DEBUG: <core> [mem/f_malloc.c:607]: fm_free(): fm_free: freeing block alloc'ed from core: db_id.c: new_db_id(272)
2018-02-23 14:33:44.968  DEBUG: htable [ht_db.c:96]: ht_db_open_con(): database connection opened successfully