Mar 23 15:13:42 CPaaSVM Keepalived_vrrp[9092]: /etc/keepalived/node01.sh exited with status 1 Mar 23 15:13:47 CPaaSVM Keepalived_vrrp[9092]: /etc/keepalived/node01.sh exited with status 1 Mar 23 15:13:52 CPaaSVM Keepalived_vrrp[9092]: /etc/keepalived/node01.sh exited with status 1 Mar 23 15:13:54 CPaaSVM systemd: Started Kamailio (OpenSER) - the Open Source SIP Server. Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:314]: subst_parser(): ok, se is 0x7f17afe58ac8 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) INFO: [core/ppcfg.c:83]: pp_subst_add(): ### added subst expression: !NODE01!172.27.45.219!g Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: NODE01 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1874]: pp_define_set(): ### setting define ID [NODE01] value [172.27.45.219] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/ppcfg.c:162]: pp_substdef_add(): ### added substdef: [NODE01]=[172.27.45.219] (0) Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=2 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:314]: subst_parser(): ok, se is 0x7f17afe590b0 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) INFO: [core/ppcfg.c:83]: pp_subst_add(): ### added subst expression: !NODE02!172.27.45.220!g Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: NODE02 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1874]: pp_define_set(): ### setting define ID [NODE02] value [172.27.45.220] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/ppcfg.c:162]: pp_substdef_add(): ### added substdef: [NODE02]=[172.27.45.220] (0) Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module db_mysql.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [db_query.c:412]: db_query_init(): About to allocate sql_buf size = 65535 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_db_mysql Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module jsonrpcs.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2638]: sr_kemi_modules_add(): adding core module Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2642]: sr_kemi_modules_add(): adding pv module Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2646]: sr_kemi_modules_add(): adding hdr module Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: jsonrpcs Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: jsonrpcs Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_jsonrpcs Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module kex.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: kex Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: kex Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_kex Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module corex.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: corex Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: corex Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_corex Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module tm.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: tm Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_tm Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module tmx.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: tmx Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: tmx Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_tmx Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module sl.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: sl Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: sl Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_sl Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module rr.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: rr Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: rr Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_rr Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module pv.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: pvx Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: pv Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_pv Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module maxfwd.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: maxfwd Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_maxfwd Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module usrloc.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_usrloc Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module registrar.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: registrar Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: registrar Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_registrar Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module textops.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: textops Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_textops Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module siputils.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: siputils Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: siputils Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_siputils Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module xlog.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: xlog Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: xlog Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_xlog Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module sanity.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: sanity Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_sanity Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module ctl.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_ctl Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module cfg_rpc.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_cfg_rpc Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module acc.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: acc Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_acc Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module counters.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: counters Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_counters Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module auth.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: auth Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_auth Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module auth_db.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: auth_db Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_auth_db Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module debugger.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: debugger Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_debugger Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module dispatcher.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: dispatcher Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_dispatcher Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module dmq.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: dmq Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_dmq Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module dialog.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: dialog Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:267]: register_module(): register PV from: dialog Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_dialog Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.y:1764]: yyparse(): loading module sst.so Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:505]: load_module(): trying to load Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/kemi.c:2660]: sr_kemi_modules_add(): adding module: sst Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1781]: pp_define(): defining id: MOD_sst Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/mem/shm.c:100]: shm_core_pools_init(): preparing to initialize shm core pools Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/mem/q_malloc.c:200]: qm_malloc_init(): QM_OPTIMIZE=16384, /ROUNDTO=2048 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/mem/q_malloc.c:202]: qm_malloc_init(): QM_HASH_SIZE=2099, qm_block size=235152 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/mem/q_malloc.c:204]: qm_malloc_init(): qm_malloc_init(0x7f17a4f5c000, 67108864), start=0x7f17a4f5c000 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/mem/q_malloc.c:213]: qm_malloc_init(): size= 67108864, init_overhead=235256 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'jsonrpcs' matches module 'jsonrpcs' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module jsonrpcs [/usr/lib64/kamailio/modules/jsonrpcs.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module jsonrpcs [/usr/lib64/kamailio/modules/jsonrpcs.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'sanity' matches module 'sanity' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module sanity [/usr/lib64/kamailio/modules/sanity.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module sanity [/usr/lib64/kamailio/modules/sanity.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'tm' matches module 'tm' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module tm [/usr/lib64/kamailio/modules/tm.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module tm [/usr/lib64/kamailio/modules/tm.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'tm' matches module 'tm' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module tm [/usr/lib64/kamailio/modules/tm.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module tm [/usr/lib64/kamailio/modules/tm.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'tm' matches module 'tm' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module tm [/usr/lib64/kamailio/modules/tm.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module tm [/usr/lib64/kamailio/modules/tm.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'rr' matches module 'rr' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module rr [/usr/lib64/kamailio/modules/rr.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module rr [/usr/lib64/kamailio/modules/rr.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'rr' matches module 'rr' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module rr [/usr/lib64/kamailio/modules/rr.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module rr [/usr/lib64/kamailio/modules/rr.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'rr' matches module 'rr' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module rr [/usr/lib64/kamailio/modules/rr.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module rr [/usr/lib64/kamailio/modules/rr.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'registrar' matches module 'registrar' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module registrar [/usr/lib64/kamailio/modules/registrar.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module registrar [/usr/lib64/kamailio/modules/registrar.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'registrar' matches module 'registrar' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module registrar [/usr/lib64/kamailio/modules/registrar.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module registrar [/usr/lib64/kamailio/modules/registrar.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'registrar' matches module 'registrar' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module registrar [/usr/lib64/kamailio/modules/registrar.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module registrar [/usr/lib64/kamailio/modules/registrar.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1893]: pp_define_get(): ### returning define ID [FLT_ACC] value [1] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1893]: pp_define_get(): ### returning define ID [FLT_ACCMISSED] value [2] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1893]: pp_define_get(): ### returning define ID [FLT_ACCFAILED] value [3] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'acc' matches module 'acc' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module acc [/usr/lib64/kamailio/modules/acc.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1893]: pp_define_get(): ### returning define ID [DBURL] value ["mysql://kamailio:kamailiorw@localhost/kamailio"] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'auth_db' matches module 'auth_db' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'auth_db' matches module 'auth_db' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'auth_db' matches module 'auth_db' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'auth_db' matches module 'auth_db' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/cfg.lex:1893]: pp_define_get(): ### returning define ID [MULTIDOMAIN] value [0] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'auth_db' matches module 'auth_db' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module auth_db [/usr/lib64/kamailio/modules/auth_db.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'debugger' matches module 'debugger' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module debugger [/usr/lib64/kamailio/modules/debugger.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module debugger [/usr/lib64/kamailio/modules/debugger.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'debugger' matches module 'debugger' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module debugger [/usr/lib64/kamailio/modules/debugger.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module debugger [/usr/lib64/kamailio/modules/debugger.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dispatcher' matches module 'dispatcher' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dispatcher [/usr/lib64/kamailio/modules/dispatcher.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:107]: set_mod_param_regex(): 'dmq' matches module 'dmq' Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/sr_module.c:725]: find_param_export(): found in module dmq [/usr/lib64/kamailio/modules/dmq.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/modparam.c:123]: set_mod_param_regex(): found in module dmq [/usr/lib64/kamailio/modules/dmq.so] Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:436]: subst_run(): running. r=1 Mar 23 15:13:54 CPaaSVM kamailio: 0(4247) DEBUG: [core/re.c:504]: subst_str(): no match Mar 23 15:13:57 CPaaSVM Keepalived_vrrp[9092]: VRRP_Script(chk_kamailio_status) succeeded Mar 23 15:13:57 CPaaSVM Keepalived_vrrp[9092]: Kernel is reporting: interface eth0 UP Mar 23 15:13:57 CPaaSVM Keepalived_vrrp[9092]: VRRP_Instance(VI_1): Transition to MASTER STATE Mar 23 15:13:57 CPaaSVM Keepalived_vrrp[9092]: VRRP_Instance(VI_1) Transition to MASTER STATE Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: VRRP_Instance(VI_1) Entering MASTER STATE Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: VRRP_Instance(VI_1) setting protocol VIPs. Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: VRRP_Instance(VI_1) Sending/queueing gratuitous ARPs on eth0 for 172.27.45.252 Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:13:58 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:14:03 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:14:03 CPaaSVM Keepalived_vrrp[9092]: VRRP_Instance(VI_1) Sending/queueing gratuitous ARPs on eth0 for 172.27.45.252 Mar 23 15:14:03 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:14:03 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:14:03 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:14:03 CPaaSVM Keepalived_vrrp[9092]: Sending gratuitous ARP on eth0 for 172.27.45.252 Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3201]: ds_ping_result_helper(): probe all, mode DS_PROBE_ALL Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3242]: ds_ping_set(): probing set #1, URI sip:172.27.44.121:5080;transport=tcp Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3266]: ds_ping_set(): Default ping_from: sip:dispatcher@localhost Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:436]: t_uac_prepare(): next_hop= Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:158]: dlg2hash(): hashid 51169 Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2054]: tcp_send(): no open tcp connection found, opening new one Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: [core/ip_addr.c:229]: print_ip(): tcpconn_new: new tcp connection: 172.27.44.121 Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:1236]: tcpconn_new(): on port 5080, type 2 Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:1559]: tcpconn_add(): hashes: 3211:1018:0, 1 Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2950]: tcpconn_1st_send(): pending write on new connection 0x7f17a523df18 (-1/412 bytes written) (err: 11 - Resource temporarily unavailable) Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:653]: send_prepared_request_impl(): uac: 0x7f17a523c7d8 branch: 0 to 172.27.44.121:5080 Mar 23 15:14:04 CPaaSVM kamailio: 25(4272) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring Mar 23 15:14:04 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:3853]: handle_ser_child(): read response= 7f17a523df18, 5, fd 59 from 25 (4272) Mar 23 15:14:04 CPaaSVM kamailio: 42(4289) DEBUG: [core/io_wait.h:377]: io_watch_add(): DBG: io_watch_add(0xa94760, 59, 2, 0x7f17a523df18), fd_no=49 Mar 23 15:14:04 CPaaSVM kamailio: 42(4289) DEBUG: [core/io_wait.h:774]: io_watch_chg(): DBG: io_watch_chg (0xa94760, 59, 0x1, 0xffffffff) fd_no=50 called Mar 23 15:14:04 CPaaSVM kamailio: 42(4289) DEBUG: [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del (0xa94760, 59, -1, 0x0) fd_no=50 called Mar 23 15:14:04 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:4517]: handle_tcpconn_ev(): sending to child, events 1 Mar 23 15:14:04 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:4190]: send2child(): selected tcp worker idx:0 proc:34 pid:4281 for activity on [tcp:172.27.45.252:5060], 0x7f17a523df18 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/tcp_read.c:1759]: handle_io(): received n=8 con=0x7f17a523df18, fd=11 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/tcp_read.c:1560]: tcp_read_req(): content-length=0 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:620]: parse_msg(): SIP Reply (status): Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:622]: parse_msg(): version: Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:624]: parse_msg(): status: <200> Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:626]: parse_msg(): reason: Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=16 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [40]; uri=[sip:172.27.44.121:5080;transport=tcp] Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [ Mar 23 15:14:04 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <10> Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - reply - call-id: [256c9bc07a9ae4c6-4272@172.27.45.252] - cseq: [10 OPTIONS] Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) exec: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} *** cfgtrace:onreply_route=[DEFAULT_ONREPLY] c=[/etc/kamailio/kamailio.cfg] l=1171 a=16 n=if Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) exec: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} *** cfgtrace:onreply_route=[DEFAULT_ONREPLY] c=[/etc/kamailio/kamailio.cfg] l=1167 a=26 n=sanity_check Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:305]: check_required_headers(): check_required_headers entered Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:313]: check_required_headers(): check_required_headers passed Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:321]: check_via1_header(): check via1 header Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:521]: check_cseq_value(): check_cseq_value entered Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:549]: check_cseq_value(): check_cseq_value passed Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:558]: check_cl(): check_cl entered Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:583]: check_cl(): check_cl passed Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:732]: check_parse_uris(): check_parse_uris entered Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:750]: check_parse_uris(): looking up From header Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:764]: check_parse_uris(): parsing From header Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=c68ee56ad01a83b9653aebc5f96cd331-0f7cdfb9 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:790]: check_parse_uris(): parsing From URI Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:808]: check_parse_uris(): looking up To header Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:830]: check_parse_uris(): check_parse_uris(): parsing To URI Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity.c:875]: check_parse_uris(): check_parse_uris passed Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} sanity [sanity_mod.c:254]: w_sanity_check(): sanity checks result: 1 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_lookup.c:1036]: t_check_msg(): msg (0x7f17afed0980) id=1/4281 global id=0/0 T start=0xffffffffffffffff Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_lookup.c:913]: t_reply_matching(): t_reply_matching: hash 51169 label 0 branch 0 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_lookup.c:967]: t_reply_matching(): reply (0x7f17afed0980) matched an active transaction (T=0x7f17a523c5c0)! Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_lookup.c:1106]: t_check_msg(): msg (0x7f17afed0980) id=1/4281 global id=1/4281 T end=0x7f17a523c5c0 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_reply.c:2242]: reply_received(): transaction found - T:0x7f17a523c5c0 branch:0 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_reply.c:2257]: reply_received(): org. status uas=0, uac[0]=0 local=2 is_invite=0) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_reply.c:1274]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_reply.c:1518]: t_should_relay_response(): rps completed - uas status: 0 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_reply.c:2118]: local_reply(): branch=0, save=0, winner=0 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_reply.c:2157]: local_reply(): local transaction completed 200/0 (totag retr: 0/1024) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7f17a523c5c0, callback type 1024, id 0 entered Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} dispatcher [dispatch.c:3158]: ds_options_callback(): OPTIONS-Request was finished with code 200 (to sip:172.27.44.121:5080;transport=tcp, group 1) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} dispatcher [dispatch.c:2702]: ds_update_state(): update state for sip:172.27.44.121:5080;transport=tcp in group 1 to 8 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} dispatcher [dispatch.c:2778]: ds_update_state(): old state was 0, set new state to 8 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} tm [t_reply.c:1667]: cleanup_uac_timers(): RETR/FR timers reset Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/receive.c:519]: receive_msg(): reply-route executed in: 1527 usec Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} dialog [dlg_var.c:97]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c6-4272@172.27.45.252} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:04 CPaaSVM kamailio: 34(4281) DEBUG: [core/io_wait.h:377]: io_watch_add(): DBG: io_watch_add(0xb06c80, 11, 2, 0x7f17a523df18), fd_no=1 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/370) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.220:49984;branch=z9hG4bK.5a48f1ec]] Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:05 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [958226500@172.27.45.220] - cseq: [1 OPTIONS] Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 958226500@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/receive.c:438]: receive_msg(): request-route executed in: 1267 usec Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:05 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 958226500@172.27.45.220} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/370) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.219:38282;branch=z9hG4bK.39f9b833]] Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:07 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [385519722@172.27.45.219] - cseq: [1 OPTIONS] Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 385519722@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/receive.c:438]: receive_msg(): request-route executed in: 1098 usec Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:07 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 385519722@172.27.45.219} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:09 CPaaSVM kamailio: 26(4273) DEBUG: tm [timer.c:628]: wait_handler(): finished transaction: 0x7f17a523c5c0 (p:0x7f17a5180250/n:0x7f17a5180250) Mar 23 15:14:09 CPaaSVM kamailio: 26(4273) DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f17a523c5c0 from timer.c:636 Mar 23 15:14:10 CPaaSVM kamailio: 34(4281) DEBUG: [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del (0xb06c80, 11, -1, 0x10) fd_no=2 called Mar 23 15:14:10 CPaaSVM kamailio: 34(4281) DEBUG: [core/tcp_read.c:1683]: release_tcpconn(): releasing con 0x7f17a523df18, state 1, fd=11, id=1 ([172.27.44.121]:5080 -> [172.27.44.121]:0) Mar 23 15:14:10 CPaaSVM kamailio: 34(4281) DEBUG: [core/tcp_read.c:1684]: release_tcpconn(): extra_data (nil) Mar 23 15:14:10 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:3620]: handle_tcp_child(): reader response= 7f17a523df18, 1 from 0 Mar 23 15:14:10 CPaaSVM kamailio: 42(4289) DEBUG: [core/io_wait.h:377]: io_watch_add(): DBG: io_watch_add(0xa94760, 59, 2, 0x7f17a523df18), fd_no=49 Mar 23 15:14:10 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:3747]: handle_tcp_child(): CONN_RELEASE 0x7f17a523df18 refcnt= 1 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/371) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.220:50751;branch=z9hG4bK.5c0755da]] Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:10 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [1589157216@172.27.45.220] - cseq: [1 OPTIONS] Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1589157216@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/receive.c:438]: receive_msg(): request-route executed in: 1062 usec Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:10 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1589157216@172.27.45.220} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/371) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.219:45536;branch=z9hG4bK.7517c30e]] Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:12 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [1730972588@172.27.45.219] - cseq: [1 OPTIONS] Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 1730972588@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} [core/receive.c:438]: receive_msg(): request-route executed in: 821 usec Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:12 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 1730972588@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:24 CPaaSVM journal: Suppressed 3664 messages from /system.slice/kamailio.service Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3201]: ds_ping_result_helper(): probe all, mode DS_PROBE_ALL Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3242]: ds_ping_set(): probing set #1, URI sip:172.27.44.121:5080;transport=tcp Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3266]: ds_ping_set(): Default ping_from: sip:dispatcher@localhost Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:436]: t_uac_prepare(): next_hop= Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:158]: dlg2hash(): hashid 51184 Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:1671]: _tcpconn_find(): found connection by peer address (id: 1) Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2545]: tcpconn_send_put(): found fd in cache (11, 0x7f17a523df18, 1) Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2767]: tcpconn_do_send(): sending... Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2801]: tcpconn_do_send(): after real write: c= 0x7f17a523df18 n=412 fd=11 Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2802]: tcpconn_do_send(): buf= Mar 23 15:14:24 CPaaSVM kamailio: OPTIONS sip:172.27.44.121:5080;transport=tcp SIP/2.0 Mar 23 15:14:24 CPaaSVM kamailio: Via: SIP/2.0/TCP 172.27.45.252;branch=z9hG4bK0f7c.7ebd0db6000000000000000000000000.0 Mar 23 15:14:24 CPaaSVM kamailio: To: Mar 23 15:14:24 CPaaSVM kamailio: From: ;tag=c68ee56ad01a83b9653aebc5f96cd331-0feddfb9 Mar 23 15:14:24 CPaaSVM kamailio: CSeq: 10 OPTIONS Mar 23 15:14:24 CPaaSVM kamailio: Call-ID: 256c9bc07a9ae4c8-4272@172.27.45.252 Mar 23 15:14:24 CPaaSVM kamailio: Max-Forwards: 70 Mar 23 15:14:24 CPaaSVM kamailio: Content-Length: 0 Mar 23 15:14:24 CPaaSVM kamailio: User-Agent: kamailio (5.3.2 (x86_64/linux)) Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:653]: send_prepared_request_impl(): uac: 0x7f17a523c7d8 branch: 0 to 172.27.44.121:5080 Mar 23 15:14:24 CPaaSVM kamailio: 25(4272) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring Mar 23 15:14:24 CPaaSVM kamailio: 42(4289) DEBUG: [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del (0xa94760, 59, -1, 0x0) fd_no=50 called Mar 23 15:14:24 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:4517]: handle_tcpconn_ev(): sending to child, events 1 Mar 23 15:14:24 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:4190]: send2child(): selected tcp worker idx:2 proc:36 pid:4283 for activity on [tcp:172.27.45.252:5060], 0x7f17a523df18 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/tcp_read.c:1759]: handle_io(): received n=8 con=0x7f17a523df18, fd=11 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/tcp_read.c:1560]: tcp_read_req(): content-length=0 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:620]: parse_msg(): SIP Reply (status): Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:622]: parse_msg(): version: Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:624]: parse_msg(): status: <200> Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:626]: parse_msg(): reason: Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=16 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [40]; uri=[sip:172.27.44.121:5080;transport=tcp] Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [ Mar 23 15:14:24 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <10> Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - reply - call-id: [256c9bc07a9ae4c8-4272@172.27.45.252] - cseq: [10 OPTIONS] Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) exec: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} *** cfgtrace:onreply_route=[DEFAULT_ONREPLY] c=[/etc/kamailio/kamailio.cfg] l=1171 a=16 n=if Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) exec: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} *** cfgtrace:onreply_route=[DEFAULT_ONREPLY] c=[/etc/kamailio/kamailio.cfg] l=1167 a=26 n=sanity_check Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:305]: check_required_headers(): check_required_headers entered Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:313]: check_required_headers(): check_required_headers passed Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:321]: check_via1_header(): check via1 header Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:521]: check_cseq_value(): check_cseq_value entered Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:549]: check_cseq_value(): check_cseq_value passed Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:558]: check_cl(): check_cl entered Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:583]: check_cl(): check_cl passed Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:732]: check_parse_uris(): check_parse_uris entered Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:750]: check_parse_uris(): looking up From header Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:764]: check_parse_uris(): parsing From header Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=c68ee56ad01a83b9653aebc5f96cd331-0feddfb9 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:790]: check_parse_uris(): parsing From URI Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:808]: check_parse_uris(): looking up To header Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:830]: check_parse_uris(): check_parse_uris(): parsing To URI Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity.c:875]: check_parse_uris(): check_parse_uris passed Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} sanity [sanity_mod.c:254]: w_sanity_check(): sanity checks result: 1 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_lookup.c:1036]: t_check_msg(): msg (0x7f17afed0980) id=1/4283 global id=0/0 T start=0xffffffffffffffff Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_lookup.c:913]: t_reply_matching(): t_reply_matching: hash 51184 label 0 branch 0 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_lookup.c:967]: t_reply_matching(): reply (0x7f17afed0980) matched an active transaction (T=0x7f17a523c5c0)! Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_lookup.c:1106]: t_check_msg(): msg (0x7f17afed0980) id=1/4283 global id=1/4283 T end=0x7f17a523c5c0 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_reply.c:2242]: reply_received(): transaction found - T:0x7f17a523c5c0 branch:0 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_reply.c:2257]: reply_received(): org. status uas=0, uac[0]=0 local=2 is_invite=0) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_reply.c:1274]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_reply.c:1518]: t_should_relay_response(): rps completed - uas status: 0 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_reply.c:2118]: local_reply(): branch=0, save=0, winner=0 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_reply.c:2157]: local_reply(): local transaction completed 200/0 (totag retr: 0/1024) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7f17a523c5c0, callback type 1024, id 0 entered Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} dispatcher [dispatch.c:3158]: ds_options_callback(): OPTIONS-Request was finished with code 200 (to sip:172.27.44.121:5080;transport=tcp, group 1) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} dispatcher [dispatch.c:2702]: ds_update_state(): update state for sip:172.27.44.121:5080;transport=tcp in group 1 to 8 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} dispatcher [dispatch.c:2778]: ds_update_state(): old state was 8, set new state to 8 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} tm [t_reply.c:1667]: cleanup_uac_timers(): RETR/FR timers reset Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/receive.c:519]: receive_msg(): reply-route executed in: 1081 usec Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} dialog [dlg_var.c:97]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c8-4272@172.27.45.252} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:24 CPaaSVM kamailio: 36(4283) DEBUG: [core/io_wait.h:377]: io_watch_add(): DBG: io_watch_add(0xb06c80, 11, 2, 0x7f17a523df18), fd_no=1 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/368) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.220:55441;branch=z9hG4bK.27716572]] Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:25 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [92345149@172.27.45.220] - cseq: [1 OPTIONS] Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) exec: {1 1 OPTIONS 92345149@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/receive.c:438]: receive_msg(): request-route executed in: 571 usec Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:25 CPaaSVM kamailio: 9(4256) DEBUG: {1 1 OPTIONS 92345149@172.27.45.220} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/371) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.219:60606;branch=z9hG4bK.2b8b72c3]] Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:27 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [1202213019@172.27.45.219] - cseq: [1 OPTIONS] Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) exec: {1 1 OPTIONS 1202213019@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/receive.c:438]: receive_msg(): request-route executed in: 233 usec Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:27 CPaaSVM kamailio: 13(4260) DEBUG: {1 1 OPTIONS 1202213019@172.27.45.219} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:29 CPaaSVM kamailio: 26(4273) DEBUG: tm [timer.c:628]: wait_handler(): finished transaction: 0x7f17a523c5c0 (p:0x7f17a5180430/n:0x7f17a5180430) Mar 23 15:14:29 CPaaSVM kamailio: 26(4273) DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f17a523c5c0 from timer.c:636 Mar 23 15:14:30 CPaaSVM kamailio: 36(4283) DEBUG: [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del (0xb06c80, 11, -1, 0x10) fd_no=2 called Mar 23 15:14:30 CPaaSVM kamailio: 36(4283) DEBUG: [core/tcp_read.c:1683]: release_tcpconn(): releasing con 0x7f17a523df18, state 1, fd=11, id=1 ([172.27.44.121]:5080 -> [172.27.44.121]:0) Mar 23 15:14:30 CPaaSVM kamailio: 36(4283) DEBUG: [core/tcp_read.c:1684]: release_tcpconn(): extra_data (nil) Mar 23 15:14:30 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:3620]: handle_tcp_child(): reader response= 7f17a523df18, 1 from 2 Mar 23 15:14:30 CPaaSVM kamailio: 42(4289) DEBUG: [core/io_wait.h:377]: io_watch_add(): DBG: io_watch_add(0xa94760, 59, 2, 0x7f17a523df18), fd_no=49 Mar 23 15:14:30 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:3747]: handle_tcp_child(): CONN_RELEASE 0x7f17a523df18 refcnt= 1 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/371) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.220:50448;branch=z9hG4bK.7e2107d8]] Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:30 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [1302035469@172.27.45.220] - cseq: [1 OPTIONS] Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) exec: {1 1 OPTIONS 1302035469@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/receive.c:438]: receive_msg(): request-route executed in: 434 usec Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:30 CPaaSVM kamailio: 16(4263) DEBUG: {1 1 OPTIONS 1302035469@172.27.45.220} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/370) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.219:36657;branch=z9hG4bK.0cb46604]] Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:32 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [625174260@172.27.45.219] - cseq: [1 OPTIONS] Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) exec: {1 1 OPTIONS 625174260@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/receive.c:438]: receive_msg(): request-route executed in: 145 usec Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:32 CPaaSVM kamailio: 15(4262) DEBUG: {1 1 OPTIONS 625174260@172.27.45.219} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3201]: ds_ping_result_helper(): probe all, mode DS_PROBE_ALL Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3242]: ds_ping_set(): probing set #1, URI sip:172.27.44.121:5080;transport=tcp Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: dispatcher [dispatch.c:3266]: ds_ping_set(): Default ping_from: sip:dispatcher@localhost Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:436]: t_uac_prepare(): next_hop= Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:158]: dlg2hash(): hashid 51183 Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:1671]: _tcpconn_find(): found connection by peer address (id: 1) Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2545]: tcpconn_send_put(): found fd in cache (11, 0x7f17a523df18, 1) Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2767]: tcpconn_do_send(): sending... Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2801]: tcpconn_do_send(): after real write: c= 0x7f17a523df18 n=412 fd=11 Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: [core/tcp_main.c:2802]: tcpconn_do_send(): buf= Mar 23 15:14:34 CPaaSVM kamailio: OPTIONS sip:172.27.44.121:5080;transport=tcp SIP/2.0 Mar 23 15:14:34 CPaaSVM kamailio: Via: SIP/2.0/TCP 172.27.45.252;branch=z9hG4bKfe7c.39aadd50000000000000000000000000.0 Mar 23 15:14:34 CPaaSVM kamailio: To: Mar 23 15:14:34 CPaaSVM kamailio: From: ;tag=c68ee56ad01a83b9653aebc5f96cd331-6a10dfb9 Mar 23 15:14:34 CPaaSVM kamailio: CSeq: 10 OPTIONS Mar 23 15:14:34 CPaaSVM kamailio: Call-ID: 256c9bc07a9ae4c9-4272@172.27.45.252 Mar 23 15:14:34 CPaaSVM kamailio: Max-Forwards: 70 Mar 23 15:14:34 CPaaSVM kamailio: Content-Length: 0 Mar 23 15:14:34 CPaaSVM kamailio: User-Agent: kamailio (5.3.2 (x86_64/linux)) Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: tm [uac.c:653]: send_prepared_request_impl(): uac: 0x7f17a523c7d8 branch: 0 to 172.27.44.121:5080 Mar 23 15:14:34 CPaaSVM kamailio: 25(4272) DEBUG: tm [../../core/onsend.h:69]: run_onsend(): required parameters are not available - ignoring Mar 23 15:14:34 CPaaSVM kamailio: 42(4289) DEBUG: [core/io_wait.h:599]: io_watch_del(): DBG: io_watch_del (0xa94760, 59, -1, 0x0) fd_no=50 called Mar 23 15:14:34 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:4517]: handle_tcpconn_ev(): sending to child, events 1 Mar 23 15:14:34 CPaaSVM kamailio: 42(4289) DEBUG: [core/tcp_main.c:4190]: send2child(): selected tcp worker idx:3 proc:37 pid:4284 for activity on [tcp:172.27.45.252:5060], 0x7f17a523df18 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/tcp_read.c:1759]: handle_io(): received n=8 con=0x7f17a523df18, fd=11 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/tcp_read.c:1560]: tcp_read_req(): content-length=0 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:620]: parse_msg(): SIP Reply (status): Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:622]: parse_msg(): version: Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:624]: parse_msg(): status: <200> Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:626]: parse_msg(): reason: Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=16 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [40]; uri=[sip:172.27.44.121:5080;transport=tcp] Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [ Mar 23 15:14:34 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <10> Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - reply - call-id: [256c9bc07a9ae4c9-4272@172.27.45.252] - cseq: [10 OPTIONS] Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) exec: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} *** cfgtrace:onreply_route=[DEFAULT_ONREPLY] c=[/etc/kamailio/kamailio.cfg] l=1171 a=16 n=if Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) exec: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} *** cfgtrace:onreply_route=[DEFAULT_ONREPLY] c=[/etc/kamailio/kamailio.cfg] l=1167 a=26 n=sanity_check Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:305]: check_required_headers(): check_required_headers entered Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:313]: check_required_headers(): check_required_headers passed Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:321]: check_via1_header(): check via1 header Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:521]: check_cseq_value(): check_cseq_value entered Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:549]: check_cseq_value(): check_cseq_value passed Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:558]: check_cl(): check_cl entered Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:583]: check_cl(): check_cl passed Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:732]: check_parse_uris(): check_parse_uris entered Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:750]: check_parse_uris(): looking up From header Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:764]: check_parse_uris(): parsing From header Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=c68ee56ad01a83b9653aebc5f96cd331-6a10dfb9 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:790]: check_parse_uris(): parsing From URI Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:808]: check_parse_uris(): looking up To header Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:830]: check_parse_uris(): check_parse_uris(): parsing To URI Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity.c:875]: check_parse_uris(): check_parse_uris passed Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} sanity [sanity_mod.c:254]: w_sanity_check(): sanity checks result: 1 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_lookup.c:1036]: t_check_msg(): msg (0x7f17afed0980) id=1/4284 global id=0/0 T start=0xffffffffffffffff Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_lookup.c:913]: t_reply_matching(): t_reply_matching: hash 51183 label 0 branch 0 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_lookup.c:967]: t_reply_matching(): reply (0x7f17afed0980) matched an active transaction (T=0x7f17a523c5c0)! Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_lookup.c:1106]: t_check_msg(): msg (0x7f17afed0980) id=1/4284 global id=1/4284 T end=0x7f17a523c5c0 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_reply.c:2242]: reply_received(): transaction found - T:0x7f17a523c5c0 branch:0 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_reply.c:2257]: reply_received(): org. status uas=0, uac[0]=0 local=2 is_invite=0) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_reply.c:1274]: t_should_relay_response(): ->>>>>>>>> T_code=0, new_code=200 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_reply.c:1518]: t_should_relay_response(): rps completed - uas status: 0 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_reply.c:2118]: local_reply(): branch=0, save=0, winner=0 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_reply.c:2157]: local_reply(): local transaction completed 200/0 (totag retr: 0/1024) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_hooks.c:252]: run_trans_callbacks_internal(): DBG: trans=0x7f17a523c5c0, callback type 1024, id 0 entered Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} dispatcher [dispatch.c:3158]: ds_options_callback(): OPTIONS-Request was finished with code 200 (to sip:172.27.44.121:5080;transport=tcp, group 1) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} dispatcher [dispatch.c:2702]: ds_update_state(): update state for sip:172.27.44.121:5080;transport=tcp in group 1 to 8 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} dispatcher [dispatch.c:2778]: ds_update_state(): old state was 8, set new state to 8 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} tm [t_reply.c:1667]: cleanup_uac_timers(): RETR/FR timers reset Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/receive.c:519]: receive_msg(): reply-route executed in: 1283 usec Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} dialog [dlg_var.c:97]: cb_dlg_locals_reset(): resetting the local dialog shortcuts on script callback: 2147483652 Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: {2 10 OPTIONS 256c9bc07a9ae4c9-4272@172.27.45.252} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:34 CPaaSVM kamailio: 37(4284) DEBUG: [core/io_wait.h:377]: io_watch_add(): DBG: io_watch_add(0xb06c80, 11, 2, 0x7f17a523df18), fd_no=1 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/370) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.220:39553;branch=z9hG4bK.12ec3666]] Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:35 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [458186775@172.27.45.220] - cseq: [1 OPTIONS] Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) exec: {1 1 OPTIONS 458186775@172.27.45.220} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/receive.c:438]: receive_msg(): request-route executed in: 669 usec Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:35 CPaaSVM kamailio: 14(4261) DEBUG: {1 1 OPTIONS 458186775@172.27.45.220} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/371) [[OPTIONS sip:172.27.45.219:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.45.219:56383;branch=z9hG4bK.5b753f3d]] Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=6 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 235, = ; state=6 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 237, = ; state=16 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=9 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [24]; uri=[sip:172.27.45.219:5060] Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [sip:172.27.45.219:5060 Mar 23 15:14:37 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [1712086629@172.27.45.219] - cseq: [1 OPTIONS] Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=788 a=26 n=sl_send_reply Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) exec: {1 1 OPTIONS 1712086629@172.27.45.219} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=789 a=2 n=exit Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/receive.c:438]: receive_msg(): request-route executed in: 316 usec Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/xavp.c:529]: xavp_destroy_list(): destroying xavp list (nil) Mar 23 15:14:37 CPaaSVM kamailio: 11(4258) DEBUG: {1 1 OPTIONS 1712086629@172.27.45.219} [core/receive.c:529]: receive_msg(): cleaning up Mar 23 15:14:39 CPaaSVM kamailio: 26(4273) DEBUG: tm [timer.c:628]: wait_handler(): finished transaction: 0x7f17a523c5c0 (p:0x7f17a5180410/n:0x7f17a5180410) Mar 23 15:14:39 CPaaSVM kamailio: 26(4273) DEBUG: tm [h_table.c:132]: free_cell_helper(): freeing transaction 0x7f17a523c5c0 from timer.c:636 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/udp_server.c:499]: udp_rcv_loop(): received on udp socket: (106/100/607) [[INVITE sip:service@172.27.45.252:5060 SIP/2.0 0D 0A Via: SIP/2.0/UDP 172.27.44.121:5050;branch=z9hG4bK-52]] Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:612]: parse_msg(): method: Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:614]: parse_msg(): uri: Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:616]: parse_msg(): version: Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/parse_via.c:1303]: parse_via_param(): Found param type 232, = ; state=16 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:171]: get_hdr_field(): [42]; uri=[sip:service@172.27.45.252:5060] Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:174]: get_hdr_field(): to body [service Mar 23 15:14:39 CPaaSVM kamailio: ], to tag [] Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:152]: get_hdr_field(): cseq : <1> Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/receive.c:325]: receive_msg(): --- received sip message - request - call-id: [1-5214@172.27.44.121] - cseq: [1 INVITE] Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=137 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} [core/receive.c:380]: receive_msg(): preparing to run routing scripts... Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=575 a=25 n=is_method Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=582 a=5 n=route Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=67 n=set_rpl_no_connect Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} pv [pv_core.c:1032]: pv_get_useragent(): no User-Agent header Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=779 a=25 n=mf_process_maxfwd_header Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=786 a=25 n=is_method Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=800 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=795 a=25 n=is_method Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=804 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=800 a=26 n=sanity_check Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:247]: check_ruri_sip_version(): check_ruri_sip_version entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:270]: check_ruri_sip_version(): check_ruri_sip_version passed Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:277]: check_ruri_scheme(): check_ruri_scheme entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:297]: check_ruri_scheme(): check_ruri_scheme passed Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:305]: check_required_headers(): check_required_headers entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:313]: check_required_headers(): check_required_headers passed Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:321]: check_via1_header(): check via1 header Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:479]: check_cseq_method(): check_cseq_method entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:512]: check_cseq_method(): check_cseq_method passed Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:521]: check_cseq_value(): check_cseq_value entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:549]: check_cseq_value(): check_cseq_value passed Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:558]: check_cl(): check_cl entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:583]: check_cl(): check_cl passed Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:595]: check_expires_value(): check_expires_value entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:632]: check_expires_value(): no expires header found Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:732]: check_parse_uris(): check_parse_uris entered Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:736]: check_parse_uris(): parsing ruri Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:750]: check_parse_uris(): looking up From header Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:764]: check_parse_uris(): parsing From header Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=5214SIPpTag001 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:790]: check_parse_uris(): parsing From URI Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:808]: check_parse_uris(): looking up To header Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:830]: check_parse_uris(): check_parse_uris(): parsing To URI Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity.c:875]: check_parse_uris(): check_parse_uris passed Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sanity [sanity_mod.c:254]: w_sanity_check(): sanity checks result: 1 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=585 a=5 n=route Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=1005 a=2 n=return Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=601 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=593 a=25 n=is_method Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=610 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=601 a=25 n=is_method Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=606 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=602 a=24 n=t_precheck_trans Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=606 a=24 n=t_check_trans Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} tm [t_lookup.c:1036]: t_check_msg(): msg (0x7f17afed0980) id=1/4248 global id=0/0 T start=0xffffffffffffffff Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} tm [t_lookup.c:498]: t_lookup_request(): start searching: hash=29961, isACK=0 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} tm [t_lookup.c:456]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK-5214-1-0] Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} tm [t_lookup.c:675]: t_lookup_request(): no transaction found Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} tm [t_lookup.c:1106]: t_check_msg(): msg (0x7f17afed0980) id=1/4248 global id=1/4248 T end=(nil) Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=610 a=5 n=route Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=811 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=808 a=24 n=has_totag Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} siputils [checks.c:120]: has_totag(): no totag Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=808 a=2 n=return Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=620 a=25 n=remove_hf Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=626 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=621 a=25 n=is_method Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=622 a=24 n=record_route Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=649 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=626 a=25 n=is_method Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=627 a=39 n=setflag Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=629 a=39 n=setflag Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=630 a=39 n=setflag Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=631 a=63 n=assign Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=638 a=16 n=if Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=633 a=25 n=sstCheckMin Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sst [sst_handlers.c:668]: ki_sst_check_min(): Session-Expires: 200; MIN-SE: 100 Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) DEBUG: {1 1 INVITE 1-5214@172.27.44.121} sst [sst_handlers.c:692]: ki_sst_check_min(): Done returning false (-1) Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) exec: {1 1 INVITE 1-5214@172.27.44.121} *** cfgtrace:request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=638 a=26 n=xlog Mar 23 15:14:39 CPaaSVM kamailio: 1(4248) INFO: {1 1 INVITE 1-5214@172.27.44.121}