[root@authdemos2 rsyslog.d]# kamailio -E -ddd
 0(9289) DEBUG: <core> [mem/q_malloc.c:202]: qm_malloc_init(): qm_malloc_init: QM_OPTIMIZE=16384, /ROUNDTO=1024
 0(9289) DEBUG: <core> [mem/q_malloc.c:204]: qm_malloc_init(): qm_malloc_init: QM_HASH_SIZE=1075, qm_block size=43048
 0(9289) DEBUG: <core> [mem/q_malloc.c:205]: qm_malloc_init(): qm_malloc_init(0x7fe11c85c010, 4194304), start=0x7fe11c85c010
 0(9289) DEBUG: <core> [mem/q_malloc.c:213]: qm_malloc_init(): qm_malloc_init: size= 4194304, init_overhead=43088
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[0] to 0
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927e20)[0] to 0
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927d80)[0] to 0
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927dc0)[0] to 0
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927da0)[0] to 0
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927e00)[0] to 0
 0(9289) DEBUG: <core> [pvapi.c:1898]: pv_init_buffer(): PV print buffer initialized to [10][1024]
 0(9289) DEBUG: <core> [main.c:2094]: main(): read 2780373734 from /dev/urandom
 0(9289) DEBUG: <core> [main.c:2100]: main(): seeding PRNG with 4223227403
 0(9289) DEBUG: <core> [main.c:2104]: main(): test random numbers 994390919 897691387 3854810294
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: WITH_DEBUG
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: WITH_AUTH
 0(9289) DEBUG: <core> [cfg.lex:1644]: sr_push_yy_state(): importing file ignored: kamailio-local.cfg (//etc/kamailio/kamailio-local.cfg)
 0(9289) DEBUG: <core> [cfg.lex:1648]: sr_push_yy_state(): including file: comsys-kamailiodb.cfg (//etc/kamailio/comsys-kamailiodb.cfg)
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: WITH_USRLOCDB
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: DBURL
 0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [DBURL] value ["postgres://kamailio:!frqkam@kamailioha/kamailio"]
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: MULTIDOMAIN
]0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [MULTIDOMAIN] value [0
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: FLT_ACC
]0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [FLT_ACC] value [1
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: FLT_ACCMISSED
]0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [FLT_ACCMISSED] value [2
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: FLT_ACCFAILED
]0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [FLT_ACCFAILED] value [3
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: FLT_NATS
]0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [FLT_NATS] value [5
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: FLB_NATB
]0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [FLB_NATB] value [6
 0(9289) DEBUG: <core> [cfg.lex:1770]: pp_define(): defining id: FLB_NATSIPPING
]0(9289) DEBUG: <core> [cfg.lex:1860]: pp_define_set(): ### setting define ID [FLB_NATSIPPING] value [7
 0(9289) DEBUG: <core> [cfg.y:1804]: yyparse(): loading modules under /usr/local/lib/kamailio/modules_k/:/usr/lib64/kamailio/modules/
loading modules under /usr/local/lib/kamailio/modules_k/:/usr/lib64/kamailio/modules/
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module db_postgres.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/db_postgres.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/db_postgres/db_postgres.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/db_postgres.so>
 0(9289) DEBUG: <core> [db_query.c:400]: db_query_init(): About to allocate sql_buf size = 65535
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module mi_fifo.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/mi_fifo.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/mi_fifo/mi_fifo.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/mi_fifo.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module kex.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/kex.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/kex/kex.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/kex.so>
 0(9289) DEBUG: <core> [sr_module.c:343]: register_module(): register PV from: kex
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module corex.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/corex.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/corex/corex.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/corex.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module tm.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/tm.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/tm/tm.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/tm.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module tmx.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/tmx.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/tmx/tmx.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/tmx.so>
 0(9289) DEBUG: <core> [sr_module.c:343]: register_module(): register PV from: tmx
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module sl.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/sl.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/sl/sl.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/sl.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module rr.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/rr.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/rr/rr.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/rr.so>
 0(9289) DEBUG: <core> [sr_module.c:343]: register_module(): register PV from: rr
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module pv.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/pv.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/pv/pv.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/pv.so>
 0(9289) DEBUG: <core> [sr_module.c:343]: register_module(): register PV from: pv
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module maxfwd.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/maxfwd.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/maxfwd/maxfwd.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/maxfwd.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module usrloc.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/usrloc.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/usrloc/usrloc.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/usrloc.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module registrar.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/registrar.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/registrar/registrar.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/registrar.so>
 0(9289) DEBUG: <core> [sr_module.c:343]: register_module(): register PV from: registrar
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module textops.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/textops.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/textops/textops.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/textops.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module siputils.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/siputils.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/siputils/siputils.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/siputils.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module xlog.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/xlog.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/xlog/xlog.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/xlog.so>
 0(9289) DEBUG: <core> [sr_module.c:343]: register_module(): register PV from: xlog
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module sanity.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/sanity.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/sanity/sanity.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/sanity.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module ctl.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/ctl.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/ctl/ctl.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/ctl.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module cfg_rpc.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/cfg_rpc.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/cfg_rpc/cfg_rpc.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/cfg_rpc.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module mi_rpc.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/mi_rpc.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/mi_rpc/mi_rpc.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/mi_rpc.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module acc.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/acc.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/acc/acc.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/acc.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module auth.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/auth.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/auth/auth.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/auth.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module auth_radius.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/auth_radius.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/auth_radius/auth_radius.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/auth_radius.so>
 0(9289) DEBUG: <core> [cfg.y:1791]: yyparse(): loading module debugger.so
 0(9289) DEBUG: <core> [sr_module.c:499]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/debugger.so>
 0(9289) DEBUG: <core> [sr_module.c:522]: load_module(): load_module: module file not found </usr/local/lib/kamailio/modules_k/debugger/debugger.so>
 0(9289) DEBUG: <core> [sr_module.c:564]: load_module(): load_module: trying to load </usr/lib64/kamailio/modules/debugger.so>
 0(9289) DEBUG: <core> [mem/q_malloc.c:202]: qm_malloc_init(): qm_malloc_init: QM_OPTIMIZE=16384, /ROUNDTO=1024
 0(9289) DEBUG: <core> [mem/q_malloc.c:204]: qm_malloc_init(): qm_malloc_init: QM_HASH_SIZE=1075, qm_block size=43048
 0(9289) DEBUG: <core> [mem/q_malloc.c:205]: qm_malloc_init(): qm_malloc_init(0x7fe1164aa000, 33554432), start=0x7fe1164aa000
 0(9289) DEBUG: <core> [mem/q_malloc.c:213]: qm_malloc_init(): qm_malloc_init: size= 33554432, init_overhead=43088
 0(9289) DEBUG: <core> [mem/shm_mem.c:203]: shm_mem_init_mallocs(): shm_mem_init: success
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'mi_fifo' matches module 'mi_fifo'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <fifo_name> in module mi_fifo [/usr/lib64/kamailio/modules/mi_fifo.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <fifo_name> in module mi_fifo [/usr/lib64/kamailio/modules/mi_fifo.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'tm' matches module 'tm'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <failure_reply_mode> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <failure_reply_mode> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'tm' matches module 'tm'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <fr_timer> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <fr_timer> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'tm' matches module 'tm'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <fr_inv_timer> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <fr_inv_timer> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'rr' matches module 'rr'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <enable_full_lr> in module rr [/usr/lib64/kamailio/modules/rr.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <enable_full_lr> in module rr [/usr/lib64/kamailio/modules/rr.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'rr' matches module 'rr'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <append_fromtag> in module rr [/usr/lib64/kamailio/modules/rr.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <append_fromtag> in module rr [/usr/lib64/kamailio/modules/rr.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'registrar' matches module 'registrar'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <method_filtering> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <method_filtering> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'registrar' matches module 'registrar'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <max_expires> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <max_expires> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'registrar' matches module 'registrar'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <gruu_enabled> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <gruu_enabled> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <early_media> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <early_media> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <report_ack> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <report_ack> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <report_cancels> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <report_cancels> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <detect_direction> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <detect_direction> in module acc [/usr/lib64/kamailio/modules/acc.so]
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_ACC] value [1
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <log_flag> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <log_flag> in module acc [/usr/lib64/kamailio/modules/acc.so]
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_ACCMISSED] value [2
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <log_missed_flag> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <log_missed_flag> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <log_extra> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <log_extra> in module acc [/usr/lib64/kamailio/modules/acc.so]
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_ACCFAILED] value [3
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'acc' matches module 'acc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <failed_transaction_flag> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <failed_transaction_flag> in module acc [/usr/lib64/kamailio/modules/acc.so]
 0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [DBURL] value ["postgres://kamailio:!frqkam@kamailioha/kamailio"]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <db_url> in module usrloc [/usr/lib64/kamailio/modules/usrloc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <db_url> in module usrloc [/usr/lib64/kamailio/modules/usrloc.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <db_mode> in module usrloc [/usr/lib64/kamailio/modules/usrloc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <db_mode> in module usrloc [/usr/lib64/kamailio/modules/usrloc.so]
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [MULTIDOMAIN] value [0
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <use_domain> in module usrloc [/usr/lib64/kamailio/modules/usrloc.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <use_domain> in module usrloc [/usr/lib64/kamailio/modules/usrloc.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'auth' matches module 'auth'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <secret> in module auth [/usr/lib64/kamailio/modules/auth.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <secret> in module auth [/usr/lib64/kamailio/modules/auth.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'auth_radius' matches module 'auth_radius'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <radius_config> in module auth_radius [/usr/lib64/kamailio/modules/auth_radius.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <radius_config> in module auth_radius [/usr/lib64/kamailio/modules/auth_radius.so]
 0(9289) DEBUG: <core> [modparam.c:96]: set_mod_param_regex(): set_mod_param_regex: 'debugger' matches module 'debugger'
 0(9289) DEBUG: <core> [sr_module.c:770]: find_param_export(): find_param_export: found <cfgtrace> in module debugger [/usr/lib64/kamailio/modules/debugger.so]
 0(9289) DEBUG: <core> [modparam.c:113]: set_mod_param_regex(): set_mod_param_regex: found <cfgtrace> in module debugger [/usr/lib64/kamailio/modules/debugger.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c87c490
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c87c630
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_check_trans> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c87d9a0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c87d2c0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c87da40
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c87ca10
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c87db40
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [cfg.y:2820]: yyparse(): parsed ambigous avp/pvar "$ru" to 2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sl_send_reply> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c87fdf0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c880570
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c880950
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_check_trans> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c880c90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <remove_hf> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <record_route> in module rr [/usr/lib64/kamailio/modules/rr.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c881c80
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c881450
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_ACC] value [1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8826a0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c882db0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c882fb0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c883150
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8832f0
 0(9289) DEBUG: <core> [cfg.y:2820]: yyparse(): parsed ambigous avp/pvar "$rU" to 2
 0(9289) DEBUG: <core> [cfg.y:2820]: yyparse(): parsed ambigous avp/pvar "$null" to 2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sl_send_reply> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c884410
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c884b90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8856b0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c885850
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_is_set> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_on_branch> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c886950
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c885220
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c885b90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8870d0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_is_set> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_on_reply> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c888670
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c888df0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8877e0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c888f90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_is_set> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_on_failure> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c88a530
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c88acb0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8896a0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c88ae50
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sl_reply_error> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c88bc40
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c88b290
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[RELAY] to 1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <mf_process_maxfwd_header> in module maxfwd [/usr/lib64/kamailio/modules/maxfwd.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sl_send_reply> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c88d510
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c88dc90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sanity_check> in module sanity [/usr/lib64/kamailio/modules/sanity.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <xlog> in module xlog [/usr/lib64/kamailio/modules/xlog.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c88de30
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c88ebc0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[REQINIT] to 2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <has_totag> in module siputils [/usr/lib64/kamailio/modules/siputils.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <loose_route> in module rr [/usr/lib64/kamailio/modules/rr.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8909e0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_ACC] value [1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x1
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_ACCFAILED] value [3
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x3
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c891ff0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <record_route> in module rr [/usr/lib64/kamailio/modules/rr.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8923d0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c892b80
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8918e0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c892090
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7fe11c892c80
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c890dc0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8914a0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7fe11c892d80
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c892f80
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c894880
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8941a0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c894920
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_check_trans> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c895df0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c895710
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c895e90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7fe11c896090
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c894e60
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c896190
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sl_send_reply> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c890300
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c890a80
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 8(8)/ 0x7fe11c894b20
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c88fc20
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c896d50
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[WITHINDLG] to 3
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <radius_www_authorize> in module auth_radius [/usr/lib64/kamailio/modules/auth_radius.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <www_challenge> in module auth [/usr/lib64/kamailio/modules/auth.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c898540
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c898cc0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_NATS] value [5
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#41 #0/1: 3(3)/ 0x5
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLB_NATB] value [6
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <setbflag> in module kex [/usr/lib64/kamailio/modules/kex.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c898e60
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c899980
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <save> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sl_reply_error> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c89abf0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c89b370
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c897490
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8996e0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8965d0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c89b670
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[REGISTRAR] to 4
 0(9289) DEBUG: <core> [usr_avp.c:895]: parse_avp_ident(): Parsing 'oexten'
 0(9289) DEBUG: <core> [cfg.y:2820]: yyparse(): parsed ambigous avp/pvar "$rU" to 2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7fe11c89ba90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7fe11c89bbb0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <lookup> in module registrar [/usr/lib64/kamailio/modules/registrar.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#63 #0/2: 21(15)/ 0x7fe11c89d4b0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#63 #1/2: 22(16)/ 0x7fe11c89d530
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c89dd10
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_newtran> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <send_reply> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#17 #0/2: 22(16)/ 0x7fe11c89e0f0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#17 #1/2: 24(18)/ 0x7fe11c89d430
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c89ccb0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c89dc10
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
]0(9289) DEBUG: <core> [cfg.lex:1879]: pp_define_get(): ### returning define ID [FLT_ACCMISSED] value [2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#39 #0/1: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8a06c0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8a1240
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8a1480
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[LOCATION] to 5
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8a21b0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8a2890
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [cfg.y:2820]: yyparse(): parsed ambigous avp/pvar "$rU" to 2
 0(9289) DEBUG: <core> [cfg.y:2820]: yyparse(): parsed ambigous avp/pvar "$null" to 2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <sl_send_reply> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8a4a10
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8a5190
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[PRESENCE] to 6
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <is_method> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8a6870
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8a6f50
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[AUTH] to 7
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[NATDETECT] to 8
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[NATMANAGE] to 9
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[DLGURI] to 10
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <append_hf> in module textops [/usr/lib64/kamailio/modules/textops.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8a88b0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8a7f90
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8a8710
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[SIPOUT] to 11
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[PSTN] to 12
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x2
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927de0)[TOVOICEMAIL] to 13
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <xdbg> in module xlog [/usr/lib64/kamailio/modules/xlog.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8a9340
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927dc0)[MANAGE_BRANCH] to 1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <xdbg> in module xlog [/usr/lib64/kamailio/modules/xlog.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8a98b0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8aa1a0
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8aa880
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927e20)[MANAGE_REPLY] to 1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#5 #0/1: 1(1)/ 0x7fe11c8aab20
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #0/2: 9(9)/ (nil)
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#24 #1/2: 3(3)/ (nil)
 0(9289) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_is_canceled> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #0/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#2 #1/2: 3(3)/ 0x1
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #0/3: 22(16)/ 0x7fe11c8aae60
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #1/3: 8(8)/ 0x7fe11c8ab540
 0(9289) DEBUG: <core> [route_struct.c:166]: mk_action(): ACTION_#16 #2/3: 0(0)/ 0x7fe100000000
 0(9289) DEBUG: <core> [route.c:164]: route_add(): mapping routing block (0x927d80)[MANAGE_FAILURE] to 1
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 0:
 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "REQINIT" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "NATDETECT" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "RELAY" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c870c90> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "200" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "WITHINDLG" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c86fb98> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 0 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "AUTH" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c8766d0> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:580]: print_action(): , type<22> 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c871138> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 0 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:405]: print_action(): setflag( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "SIPOUT" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "PRESENCE" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "REGISTRAR" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c870c90> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "484" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "PSTN" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "LOCATION" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 1:
 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c86f5b0> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "MANAGE_BRANCH" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c86f578> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "MANAGE_REPLY" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c86f540> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "MANAGE_FAILURE" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c870d38> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 0 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 2:
 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c870c90> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "483" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c8775d0> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "Malformed SIP message from $si:$sp
" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 3:
 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "DLGURI" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:405]: print_action(): setflag( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:405]: print_action(): setflag( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 3 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "NATMANAGE" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c871138> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 0 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "RELAY" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "PRESENCE" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "RELAY" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c870c90> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "404" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 4:
 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c879b28> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "sipauthdev.frequentis.frq" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c86e528> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:580]: print_action(): , type<22> 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c870d38> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 0 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 5:
 0(9289) DEBUG: <core> [route_struct.c:479]: print_action(): assign( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<21> 0(9289) DEBUG: <core> [route_struct.c:554]: print_action(): , type<22> 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:479]: print_action(): assign( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<21> 0(9289) DEBUG: <core> [route_struct.c:554]: print_action(): , type<22> 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "TOVOICEMAIL" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c86ee40> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 0 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:485]: print_action(): UNKNOWN( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:554]: print_action(): , type<24> 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:405]: print_action(): setflag( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "RELAY" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 6:
 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c870c90> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "404" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 7:
 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 8:
 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 9:
 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 10:
 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 11:
 0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c8765b8> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "P-hint: outbound
" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "RELAY" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 12:
 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl():  routing table 13:
 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 2 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl(): onreply routing table 1:
 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c877678> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "incoming reply
" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "NATMANAGE" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl(): failure routing table 1:
 0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "NATMANAGE" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:438]: print_action(): if ( 0(9289) DEBUG: <core> [route_struct.c:520]: print_action(): type<22> 0(9289) DEBUG: <core> [route_struct.c:522]: print_action(): ) { 0(9289) DEBUG: <core> [route_struct.c:375]: print_action(): drop( 0(9289) DEBUG: <core> [route_struct.c:492]: print_action(): 1 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:556]: print_action(): } else { 0(9289) DEBUG: <core> [route_struct.c:582]: print_action(): };  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
 0(9289) DEBUG: <core> [route.c:2206]: print_rl(): branch routing table 1:
 0(9289) DEBUG: <core> [route_struct.c:455]: print_action():  external_module_call( 0(9289) DEBUG: <core> [route_struct.c:504]: print_action(): f_ptr<0x7fe11c877678> 0(9289) DEBUG: <core> [route_struct.c:530]: print_action(): , 1 0(9289) DEBUG: <core> [route_struct.c:561]: print_action(): , "new branch [$T_branch_idx] to $ru
" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route_struct.c:384]: print_action(): route( 0(9289) DEBUG: <core> [route_struct.c:489]: print_action(): "NATMANAGE" 0(9289) DEBUG: <core> [route_struct.c:583]: print_action(): );  0(9289) DEBUG: <core> [route.c:2208]: print_rl():
Listening on
             udp: 10.17.3.34:5060
Aliases:
             udp: authdemos2:5060
             udp: authdemos2.sipauthdev.frequentis.frq:5060

 0(9289) DEBUG: <core> [cfg/cfg.c:178]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'core' (num=49, size=196)
 0(9289) DEBUG: <core> [cfg/cfg.c:178]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'tcp' (num=26, size=104)
 0(9289) DEBUG: <core> [timer.c:272]: init_timer(): init_timer: starting with *ticks=56195250
 0(9289) DEBUG: <core> [timer.c:314]: init_timer(): init_timer: timer_list between 0x7fe1164b4a20 and 0x7fe1164f8a20
 0(9291) DEBUG: <core> [daemonize.c:219]: enable_dumpable(): trying enable core dumping...
 0(9291) DEBUG: <core> [daemonize.c:237]: enable_dumpable(): core dumping is enabled now (1)...
 0(9291) DEBUG: <core> [daemonize.c:575]: set_core_dump(): core dump limits set to 18446744073709551615
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: db_postgres
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: mi_fifo
 0(9291) DEBUG: mi_fifo [mi_fifo.c:124]: mi_mod_init(): testing mi_fifo existance ...
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: kex
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: corex
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: tm
 0(9291) DEBUG: tm [tm.c:754]: mod_init(): TM - (sizeof cell=11736, sip_msg=1624) initializing...
 0(9291) DEBUG: tm [callid.c:105]: init_callid(): Call-ID initialization: '0f6c67533e80fb47'
 0(9291) DEBUG: tm [lock.c:87]: lock_initialize(): DEBUG: lock_initialize: lock initialization started
 0(9291) DEBUG: tm [timer.c:226]: tm_init_timers(): tm: tm_init_timers: fr=480 fr_inv=1920 wait=80 delete=4 t1=500 t2=4000 max_inv_lifetime=2880 max_noninv_lifetime=512
 0(9291) DEBUG: <core> [cfg/cfg.c:178]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'tm' (num=36, size=168)
 0(9291) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5 calculated: 642aeb470c587bddafa73c2f37115a7b
 0(9291) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5 calculated: ff994aee3c3e3ea2e0b6e2c772967475
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: tmx
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <load_tm> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_newtran> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay_to_tcp> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay_to_udp> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_forward_nonack> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_release> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: sl
 0(9291) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): DEBUG: MD5 calculated: d0ce600ce20baff976b6a0a9165e3282
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <load_tm> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_newtran> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay_to_tcp> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay_to_udp> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_forward_nonack> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_release> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: rr
 0(9291) DEBUG: <core> [sr_module.c:680]: find_mod_export_record(): find_export_record: <bind_ob> not found
 0(9291) INFO: rr [../outbound/api.h:49]: ob_load_api(): Failed to import bind_ob
 0(9291) INFO: rr [rr_mod.c:159]: mod_init(): outbound module not available
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: pv
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: maxfwd
 0(9291) DEBUG: <core> [cfg/cfg.c:178]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'maxfwd' (num=1, size=4)
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: usrloc
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-244b-] (0 / 19)
 0(9291) INFO: usrloc [hslot.c:53]: ul_init_locks(): locks array size 512
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <db_bind_api> in module db_postgres [/usr/lib64/kamailio/modules/db_postgres.so]
 0(9291) DEBUG: <core> [db.c:213]: db_bind_mod(): using db bind api for db_postgres
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: registrar
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <bind_sl> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9291) DEBUG: <core> [cfg/cfg.c:178]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'registrar' (num=10, size=56)
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <ul_bind_usrloc> in module usrloc [/usr/lib64/kamailio/modules/usrloc.so]
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: textops
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: siputils
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <bind_sl> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9291) DEBUG: <core> [usr_avp.c:895]: parse_avp_ident(): Parsing 's:rpid'
 0(9291) DEBUG: <core> [cfg/cfg.c:178]: cfg_declare(): DEBUG: register_cfg_def(): new config group has been registered: 'siputils' (num=1, size=4)
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: xlog
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: sanity
 0(9291) DEBUG: sanity [mod_sanity.c:103]: mod_init(): sanity initializing
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <bind_sl> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9291) DEBUG: sanity [mod_sanity.c:111]: mod_init(): parsing proxy requires string:
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: ctl
 0(9291) DEBUG: ctl [ctl.c:242]: mod_init(): listening on:
 0(9291) DEBUG: ctl [ctl.c:252]: mod_init():         [binrpc:unix stream] /tmp/kamailio_ctl
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: cfg_rpc
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: mi_rpc
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: acc
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <load_tm> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_newtran> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay_to_tcp> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay_to_udp> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_relay> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_forward_nonack> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <t_release> in module tm [/usr/lib64/kamailio/modules/tm.so]
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: auth
 0(9291) DEBUG: auth [auth_mod.c:278]: mod_init(): auth module - initializing
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <bind_sl> in module sl [/usr/lib64/kamailio/modules/sl.so]
 0(9291) DEBUG: <core> [usr_avp.c:895]: parse_avp_ident(): Parsing 'digest_challenge'
 0(9291) INFO: auth [auth_mod.c:350]: mod_init(): auth: qop set, but nonce-count (nc_enabled) support disabled
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: auth_radius
 0(9291) DEBUG: <core> [sr_module.c:674]: find_mod_export_record(): find_export_record: found <bind_auth_s> in module auth [/usr/lib64/kamailio/modules/auth.so]
 0(9291) DEBUG: <core> [sr_module.c:935]: init_mod(): DEBUG: init_mod: debugger
 0(9291) DEBUG: <core> [main.c:2539]: main(): Expect (at least) 9 SER processes in your process list
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [CANCEL/2]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_check_trans()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [OPTIONS/512]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sl_send_reply()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_check_trans()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing remove_hf()
 0(9291) DEBUG: textops [textops.c:1669]: hname_fixup(): using hdr type (9) instead of <Route>
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1746]: fixup_method(): using id for methods [INVITE,SUBSCRIBE/65]
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing record_route()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [INVITE/1]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sl_send_reply()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1746]: fixup_method(): using id for methods [INVITE,BYE,SUBSCRIBE,UPDATE/2121]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_is_set()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_on_branch()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1746]: fixup_method(): using id for methods [INVITE,SUBSCRIBE,UPDATE/2113]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_is_set()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_on_reply()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [INVITE/1]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_is_set()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_on_failure()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_relay()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sl_reply_error()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing mf_process_maxfwd_header()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sl_send_reply()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sanity_check()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing xlog()
 0(9291) DEBUG: <core> [pvapi.c:390]: pv_spec_lookup(): PV <$si> is not in cache
 0(9291) DEBUG: <core> [pvapi.c:269]: pv_cache_add(): PV cache not initialized, doing it now
 0(9291) DEBUG: <core> [pvapi.c:294]: pv_cache_add(): pvar [$si] added in cache
 0(9291) DEBUG: <core> [pvapi.c:390]: pv_spec_lookup(): PV <$sp> is not in cache
 0(9291) DEBUG: <core> [pvapi.c:294]: pv_cache_add(): pvar [$sp] added in cache
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing has_totag()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing loose_route()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [BYE/8]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [ACK/4]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [NOTIFY/128]
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing record_route()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [SUBSCRIBE/64]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 3
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [ACK/4]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_check_trans()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sl_send_reply()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 3
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [REGISTER/32]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing radius_www_authorize()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing www_challenge()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing setbflag()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing save()
 0(9291) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c899fe0 not found in pool
 0(9291) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c899fe0
 0(9291) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c89a130=pkg_malloc(80)
 0(9291) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 0(9291) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a5efe0)
 0(9291) DEBUG: db_postgres [km_val.c:163]: db_postgres_val2str(): PQescapeStringConn: in: 8 chars, out: 8 chars
 0(9291) DEBUG: db_postgres [km_dbase.c:230]: db_postgres_submit_query(): sending query ok: 0x7fe11c899fa0 (2) - [select table_version from version where table_name='location']
 0(9291) DEBUG: <core> [db_res.c:118]: db_new_result(): allocate 56 bytes for result set at 0x7fe11c89a1a0
 0(9291) DEBUG: db_postgres [km_dbase.c:509]: db_postgres_store_result(): 0x7fe11c899fa0 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x2a90c80)
 0(9291) DEBUG: db_postgres [km_res.c:108]: db_postgres_get_columns(): 1 columns returned from the query
 0(9291) DEBUG: <core> [db_res.c:155]: db_allocate_columns(): allocate 8 bytes for result names at 0x7fe11c89a200
 0(9291) DEBUG: <core> [db_res.c:165]: db_allocate_columns(): allocate 4 bytes for result types at 0x7fe11c89a230
 0(9291) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7fe11c89a260
 0(9291) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c89a260)[0]=[table_version]
 0(9291) DEBUG: db_postgres [km_res.c:140]: db_postgres_get_columns(): use DB1_INT result type
 0(9291) DEBUG: db_postgres [km_res.c:222]: db_postgres_convert_rows(): allocate for 1 columns 8 bytes in row buffer at 0x7fe11c89a290
 0(9291) DEBUG: <core> [db_res.c:184]: db_allocate_rows(): allocate 16 bytes for rows at 0x7fe11c89a2c0
 0(9291) DEBUG: db_postgres [km_res.c:243]: db_postgres_convert_rows(): PQgetvalue(0x7fe11c899fa0,0,0)=[6]
 0(9291) DEBUG: db_postgres [km_res.c:252]: db_postgres_convert_rows(): [0][0] Column[table_version]=[6]
 0(9291) DEBUG: <core> [db_row.c:119]: db_allocate_row(): allocate 32 bytes for row values at 0x7fe11c89a2f0
 0(9291) DEBUG: <core> [db_val.c:73]: db_str2val(): converting INT [6]
 0(9291) DEBUG: db_postgres [km_res.c:267]: db_postgres_convert_rows(): freeing row buffer at 0x7fe11c89a290
 0(9291) DEBUG: db_postgres [km_dbase.c:390]: db_postgres_free_query(): PQclear(0x2a90c80) result set
 0(9291) DEBUG: <core> [db_res.c:81]: db_free_columns(): freeing 1 columns
 0(9291) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[0] at 0x7fe11c89a260
 0(9291) DEBUG: <core> [db_res.c:94]: db_free_columns(): freeing result names at 0x7fe11c89a200
 0(9291) DEBUG: <core> [db_res.c:99]: db_free_columns(): freeing result types at 0x7fe11c89a230
 0(9291) DEBUG: <core> [db_res.c:54]: db_free_rows(): freeing 1 rows
 0(9291) DEBUG: <core> [db_row.c:97]: db_free_row(): freeing row values at 0x7fe11c89a2f0
 0(9291) DEBUG: <core> [db_res.c:62]: db_free_rows(): freeing rows at 0x7fe11c89a2c0
 0(9291) DEBUG: <core> [db_res.c:136]: db_free_result(): freeing result set at 0x7fe11c89a1a0
 0(9291) DEBUG: db_postgres [km_val.c:139]: db_postgres_val2str(): PQescapeStringConn: in: 10 chars, out: 10 chars
 0(9291) DEBUG: db_postgres [km_dbase.c:230]: db_postgres_submit_query(): sending query ok: 0x7fe11c899fa0 (2) - [select username from location where username='dummy_user']
 0(9291) DEBUG: <core> [db_res.c:118]: db_new_result(): allocate 56 bytes for result set at 0x7fe11c89a1a0
 0(9291) DEBUG: db_postgres [km_dbase.c:509]: db_postgres_store_result(): 0x7fe11c899fa0 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x2a90a90)
 0(9291) DEBUG: db_postgres [km_res.c:108]: db_postgres_get_columns(): 1 columns returned from the query
 0(9291) DEBUG: <core> [db_res.c:155]: db_allocate_columns(): allocate 8 bytes for result names at 0x7fe11c89a2c0
 0(9291) DEBUG: <core> [db_res.c:165]: db_allocate_columns(): allocate 4 bytes for result types at 0x7fe11c89a230
 0(9291) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7fe11c89a200
 0(9291) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c89a200)[0]=[username]
 0(9291) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 0(9291) DEBUG: db_postgres [km_res.c:211]: db_postgres_convert_rows(): no rows returned from the query
 0(9291) DEBUG: db_postgres [km_dbase.c:390]: db_postgres_free_query(): PQclear(0x2a90a90) result set
 0(9291) DEBUG: <core> [db_res.c:81]: db_free_columns(): freeing 1 columns
 0(9291) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[0] at 0x7fe11c89a200
 0(9291) DEBUG: <core> [db_res.c:94]: db_free_columns(): freeing result names at 0x7fe11c89a2c0
 0(9291) DEBUG: <core> [db_res.c:99]: db_free_columns(): freeing result types at 0x7fe11c89a230
 0(9291) DEBUG: <core> [db_res.c:136]: db_free_result(): freeing result set at 0x7fe11c89a1a0
 0(9291) DEBUG: <core> [db_pool.c:102]: pool_remove(): removing connection from the pool
 0(9291) DEBUG: db_postgres [km_pg_con.c:122]: db_postgres_free_connection(): PQfinish(0x2a5efe0)
 0(9291) DEBUG: db_postgres [km_pg_con.c:126]: db_postgres_free_connection(): pkg_free(0x7fe11c89a130)
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sl_reply_error()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing lookup()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_newtran()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing send_reply()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing send_reply()
 0(9291) DEBUG: <core> [switch.c:232]: fix_switch(): SWITCH: 3 cases, 0 default
 0(9291) DEBUG: <core> [switch.c:402]: fix_switch(): SWITCH: optimized to condtable (3) default: no
  0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [INVITE/1]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1746]: fixup_method(): using id for methods [PUBLISH,SUBSCRIBE/8256]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [PUBLISH/8192]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 7
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing sl_send_reply()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing is_method()
 0(9291) DEBUG: textops [textops.c:1753]: fixup_method(): using id for method [REGISTER/32]
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 3
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 3
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing append_hf()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing xdbg()
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 3
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 2
 0(9291) DEBUG: <core> [rvalue.c:2865]: fix_rval(): RV is str: "[12][0-9][0-9]"
 0(9291) DEBUG: <core> [route.c:507]: exp_optimize_right(): right EXP optimized: op11(O52, _ST22_) => op11(O52, _ST1_)
 0(9291) DEBUG: <core> [rvalue.c:2857]: fix_rval(): RV fixing type 4
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing t_is_canceled()
 0(9291) DEBUG: <core> [route.c:928]: fix_actions(): fixing xdbg()
 0(9291) DEBUG: <core> [pvapi.c:390]: pv_spec_lookup(): PV <$T_branch_idx> is not in cache
 0(9291) DEBUG: <core> [pvapi.c:294]: pv_cache_add(): pvar [$T_branch_idx] added in cache
 0(9291) DEBUG: <core> [pvapi.c:390]: pv_spec_lookup(): PV <$ru> is not in cache
 0(9291) DEBUG: <core> [pvapi.c:294]: pv_cache_add(): pvar [$ru] added in cache
 0(9291) INFO: <core> [udp_server.c:179]: probe_max_receive_buffer(): INFO: udp_init: SO_RCVBUF is initially 229376
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 231424
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=231424,verify=462848
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 233472
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=233472,verify=466944
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 235520
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=235520,verify=471040
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 237568
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=237568,verify=475136
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 239616
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=239616,verify=479232
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 241664
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=241664,verify=483328
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 243712
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=243712,verify=487424
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 245760
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=245760,verify=491520
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 247808
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=247808,verify=495616
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 249856
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=249856,verify=499712
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 251904
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=251904,verify=503808
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 253952
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=253952,verify=507904
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 256000
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=256000,verify=512000
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 258048
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=258048,verify=516096
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 260096
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=260096,verify=520192
 0(9291) DEBUG: <core> [udp_server.c:187]: probe_max_receive_buffer(): DEBUG: udp_init: trying SO_RCVBUF: 262144
 0(9291) DEBUG: <core> [udp_server.c:211]: probe_max_receive_buffer(): DEBUG: setting SO_RCVBUF; set=262144,verify=524288
 0(9291) INFO: <core> [udp_server.c:230]: probe_max_receive_buffer(): INFO: udp_init: SO_RCVBUF is finally 524288
 0(9291) DEBUG: <core> [daemonize.c:219]: enable_dumpable(): trying enable core dumping...
 0(9291) DEBUG: <core> [daemonize.c:237]: enable_dumpable(): core dumping is enabled now (1)...
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): mi_fifo
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): kex
 0(9291) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (-127)
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): corex
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): tm
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): sl
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): usrloc
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): registrar
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): ctl
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): mi_rpc
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): acc
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-127): debugger
 0(9291) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (-127)
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): mi_fifo
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): kex
 1(9298) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (1)
 1(9298) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-2452-] (0 / 19)
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): corex
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): tm
 1(9298) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9298@10.17.3.34'
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): sl
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): usrloc
 1(9298) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-2452-] (0 / 19)
 1(9298) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8a9380 not found in pool
 1(9298) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8a9380
 1(9298) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8ab890=pkg_malloc(80)
 1(9298) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): mi_fifo
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): kex
 2(9299) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (2)
 2(9299) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-2453-] (0 / 19)
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): corex
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): tm
 2(9299) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9299@10.17.3.34'
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): sl
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): usrloc
 2(9299) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-2453-] (0 / 19)
 2(9299) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8a9380 not found in pool
 2(9299) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8a9380
 2(9299) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8ab890=pkg_malloc(80)
 2(9299) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): mi_fifo
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): kex
 0(9291) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (0)
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): corex
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): tm
 0(9291) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9291@10.17.3.34'
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): sl
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): usrloc
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8a9380 not found in pool
 0(9291) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8a9380
 0(9291) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8ab890=pkg_malloc(80)
 0(9291) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): mi_fifo
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): kex
 5(9304) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (-1)
 5(9304) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-2458-] (0 / 19)
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): corex
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): tm
 5(9304) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9304@10.17.3.34'
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): sl
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): usrloc
 5(9304) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-2458-] (0 / 19)
 5(9304) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8a9380 not found in pool
 5(9304) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8a9380
 5(9304) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8ab890=pkg_malloc(80)
 5(9304) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): mi_fifo
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): kex
 4(9302) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (4)
 4(9302) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-2456-] (0 / 19)
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): corex
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): tm
 4(9302) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9302@10.17.3.34'
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): sl
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): usrloc
 4(9302) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-2456-] (0 / 19)
 4(9302) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8a9380 not found in pool
 4(9302) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8a9380
 4(9302) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8ab890=pkg_malloc(80)
 4(9302) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): mi_fifo
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): kex
 3(9301) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (3)
 3(9301) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-2455-] (0 / 19)
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): corex
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): tm
 3(9301) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9301@10.17.3.34'
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): sl
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): usrloc
 3(9301) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-2455-] (0 / 19)
 3(9301) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8a9380 not found in pool
 3(9301) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8a9380
 3(9301) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8ab890=pkg_malloc(80)
 3(9301) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): mi_fifo
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): kex
 6(9305) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (-1)
 6(9305) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-2459-] (0 / 19)
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): corex
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): tm
 6(9305) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9305@10.17.3.34'
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): sl
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): usrloc
 6(9305) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-2459-] (0 / 19)
 6(9305) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8a9380 not found in pool
 6(9305) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8a9380
 6(9305) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8ab890=pkg_malloc(80)
 6(9305) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 7(9306) DEBUG: mi_fifo [mi_fifo.c:223]: fifo_process(): new process with pid = 9306 created
 7(9306) DEBUG: mi_fifo [fifo_fnc.c:76]: mi_init_fifo_server(): FIFO created @ /tmp/kamailio_fifo
 7(9306) DEBUG: mi_fifo [fifo_fnc.c:91]: mi_init_fifo_server(): fifo /tmp/kamailio_fifo opened, mode=660
 7(9306) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c88c900 not found in pool
 7(9306) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c88c900
 7(9306) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c88c960=pkg_malloc(80)
 7(9306) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 1(9298) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 1(9298) DEBUG: db_postgres [km_dbase.c:230]: db_postgres_submit_query(): sending query ok: 0x7fe11c8a9340 (2) - [select username,contact,expires,q,callid,cseq,flags,cflags,user_agent,received,path,socket,methods,last_modified,ruid,instance,reg_id from location ]
 1(9298) DEBUG: <core> [db_res.c:118]: db_new_result(): allocate 56 bytes for result set at 0x7fe11c8ab900
 1(9298) DEBUG: db_postgres [km_dbase.c:293]: db_postgres_fetch_result(): 0x7fe11c8a9340 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x2a67590)
 1(9298) DEBUG: db_postgres [km_res.c:108]: db_postgres_get_columns(): 17 columns returned from the query
 1(9298) DEBUG: <core> [db_res.c:155]: db_allocate_columns(): allocate 136 bytes for result names at 0x7fe11c8ab9c0
 1(9298) DEBUG: <core> [db_res.c:165]: db_allocate_columns(): allocate 68 bytes for result types at 0x7fe11c8aba70
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[0] at 0x7fe11c8ab960
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8ab960)[0]=[username]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[1] at 0x7fe11c8ab990
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8ab990)[1]=[contact]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[2] at 0x7fe11c8abae0
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abae0)[2]=[expires]
 1(9298) DEBUG: db_postgres [km_res.c:158]: db_postgres_get_columns(): use DB1_DATETIME result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[3] at 0x7fe11c8abb10
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abb10)[3]=[q]
 1(9298) DEBUG: db_postgres [km_res.c:151]: db_postgres_get_columns(): use DB1_DOUBLE result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[4] at 0x7fe11c8abb40
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abb40)[4]=[callid]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[5] at 0x7fe11c8abb70
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abb70)[5]=[cseq]
 1(9298) DEBUG: db_postgres [km_res.c:140]: db_postgres_get_columns(): use DB1_INT result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[6] at 0x7fe11c8abba0
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abba0)[6]=[flags]
 1(9298) DEBUG: db_postgres [km_res.c:140]: db_postgres_get_columns(): use DB1_INT result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[7] at 0x7fe11c8abbd0
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abbd0)[7]=[cflags]
 1(9298) DEBUG: db_postgres [km_res.c:140]: db_postgres_get_columns(): use DB1_INT result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[8] at 0x7fe11c8abc00
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abc00)[8]=[user_agent]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[9] at 0x7fe11c8abc30
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abc30)[9]=[received]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[10] at 0x7fe11c8abc60
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c8abc60)[10]=[path]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[11] at 0x7fe11c88c8c0
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c88c8c0)[11]=[socket]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[12] at 0x7fe11c88c8f0
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c88c8f0)[12]=[methods]
 1(9298) DEBUG: db_postgres [km_res.c:140]: db_postgres_get_columns(): use DB1_INT result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[13] at 0x7fe11c88c920
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c88c920)[13]=[last_modified]
 1(9298) DEBUG: db_postgres [km_res.c:158]: db_postgres_get_columns(): use DB1_DATETIME result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[14] at 0x7fe11c88c950
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c88c950)[14]=[ruid]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[15] at 0x7fe11c88c980
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c88c980)[15]=[instance]
 1(9298) DEBUG: db_postgres [km_res.c:166]: db_postgres_get_columns(): use DB1_STRING result type
 1(9298) DEBUG: db_postgres [km_res.c:126]: db_postgres_get_columns(): allocate 16 bytes for RES_NAMES[16] at 0x7fe11c88c9b0
 1(9298) DEBUG: db_postgres [km_res.c:133]: db_postgres_get_columns(): RES_NAMES(0x7fe11c88c9b0)[16]=[reg_id]
 1(9298) DEBUG: db_postgres [km_res.c:140]: db_postgres_get_columns(): use DB1_INT result type
 1(9298) DEBUG: usrloc [udomain.c:429]: preload_udomain(): table is empty
 1(9298) DEBUG: <core> [db_res.c:81]: db_free_columns(): freeing 17 columns
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[0] at 0x7fe11c8ab960
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[1] at 0x7fe11c8ab990
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[2] at 0x7fe11c8abae0
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[3] at 0x7fe11c8abb10
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[4] at 0x7fe11c8abb40
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[5] at 0x7fe11c8abb70
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[6] at 0x7fe11c8abba0
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[7] at 0x7fe11c8abbd0
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[8] at 0x7fe11c8abc00
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[9] at 0x7fe11c8abc30
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[10] at 0x7fe11c8abc60
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[11] at 0x7fe11c88c8c0
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[12] at 0x7fe11c88c8f0
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[13] at 0x7fe11c88c920
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[14] at 0x7fe11c88c950
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[15] at 0x7fe11c88c980
 1(9298) DEBUG: <core> [db_res.c:85]: db_free_columns(): freeing RES_NAMES[16] at 0x7fe11c88c9b0
 1(9298) DEBUG: <core> [db_res.c:94]: db_free_columns(): freeing result names at 0x7fe11c8ab9c0
 1(9298) DEBUG: <core> [db_res.c:99]: db_free_columns(): freeing result types at 0x7fe11c8aba70
 1(9298) DEBUG: <core> [db_res.c:136]: db_free_result(): freeing result set at 0x7fe11c8ab900
 1(9298) DEBUG: db_postgres [km_dbase.c:390]: db_postgres_free_query(): PQclear(0x2a67590) result set
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): registrar
 1(9298) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-2452-] (0 / 19)
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): ctl
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): mi_rpc
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): acc
 1(9298) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (1): debugger
 1(9298) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (1)
 0(9291) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): registrar
 0(9291) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-244b-] (0 / 19)
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): ctl
 0(9291) DEBUG: ctl [ctl.c:311]: mod_child(): ctl: mod_child(0), ctrl_sock_lst=0x2a597b0
 0(9291) DEBUG: ctl [ctl.c:319]: mod_child(): ctl: mod_child(0), fork_process=9338, csl=0x2a597b0
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): mi_rpc
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): acc
 0(9291) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (0): debugger
 0(9291) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (0)
 0(9291) DEBUG: <core> [main.c:1757]: main_loop(): Expect maximum 40  open fds
 4(9302) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): registrar
 4(9302) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-2456-] (0 / 19)
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): ctl
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): mi_rpc
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): acc
 4(9302) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (4): debugger
 4(9302) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (4)
 2(9299) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): registrar
 2(9299) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-2453-] (0 / 19)
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): ctl
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): mi_rpc
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): acc
 2(9299) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (2): debugger
 2(9299) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (2)
