Restarting Kamailio:
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
 0(9076) DEBUG: <core> [cfg.y:1785]: loading modules under /usr/local/kamailio-3.3/lib64/kamailio/modules_k/:/usr/local/kamailio-3.3/lib64/kamailio/modules/
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module db_mysql.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/db_mysql.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/db_mysql/db_mysql.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so>
 0(9076) DEBUG: <core> [db_query.c:375]: About to allocate sql_buf size = 65535
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module mi_fifo.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_fifo.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module kex.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: kex
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module tm.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tm.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tm/tm.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module tmx.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tmx.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: tmx
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module sl.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sl.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sl/sl.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module rr.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: rr
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module pv.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/pv.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: pv
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module maxfwd.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/maxfwd.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module usrloc.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module registrar.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: registrar
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module textops.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module siputils.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module xlog.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: xlog
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module sanity.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sanity.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sanity/sanity.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/sanity.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module ctl.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/ctl.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/ctl/ctl.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module cfg_rpc.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/cfg_rpc.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/cfg_rpc/cfg_rpc.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/cfg_rpc.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module mi_rpc.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_rpc.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_rpc/mi_rpc.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/mi_rpc.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module acc.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module mi_datagram.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_datagram.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module auth.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth/auth.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module auth_db.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module permissions.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module nathelper.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: nathelper
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module rtpproxy.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/rtpproxy.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/rtpproxy/rtpproxy.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: rtpproxy
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module tls.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tls.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tls/tls.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/tls.so>
 0(9076) DEBUG: <core> [mem/f_malloc.c:293]: fm_malloc_init: F_OPTIMIZE=16384, /ROUNDTO=2048
 0(9076) DEBUG: <core> [mem/f_malloc.c:295]: fm_malloc_init: F_HASH_SIZE=2099, fm_block size=33896
 0(9076) DEBUG: <core> [mem/f_malloc.c:296]: fm_malloc_init(0x7f9d866b8000, 67108864), start=0x7f9d866b8000
 0(9076) DEBUG: <core> [mem/shm_mem.c:203]: shm_mem_init: success
 0(9076) INFO: tls [tls_init.c:377]: tls: init_tls: disabling compression...
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: tls
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module htable.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/htable.so>
 0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: htable
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module pike.so
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so>
 0(9076) DEBUG: <core> [cfg.y:1772]: loading module debugger.so
 0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/debugger.so>
 0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/debugger/debugger.so>
 0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/debugger.so>
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'ctl' matches module 'ctl'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <user> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <user> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'ctl' matches module 'ctl'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <group> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <group> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'mi_fifo' matches module 'mi_fifo'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fifo_name> in module mi_fifo [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_fifo.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fifo_name> in module mi_fifo [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_fifo.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <failure_reply_mode> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <failure_reply_mode> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fr_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fr_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fr_inv_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fr_inv_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rr' matches module 'rr'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <enable_full_lr> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <enable_full_lr> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rr' matches module 'rr'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <append_fromtag> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <append_fromtag> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'registrar' matches module 'registrar'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <method_filtering> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <method_filtering> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'registrar' matches module 'registrar'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <max_expires> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <max_expires> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'mi_datagram' matches module 'mi_datagram'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <socket_name> in module mi_datagram [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_datagram.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <socket_name> in module mi_datagram [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_datagram.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <early_media> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <early_media> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <report_ack> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <report_ack> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <report_cancels> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <report_cancels> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <detect_direction> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <detect_direction> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACC] value [1]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACCMISSED] value [2]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_missed_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_missed_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_extra> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_extra> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACCFAILED] value [3]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <failed_transaction_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <failed_transaction_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [DBURL] value ["mysql://kamailio_rw:openserrw@localhost/kamailio"]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_mode> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_mode> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MULTIDOMAIN] value [0]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <use_domain> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <use_domain> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [DBURL] value ["mysql://kamailio_rw:openserrw@localhost/kamailio"]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <calculate_ha1> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <calculate_ha1> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <password_column> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <password_column> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <load_credentials> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <load_credentials> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MULTIDOMAIN] value [0]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <use_domain> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <use_domain> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [DBURL] value ["mysql://kamailio_rw:openserrw@localhost/kamailio"]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'permissions' matches module 'permissions'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'permissions' matches module 'permissions'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_mode> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_mode> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rtpproxy' matches module 'rtpproxy'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <rtpproxy_sock> in module rtpproxy [/usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <rtpproxy_sock> in module rtpproxy [/usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <natping_interval> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <natping_interval> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <ping_nated_only> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <ping_nated_only> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATSIPPING] value [7]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sipping_bflag> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sipping_bflag> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sipping_from> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sipping_from> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper|registrar' matches module 'nathelper'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <received_avp> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <received_avp> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper|registrar' matches module 'registrar'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <received_avp> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <received_avp> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <nat_bflag> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <nat_bflag> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tls' matches module 'tls'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <config> in module tls [/usr/local/kamailio-3.3/lib64/kamailio/modules/tls.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <config> in module tls [/usr/local/kamailio-3.3/lib64/kamailio/modules/tls.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'pike' matches module 'pike'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sampling_time_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sampling_time_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'pike' matches module 'pike'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <reqs_density_per_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <reqs_density_per_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'pike' matches module 'pike'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <remove_latency> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <remove_latency> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'htable' matches module 'htable'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <htable> in module htable [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/htable.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <htable> in module htable [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/htable.so]
 0(9076) DEBUG: htable [ht_api.c:762]: htable [ipban] - expire [300]
 0(9076) DEBUG: htable [ht_api.c:767]: htable [ipban] - size [8]
 0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'debugger' matches module 'debugger'
 0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <cfgtrace> in module debugger [/usr/local/kamailio-3.3/lib64/kamailio/modules/debugger.so]
 0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <cfgtrace> in module debugger [/usr/local/kamailio-3.3/lib64/kamailio/modules/debugger.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90341fa8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90342128
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d903422a8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90342428
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90343020
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90343780
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903427b8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90343870
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <remove_hf> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <record_route> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903447b0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90344f60
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90345180
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903458e0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <options_reply> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903461c0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90345f18
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d903469d0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90347430
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90346d60
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903474c0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903485c8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90348d28
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d903497e8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90349968
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_branch> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_reply> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_failure> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90349cd8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90349028
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_reply_error> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034b148
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034a758
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: htable [ht_var.c:162]: htable [ipban] - key [$si]
 0(9076) DEBUG: htable [ht_api.c:211]: htable found [ipban]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xdbg> in module xlog [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <pike_check_req> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xlog> in module xlog [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so]
 0(9076) DEBUG: htable [ht_var.c:162]: htable [ipban] - key [$si]
 0(9076) DEBUG: htable [ht_api.c:211]: htable found [ipban]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #0/2: 21(15)/ 0x7f9d9034f438
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #1/2: 22(16)/ 0x7f9d9034ed68
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034e698
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034dbb0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034cfd0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034d730
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d9034f688
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034b928
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034f778
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <mf_process_maxfwd_header> in module maxfwd [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/maxfwd.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90350358
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90350ab8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sanity_check> in module sanity [/usr/local/kamailio-3.3/lib64/kamailio/modules/sanity.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xlog> in module xlog [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90350c38
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903518c0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <loose_route> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACC] value [1]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x1
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACCFAILED] value [3]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x3
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903537b0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90353e80
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90354150
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90354d30
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90355490
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903544e0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90355580
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90352ff0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90354060
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d903557f0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90352920
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903561c0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_NATS] value [5]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#42 #0/1: 3(3)/ 0x5
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATSIPPING] value [7]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90355a60
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90356730
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <save> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_reply_error> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903581b8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90358918
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <lookup> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #0/2: 21(15)/ 0x7f9d90359dc0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #1/2: 22(16)/ 0x7f9d90359e30
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#18 #0/2: 22(16)/ 0x7f9d9035a800
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#18 #1/2: 24(18)/ 0x7f9d90359d18
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903595b8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9035a500
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <www_authorize> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <www_challenge> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9035ffb0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9035f4f8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9035daa0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9035e910
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d90360de0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <consume_credentials> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <proxy_authorize> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <proxy_challenge> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90363190
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903626d8
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90360680
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90361af0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d90363fc0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <consume_credentials> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9035cd00
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90360ed0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d903640b0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <nat_uac_test> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_register> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_contact> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90364cd0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90363db0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d90365430
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_NATS] value [5]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x5
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90364600
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90365520
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_request> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <check_route_param> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90368030
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90368790
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_NATS] value [5]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#42 #0/1: 3(3)/ 0x5
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9036adf0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9036b4c0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_request> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <add_rr_param> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9036ce10
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9036d570
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_reply> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_contact> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9036f250
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9036db70
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <rtpproxy_manage> in module rtpproxy [/usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d9036fb00
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90370bc0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903704f0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90370c50
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90370f00
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_is_canceled> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90371200
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903718d0
 0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 0:
 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "ANTIFLOOD" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "REQINIT" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATDETECT" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "WITHINDLG" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332080> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332978> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90337e68> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333aa0> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "604" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903385a8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "AUTH" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "REGISTRAR" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "484" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "LOCATION" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "RELAY" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 1:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903323c8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "INVITE_BRANCH" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332390> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "INVITE_REPLY" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332358> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "INVITE_FAILURE" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903337e8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 2:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90338d58> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "request from blocked IP - $rm from $fu (IP:$si:$sp)
" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90338ce8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "L_ALERT" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:485]: assign( 0(9076) DEBUG: <core> [route_struct.c:526]: type<21> 0(9076) DEBUG: <core> [route_struct.c:560]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 3:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "483" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90338cb0> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "Malformed SIP message from $si:$sp
" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 4:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:411]: setflag( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:411]: setflag( 0(9076) DEBUG: <core> [route_struct.c:498]: 3 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "RELAY" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332080> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "404" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 5:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331660> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331660> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903337e8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 6:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:485]: assign( 0(9076) DEBUG: <core> [route_struct.c:526]: type<21> 0(9076) DEBUG: <core> [route_struct.c:560]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331c58> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:491]: UNKNOWN( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:560]: , type<24> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 7:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b080> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "XX.XX.XX.XX" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "403" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b048> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b0b8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "XX.XX.XX.XX" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "403" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b048> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 8:
 0(9076) DEBUG: <core> [route_struct.c:464]: force_rport( 0(9076) DEBUG: <core> [route_struct.c:526]: type<0> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033bd18> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033bbc8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:411]: setflag( 0(9076) DEBUG: <core> [route_struct.c:498]: 5 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]:  routing table 9:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331660> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333bb8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , ";nat=yes" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033bbc8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033c3f8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]: onreply routing table 1:
 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]: failure routing table 1:
 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: };  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [route.c:2207]: branch routing table 1:
 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(9076) DEBUG: <core> [route_struct.c:589]: );  0(9076) DEBUG: <core> [route.c:2209]: 
 0(9076) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'core' (num=49, size=196)
 0(9076) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'tcp' (num=26, size=104)
 0(9076) DEBUG: <core> [timer.c:272]: init_timer: starting with *ticks=1909742625
 0(9076) DEBUG: <core> [timer.c:314]: init_timer: timer_list between 0x7f9d866c0978 and 0x7f9d86704978
 0(9076) INFO: <core> [tcp_main.c:4832]: init_tcp: using epoll_lt as the io watch method (auto detected)
