root@server:~# kamailio -f /usr/local/etc/kamailio/kamailio-pcscf.cfg
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: DISPATCHER_LIST_IMS
 0(1786) DEBUG: <core> [cfg.lex:1830]: pp_define_set(): ### setting define ID [DISPATCHER_LIST_IMS] value ["1"]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1678]: yyparse(): loading modules under /usr/local/lib64/kamailio/modules/
loading modules under config path: /usr/local/lib64/kamailio/modules/
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module tm
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/tm.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_tm
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module tmx
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/tmx.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: tmx
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_tmx
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module pv
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/pv.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: pv
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_pv
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module sl
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/sl.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_sl
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module rr
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/rr.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: rr
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_rr
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module dialog_ng
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/dialog_ng.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_dialog_ng
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module ims_usrloc_pcscf
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_ims_usrloc_pcscf
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module textops
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/textops.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_textops
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module textopsx
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/textopsx.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_textopsx
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module maxfwd
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/maxfwd.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_maxfwd
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module xlog
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/xlog.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: xlog
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_xlog
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module ims_registrar_pcscf
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: ims_registrar_pcscf
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_ims_registrar_pcscf
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module sanity
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/sanity.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_sanity
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module siputils
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/siputils.so>
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_siputils
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module kex
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/kex.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: kex
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_kex
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module sdpops.so
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/sdpops.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: sdpops
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_sdpops
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module db_mysql
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/db_mysql.so>
 0(1786) DEBUG: <core> [db_query.c:403]: db_query_init(): About to allocate sql_buf size = 65535
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_db_mysql
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module htable
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/htable.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: htable
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_htable
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.y:1659]: yyparse(): loading module nathelper
 0(1786) DEBUG: <core> [sr_module.c:569]: load_module(): trying to load </usr/local/lib64/kamailio/modules/nathelper.so>
 0(1786) DEBUG: <core> [sr_module.c:334]: register_module(): register PV from: nathelper
 0(1786) DEBUG: <core> [cfg.lex:1740]: pp_define(): defining id: MOD_nathelper
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [mem/shm_mem.c:210]: shm_mem_init(): shm_mem_init: 4096 bytes/page
 0(1786) DEBUG: <core> [mem/q_malloc.c:184]: qm_malloc_init(): qm_malloc_init: QM_OPTIMIZE=16384, /ROUNDTO=2048
 0(1786) DEBUG: <core> [mem/q_malloc.c:186]: qm_malloc_init(): qm_malloc_init: QM_HASH_SIZE=2099, qm_block size=218360
 0(1786) DEBUG: <core> [mem/q_malloc.c:187]: qm_malloc_init(): qm_malloc_init(0x7f2f3f7b6000, 67108864), start=0x7f2f3f7b6000
 0(1786) DEBUG: <core> [mem/q_malloc.c:195]: qm_malloc_init(): qm_malloc_init: size= 67108864, init_overhead=218456
 0(1786) DEBUG: <core> [mem/shm_mem.c:193]: shm_mem_init_mallocs(): shm_mem_init: success
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'tm' matches module 'tm'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <failure_reply_mode> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <failure_reply_mode> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'tm' matches module 'tm'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <fr_timer> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <fr_timer> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'tm' matches module 'tm'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <fr_inv_timer> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <fr_inv_timer> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'tm' matches module 'tm'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <auto_inv_100> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <auto_inv_100> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'rr' matches module 'rr'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <enable_full_lr> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <enable_full_lr> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'rr' matches module 'rr'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <append_fromtag> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <append_fromtag> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'rr' matches module 'rr'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <add_username> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <add_username> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'rr' matches module 'rr'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <custom_user_avp> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <custom_user_avp> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [DB_URL] value ["mysql://pcscf:heslo@server.net1.test/pcscf"]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_usrloc_pcscf' matches module 'ims_usrloc_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <db_url> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <db_url> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_usrloc_pcscf' matches module 'ims_usrloc_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <db_mode> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <db_mode> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_usrloc_pcscf' matches module 'ims_usrloc_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <hashing_type> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <hashing_type> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_usrloc_pcscf' matches module 'ims_usrloc_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <lookup_check_received> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <lookup_check_received> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_usrloc_pcscf' matches module 'ims_usrloc_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <enable_debug_file> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <enable_debug_file> in module ims_usrloc_pcscf [/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_registrar_pcscf' matches module 'ims_registrar_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <is_registered_fallback2ip> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <is_registered_fallback2ip> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_registrar_pcscf' matches module 'ims_registrar_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <subscribe_to_reginfo> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <subscribe_to_reginfo> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'ims_registrar_pcscf' matches module 'ims_registrar_pcscf'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <publish_reginfo> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <publish_reginfo> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [FLT_DIALOG] value [2]
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'dialog_ng' matches module 'dialog_ng'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <dlg_flag> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <dlg_flag> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'dialog_ng' matches module 'dialog_ng'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <timeout_avp> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <timeout_avp> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'dialog_ng' matches module 'dialog_ng'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <detect_spirals> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <detect_spirals> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [modparam.c:81]: set_mod_param_regex(): 'dialog_ng' matches module 'dialog_ng'
 0(1786) DEBUG: <core> [sr_module.c:785]: find_param_export(): found <profiles_no_value> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [modparam.c:97]: set_mod_param_regex(): found <profiles_no_value> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <xlog> in module xlog [/usr/local/lib64/kamailio/modules/xlog.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <xlog> in module xlog [/usr/local/lib64/kamailio/modules/xlog.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f4888a9d8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f4888adc0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [FLT_DIALOG] value [2]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x2
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4888b578
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f4888b2f0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <has_totag> in module siputils [/usr/local/lib64/kamailio/modules/siputils.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <loose_route> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:268]: pv_cache_add(): PV cache not initialized, doing it now
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$route_uri] added in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [FLT_MO] value [5]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x5
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4888e1f0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f4888e918
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <isdsturiset> in module kex [/usr/local/lib64/kamailio/modules/kex.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <handle_ruri_alias> in module nathelper [/usr/local/lib64/kamailio/modules/nathelper.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4888f4f8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f4888fd00
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f4888ff90
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_check_trans> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488910a0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488918a8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f48891b38
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488906e8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48891c80
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <sl_send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4888c960
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f4888ea60
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f48891ea8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4888c010
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48892288
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_check_trans> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f48893948
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48892e70
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f48893220
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48892fb8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_check_trans> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f48895038
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f48894830
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48895118
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f48896e00
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488966d8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48896ee0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x4
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488975c0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48897e30
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x4
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488988c8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f48899168
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar [$route_uri] found in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f48899968
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f4889c150
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4889b8e0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f4889c008
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f4889c230
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <mf_process_maxfwd_header> in module maxfwd [/usr/local/lib64/kamailio/modules/maxfwd.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <sl_send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4889d840
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f4889e048
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <sanity_check> in module sanity [/usr/local/lib64/kamailio/modules/sanity.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <xlog> in module xlog [/usr/local/lib64/kamailio/modules/xlog.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f4889eb48
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f4889fb50
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <has_totag> in module siputils [/usr/local/lib64/kamailio/modules/siputils.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [select.c:111]: w_parse_select(): part 0: cfg_get
 0(1786) DEBUG: <core> [select.c:111]: w_parse_select(): part 1: system
 0(1786) DEBUG: <core> [select.c:111]: w_parse_select(): part 2: shutdownmode
 0(1786) DEBUG: <core> [select.c:158]: w_parse_select(): end, total elements: 3, calling resolve_select
 0(1786) DEBUG: <core> [select.c:263]: resolve_select(): 'cfg_get'
 0(1786) DEBUG: <core> [select.c:263]: resolve_select(): 'shutdownmode'
 0(1786) DEBUG: <core> [cfg/cfg_select.c:174]: select_cfg_var(): DEBUG: select_cfg_var(): select fixup is postponed: system.shutdownmode
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$sel(cfg_get.system.shutdownmode)] added in cache
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488a2db0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488a35b8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=0
 0(1786) DEBUG: <core> [re.c:457]: subst_run(): matched (4, 11): [NETWORKNAME]
 0(1786) DEBUG: <core> [ppcfg.c:193]: pp_subst_run(): preprocess subst applied [#1] to [sip:NETWORKNAME] - returning new string [sip:net1.test]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <options_reply> in module siputils [/usr/local/lib64/kamailio/modules/siputils.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488a67a8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488a6fb0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_lookup_request> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488a75b0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488a7cd8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488a8270
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488a8a78
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d80)[REQINIT] to 1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d80)[NAT] to 2
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d80)[RTPPROXY] to 3
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f488a8fa8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488aa360
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488a9088
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d00)[RTPPROXY_reply] to 1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_present_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_save_pending> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488ab628
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488abe30
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_on_reply> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_on_failure> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$var(ws_transport)] added in cache
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7f2f488accf8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7f2f488acff0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=0
 0(1786) DEBUG: <core> [re.c:457]: subst_run(): matched (16, 8): [HOSTNAME]
 0(1786) DEBUG: <core> [ppcfg.c:193]: pp_subst_run(): preprocess subst applied [#1] to [Path: <sip:term@HOSTNAME:] - returning new string [Path: <sip:term@pcscf.net1.test:]
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [PORT] value [4060]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_present_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$var(new_hdr)] added in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=0
 0(1786) DEBUG: <core> [re.c:457]: subst_run(): matched (0, 11): [NETWORKNAME]
 0(1786) DEBUG: <core> [ppcfg.c:193]: pp_subst_run(): preprocess subst applied [#1] to [NETWORKNAME, ] - returning new string [net1.test, ]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$hdr(P-Visited-Network-ID)] added in cache
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7f2f488afcc8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7f2f488b0fd8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=0
 0(1786) DEBUG: <core> [re.c:457]: subst_run(): matched (22, 11): [NETWORKNAME]
 0(1786) DEBUG: <core> [ppcfg.c:193]: pp_subst_run(): preprocess subst applied [#1] to [P-Visited-Network-ID: NETWORKNAME
] - returning new string [P-Visited-Network-ID: net1.test
]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488ae9e8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488ae428
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f488b1700
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d80)[REGISTER] to 4
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_check_status> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_save> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488b2550
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488b2c78
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d00)[REGISTER_reply] to 2
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_check_status> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488b36a8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488b3eb0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d40)[REGISTER_failure] to 1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <loose_route> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_is_registered> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x4
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488b5808
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488b4be0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <remove_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <remove_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_present_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_assert_identity> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_present_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_assert_identity> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_assert_identity> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488bd8b0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488bce90
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f488bd448
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488bb950
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488bc7a0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f488bdfd8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488b9cb0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488b8d80
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f488be120
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_follows_service_routes> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <pcscf_force_service_routes> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488bee28
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488bf550
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_present_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: pv [pv_core.c:2820]: pv_parse_hdr_name(): using hdr type (17) instead of <Require>
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$hdr(Require)] added in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <remove_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar [$var(new_hdr)] found in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: pv [pv_core.c:2820]: pv_parse_hdr_name(): using hdr type (17) instead of <Require>
 0(1786) DEBUG: <core> [re.c:313]: subst_parser(): ok, se is 0x7f2f488c3a00
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$(hdr(Require){re.subst,/[, ]*sec-agree//gi})] added in cache
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7f2f488c35e0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7f2f488c3af0
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$(var(new_hdr){s.len})] added in cache
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488c5410
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488c5c18
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488c22d8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488c2ae0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <is_present_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: pv [pv_core.c:2820]: pv_parse_hdr_name(): using hdr type (18) instead of <Proxy-Require>
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$hdr(Proxy-Require)] added in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <remove_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar [$var(new_hdr)] found in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: pv [pv_core.c:2820]: pv_parse_hdr_name(): using hdr type (18) instead of <Proxy-Require>
 0(1786) DEBUG: <core> [re.c:313]: subst_parser(): ok, se is 0x7f2f488ca340
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$(hdr(Proxy-Require){re.subst,/[, ]*sec-agree//gi})] added in cache
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7f2f488c9f18
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7f2f488ca430
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar [$(var(new_hdr){s.len})] found in cache
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488cbbd0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488cc3d8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488c8c70
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488c9478
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <remove_hf> in module textops [/usr/local/lib64/kamailio/modules/textops.so]
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [FLT_MO] value [5]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x5
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f488cd550
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [usr_avp.c:882]: parse_avp_ident(): Parsing 'RR_CUSTOM_USER_AVP'
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$avp(RR_CUSTOM_USER_AVP)] added in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7f2f488cd950
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7f2f488cdab8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <record_route> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <set_dlg_profile> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_on_reply> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x4
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d80)[Orig_Initial] to 5
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f488cf478
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488cfea0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488cf558
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d00)[Orig_Initial_reply] to 3
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <loose_route> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar [$avp(RR_CUSTOM_USER_AVP)] found in cache
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7f2f488d0b88
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7f2f488d0cf0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <record_route> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <set_dlg_profile> in module dialog_ng [/usr/local/lib64/kamailio/modules/dialog_ng.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_on_failure> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_on_reply> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <isdsturiset> in module kex [/usr/local/lib64/kamailio/modules/kex.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <handle_ruri_alias> in module nathelper [/usr/local/lib64/kamailio/modules/nathelper.so]
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$rc] added in cache
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [FLT_NAT] value [3]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x3
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488d4650
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488d4d78
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488d30d8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488d26e8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [cfg.lex:1849]: pp_define_get(): ### returning define ID [FLT_MO] value [5]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#40 #0/1: 3(3)/ 0x5
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f488d5298
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d80)[Term_Initial] to 6
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7f2f488d6818
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488d60f0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488d68f8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d00)[Term_Initial_reply] to 4
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_is_canceled> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488d6fc0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488d76e8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_check_status> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488d7c80
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488d8488
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 0(0)/ (nil)
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d40)[Term_Initial_failure] to 2
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <xlog> in module xlog [/usr/local/lib64/kamailio/modules/xlog.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <reginfo_handle_notify> in module ims_registrar_pcscf [/usr/local/lib64/kamailio/modules/ims_registrar_pcscf.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/local/lib64/kamailio/modules/sl.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x4
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [re.c:436]: subst_run(): running. r=1
 0(1786) DEBUG: <core> [re.c:505]: subst_str(): no match
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_reply> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #0/2: 3(3)/ (nil)
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x4
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7f2f488d9de0
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7f2f488d96b8
 0(1786) DEBUG: <core> [route_struct.c:129]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7f2f488dac30
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d80)[NOTIFY] to 7
 0(1786) DEBUG: <core> [ppcfg.c:224]: pp_ifdef_level_check(): same number of pairing preprocessor directives #!IF[N]DEF - #!ENDIF
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 0:
 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48883668> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "L_INFO" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48883630> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "$rm ($fu ($si:$sp) to $tu, $ci)
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "REQINIT" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "NAT" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:368]: print_action(): setflag( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 2 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:368]: print_action(): setflag( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 5 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48887588> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "RTPPROXY" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877780> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877780> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f488802d8> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "404" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877780> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48878158> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "REGISTER" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "NOTIFY" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "403" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 0 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 4 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "403" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 0 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 4 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "Term_Initial" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "Orig_Initial" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 1:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f488802d8> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "483" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48883630> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Malformed SIP message from $si:$sp
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "503" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f488847e8> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "100" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 2:
 0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 3:
 0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 4:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48883c90> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "location" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877b00> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "REGISTER_reply" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877a90> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "REGISTER_failure" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:442]: print_action(): assign( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<21> 0(1786) DEBUG: <core> [route_struct.c:517]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Path: <sip:term@pcscf.net1.test:4060;$var(ws_transport)lr>
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Supported: path
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Require: path
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:442]: print_action(): assign( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<21> 0(1786) DEBUG: <core> [route_struct.c:517]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "P-Visited-Network-ID: $var(new_hdr)
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "P-Visited-Network-ID: net1.test
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877780> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 5:
 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f488808e0> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "403" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 0 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 4 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48882028> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:543]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48882028> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:543]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "P-Asserted-Identity: $hdr(P-Preferred-Identity)
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "P-Asserted-Identity: $hdr(P-Asserted-Identity)
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "P-Asserted-Identity: <$fu>
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "P-Asserted-Identity: <$pcscf_asserted_identity>
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48883d00> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "location" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48882028> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:543]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:442]: print_action(): assign( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<21> 0(1786) DEBUG: <core> [route_struct.c:517]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Require: $var(new_hdr)
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48882028> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:543]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:442]: print_action(): assign( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<21> 0(1786) DEBUG: <core> [route_struct.c:517]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881f10> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Proxy-Require: $var(new_hdr)
" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48882028> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:543]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:368]: print_action(): setflag( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 5 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "RTPPROXY" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:442]: print_action(): assign( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<21> 0(1786) DEBUG: <core> [route_struct.c:517]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880918> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881188> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "orig" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877b00> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Orig_Initial_reply" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877780> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 0 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 4 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 6:
 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f488808e0> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:442]: print_action(): assign( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<21> 0(1786) DEBUG: <core> [route_struct.c:517]: print_action(): , type<22> 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880918> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48881188> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "term" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877a90> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Term_Initial_failure" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877b00> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "Term_Initial_reply" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48887588> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:368]: print_action(): setflag( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 3 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:371]: print_action(): resetflag( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 5 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "RTPPROXY" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877780> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 0 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl():  routing table 7:
 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48883668> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "L_DBG" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "200" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 0 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 4 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48877358> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "500" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 0 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 4 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl(): onreply routing table 1:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "RTPPROXY" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl(): onreply routing table 2:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48883c58> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "location" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl(): onreply routing table 3:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "RTPPROXY" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl(): onreply routing table 4:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:347]: print_action(): route( 0(1786) DEBUG: <core> [route_struct.c:452]: print_action(): "RTPPROXY" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl(): failure routing table 1:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "504" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:2132]: print_rl(): failure routing table 2:
 0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route_struct.c:401]: print_action(): if ( 0(1786) DEBUG: <core> [route_struct.c:483]: print_action(): type<22> 0(1786) DEBUG: <core> [route_struct.c:485]: print_action(): ) { 0(1786) DEBUG: <core> [route_struct.c:418]: print_action():  external_module_call( 0(1786) DEBUG: <core> [route_struct.c:467]: print_action(): f_ptr<0x7f2f48880348> 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 2 0(1786) DEBUG: <core> [route_struct.c:524]: print_action(): , "404" 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:338]: print_action(): drop( 0(1786) DEBUG: <core> [route_struct.c:455]: print_action(): 1 0(1786) DEBUG: <core> [route_struct.c:493]: print_action(): , 1 0(1786) DEBUG: <core> [route_struct.c:546]: print_action(): );  0(1786) DEBUG: <core> [route_struct.c:519]: print_action(): } else { 0(1786) DEBUG: <core> [route_struct.c:545]: print_action(): };  0(1786) DEBUG: <core> [route.c:2134]: print_rl():
 0(1786) DEBUG: <core> [route.c:129]: route_add(): mapping routing block (0xa88d60)[core:receive-parse-error] to 1
 0(1786) INFO: <core> [sctp_core.c:75]: sctp_core_check_support(): SCTP API not enabled - if you want to use it, load sctp module