[root@authdemos2 rsyslog.d]#  5(9304) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): registrar
 5(9304) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-2458-] (0 / 19)
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): ctl
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): mi_rpc
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): acc
 5(9304) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): debugger
 5(9304) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (-1)
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): mi_fifo
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): kex
 8(9338) DEBUG: kex [kex_mod.c:156]: child_init(): rank is (-2)
 8(9338) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [srid-56000edc-247a-] (0 / 19)
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): corex
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): tm
 8(9338) DEBUG: tm [callid.c:137]: child_init_callid(): DEBUG: callid: '0f6c67533e80fb47-9338@10.17.3.34'
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): sl
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): usrloc
 8(9338) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-247a-] (0 / 19)
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): registrar
 8(9338) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-247a-] (0 / 19)
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): ctl
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): mi_rpc
 8(9338) DEBUG: mi_rpc [mi_rpc_mod.c:106]: child_init(): initializing child[-2] for rpc handling
 8(9338) DEBUG: <core> [db_id.c:292]: cmp_db_id(): identical DB URLs, but different DB connection pid [9338/9291]
 8(9338) DEBUG: <core> [db.c:318]: db_do_init2(): connection 0x7fe11c8ab940 not found in pool
 8(9338) DEBUG: db_postgres [km_pg_con.c:49]: db_postgres_new_connection(): db_id = 0x7fe11c8ab940
 8(9338) DEBUG: db_postgres [km_pg_con.c:62]: db_postgres_new_connection(): 0x7fe11c8aba90=pkg_malloc(80)
 8(9338) DEBUG: db_postgres [km_pg_con.c:74]: db_postgres_new_connection(): opening connection: postgres://xxxx:xxxx@kamailioha/kamailio
 7(9306) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 7(9306) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edc-245a-] (0 / 19)
 3(9301) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): registrar
 3(9301) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-2455-] (0 / 19)
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): ctl
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): mi_rpc
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): acc
 3(9301) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (3): debugger
 3(9301) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (3)
 6(9305) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a81cc0)
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): registrar
 6(9305) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [uloc-56000edc-2459-] (0 / 19)
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): ctl
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): mi_rpc
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): acc
 6(9305) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-1): debugger
 6(9305) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (-1)
 8(9338) DEBUG: db_postgres [km_pg_con.c:78]: db_postgres_new_connection(): PQsetdbLogin(0x2a67590)
 8(9338) DEBUG: <core> [sruid.c:100]: sruid_init(): root for sruid is [ulcx-56000edd-247a-] (0 / 19)
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): acc
 8(9338) DEBUG: <core> [sr_module.c:892]: init_mod_child(): DEBUG: init_mod_child (-2): debugger
 8(9338) DEBUG: debugger [debugger_mod.c:124]: child_init(): rank is (-2)
 8(9338) DEBUG: ctl [ctl.c:319]: mod_child(): ctl: mod_child(0), fork_process=0, csl=0x2a597b0
 8(9338) DEBUG: ctl [ctl.c:326]: mod_child(): ctl: 0 io_listen_loop(1, 0x2a597b0)
 8(9338) INFO: ctl [io_listener.c:221]: io_listen_loop(): io_listen_loop: using epoll_lt as the io watch method (auto detected)
 8(9338) DEBUG: ctl [io_listener.c:257]: io_listen_loop(): io_listen_loop: adding socket 4, type 2, transport 3 (/tmp/kamailio_ctl)
 8(9338) DEBUG: ctl [../../io_wait.h:390]: io_watch_add(): DBG: io_watch_add(0x7fe119762680, 4, 2, 0x2a597b0), fd_no=0
 1(9298) DEBUG: <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
 1(9298) DEBUG: <core> [parser/msg_parser.c:625]: parse_msg():  method:  <REGISTER>
 1(9298) DEBUG: <core> [parser/msg_parser.c:627]: parse_msg():  uri:     <sip:sipauthdev.frequentis.frq>
 1(9298) DEBUG: <core> [parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
 4(9302) DEBUG: <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
 4(9302) DEBUG: <core> [parser/msg_parser.c:625]: parse_msg():  method:  <SUBSCRIBE>
 4(9302) DEBUG: <core> [parser/msg_parser.c:627]: parse_msg():  uri:     <sip:User1234@sipauthdev.frequentis.frq>
 4(9302) DEBUG: <core> [parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
 1(9298) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511834d1da376e69b66>; state=6
 1(9298) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17
 1(9298) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 4(9302) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511834e1da376e69b66>; state=6
 4(9302) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17
 4(9302) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 1(9298) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 1(9298) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 4(9302) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 1(9298) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 4(9302) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 4(9302) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 1(9298) DEBUG: <core> [receive.c:192]: receive_msg(): preparing to run routing scripts...
 4(9302) DEBUG: <core> [receive.c:192]: receive_msg(): preparing to run routing scripts...
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=482 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=482 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=600 a=25 n=mf_process_maxfwd_header
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=600 a=25 n=mf_process_maxfwd_header
 1(9298) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=10
 4(9302) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=10
 1(9298) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [42]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 4(9302) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [42]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 1(9298) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>
]
 4(9302) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>
]
 4(9302) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <5> <SUBSCRIBE>
 1(9298) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <4> <REGISTER>
 4(9302) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
 1(9298) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
 4(9302) DEBUG: maxfwd [maxfwd.c:161]: process_maxfwd_header(): value 70 decreased to 16
 1(9298) DEBUG: maxfwd [maxfwd.c:161]: process_maxfwd_header(): value 70 decreased to 16
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=611 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=26 n=sanity_check
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=611 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=26 n=sanity_check
 4(9302) DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
 4(9302) DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
 1(9298) DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
 4(9302) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=3338315148
 1(9298) DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
 4(9302) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 4(9302) DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=485 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=796 a=2 n=return
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=16 n=if
 1(9298) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=1971630636
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=488 a=25 n=is_method
 1(9298) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=16 n=if
 1(9298) DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=485 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=796 a=2 n=return
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=654 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=488 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=654 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=614 a=24 n=has_totag
 1(9298) DEBUG: siputils [checks.c:103]: has_totag(): no totag
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=514 a=24 n=t_check_trans
 1(9298) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffffffffffff
 1(9298) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=56871, isACK=0
 1(9298) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 1(9298) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 1(9298) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=1 T end=(nil)
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=517 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=780 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=770 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=777 a=2 n=return
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=521 a=25 n=remove_hf
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=522 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=847 a=16 n=if
 1(9298) DEBUG: <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - checking if host==us: 25==10 && [sipauthdev.frequentis.frq] == [10.17.3.34]
 1(9298) DEBUG: <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
 1(9298) DEBUG: <core> [forward.c:462]: check_self(): check_self: host != me
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=844 a=25 n=append_hf
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=845 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=614 a=24 n=has_totag
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=561 a=25 n=is_method
 4(9302) DEBUG: siputils [checks.c:103]: has_totag(): no totag
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=25 n=is_method
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=514 a=24 n=t_check_trans
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=24 n=t_relay
 4(9302) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffffffffffff
 1(9298) DEBUG: tm [t_lookup.c:1400]: t_newtran(): DEBUG: t_newtran: msg id=1 , global msg id=1 , T on entrance=(nil)
 4(9302) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=60298, isACK=0
 1(9298) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=56871, isACK=0
 1(9298) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 1(9298) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 4(9302) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 4(9302) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 4(9302) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=1 T end=(nil)
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=517 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=780 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=770 a=25 n=is_method
 1(9298) DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fe11670e8f0, callback type 1, id 0 entered
 4(9302) DEBUG: <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - checking if host==us: 25==10 && [sipauthdev.frequentis.frq] == [10.17.3.34]
 4(9302) DEBUG: <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
 4(9302) DEBUG: <core> [forward.c:462]: check_self(): check_self: host != me
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=521 a=25 n=remove_hf
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=522 a=25 n=is_method
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=523 a=24 n=record_route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=25 n=is_method
 1(9298) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(_sip._udp.sipauthdev.frequentis.frq(35), 33), h=109
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=847 a=16 n=if
 4(9302) DEBUG: <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - checking if host==us: 25==10 && [sipauthdev.frequentis.frq] == [10.17.3.34]
 4(9302) DEBUG: <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
 4(9302) DEBUG: <core> [forward.c:462]: check_self(): check_self: host != me
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=844 a=25 n=append_hf
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=845 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=561 a=25 n=is_method
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=563 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=562 a=25 n=t_is_set
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=562 a=25 n=t_on_branch
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=25 n=is_method
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=566 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=565 a=25 n=t_is_set
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=565 a=25 n=t_on_reply
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=25 n=is_method
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=16 n=if
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=24 n=t_relay
 4(9302) DEBUG: tm [t_lookup.c:1400]: t_newtran(): DEBUG: t_newtran: msg id=1 , global msg id=1 , T on entrance=(nil)
 4(9302) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=60298, isACK=0
 4(9302) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 4(9302) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 4(9302) DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fe1167128b0, callback type 1, id 0 entered
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=928 a=25 n=xdbg
 4(9302) DEBUG: <script>: new branch [0] to sip:User1234@sipauthdev.frequentis.frq
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=929 a=5 n=route
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=827 a=2 n=return
 4(9302) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(_sip._udp.sipauthdev.frequentis.frq(35), 33), h=109
 1(9298) DEBUG: <core> [resolve.c:757]: get_record(): get_record: lookup(_sip._udp.sipauthdev.frequentis.frq, 33) failed
 1(9298) DEBUG: <core> [dns_cache.c:897]: dns_cache_mk_bad_entry(): dns_cache_mk_bad_entry(_sip._udp.sipauthdev.frequentis.frq, 33, 60, 1)
 1(9298) DEBUG: <core> [dns_cache.c:830]: dns_cache_add(): dns_cache_add: adding _sip._udp.sipauthdev.frequentis.frq(35) 33 (flags=1) at 109
 1(9298) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(sipauthdev.frequentis.frq(25), 1), h=61
 4(9302) DEBUG: <core> [resolve.c:757]: get_record(): get_record: lookup(_sip._udp.sipauthdev.frequentis.frq, 33) failed
 4(9302) DEBUG: <core> [dns_cache.c:897]: dns_cache_mk_bad_entry(): dns_cache_mk_bad_entry(_sip._udp.sipauthdev.frequentis.frq, 33, 60, 1)
 4(9302) DEBUG: <core> [dns_cache.c:830]: dns_cache_add(): dns_cache_add: adding _sip._udp.sipauthdev.frequentis.frq(35) 33 (flags=1) at 109
 4(9302) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(sipauthdev.frequentis.frq(25), 1), h=61
 1(9298) DEBUG: <core> [resolve.c:954]: get_record(): get_record: skipping 0 NS (p=0x88713b, end=0x88713b)
 1(9298) DEBUG: <core> [resolve.c:970]: get_record(): get_record: parsing 0 ARs (p=0x88713b, end=0x88713b)
 1(9298) DEBUG: <core> [dns_cache.c:1779]: dns_get_related(): dns_get_related(0x7fe116716e20 (sipauthdev.frequentis.frq, 1), 1, *(nil)) (0)
 1(9298) DEBUG: <core> [dns_cache.c:872]: dns_cache_add_unsafe(): dns_cache_add: adding sipauthdev.frequentis.frq(25) 1 (flags=0) at 61
 1(9298) DEBUG: <core> [msg_translator.c:206]: check_via_address(): check_via_address(10.17.3.33, 10.17.3.33, 0)
 4(9302) DEBUG: <core> [resolve.c:954]: get_record(): get_record: skipping 0 NS (p=0x88713b, end=0x88713b)
 4(9302) DEBUG: <core> [resolve.c:970]: get_record(): get_record: parsing 0 ARs (p=0x88713b, end=0x88713b)
 4(9302) DEBUG: <core> [dns_cache.c:1779]: dns_get_related(): dns_get_related(0x7fe116717190 (sipauthdev.frequentis.frq, 1), 1, *(nil)) (0)
 4(9302) DEBUG: <core> [dns_cache.c:872]: dns_cache_add_unsafe(): dns_cache_add: adding sipauthdev.frequentis.frq(25) 1 (flags=0) at 61
 1(9298) DEBUG: tm [t_funcs.c:393]: t_relay_to(): SER: new transaction fwd'ed
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=2 n=exit
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [msg_translator.c:206]: check_via_address(): check_via_address(10.17.3.33, 10.17.3.33, 0)
 1(9298) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 1(9298) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up
 4(9302) DEBUG: tm [t_funcs.c:393]: t_relay_to(): SER: new transaction fwd'ed
 4(9302) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=2 n=exit
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 4(9302) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up
 2(9299) DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply  (status):
 2(9299) DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version: <SIP/2.0>
 2(9299) DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <200>
 2(9299) DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:  <OK>
 2(9299) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK72ed.a1349995.0>; state=16
 2(9299) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 2(9299) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 2(9299) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 2(9299) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 2(9299) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffffffffffff
 2(9299) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511834d1da376e69b66>; state=6
 2(9299) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <5060>; state=16
 2(9299) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 2(9299) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=62
 2(9299) DEBUG: <core> [parser/msg_parser.c:526]: parse_headers(): parse_headers: this is the second via
 2(9299) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=8006059dd75ee511834f1da376e69b66
 2(9299) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 2(9299) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [79]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 2(9299) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>]
 2(9299) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <4> <REGISTER>
 3(9301) DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply  (status):
 2(9299) DEBUG: tm [t_lookup.c:972]: t_reply_matching(): DEBUG: t_reply_matching: hash 56871 label 1503216410 branch 0
 3(9301) DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version: <SIP/2.0>
 2(9299) DEBUG: tm [t_lookup.c:1032]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe11670e8f0)!
 3(9301) DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <200>
 2(9299) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=1 T end=0x7fe11670e8f0
 3(9301) DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:  <OK>
 2(9299) DEBUG: tm [t_reply.c:2089]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
 2(9299) DEBUG: tm [t_reply.c:1211]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
 2(9299) DEBUG: tm [t_reply.c:1710]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0
 3(9301) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKa8be.2fa26441.0>; state=16
 3(9301) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 2(9299) DEBUG: <core> [msg_translator.c:2008]: build_res_buf_from_sip_res():  old size: 514, new size: 455
 2(9299) DEBUG: <core> [msg_translator.c:2026]: build_res_buf_from_sip_res(): build_res_from_sip_res: copied size: orig:75, new: 16, rest: 439 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.17.3.33:5060;branch=z9hG4bK8006059dd75ee511834d1da376e69b66;rport=5060