loading modules under /usr/local/kamailio-3.3/lib64/kamailio/modules_k/:/usr/local/kamailio-3.3/lib64/kamailio/modules/
Listening on 
             tls: XX.XX.XX.XX [XX.XX.XX.XX]:5061
Aliases: 
             *: XX.XX.XX.XX:*

 0(9078) DEBUG: <core> [daemonize.c:219]: trying enable core dumping...
 0(9078) DEBUG: <core> [daemonize.c:237]: core dumping is enabled now (1)...
 0(9078) DEBUG: <core> [daemonize.c:575]: core dump limits set to 18446744073709551615
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: db_mysql
 0(9078) DEBUG: db_mysql [km_db_mysql.c:92]: MySQL client version is 5.1.63
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: mi_fifo
 0(9078) DEBUG: mi_fifo [mi_fifo.c:124]: testing mi_fifo existance ...
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: kex
 0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2376-] (0 / 19)
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: tm
 0(9078) DEBUG: tm [tm.c:738]: TM - (sizeof cell=4776, sip_msg=1600) initializing...
 0(9078) DEBUG: tm [callid.c:105]: Call-ID initialization: '746bd51a16d56563'
 0(9078) DEBUG: tm [lock.c:87]: DEBUG: lock_initialize: lock initialization started
 0(9078) DEBUG: tm [timer.c:226]: tm: tm_init_timers: fr=160 fr_inv=960 wait=80 delete=4 t1=500 t2=4000 max_inv_lifetime=2880 max_noninv_lifetime=512
 0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'tm' (num=35, size=168)
 0(9078) DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: 6fbeced333041219cc0e266155e5d989
 0(9078) DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: 7321ae68013f6a299ac7e32ffb4f2546
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: tmx
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: sl
 0(9078) DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: 8f06e0b537f8292d6acbb4c9371a7e8e
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: rr
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: pv
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: maxfwd
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: usrloc
 0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [ulcx-50927432-2376-] (0 / 19)
 0(9078) INFO: usrloc [hslot.c:53]: locks array size 512
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
 0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: registrar
 0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2376-] (0 / 19)
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'registrar' (num=10, size=56)
 0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 'RECEIVED'
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <ul_bind_usrloc> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: textops
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: siputils
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 's:rpid'
 0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'siputils' (num=1, size=4)
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: xlog
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: sanity
 0(9078) DEBUG: sanity [mod_sanity.c:103]: sanity initializing
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9078) DEBUG: sanity [mod_sanity.c:111]: parsing proxy requires string:
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: ctl
 0(9078) DEBUG: ctl [ctl.c:242]: listening on:
 0(9078) DEBUG: ctl [ctl.c:252]:         [binrpc:unix stream] /tmp/kamailio_ctl
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: cfg_rpc
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: mi_rpc
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: acc
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: mi_datagram
 0(9078) DEBUG: mi_datagram [mi_datagram.c:158]: testing socket existance...
 0(9078) DEBUG: mi_datagram [mi_datagram.c:165]: the socket's name/addres is udp:127.0.0.1:8033
 0(9078) DEBUG: mi_datagram [mi_datagram.c:172]: we have an udp socket
 0(9078) DEBUG: mi_datagram [mi_datagram.c:180]: the remaining address after separating the protocol is 127.0.0.1:8033
 0(9078) DEBUG: mi_datagram [mi_datagram.c:192]: the port string is 8033
 0(9078) DEBUG: mi_datagram [mi_datagram.c:208]: the ip is 127.0.0.1
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: auth
 0(9078) DEBUG: auth [auth_mod.c:257]: auth module - initializing
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
 0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 'digest_challenge'
 0(9078) INFO: auth [auth_mod.c:329]: auth: qop set, but nonce-count (nc_enabled) support disabled
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: auth_db
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
 0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_auth_s> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: permissions
 0(9078) INFO: permissions [parse_config.c:251]: file not found: /usr/local/kamailio-3.3/etc/kamailio/permissions.allow
 0(9078) INFO: permissions [permissions.c:610]: default allow file (/usr/local/kamailio-3.3/etc/kamailio/permissions.allow) not found => empty rule set
 0(9078) INFO: permissions [parse_config.c:251]: file not found: /usr/local/kamailio-3.3/etc/kamailio/permissions.deny
 0(9078) INFO: permissions [permissions.c:619]: default deny file (/usr/local/kamailio-3.3/etc/kamailio/permissions.deny) not found => empty rule set
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
 0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
 0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d90378070 not found in pool
 0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
 0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d903786c8
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d903786e0
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d903786f8
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903786f8)[0]=[table_version]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90378718
 0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90378738
 0(9078) DEBUG: <core> [db_val.c:73]: converting INT [5]
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d903786f8
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d903786c8
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d903786e0
 0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90378738
 0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90378718
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
 0(9078) DEBUG: db_mysql [km_res.c:68]: 4 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 32 bytes for result names at 0x7f9d90378738
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 16 bytes for result types at 0x7f9d90378718
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d903786f8
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903786f8)[0]=[src_ip]
 0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[1] at 0x7f9d90378768
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378768)[1]=[proto]
 0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[2] at 0x7f9d90378788
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378788)[2]=[from_pattern]
 0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[3] at 0x7f9d903787a8
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903787a8)[3]=[tag]
 0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 0(9078) DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
 0(9078) DEBUG: permissions [trusted.c:102]: number of rows in trusted table: 0
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 4 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d903786f8
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0x7f9d90378768
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0x7f9d90378788
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0x7f9d903787a8
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90378738
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90378718
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
 0(9078) DEBUG: permissions [trusted.c:145]: trusted table reloaded successfully.
 0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
 0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
 0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d90378028 not found in pool
 0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
 0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d903786c8
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d9032aa88
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90378718
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378718)[0]=[table_version]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d903787a8
 0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90378070
 0(9078) DEBUG: <core> [db_val.c:73]: converting INT [5]
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90378718
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d903786c8
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d9032aa88
 0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90378070
 0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d903787a8
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
 0(9078) DEBUG: db_mysql [km_res.c:68]: 5 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 40 bytes for result names at 0x7f9d90378070
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 20 bytes for result types at 0x7f9d903787c8
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d903787a8
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903787a8)[0]=[grp]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[1] at 0x7f9d9032aa88
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d9032aa88)[1]=[ip_addr]
 0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[2] at 0x7f9d90378718
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378718)[2]=[mask]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[3] at 0x7f9d90378788
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378788)[3]=[port]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[4] at 0x7f9d90378768
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378768)[4]=[tag]
 0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 0(9078) DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
 0(9078) DEBUG: permissions [address.c:134]: Number of rows in address table: 0
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 5 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d903787a8
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0x7f9d9032aa88
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0x7f9d90378718
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0x7f9d90378788
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[4] at 0x7f9d90378768
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90378070
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d903787c8
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
 0(9078) DEBUG: permissions [address.c:200]: address table reloaded successfully.
 0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: nathelper
 0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 'RECEIVED'
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <ul_bind_usrloc> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: rtpproxy
 0(9078) DEBUG: rtpproxy [rtpproxy.c:535]: url is udp:127.0.0.1:7722, len is 18
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: tls
 0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'tls' (num=27, size=216)
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: htable
 0(9078) DEBUG: htable [htable.c:175]: starting auto-expire timer
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: pike
 0(9078) INFO: pike [ip_tree.c:88]: probing 256 set size
 0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: debugger
 0(9078) INFO: tls [tls_init.c:541]: tls: _init_tls_h:  compiled  with  openssl  version "OpenSSL 1.0.0e 6 Sep 2011" (0x1000005f), kerberos support: off, compression: on
 0(9078) INFO: tls [tls_init.c:549]: tls: init_tls_h: installed openssl library version "OpenSSL 1.0.0e 6 Sep 2011" (0x1000005f), kerberos support: off,  zlib compression: on
 compiler: cc -fPIC -DOPENSSL_PIC -DZLIB -DOPENSSL_THREADS -D_REENTRANT -DDSO_DLFCN -DHAVE_DLFCN_H -m64 -DL_ENDIAN -DTERMIO -O3 -Wa,--noexecstack -g -Wall -DMD32_REG_T=int -DOPENSSL_IA32_SSE2 -DOPENSSL_BN_ASM_MONT -DSHA1_ASM -DSHA256_ASM -DSHA512_ASM -DMD5_ASM -DAES_ASM -DWHIRLPOOL_ASM
 0(9078) WARNING: tls [tls_init.c:603]: tls: openssl bug #1491 (crash/mem leaks on low memory) workaround enabled (on low memory tls operations will fail preemptively) with free memory thresholds 4194304 and 2097152 bytes
 0(9078) INFO: <core> [cfg/cfg_ctx.c:613]: INFO: cfg_set_now(): tls.low_mem_threshold1 has been changed to 4194304
 0(9078) INFO: <core> [cfg/cfg_ctx.c:613]: INFO: cfg_set_now(): tls.low_mem_threshold2 has been changed to 2097152
 0(9078) DEBUG: <core> [main.c:2519]: Expect (at least) 8 SER processes in your process list
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [CANCEL/2]
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing t_check_trans()
 0(9078) DEBUG: <core> [route.c:929]: fixing t_relay()
 0(9078) DEBUG: <core> [route.c:929]: fixing t_check_trans()
 0(9078) DEBUG: <core> [route.c:929]: fixing remove_hf()
 0(9078) DEBUG: textops [textops.c:1669]: using hdr type (9) instead of <Route>
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1746]: using id for methods [INVITE,SUBSCRIBE/65]
 0(9078) DEBUG: <core> [route.c:929]: fixing record_route()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [OPTIONS/512]
 0(9078) DEBUG: <core> [route.c:929]: fixing options_reply()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [REGISTER/32]
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [INVITE/1]
 0(9078) DEBUG: <core> [route.c:929]: fixing t_on_branch()
 0(9078) DEBUG: <core> [route.c:929]: fixing t_on_reply()
 0(9078) DEBUG: <core> [route.c:929]: fixing t_on_failure()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing t_relay()
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_reply_error()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [route.c:929]: fixing xdbg()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing pike_check_req()
 0(9078) DEBUG: <core> [route.c:929]: fixing xlog()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
 0(9078) DEBUG: <core> [rvalue.c:2861]: RV is int: 1
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing mf_process_maxfwd_header()
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing sanity_check()
 0(9078) DEBUG: <core> [route.c:929]: fixing xlog()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing has_totag()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing loose_route()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [BYE/8]
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [ACK/4]
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing t_check_trans()
 0(9078) DEBUG: <core> [route.c:929]: fixing t_relay()
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing setbflag()
 0(9078) DEBUG: <core> [route.c:929]: fixing setbflag()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing save()
 0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d903571e0 not found in pool
 0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
 0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356908
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356920
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356938
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356938)[0]=[table_version]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90356958
 0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90356978
 0(9078) DEBUG: <core> [db_val.c:73]: converting INT [5]
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356938
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356908
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356920
 0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90356978
 0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90356958
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
 0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356920
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356908
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356958
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356958)[0]=[username]
 0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 0(9078) DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356958
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356920
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356908
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
 0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_reply_error()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing lookup()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [route.c:929]: fixing t_newtran()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [route.c:929]: fixing send_reply()
 0(9078) DEBUG: <core> [route.c:929]: fixing send_reply()
 0(9078) DEBUG: <core> [switch.c:232]: SWITCH: 3 cases, 0 default
 0(9078) DEBUG: <core> [switch.c:402]: SWITCH: optimized to condtable (3) default: no
  0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [REGISTER/32]
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing www_authorize()
 0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d90357420 not found in pool
 0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
 0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356908
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356920
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90378738
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378738)[0]=[table_version]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90356978
 0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d903574b0
 0(9078) DEBUG: <core> [db_val.c:73]: converting INT [6]
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90378738
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356908
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356920
 0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d903574b0
 0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90356978
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
 0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
 0(9078) DEBUG: <core> [route.c:929]: fixing www_challenge()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(9078) DEBUG: <core> [route.c:929]: fixing consume_credentials()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing proxy_authorize()
 0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d903575f0 not found in pool
 0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
 0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356920
 0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356908
 0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356978
 0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356978)[0]=[table_version]
 0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d903574b0
 0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90378738
 0(9078) DEBUG: <core> [db_val.c:73]: converting INT [6]
 0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356978
 0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356920
 0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356908
 0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90378738
 0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d903574b0
 0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
 0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
 0(9078) DEBUG: <core> [route.c:929]: fixing proxy_challenge()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(9078) DEBUG: <core> [route.c:929]: fixing consume_credentials()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing nat_uac_test()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
 0(9078) DEBUG: textops [textops.c:1753]: using id for method [REGISTER/32]
 0(9078) DEBUG: <core> [route.c:929]: fixing fix_nated_register()
 0(9078) DEBUG: <core> [route.c:929]: fixing fix_nated_contact()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_request()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing has_totag()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing check_route_param()
 0(9078) DEBUG: <core> [route.c:929]: fixing setbflag()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_request()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing has_totag()
 0(9078) DEBUG: <core> [route.c:929]: fixing add_rr_param()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing is_reply()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(9078) DEBUG: <core> [route.c:929]: fixing fix_nated_contact()
 0(9078) DEBUG: <core> [route.c:929]: fixing rtpproxy_manage()
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 2
 0(9078) DEBUG: <core> [rvalue.c:2865]: RV is str: "[12][0-9][0-9]"
 0(9078) DEBUG: <core> [route.c:506]: right EXP optimized: op11(O52, _ST22_) => op11(O52, _ST1_)
 0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(9078) DEBUG: <core> [route.c:929]: fixing t_is_canceled()
 0(9078) DEBUG: <core> [tcp_main.c:2840]: tcp_init: added XX.XX.XX.XX:5061
 0(9078) DEBUG: <core> [daemonize.c:219]: trying enable core dumping...
 0(9078) DEBUG: <core> [daemonize.c:221]: core dumping is disabled now...
 0(9078) DEBUG: <core> [daemonize.c:225]: core dumping has just been enabled...
 0(9078) DEBUG: <core> [daemonize.c:232]: current core file limit: 18446744073709551615 (max: 18446744073709551615)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_fifo
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): kex
 0(9078) DEBUG: kex [kex_mod.c:160]: rank is (-127)
 0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2376-] (0 / 19)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): tm
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): sl
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): usrloc
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): registrar
 0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2376-] (0 / 19)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): ctl
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_rpc
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): acc
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_datagram
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): auth_db
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): permissions
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): nathelper
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): rtpproxy
 0(9078) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): tls
 0(9078) INFO: tls [tls_domain.c:187]: TLSs<default>: tls_method=12
 0(9078) INFO: tls [tls_domain.c:199]: TLSs<default>: certificate='/usr/local/kamailio-3.3/etc/kamailio/kamailio.pem'
 0(9078) INFO: tls [tls_domain.c:206]: TLSs<default>: ca_list='(null)'
 0(9078) INFO: tls [tls_domain.c:213]: TLSs<default>: crl='(null)'
 0(9078) INFO: tls [tls_domain.c:217]: TLSs<default>: require_certificate=0
 0(9078) INFO: tls [tls_domain.c:224]: TLSs<default>: cipher_list='(null)'
 0(9078) INFO: tls [tls_domain.c:231]: TLSs<default>: private_key='/usr/local/kamailio-3.3/etc/kamailio/kamailio.key'
 0(9078) INFO: tls [tls_domain.c:235]: TLSs<default>: verify_certificate=0
 0(9078) INFO: tls [tls_domain.c:238]: TLSs<default>: verify_depth=9
 0(9078) DEBUG: tls [tls_domain.c:438]: TLSs<default>: No CA list configured
 0(9078) DEBUG: tls [tls_domain.c:475]: TLSs<default>: No CRL configured
 0(9078) INFO: tls [tls_domain.c:576]: TLSs<default>: No client certificate required and no checks performed
 0(9078) INFO: tls [tls_domain.c:187]: TLSc<default>: tls_method=9
 0(9078) INFO: tls [tls_domain.c:199]: TLSc<default>: certificate='(null)'
 0(9078) INFO: tls [tls_domain.c:206]: TLSc<default>: ca_list='(null)'
 0(9078) INFO: tls [tls_domain.c:213]: TLSc<default>: crl='(null)'
 0(9078) INFO: tls [tls_domain.c:217]: TLSc<default>: require_certificate=1
 0(9078) INFO: tls [tls_domain.c:224]: TLSc<default>: cipher_list='(null)'
 0(9078) INFO: tls [tls_domain.c:231]: TLSc<default>: private_key='(null)'
 0(9078) INFO: tls [tls_domain.c:235]: TLSc<default>: verify_certificate=1
 0(9078) INFO: tls [tls_domain.c:238]: TLSc<default>: verify_depth=9
 0(9078) DEBUG: tls [tls_domain.c:408]: TLSc<default>: No certificate configured
 0(9078) DEBUG: tls [tls_domain.c:438]: TLSc<default>: No CA list configured
 0(9078) DEBUG: tls [tls_domain.c:475]: TLSc<default>: No CRL configured
 0(9078) INFO: tls [tls_domain.c:561]: TLSc<default>: Server MUST present valid certificate
 0(9078) DEBUG: tls [tls_domain.c:917]: TLSs<default>: Key '/usr/local/kamailio-3.3/etc/kamailio/kamailio.key' successfuly loaded
 0(9078) DEBUG: tls [tls_domain.c:877]: TLSc<default>: No private key specified
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): htable
 0(9078) DEBUG: htable [htable.c:194]: rank is (-127)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): debugger
 0(9078) DEBUG: debugger [debugger_mod.c:124]: rank is (-127)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_fifo
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): kex
 0(9078) DEBUG: kex [kex_mod.c:160]: rank is (0)
 0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2376-] (0 / 19)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): tm
 0(9078) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9078@XX.XX.XX.XX'
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): sl
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): usrloc
 0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d9036fb00 not found in pool
 0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): registrar
 0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2376-] (0 / 19)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): ctl
 0(9078) DEBUG: ctl [ctl.c:311]: ctl: mod_child(0), ctrl_sock_lst=0x1a661b0
 0(9078) DEBUG: ctl [ctl.c:319]: ctl: mod_child(0), fork_process=9082, csl=0x1a661b0
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_rpc
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): acc
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_datagram
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): auth_db
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): permissions
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): nathelper
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): rtpproxy
 3(9081) DEBUG: mi_fifo [mi_fifo.c:223]: new process with pid = 9081 created
 3(9081) DEBUG: mi_fifo [fifo_fnc.c:76]: FIFO created @ /tmp/kamailio_fifo
 3(9081) DEBUG: mi_fifo [fifo_fnc.c:91]: fifo /tmp/kamailio_fifo opened, mode=660
 3(9081) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
 3(9081) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
 2(9080) DEBUG: kex [kex_mod.c:160]: rank is (-1)
 2(9080) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2378-] (0 / 19)
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
 2(9080) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9080@XX.XX.XX.XX'
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
 2(9080) DEBUG: <core> [db.c:316]: connection 0x7f9d9036fb00 not found in pool
 2(9080) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 5(9083) INFO: mi_datagram [mi_datagram.c:329]: a new child 0/9083
 5(9083) DEBUG: <core> [db_id.c:292]: identical DB URLs, but different DB connection pid [9083/9078]
 5(9083) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
 5(9083) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_fifo
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): kex
 4(9082) DEBUG: kex [kex_mod.c:160]: rank is (-2)
 4(9082) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-237a-] (0 / 19)
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): tm
 4(9082) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9082@XX.XX.XX.XX'
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): sl
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): usrloc
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): registrar
 4(9082) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-237a-] (0 / 19)
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): ctl
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_rpc
 4(9082) DEBUG: mi_rpc [mi_rpc_mod.c:106]: initializing child[-2] for rpc handling
 4(9082) DEBUG: <core> [db_id.c:292]: identical DB URLs, but different DB connection pid [9082/9078]
 4(9082) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
 4(9082) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
 6(9084) DEBUG: kex [kex_mod.c:160]: rank is (-1)
 6(9084) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-237c-] (0 / 19)
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
 6(9084) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9084@XX.XX.XX.XX'
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
 6(9084) DEBUG: <core> [db_id.c:292]: identical DB URLs, but different DB connection pid [9084/9078]
 6(9084) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
 6(9084) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 3(9081) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 3(9081) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 3(9081) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 3(9081) DEBUG: <core> [db.c:325]: connection 0x7f9d90370bc0 found in pool
 3(9081) DEBUG: <core> [db.c:325]: connection 0x7f9d90356b90 found in pool
 5(9083) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 5(9083) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 5(9083) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 5(9083) DEBUG: <core> [db.c:325]: connection 0x7f9d90371c60 found in pool
 5(9083) DEBUG: <core> [db.c:325]: connection 0x7f9d90371d68 found in pool
 2(9080) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 2(9080) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 2(9080) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
 2(9080) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2378-] (0 / 19)
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_datagram
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
 2(9080) DEBUG: <core> [db.c:325]: connection 0x7f9d90370f00 found in pool
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): permissions
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
 4(9082) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 4(9082) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 4(9082) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371c60 found in pool
 4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371d68 found in pool
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): acc
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_datagram
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): auth_db
 4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371e70 found in pool
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): permissions
 4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371f78 found in pool
 6(9084) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 6(9084) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 6(9084) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
 6(9084) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-237c-] (0 / 19)
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_datagram
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
 6(9084) DEBUG: <core> [db.c:325]: connection 0x7f9d90371c60 found in pool
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): permissions
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
 4(9082) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90356b48
 4(9082) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 4(9082) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356b88
 4(9082) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356ba0
 4(9082) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356bb8
 4(9082) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356bb8)[0]=[table_version]
 4(9082) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 4(9082) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90356bd8
 4(9082) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90356bf8
 4(9082) DEBUG: <core> [db_val.c:73]: converting INT [5]
 4(9082) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 4(9082) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356bb8
 4(9082) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356b88
 4(9082) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356ba0
 4(9082) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 4(9082) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90356bf8
 4(9082) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90356bd8
 4(9082) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90356b48
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): nathelper
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): rtpproxy
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
 1(9079) DEBUG: kex [kex_mod.c:160]: rank is (-1)
 1(9079) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2377-] (0 / 19)
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
 1(9079) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9079@XX.XX.XX.XX'
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
 1(9079) DEBUG: <core> [db.c:316]: connection 0x7f9d9036fb00 not found in pool
 1(9079) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
 1(9079) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 1(9079) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 1(9079) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
 1(9079) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2377-] (0 / 19)
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_datagram
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
 1(9079) DEBUG: <core> [db.c:325]: connection 0x7f9d90370f00 found in pool
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): permissions
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
 0(9078) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
 4(9082) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
 6(9084) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
 2(9080) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
 1(9079) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): tls
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): htable
 0(9078) DEBUG: htable [htable.c:194]: rank is (0)
 0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): debugger
 0(9078) DEBUG: debugger [debugger_mod.c:124]: rank is (0)
 0(9078) DEBUG: <core> [main.c:1752]: Expect maximum 2091  open fds