Listening on
             udp: pcscf.net1.test [10.200.8.175]:4060
Aliases:
             *: pcscf.net1.test:*

WARNING: no fork mode
 0(1786) DEBUG: <core> [cfg/cfg.c:176]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'core' (num=50, size=200)
 0(1786) DEBUG: <core> [cfg/cfg.c:176]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'tcp' (num=26, size=104)
 0(1786) DEBUG: <core> [timer.c:255]: init_timer(): starting with *ticks=1073309636
 0(1786) DEBUG: <core> [timer.c:297]: init_timer(): timer_list between 0x7f2f3f7eb908 and 0x7f2f3f82f908
 0(1786) DEBUG: <core> [daemonize.c:583]: set_core_dump(): core dump limits set to 18446744073709551615
 0(1786) DEBUG: <core> [async_task.c:88]: async_task_init(): start initializing asynk task framework
 0(1786) DEBUG: <core> [sr_module.c:959]: init_mod(): tm
 0(1786) DEBUG: tm [tm.c:756]: mod_init(): TM - (sizeof cell=488, sip_msg=1768) initializing...
 0(1786) DEBUG: tm [callid.c:101]: init_callid(): Call-ID initialization: '33fecdce43447b1a'
 0(1786) DEBUG: tm [lock.c:74]: lock_initialize(): DEBUG: lock_initialize: lock initialization started
 0(1786) DEBUG: tm [timer.c:199]: tm_init_timers(): tm: tm_init_timers: fr=160 fr_inv=1920 wait=80 delete=4 t1=500 t2=4000 max_inv_lifetime=2880 max_noninv_lifetime=512
 0(1786) DEBUG: <core> [cfg/cfg.c:176]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'tm' (num=36, size=168)
 0(1786) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 66b1efce7231ddd768c5d77a405d510a
 0(1786) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: bc3e12c508467e6a701efbd89bebab0f
 0(1786) DEBUG: <core> [sr_module.c:959]: init_mod(): tmx
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <load_tm> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_newtran> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay_to_udp> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_forward_nonack> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_release> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:959]: init_mod(): pv
 0(1786) DEBUG: <core> [sr_module.c:959]: init_mod(): sl
 0(1786) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated: 0664f5bfd97dfb4863c26993bf5240ef
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <load_tm> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_newtran> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay_to_udp> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_forward_nonack> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_release> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:959]: init_mod(): rr
 0(1786) DEBUG: <core> [sr_module.c:695]: find_mod_export_record(): find_export_record: <bind_ob> not found
 0(1786) INFO: rr [../outbound/api.h:54]: ob_load_api(): Failed to import bind_ob
 0(1786) INFO: rr [rr_mod.c:174]: mod_init(): outbound module not available
 0(1786) DEBUG: <core> [usr_avp.c:882]: parse_avp_ident(): Parsing 'RR_CUSTOM_USER_AVP'
 0(1786) DEBUG: <core> [sr_module.c:959]: init_mod(): dialog_ng
 0(1786) DEBUG: <core> [parser/msg_parser.c:606]: parse_msg(): SIP Request:
 0(1786) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg():  method:  <OPTIONS>
 0(1786) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():  uri:     <sip:you@kamailio.org>
 0(1786) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  version: <SIP/2.0>
 0(1786) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of header reached, state=2
 0(1786) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers(): parse_headers: Via found, flags=2
 0(1786) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers(): parse_headers: this is the first via
 0(1786) DEBUG: <core> [usr_avp.c:882]: parse_avp_ident(): Parsing 'DLG_TIMEOUT_AVP'
 0(1786) DEBUG: <core> [pvapi.c:419]: pv_spec_lookup(): PV <$ru> is not in cache
 0(1786) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar [$ru] added in cache
 0(1786) DEBUG: dialog_ng [dlg_profile.c:128]: add_profile_definitions(): creating profile <orig>
 0(1786) DEBUG: dialog_ng [dlg_profile.c:128]: add_profile_definitions(): creating profile <term>
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <load_tm> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_newtran> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay_to_udp> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_forward_nonack> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <t_release> in module tm [/usr/local/lib64/kamailio/modules/tm.so]
 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <load_rr> in module rr [/usr/local/lib64/kamailio/modules/rr.so]
 0(1786) DEBUG: <core> [sr_module.c:959]: init_mod(): ims_usrloc_pcscf
 0(1786) INFO: ims_usrloc_pcscf [hslot.c:62]: ul_init_locks(): locks array size 512
 0(1786) DEBUG: ims_usrloc_pcscf [ul_mod.c:186]: mod_init(): Registering cache timer 0(1786) DEBUG: <core> [sr_module.c:689]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_mysql [/usr/local/lib64/kamailio/modules/db_mysql.so]
 0(1786) DEBUG: <core> [db.c:209]: db_bind_mod(): using db bind api for db_mysql
 0(1786) DEBUG: <core> [db.c:314]: db_do_init2(): connection 0x7f2f488e81b8 not found in pool
 0(1786) DEBUG: db_mysql [km_my_con.c:98]: db_mysql_new_connection(): opening connection: mysql://xxxx:xxxx@server.net1.test/pcscf
 0(1786) ERROR: db_mysql [km_my_con.c:124]: db_mysql_new_connection(): driver error: Access denied for user 'pcscf'@'pcscf.net1.test' (using password: YES)
 0(1786) ERROR: <core> [db.c:318]: db_do_init2(): could not add connection to the pool 0(1786) ERROR: ims_usrloc_pcscf [usrloc_db.c:79]: init_db(): unable to connect to the database
 0(1786) ERROR: ims_usrloc_pcscf [ul_mod.c:203]: mod_init(): Error initializing db connection
 0(1786) ERROR: <core> [sr_module.c:962]: init_mod(): Error while initializing module ims_usrloc_pcscf (/usr/local/lib64/kamailio/modules/ims_usrloc_pcscf.so)
ERROR: error while initializing modules
 0(1786) DEBUG: tm [t_funcs.c:86]: tm_shutdown(): DEBUG: tm_shutdown : start
 0(1786) DEBUG: tm [t_funcs.c:89]: tm_shutdown(): DEBUG: tm_shutdown : emptying hash table
 0(1786) DEBUG: tm [t_funcs.c:91]: tm_shutdown(): DEBUG: tm_shutdown : removing semaphores
 0(1786) DEBUG: tm [t_funcs.c:93]: tm_shutdown(): DEBUG: tm_shutdown : destroying tmcb lists
 0(1786) DEBUG: tm [t_funcs.c:96]: tm_shutdown(): DEBUG: tm_shutdown : done
 0(1786) INFO: <core> [sctp_core.c:53]: sctp_core_destroy(): SCTP API not initialized
 0(1786) DEBUG: <core> [mem/shm_mem.c:232]: shm_mem_destroy(): shm_mem_destroy
 0(1786) DEBUG: <core> [mem/shm_mem.c:235]: shm_mem_destroy(): destroying the shared memory lock