From: <sip:User1234@sipauthdev.frequentis.frq>;tag=1971630636
To: <sip:User1234@sipauthdev.frequentis.frq>;tag=8006059dd75ee511834f1da376e69b66
Call-ID: 00B6D55D-D55E-E511-8347-1DA376E69B66@10.17.3.33
CSeq: 4 REGISTER
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE
Server: SIPPER for PhonerLite
Content-Length: 0


 3(9301) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 2(9299) DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize: resize(0) called
 3(9301) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 3(9301) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 2(9299) DEBUG: tm [t_reply.c:1551]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
 3(9301) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffffffffffff
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 3(9301) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511834e1da376e69b66>; state=6
 2(9299) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up
 3(9301) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <5060>; state=16
 3(9301) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 3(9301) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=62
 3(9301) DEBUG: <core> [parser/msg_parser.c:526]: parse_headers(): parse_headers: this is the second via
 3(9301) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=8006059dd75ee51183501da376e69b66
 3(9301) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 3(9301) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [79]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 3(9301) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>]
 3(9301) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <5> <SUBSCRIBE>
 3(9301) DEBUG: tm [t_lookup.c:972]: t_reply_matching(): DEBUG: t_reply_matching: hash 60298 label 340142834 branch 0
 3(9301) DEBUG: tm [t_lookup.c:1032]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe1167128b0)!
 3(9301) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=1 global id=1 T end=0x7fe1167128b0
 3(9301) DEBUG: tm [t_reply.c:2089]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=934 a=25 n=xdbg
 3(9301) DEBUG: <script>: incoming reply
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=937 a=16 n=if
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=936 a=5 n=route
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=827 a=2 n=return
 3(9301) DEBUG: tm [t_reply.c:1211]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
 3(9301) DEBUG: tm [t_reply.c:1710]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0
 3(9301) DEBUG: <core> [msg_translator.c:2008]: build_res_buf_from_sip_res():  old size: 594, new size: 535
 3(9301) DEBUG: <core> [msg_translator.c:2026]: build_res_buf_from_sip_res(): build_res_from_sip_res: copied size: orig:75, new: 16, rest: 519 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.17.3.33:5060;branch=z9hG4bK8006059dd75ee511834e1da376e69b66;rport=5060