kamailio started.
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_fifo
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): kex
 7(9085) DEBUG: kex [kex_mod.c:160]: rank is (-4)
 7(9085) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-237d-] (0 / 19)
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): tm
 7(9085) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9085@XX.XX.XX.XX'
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): sl
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): usrloc
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): registrar
 7(9085) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-237d-] (0 / 19)
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): ctl
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_rpc
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): acc
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_datagram
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): auth_db
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): permissions
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): nathelper
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): rtpproxy
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tls
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): htable
 6(9084) DEBUG: htable [htable.c:194]: rank is (-1)
 6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
 6(9084) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): tls
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): htable
 4(9082) DEBUG: htable [htable.c:194]: rank is (-2)
 4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): debugger
 4(9082) DEBUG: debugger [debugger_mod.c:124]: rank is (-2)
 4(9082) DEBUG: ctl [ctl.c:319]: ctl: mod_child(0), fork_process=0, csl=0x1a661b0
 4(9082) DEBUG: ctl [ctl.c:326]: ctl: 0 io_listen_loop(1, 0x1a661b0)
 4(9082) INFO: ctl [io_listener.c:225]: io_listen_loop:  using epoll_lt io watch method (config)
 4(9082) DEBUG: ctl [io_listener.c:257]: io_listen_loop: adding socket 4, type 2, transport 3 (/tmp/kamailio_ctl)
 4(9082) DEBUG: ctl [../../io_wait.h:390]: DBG: io_watch_add(0x7f9d8ce5e440, 4, 2, 0x1a661b0), fd_no=0
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tls
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): htable
 2(9080) DEBUG: htable [htable.c:194]: rank is (-1)
 2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
 2(9080) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tls
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): htable
 1(9079) DEBUG: htable [htable.c:194]: rank is (-1)
 1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
 1(9079) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
 7(9085) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): tls
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): htable
 7(9085) DEBUG: htable [htable.c:194]: rank is (-4)
 7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): debugger
 7(9085) DEBUG: debugger [debugger_mod.c:124]: rank is (-4)
 7(9085) DEBUG: <core> [local_timer.c:74]: init_local_timer: timer_list between 0x86c228 and 0x8b0228
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 6, 1, 0x7f9d90330ba0), fd_no=0
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 8, 4, 0x7f9d86923074), fd_no=1
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 9, 4, 0x7f9d86923100), fd_no=2
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 10, 4, 0x7f9d8692318c), fd_no=3
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 12, 4, 0x7f9d86923218), fd_no=4
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 14, 4, 0x7f9d869232a4), fd_no=5
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 4, 4, 0x7f9d86923330), fd_no=6
 7(9085) DEBUG: <core> [ip_addr.c:247]: tcpconn_new: new tcp connection: 188.57.131.155
 7(9085) DEBUG: <core> [tcp_main.c:1089]: tcpconn_new: on port 32404, type 3
 7(9085) DEBUG: <core> [tcp_main.c:1403]: tcpconn_add: hashes: 3324:1057:1987, 1
 7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 16, 2, 0x7f9d869656a8), fd_no=7
 7(9085) DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x86c020, 16, -1, 0x0) fd_no=8 called
 7(9085) DEBUG: <core> [tcp_main.c:4298]: tcp: DBG: sending to child, events 1
 7(9085) DEBUG: <core> [tcp_main.c:3969]: selected tcp worker 0 0(0) for activity on [tls:XX.XX.XX.XX:5061], 0x7f9d869656a8
 7(9085) : <core> [tcp_main.c:3578]: BUG: handle_ser_child: fd -1 for 0 (pid 9078)
 7(9085) : <core> [pass_fd.c:103]: ERROR: recv_all: 1st recv on 56 failed: Bad file descriptor
 7(9085) : <core> [tcp_main.c:3363]: ERROR: handle_tcp_child: read from tcp child 0  (pid 0, no 0) Bad file descriptor [9]
 7(9085) : <core> [pass_fd.c:209]: ERROR: send_fd: sendmsg failed sending 16 on 56: Bad file descriptor (9)
 7(9085) ERROR: <core> [tcp_main.c:4006]: ERROR: send2child: send_fd failed for 0x7f9d869656a8 (flags 0x4018), fd 16
 7(9085) DEBUG: tls [tls_server.c:601]: Closing SSL connection (nil)