Record-Route: <sip:10.17.3.34;lr=on>
From: <sip:User1234@sipauthdev.frequentis.frq>;tag=3338315148
To: <sip:User1234@sipauthdev.frequentis.frq>;tag=8006059dd75ee51183501da376e69b66
Call-ID: 00B6D55D-D55E-E511-8348-1DA376E69B66@10.17.3.33
CSeq: 5 SUBSCRIBE
Contact: <sip:User1234@10.17.3.33:5060>
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE
Server: SIPPER for PhonerLite
Content-Length: 0


 3(9301) DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize: resize(0) called
 3(9301) DEBUG: tm [t_reply.c:1551]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 3(9301) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up
 1(9298) DEBUG: <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
 1(9298) DEBUG: <core> [parser/msg_parser.c:625]: parse_msg():  method:  <REGISTER>
 1(9298) DEBUG: <core> [parser/msg_parser.c:627]: parse_msg():  uri:     <sip:sipauthdev.frequentis.frq>
 1(9298) DEBUG: <core> [parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
 1(9298) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511a818f7b8010e2862>; state=6
 1(9298) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17
 1(9298) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 1(9298) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 1(9298) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 1(9298) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 1(9298) DEBUG: <core> [receive.c:192]: receive_msg(): preparing to run routing scripts...
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=482 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=600 a=25 n=mf_process_maxfwd_header
 1(9298) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=10
 1(9298) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [42]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 1(9298) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>
]
 1(9298) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <1> <REGISTER>
 1(9298) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
 1(9298) DEBUG: maxfwd [maxfwd.c:161]: process_maxfwd_header(): value 70 decreased to 16
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=611 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=26 n=sanity_check
 1(9298) DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
 1(9298) DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
 1(9298) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=3477967982
 1(9298) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 1(9298) DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=485 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=796 a=2 n=return
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=488 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=654 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=614 a=24 n=has_totag
 1(9298) DEBUG: siputils [checks.c:103]: has_totag(): no totag
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=514 a=24 n=t_check_trans
 1(9298) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=1 T start=0xffffffffffffffff
 1(9298) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=39953, isACK=0
 1(9298) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 1(9298) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 1(9298) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T end=(nil)
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=517 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=780 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=770 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=777 a=2 n=return
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=521 a=25 n=remove_hf
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=522 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=847 a=16 n=if
 1(9298) DEBUG: <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - checking if host==us: 25==10 && [sipauthdev.frequentis.frq] == [10.17.3.34]
 1(9298) DEBUG: <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
 1(9298) DEBUG: <core> [forward.c:462]: check_self(): check_self: host != me
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=844 a=25 n=append_hf
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=845 a=5 n=route
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=561 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=25 n=is_method
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=16 n=if
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=24 n=t_relay
 1(9298) DEBUG: tm [t_lookup.c:1400]: t_newtran(): DEBUG: t_newtran: msg id=2 , global msg id=2 , T on entrance=(nil)
 1(9298) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=39953, isACK=0
 1(9298) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 1(9298) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 1(9298) DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fe116717960, callback type 1, id 0 entered
 1(9298) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(_sip._udp.sipauthdev.frequentis.frq(35), 33), h=109
 1(9298) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(sipauthdev.frequentis.frq(25), 1), h=61
 1(9298) DEBUG: <core> [msg_translator.c:206]: check_via_address(): check_via_address(10.17.3.33, 10.17.3.33, 0)
 1(9298) DEBUG: tm [t_funcs.c:393]: t_relay_to(): SER: new transaction fwd'ed
 1(9298) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=2 n=exit
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 1(9298) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 1(9298) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up
 4(9302) DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply  (status):
 4(9302) DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version: <SIP/2.0>
 4(9302) DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <200>
 4(9302) DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:  <OK>
 4(9302) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK11c9.8a15d9b7.0>; state=16
 4(9302) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 4(9302) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 4(9302) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 4(9302) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 4(9302) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=1 T start=0xffffffffffffffff
 4(9302) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511a818f7b8010e2862>; state=6
 4(9302) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <5060>; state=16
 4(9302) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 4(9302) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=62
 4(9302) DEBUG: <core> [parser/msg_parser.c:526]: parse_headers(): parse_headers: this is the second via
 4(9302) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=8006059dd75ee511a819f7b8010e2862
 4(9302) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 4(9302) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [79]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 4(9302) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>]
 4(9302) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <1> <REGISTER>
 4(9302) DEBUG: tm [t_lookup.c:972]: t_reply_matching(): DEBUG: t_reply_matching: hash 39953 label 2073907624 branch 0
 4(9302) DEBUG: tm [t_lookup.c:1032]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe116717960)!
 4(9302) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T end=0x7fe116717960
 4(9302) DEBUG: tm [t_reply.c:2089]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
 4(9302) DEBUG: tm [t_reply.c:1211]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
 4(9302) DEBUG: tm [t_reply.c:1710]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0
 4(9302) DEBUG: <core> [msg_translator.c:2008]: build_res_buf_from_sip_res():  old size: 645, new size: 586
 4(9302) DEBUG: <core> [msg_translator.c:2026]: build_res_buf_from_sip_res(): build_res_from_sip_res: copied size: orig:75, new: 16, rest: 570 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.17.3.33:5060;branch=z9hG4bK8006059dd75ee511a818f7b8010e2862;rport=5060
From: <sip:User1234@sipauthdev.frequentis.frq>;tag=3477967982
To: <sip:User1234@sipauthdev.frequentis.frq>;tag=8006059dd75ee511a819f7b8010e2862
Call-ID: 8006059D-D75E-E511-A816-F7B8010E2862@10.17.3.33
CSeq: 1 REGISTER
Contact: <sip:User1234@10.17.3.33:5060>;+sip.instance="<urn:uuid:8014B9FB-ED59-E511-865E-7681A283F70F>";expires=900
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE
Server: SIPPER for PhonerLite
Expires: 900
Content-Length: 0


 4(9302) DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize: resize(0) called
 4(9302) DEBUG: tm [t_reply.c:1551]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 4(9302) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 4(9302) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up
 2(9299) DEBUG: <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
 2(9299) DEBUG: <core> [parser/msg_parser.c:625]: parse_msg():  method:  <SUBSCRIBE>
 2(9299) DEBUG: <core> [parser/msg_parser.c:627]: parse_msg():  uri:     <sip:User1234@sipauthdev.frequentis.frq>
 2(9299) DEBUG: <core> [parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
 2(9299) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511a81af7b8010e2862>; state=6
 2(9299) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17
 2(9299) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 2(9299) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 2(9299) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 2(9299) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 2(9299) DEBUG: <core> [receive.c:192]: receive_msg(): preparing to run routing scripts...
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=482 a=5 n=route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=600 a=25 n=mf_process_maxfwd_header
 2(9299) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=10
 2(9299) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [42]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 2(9299) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>
]
 2(9299) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <2> <SUBSCRIBE>
 2(9299) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
 2(9299) DEBUG: maxfwd [maxfwd.c:161]: process_maxfwd_header(): value 70 decreased to 16
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=611 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=605 a=26 n=sanity_check
 2(9299) DEBUG: <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body : content_length=0
 2(9299) DEBUG: <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
 2(9299) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=3151352768
 2(9299) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 2(9299) DEBUG: sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=485 a=5 n=route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=796 a=2 n=return
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=488 a=25 n=is_method
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=496 a=25 n=is_method
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=510 a=5 n=route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=654 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=614 a=24 n=has_totag
 2(9299) DEBUG: siputils [checks.c:103]: has_totag(): no totag
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=514 a=24 n=t_check_trans
 2(9299) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=1 T start=0xffffffffffffffff
 2(9299) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=40175, isACK=0
 2(9299) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 2(9299) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 2(9299) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T end=(nil)
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=517 a=5 n=route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=780 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=770 a=25 n=is_method
 2(9299) DEBUG: <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - checking if host==us: 25==10 && [sipauthdev.frequentis.frq] == [10.17.3.34]
 2(9299) DEBUG: <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
 2(9299) DEBUG: <core> [forward.c:462]: check_self(): check_self: host != me
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=521 a=25 n=remove_hf
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=522 a=25 n=is_method
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=523 a=24 n=record_route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=526 a=25 n=is_method
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=532 a=5 n=route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=847 a=16 n=if
 2(9299) DEBUG: <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - checking if host==us: 25==10 && [sipauthdev.frequentis.frq] == [10.17.3.34]
 2(9299) DEBUG: <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - checking if port 5060 (advertise 0) matches port 5060
 2(9299) DEBUG: <core> [forward.c:462]: check_self(): check_self: host != me
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=844 a=25 n=append_hf
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=845 a=5 n=route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=561 a=25 n=is_method
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=563 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=562 a=25 n=t_is_set
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=562 a=25 n=t_on_branch
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=564 a=25 n=is_method
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=566 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=565 a=25 n=t_is_set
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=565 a=25 n=t_on_reply
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=567 a=25 n=is_method
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=16 n=if
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=571 a=24 n=t_relay
 2(9299) DEBUG: tm [t_lookup.c:1400]: t_newtran(): DEBUG: t_newtran: msg id=2 , global msg id=2 , T on entrance=(nil)
 2(9299) DEBUG: tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start searching: hash=40175, isACK=0
 2(9299) DEBUG: tm [t_lookup.c:492]: matching_3261(): DEBUG: RFC3261 transaction matching failed
 2(9299) DEBUG: tm [t_lookup.c:716]: t_lookup_request(): DEBUG: t_lookup_request: no transaction found
 2(9299) DEBUG: tm [t_hooks.c:374]: run_reqin_callbacks_internal(): DBG: trans=0x7fe11671bed0, callback type 1, id 0 entered
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=928 a=25 n=xdbg
 2(9299) DEBUG: <script>: new branch [0] to sip:User1234@sipauthdev.frequentis.frq
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=929 a=5 n=route
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=827 a=2 n=return
 2(9299) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(_sip._udp.sipauthdev.frequentis.frq(35), 33), h=109
 2(9299) DEBUG: <core> [dns_cache.c:569]: _dns_hash_find(): dns_hash_find(sipauthdev.frequentis.frq(25), 1), h=61
 2(9299) DEBUG: <core> [msg_translator.c:206]: check_via_address(): check_via_address(10.17.3.33, 10.17.3.33, 0)
 2(9299) DEBUG: tm [t_funcs.c:393]: t_relay_to(): SER: new transaction fwd'ed
 2(9299) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=574 a=2 n=exit
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 2(9299) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 2(9299) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up
 3(9301) DEBUG: <core> [parser/msg_parser.c:633]: parse_msg(): SIP Reply  (status):
 3(9301) DEBUG: <core> [parser/msg_parser.c:635]: parse_msg():  version: <SIP/2.0>
 3(9301) DEBUG: <core> [parser/msg_parser.c:637]: parse_msg():  status:  <200>
 3(9301) DEBUG: <core> [parser/msg_parser.c:639]: parse_msg():  reason:  <OK>
 3(9301) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKfec9.a780b92.0>; state=16
 3(9301) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 3(9301) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=2
 3(9301) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this is the first via
 3(9301) DEBUG: <core> [receive.c:151]: receive_msg(): After parse_msg...
 3(9301) DEBUG: tm [t_lookup.c:1100]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=1 T start=0xffffffffffffffff
 3(9301) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK8006059dd75ee511a81af7b8010e2862>; state=6
 3(9301) DEBUG: <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 235, <rport> = <5060>; state=16
 3(9301) DEBUG: <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, state=5
 3(9301) DEBUG: <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via found, flags=62
 3(9301) DEBUG: <core> [parser/msg_parser.c:526]: parse_headers(): parse_headers: this is the second via
 3(9301) DEBUG: <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: tag=8006059dd75ee511a81bf7b8010e2862
 3(9301) DEBUG: <core> [parser/parse_to.c:799]: parse_to(): end of header reached, state=29
 3(9301) DEBUG: <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: get_hdr_field: <To> [79]; uri=[sip:User1234@sipauthdev.frequentis.frq]
 3(9301) DEBUG: <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body [<sip:User1234@sipauthdev.frequentis.frq>]
 3(9301) DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq <CSeq>: <2> <SUBSCRIBE>
 3(9301) DEBUG: tm [t_lookup.c:972]: t_reply_matching(): DEBUG: t_reply_matching: hash 40175 label 43714682 branch 0
 3(9301) DEBUG: tm [t_lookup.c:1032]: t_reply_matching(): DEBUG: t_reply_matching: reply matched (T=0x7fe11671bed0)!
 3(9301) DEBUG: tm [t_lookup.c:1169]: t_check_msg(): DEBUG: t_check_msg: msg id=2 global id=2 T end=0x7fe11671bed0
 3(9301) DEBUG: tm [t_reply.c:2089]: reply_received(): DEBUG: reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=934 a=25 n=xdbg
 3(9301) DEBUG: <script>: incoming reply
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=937 a=16 n=if
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=936 a=5 n=route
 3(9301) ERROR: *** cfgtrace: c=[//etc/kamailio/kamailio.cfg] l=827 a=2 n=return
 3(9301) DEBUG: tm [t_reply.c:1211]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200
 3(9301) DEBUG: tm [t_reply.c:1710]: relay_reply(): DEBUG: relay_reply: branch=0, save=0, relay=0
 3(9301) DEBUG: <core> [msg_translator.c:2008]: build_res_buf_from_sip_res():  old size: 593, new size: 535
 3(9301) DEBUG: <core> [msg_translator.c:2026]: build_res_buf_from_sip_res(): build_res_from_sip_res: copied size: orig:74, new: 16, rest: 519 msg=
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.17.3.33:5060;branch=z9hG4bK8006059dd75ee511a81af7b8010e2862;rport=5060
Record-Route: <sip:10.17.3.34;lr=on>
From: <sip:User1234@sipauthdev.frequentis.frq>;tag=3151352768
To: <sip:User1234@sipauthdev.frequentis.frq>;tag=8006059dd75ee511a81bf7b8010e2862
Call-ID: 8006059D-D75E-E511-A817-F7B8010E2862@10.17.3.33
CSeq: 2 SUBSCRIBE
Contact: <sip:User1234@10.17.3.33:5060>
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE
Server: SIPPER for PhonerLite
Content-Length: 0


 3(9301) DEBUG: <core> [mem/shm_mem.c:111]: _shm_resize(): WARNING:vqm_resize: resize(0) called
 3(9301) DEBUG: tm [t_reply.c:1551]: cleanup_uac_timers(): DEBUG: cleanup_uac_timers: RETR/FR timers reset
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: destroying list (nil)
 3(9301) DEBUG: <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
 3(9301) DEBUG: <core> [receive.c:295]: receive_msg(): receive_msg: cleaning up