master@DualStackCS:~$ sudo rtpproxy -F -l 10.10.10.10 -6 /3001:0:0:1::10 
[sudo] password for master: 
master@DualStackCS:~$ ps aux|grep rtpproxy
root      1522  0.0  0.0  11384   696 ?        Ssl  15:31   0:00 rtpproxy -F -l 10.10.10.10 -6  3001:0:0:1::10
master    1528  0.0  0.0   3324   804 pts/1    S+   15:31   0:00 grep --color=auto rtpproxy
master@DualStackCS:~$ sudo /etc/init.d/kamailio start
^C
master@DualStackCS:~$ sudo /etc/init.d/kamailio start
Starting Kamailio:
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [ADDR_IPV4] value [10.10.10.10]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [ADDR_IPV6] value [[3001:0:0:1::10]]
 0(1563) DEBUG: <core> [cfg.y:1706]: loading modules under /usr/local/lib/kamailio/modules_k/:/usr/local/lib/kamailio/modules/
loading modules under /usr/local/lib/kamailio/modules_k/:/usr/local/lib/kamailio/modules/
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module db_mysql.so
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/db_mysql.so>
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/db_mysql/db_mysql.so>
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/db_mysql.so>
 0(1563) DEBUG: <core> [db_query.c:351]: About to allocate sql_buf size = 65535
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module mi_fifo.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/mi_fifo.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module kex.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/kex.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module tm.so
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/tm.so>
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/tm/tm.so>
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/tm.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module tmx.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/tmx.so>
 0(1563) DEBUG: <core> [sr_module.c:343]: register PV from: tmx
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module sl.so
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/sl.so>
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/sl/sl.so>
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/sl.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module rr.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/rr.so>
 0(1563) DEBUG: <core> [sr_module.c:343]: register PV from: rr
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module pv.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/pv.so>
 0(1563) DEBUG: <core> [sr_module.c:343]: register PV from: pv
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module maxfwd.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/maxfwd.so>                                                                 
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module usrloc.so                                                                                                                       
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/usrloc.so>                                                                 
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module registrar.so                                                                                                                    
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/registrar.so>                                                              
 0(1563) DEBUG: <core> [sr_module.c:343]: register PV from: registrar                                                                                                               
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module textops.so                                                                                                                      
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/textops.so>                                                                
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module siputils.so                                                                                                                     
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/siputils.so>                                                               
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module xlog.so                                                                                                                         
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/xlog.so>                                                                   
 0(1563) DEBUG: <core> [sr_module.c:343]: register PV from: xlog                                                                                                                    
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module sanity.so                                                                                                                       
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/sanity.so>                                                          
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/sanity/sanity.so>                                                   
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/sanity.so>                                                                   
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module ctl.so                                                                                                                          
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/ctl.so>                                                             
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/ctl/ctl.so>                                                         
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/ctl.so>                                                                      
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module mi_rpc.so
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/mi_rpc.so>
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/mi_rpc/mi_rpc.so>
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/mi_rpc.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module acc.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/acc.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module auth.so
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/auth.so>
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/auth/auth.so>
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/auth.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module auth_db.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/auth_db.so>
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module nathelper.so
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules_k/nathelper.so>
 0(1563) DEBUG: <core> [sr_module.c:343]: register PV from: nathelper
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module rtpproxy.so
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/rtpproxy.so>
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/rtpproxy/rtpproxy.so>
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/rtpproxy.so>
 0(1563) DEBUG: <core> [sr_module.c:343]: register PV from: rtpproxy
 0(1563) DEBUG: <core> [cfg.y:1693]: loading module debugger.so
 0(1563) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/lib/kamailio/modules_k/debugger.so>
 0(1563) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/lib/kamailio/modules_k/debugger/debugger.so>
 0(1563) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/lib/kamailio/modules/debugger.so>
 0(1563) DEBUG: <core> [mem/q_malloc.c:202]: qm_malloc_init: QM_OPTIMIZE=16384, /ROUNDTO=4096
 0(1563) DEBUG: <core> [mem/q_malloc.c:204]: qm_malloc_init: QM_HASH_SIZE=4115, qm_block size=214004
 0(1563) DEBUG: <core> [mem/q_malloc.c:205]: qm_malloc_init(0xb34a2000, 67108864), start=0xb34a2000
 0(1563) DEBUG: <core> [mem/q_malloc.c:213]: qm_malloc_init: size= 67108864, init_overhead=214052
 0(1563) DEBUG: <core> [mem/shm_mem.c:203]: shm_mem_init: success
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'mi_fifo' matches module 'mi_fifo'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fifo_name> in module mi_fifo [/usr/local/lib/kamailio/modules_k/mi_fifo.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fifo_name> in module mi_fifo [/usr/local/lib/kamailio/modules_k/mi_fifo.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <failure_reply_mode> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <failure_reply_mode> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fr_timer> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fr_timer> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fr_inv_timer> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fr_inv_timer> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rr' matches module 'rr'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <enable_full_lr> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <enable_full_lr> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rr' matches module 'rr'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <append_fromtag> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <append_fromtag> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'registrar' matches module 'registrar'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <method_filtering> in module registrar [/usr/local/lib/kamailio/modules_k/registrar.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <method_filtering> in module registrar [/usr/local/lib/kamailio/modules_k/registrar.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <early_media> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <early_media> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <report_ack> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <report_ack> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <report_cancels> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <report_cancels> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <detect_direction> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <detect_direction> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_ACC] value [1]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_flag> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_flag> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_ACCMISSED] value [2]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_missed_flag> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_missed_flag> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_extra> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_extra> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_ACCFAILED] value [3]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <failed_transaction_flag> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <failed_transaction_flag> in module acc [/usr/local/lib/kamailio/modules_k/acc.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [DBURL] value ["mysql://openser:siph434@localhost/DualStackDB"]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_mode> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_mode> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [MULTIDOMAIN] value [0]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <use_domain> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <use_domain> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [DBURL] value ["mysql://openser:siph434@localhost/DualStackDB"]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <calculate_ha1> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <calculate_ha1> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <password_column> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <password_column> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <load_credentials> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <load_credentials> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [MULTIDOMAIN] value [0]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <use_domain> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <use_domain> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rtpproxy' matches module 'rtpproxy'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <rtpproxy_sock> in module rtpproxy [/usr/local/lib/kamailio/modules/rtpproxy.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <rtpproxy_sock> in module rtpproxy [/usr/local/lib/kamailio/modules/rtpproxy.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <natping_interval> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <natping_interval> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <ping_nated_only> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <ping_nated_only> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_NATSIPPING] value [2]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sipping_bflag> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sipping_bflag> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sipping_from> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sipping_from> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper|registrar' matches module 'nathelper'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <received_avp> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <received_avp> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper|registrar' matches module 'registrar'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <received_avp> in module registrar [/usr/local/lib/kamailio/modules_k/registrar.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <received_avp> in module registrar [/usr/local/lib/kamailio/modules_k/registrar.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_NATB] value [1]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <nat_bflag> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <nat_bflag> in module usrloc [/usr/local/lib/kamailio/modules_k/usrloc.so]
 0(1563) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'debugger' matches module 'debugger'
 0(1563) DEBUG: <core> [sr_module.c:770]: find_param_export: found <cfgtrace> in module debugger [/usr/local/lib/kamailio/modules/debugger.so]
 0(1563) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <cfgtrace> in module debugger [/usr/local/lib/kamailio/modules/debugger.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74e8438
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74e858c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74e86e0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74e8f40
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74e93a4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74e8a38
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74e9448
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74e9788
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <remove_hf> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <record_route> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ea098
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74ea584
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_ACC] value [1]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ea8d0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74eacbc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74eae04
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74eaf58
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74eb0ac
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ebb24
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74ebf88
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74ec688
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74ec7dc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74ec180
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_branch> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_reply> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74eca7c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74ecf24
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_failure> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ed820
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74ed6a8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_reply_error> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ee37c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74ede7c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <mf_process_maxfwd_header> in module maxfwd [/usr/local/lib/kamailio/modules_k/maxfwd.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74eef48
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74ef3ac
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sanity_check> in module sanity [/usr/local/lib/kamailio/modules/sanity.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xlog> in module xlog [/usr/local/lib/kamailio/modules_k/xlog.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ef964
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f02d8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/lib/kamailio/modules_k/siputils.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <loose_route> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_ACC] value [1]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x1
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_ACCFAILED] value [3]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x3
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f1348
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f16fc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74f1ed4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f1aec
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f1f84
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74f20cc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74f3008
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f2c54
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f30b8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f39b0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f3e14
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb74f3f5c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f34a8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f4000
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f0d90
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f1844
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb74f3200
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f0888
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f4388
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_NATS] value [5]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#42 #0/1: 3(3)/ 0x5
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_NATB] value [1]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f4f88
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f533c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_IPV6] value [6]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f5c3c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f60a0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <save> in module registrar [/usr/local/lib/kamailio/modules_k/registrar.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_reply_error> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f6b90
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f6ff4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f4bd4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f5794
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [usr_avp.c:895]: Parsing 'oexten'
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #0/2: 21(15)/ 0xb74f7488
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #1/2: 22(16)/ 0xb74f7598
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <lookup> in module registrar [/usr/local/lib/kamailio/modules_k/registrar.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #0/2: 21(15)/ 0xb74f84b0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #1/2: 22(16)/ 0xb74f8510
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb74f8968
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#18 #0/2: 22(16)/ 0xb74f8cc0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#18 #1/2: 24(18)/ 0xb74f840c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74f7fa8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74f88c4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_ACCMISSED] value [2]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74faa48
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74fa770
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74fb43c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74fb7f0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74fcbc8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74fd02c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <www_authorize> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <www_challenge> in module auth [/usr/local/lib/kamailio/modules/auth.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74fde30
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74fe768
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ff4f0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74ff8a4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <proxy_authorize> in module auth_db [/usr/local/lib/kamailio/modules_k/auth_db.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <proxy_challenge> in module auth [/usr/local/lib/kamailio/modules/auth.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7500acc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7500330
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1

 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7502f3c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb75033a0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb75040c0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7504524
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7505244
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb75056a8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7500e80
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb75035dc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb75058e4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <consume_credentials> in module auth [/usr/local/lib/kamailio/modules/auth.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/lib/kamailio/modules/sl.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7506014
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7506478
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74ffe5c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb75012d8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb75066b0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb74fd9cc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb74fedc4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb7506754
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb750720c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7506b78
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <nat_uac_test> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_register> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_contact> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7507d7c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750821c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb7508370
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_NATS] value [5]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x5
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb75077c4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7508414
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_request> in module siputils [/usr/local/lib/kamailio/modules_k/siputils.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/lib/kamailio/modules_k/siputils.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <check_route_param> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_NATB] value [1]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <check_route_param> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_V4V6] value [7]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb750a638
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750aa9c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb750978c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7509bf0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb750aef4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7508e20
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750af98
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7508918
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750b03c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLT_NATS] value [5]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#42 #0/1: 3(3)/ 0x5
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_NATB] value [1]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_V4V6] value [7]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb750cebc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750d270
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_V4V6] value [7]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <rtpproxy_manage> in module rtpproxy [/usr/local/lib/kamailio/modules/rtpproxy.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <rtpproxy_manage> in module rtpproxy [/usr/local/lib/kamailio/modules/rtpproxy.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb750e078
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750e4dc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb750eacc
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <rtpproxy_manage> in module rtpproxy [/usr/local/lib/kamailio/modules/rtpproxy.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <rtpproxy_manage> in module rtpproxy [/usr/local/lib/kamailio/modules/rtpproxy.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb750f4b4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750f918
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb750ff08
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb750d8c0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb750f00c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb7510448
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_request> in module siputils [/usr/local/lib/kamailio/modules_k/siputils.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/lib/kamailio/modules_k/siputils.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_V4V6] value [7]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <add_rr_param> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <add_rr_param> in module rr [/usr/local/lib/kamailio/modules_k/rr.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb751185c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7511cc0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb7511ec4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7510fa0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7512054
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb75106e4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb75120f8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_reply> in module siputils [/usr/local/lib/kamailio/modules_k/siputils.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_NATB] value [1]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_contact> in module nathelper [/usr/local/lib/kamailio/modules_k/nathelper.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7513514
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7513978
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7512d5c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7513a1c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb75125f4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7513ac0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <append_hf> in module textops [/usr/local/lib/kamailio/modules_k/textops.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb7514760
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb75141a8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb751460c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/lib/kamailio/modules_k/siputils.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_IPV6] value [6]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_V4V6] value [7]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_IPV6] value [6]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [cfg.lex:1852]: ### returning define ID [FLB_V4V6] value [7]
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/lib/kamailio/modules_k/kex.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7518444
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb75188a8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7516b14
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7516f78
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0xb7518d00
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb7515338
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7518da4
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xdbg> in module xlog [/usr/local/lib/kamailio/modules_k/xlog.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb751928c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb75193e0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xdbg> in module xlog [/usr/local/lib/kamailio/modules_k/xlog.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb75199ec
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb751a094
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb7519a9c
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0xb751a4f8
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
 0(1563) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_is_canceled> in module tm [/usr/local/lib/kamailio/modules/tm.so]
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0xb751a7a0
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0xb751ab54
 0(1563) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ (nil)
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 0:
 0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "REQINIT" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "NATDETECT" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "WITHINDLG" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ddc64> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74de184> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "AUTH" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e29a4> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74df064> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:411]: setflag( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "SIPOUT" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "PRESENCE" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "REGISTRAR" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "484" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "PSTN" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "LOCATION" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "RELAY" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 1:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dde44> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , "MANAGE_BRANCH" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dde24> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , "MANAGE_REPLY" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dde04> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , "MANAGE_FAILURE" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dedac> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 2:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "483" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e3310> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , "Malformed SIP message from $si:$sp
" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 3:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:411]: setflag( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:411]: setflag( 0(1563) DEBUG: <core> [route_struct.c:498]: 3 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "RELAY" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "PRESENCE" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ddc64> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "404" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 4:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dd630> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dd630> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dedac> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 5:
 0(1563) DEBUG: <core> [route_struct.c:485]: assign( 0(1563) DEBUG: <core> [route_struct.c:526]: type<21> 0(1563) DEBUG: <core> [route_struct.c:560]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:485]: assign( 0(1563) DEBUG: <core> [route_struct.c:526]: type<21> 0(1563) DEBUG: <core> [route_struct.c:560]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "TOVOICEMAIL" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dda04> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:491]: UNKNOWN( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:560]: , type<24> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:411]: setflag( 0(1563) DEBUG: <core> [route_struct.c:498]: 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 6:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "404" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 7:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4200> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "$td" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "403" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4220> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "$fd" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "403" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "403" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "403" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e41e0> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74ded4c> 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:567]: , "403" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 8:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:464]: force_rport( 0(1563) DEBUG: <core> [route_struct.c:526]: type<0> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e47d0> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4710> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:411]: setflag( 0(1563) DEBUG: <core> [route_struct.c:498]: 5 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 9:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dd630> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dd630> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4d28> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4d28> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4d28> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4d28> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74df104> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , ";nat=v46" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74df104> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , ";nat=yes" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e4710> 0(1563) DEBUG: <core> [route_struct.c:536]: , 0 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 10:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e2904> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , "P-hint: outbound
" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "RELAY" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 11:
 0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 12:
 0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]:  routing table 13:
 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dd630> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74dd630> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:586]: , type<22> 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 2 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]: onreply routing table 1:
 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e3350> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , "incoming reply
" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]: failure routing table 1:
 0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:444]: if ( 0(1563) DEBUG: <core> [route_struct.c:526]: type<22> 0(1563) DEBUG: <core> [route_struct.c:528]: ) { 0(1563) DEBUG: <core> [route_struct.c:381]: drop( 0(1563) DEBUG: <core> [route_struct.c:498]: 1 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:562]: } else { 0(1563) DEBUG: <core> [route_struct.c:588]: };  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) DEBUG: <core> [route.c:2207]: branch routing table 1:
 0(1563) DEBUG: <core> [route_struct.c:461]:  external_module_call( 0(1563) DEBUG: <core> [route_struct.c:510]: f_ptr<0xb74e3350> 0(1563) DEBUG: <core> [route_struct.c:536]: , 1 0(1563) DEBUG: <core> [route_struct.c:567]: , "new branch [$T_branch_idx] to $ru
" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "IPV4V6" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route_struct.c:390]: route( 0(1563) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(1563) DEBUG: <core> [route_struct.c:589]: );  0(1563) DEBUG: <core> [route.c:2209]: 
 0(1563) WARNING: <core> [socket_info.c:1275]: WARNING: fix_hostname: could not rev. resolve 3001:0:0:1:0:0:0:10


 0(1579) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 0(1579) DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb74f5a10
 0(1579) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(1579) DEBUG: <core> [db_res.c:155]: allocate 4 bytes for result names at 0xb74f634c
 0(1579) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0xb74f6380
 0(1579) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb74f5a5c
 0(1579) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb74f5a5c)[0]=[table_version]
 0(1579) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(1579) DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb74f5a94
 0(1579) DEBUG: <core> [db_row.c:119]: allocate 20 bytes for row values at 0xb74f5acc
 0(1579) DEBUG: <core> [db_val.c:73]: converting INT [6]
 0(1579) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(1579) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb74f5a5c
 0(1579) DEBUG: <core> [db_res.c:94]: freeing result names at 0xb74f634c
 0(1579) DEBUG: <core> [db_res.c:99]: freeing result types at 0xb74f6380
 0(1579) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 0(1579) DEBUG: <core> [db_row.c:97]: freeing row values at 0xb74f5acc
 0(1579) DEBUG: <core> [db_res.c:62]: freeing rows at 0xb74f5a94
 0(1579) DEBUG: <core> [db_res.c:136]: freeing result set at 0xb74f5a10
 0(1579) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
 0(1579) DEBUG: <core> [route.c:929]: fixing www_challenge()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing proxy_authorize()
 0(1579) DEBUG: <core> [db.c:308]: connection 0xb74f54a4 not found in pool
 0(1579) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 0(1579) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(1579) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(1579) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 0(1579) DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb74f5540
 0(1579) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 0(1579) DEBUG: <core> [db_res.c:155]: allocate 4 bytes for result names at 0xb74f6380
 0(1579) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0xb74f634c
 0(1579) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb74f6278
 0(1579) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb74f6278)[0]=[table_version]
 0(1579) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 0(1579) DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb74f6424
 0(1579) DEBUG: <core> [db_row.c:119]: allocate 20 bytes for row values at 0xb74f5acc
 0(1579) DEBUG: <core> [db_val.c:73]: converting INT [6]
 0(1579) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 0(1579) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb74f6278
 0(1579) DEBUG: <core> [db_res.c:94]: freeing result names at 0xb74f6380
 0(1579) DEBUG: <core> [db_res.c:99]: freeing result types at 0xb74f634c
 0(1579) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 0(1579) DEBUG: <core> [db_row.c:97]: freeing row values at 0xb74f5acc
 0(1579) DEBUG: <core> [db_res.c:62]: freeing rows at 0xb74f6424
 0(1579) DEBUG: <core> [db_res.c:136]: freeing result set at 0xb74f5540
 0(1579) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
 0(1579) DEBUG: <core> [route.c:929]: fixing proxy_challenge()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing is_method()
 0(1579) DEBUG: textops [textops.c:1743]: using id for method [PUBLISH/8192]
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
 0(1579) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(1579) DEBUG: <core> [route.c:929]: fixing consume_credentials()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
 0(1579) DEBUG: <core> [rvalue.c:2861]: RV is int: 10
 0(1579) DEBUG: <core> [route.c:506]: right EXP optimized: op10(O60, _ST22_) => op10(O60, _ST3_)
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing nat_uac_test()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing is_method()
 0(1579) DEBUG: textops [textops.c:1743]: using id for method [REGISTER/32]
 0(1579) DEBUG: <core> [route.c:929]: fixing fix_nated_register()
 0(1579) DEBUG: <core> [route.c:929]: fixing fix_nated_contact()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing is_request()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing has_totag()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing check_route_param()
 0(1579) DEBUG: <core> [route.c:929]: fixing setbflag()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing check_route_param()
 0(1579) DEBUG: <core> [route.c:929]: fixing setbflag()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
 0(1579) DEBUG: <core> [rvalue.c:2861]: RV is int: 10
 0(1579) DEBUG: <core> [route.c:506]: right EXP optimized: op10(O60, _ST22_) => op10(O60, _ST3_)
 0(1579) DEBUG: <core> [route.c:929]: fixing rtpproxy_manage()
 0(1579) DEBUG: <core> [route.c:929]: fixing rtpproxy_manage()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
 0(1579) DEBUG: <core> [rvalue.c:2861]: RV is int: 10
 0(1579) DEBUG: <core> [route.c:506]: right EXP optimized: op10(O60, _ST22_) => op10(O60, _ST3_)
 0(1579) DEBUG: <core> [route.c:929]: fixing rtpproxy_manage()
 0(1579) DEBUG: <core> [route.c:929]: fixing rtpproxy_manage()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing is_request()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing has_totag()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(1579) DEBUG: <core> [route.c:929]: fixing add_rr_param()
 0(1579) DEBUG: <core> [route.c:929]: fixing add_rr_param()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing is_reply()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
 0(1579) DEBUG: <core> [rvalue.c:2861]: RV is int: 2
 0(1579) DEBUG: <core> [route.c:506]: right EXP optimized: op10(O60, _ST22_) => op10(O60, _ST3_)
 0(1579) DEBUG: <core> [route.c:929]: fixing fix_nated_contact()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [route.c:929]: fixing append_hf()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing has_totag()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
 0(1579) DEBUG: <core> [rvalue.c:2861]: RV is int: 10
 0(1579) DEBUG: <core> [route.c:506]: right EXP optimized: op10(O60, _ST22_) => op10(O60, _ST3_)
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(1579) DEBUG: <core> [route.c:929]: fixing setbflag()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
 0(1579) DEBUG: <core> [rvalue.c:2861]: RV is int: 2
 0(1579) DEBUG: <core> [route.c:506]: right EXP optimized: op10(O60, _ST22_) => op10(O60, _ST3_)
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing isbflagset()
 0(1579) DEBUG: <core> [route.c:929]: fixing setbflag()
 0(1579) DEBUG: <core> [route.c:929]: fixing xdbg()
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 2
 0(1579) DEBUG: <core> [rvalue.c:2865]: RV is str: "[12][0-9][0-9]"
 0(1579) DEBUG: <core> [route.c:506]: right EXP optimized: op11(O52, _ST22_) => op11(O52, _ST1_)
 0(1579) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
 0(1579) DEBUG: <core> [route.c:929]: fixing t_is_canceled()
 0(1579) DEBUG: <core> [route.c:929]: fixing xdbg()
 0(1579) INFO: <core> [udp_server.c:179]: INFO: udp_init: SO_RCVBUF is initially 112640
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 225280
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=225280,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 227328
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=227328,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 229376
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=229376,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 231424
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=231424,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 233472
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=233472,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 235520
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=235520,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 237568
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=237568,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 239616
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=239616,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 241664
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=241664,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 243712
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=243712,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 245760
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=245760,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 247808
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=247808,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 249856
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=249856,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 251904
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=251904,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 253952
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=253952,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 256000
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=256000,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 258048
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=258048,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 260096
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=260096,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 262144
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=262144,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:213]: DEBUG: setting SO_RCVBUF has no effect
 0(1579) INFO: <core> [udp_server.c:230]: INFO: udp_init: SO_RCVBUF is finally 262142
 0(1579) INFO: <core> [udp_server.c:179]: INFO: udp_init: SO_RCVBUF is initially 112640
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 225280
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=225280,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 227328
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=227328,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 229376
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=229376,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 231424
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=231424,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 233472
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=233472,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 235520
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=235520,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 237568
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=237568,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 239616
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=239616,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 241664
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=241664,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 243712
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=243712,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 245760
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=245760,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 247808
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=247808,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 249856
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=249856,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 251904
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=251904,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 253952
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=253952,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 256000
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=256000,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 258048
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=258048,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 260096
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=260096,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:187]: DEBUG: udp_init: trying SO_RCVBUF: 262144
 0(1579) DEBUG: <core> [udp_server.c:211]: DEBUG: setting SO_RCVBUF; set=262144,verify=262142
 0(1579) DEBUG: <core> [udp_server.c:213]: DEBUG: setting SO_RCVBUF has no effect
 0(1579) INFO: <core> [udp_server.c:230]: INFO: udp_init: SO_RCVBUF is finally 262142
 0(1579) DEBUG: <core> [tcp_main.c:2831]: tcp_init: added 10.10.10.10:5060
 0(1579) DEBUG: <core> [tcp_main.c:2831]: tcp_init: added [3001:0:0:1:0:0:0:10]:5060
 0(1579) DEBUG: <core> [daemonize.c:219]: trying enable core dumping...
 0(1579) DEBUG: <core> [daemonize.c:221]: core dumping is disabled now...
 0(1579) DEBUG: <core> [daemonize.c:225]: core dumping has just been enabled...
 0(1579) DEBUG: <core> [daemonize.c:232]: current core file limit: 4294967295 (max: 4294967295)
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_fifo
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): kex
 0(1579) DEBUG: kex [kex_mod.c:145]: rank is (-127)
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): tm
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): sl
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): usrloc
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): registrar
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): ctl
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_rpc
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): acc
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): auth_db
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): nathelper
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): rtpproxy
 0(1579) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 0(1579) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 0(1579) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 0(1579) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): debugger
 0(1579) DEBUG: debugger [debugger_mod.c:124]: rank is (-127)
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): mi_fifo
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_fifo
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): kex
 4(1583) DEBUG: kex [kex_mod.c:145]: rank is (4)
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): tm
 4(1583) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1583@10.10.10.10'
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): sl
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): usrloc
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): registrar
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): ctl
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): mi_rpc
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): acc
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): auth_db
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): kex
 0(1579) DEBUG: kex [kex_mod.c:145]: rank is (0)
 4(1583) DEBUG: <core> [db.c:308]: connection 0xb751180c not found in pool
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): tm
 4(1583) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 0(1579) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1579@10.10.10.10'
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): sl
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): usrloc
 0(1579) DEBUG: <core> [db.c:308]: connection 0xb75193e0 not found in pool
 0(1579) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 4(1583) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 4(1583) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 4(1583) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): nathelper
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): rtpproxy
 4(1583) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 4(1583) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 4(1583) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 4(1583) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 4(1583) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (4): debugger
 4(1583) DEBUG: debugger [debugger_mod.c:124]: rank is (4)
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): mi_fifo
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): kex
 2(1581) DEBUG: kex [kex_mod.c:145]: rank is (2)
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): tm
 2(1581) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1581@10.10.10.10'
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): sl
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): usrloc
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): registrar
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): ctl
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): mi_rpc
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): acc
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): auth_db
 2(1581) DEBUG: <core> [db.c:308]: connection 0xb751180c not found in pool
 2(1581) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): mi_fifo
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): kex
 6(1585) DEBUG: kex [kex_mod.c:145]: rank is (6)
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): tm
 6(1585) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1585@10.10.10.10'
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): sl
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): usrloc
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): registrar
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): ctl
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): mi_rpc
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): acc
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): auth_db
 6(1585) DEBUG: <core> [db.c:308]: connection 0xb751180c not found in pool
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): mi_fifo
 6(1585) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): kex
 5(1584) DEBUG: kex [kex_mod.c:145]: rank is (5)
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): tm
 5(1584) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1584@10.10.10.10'
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): sl
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): usrloc
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): registrar
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): ctl
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): mi_rpc
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): acc
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): auth_db
 5(1584) DEBUG: <core> [db.c:308]: connection 0xb751180c not found in pool
 5(1584) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 0(1579) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 0(1579) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 0(1579) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): registrar
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): ctl
 0(1579) DEBUG: ctl [ctl.c:311]: ctl: mod_child(0), ctrl_sock_lst=0xb75207b0
 0(1579) DEBUG: ctl [ctl.c:319]: ctl: mod_child(0), fork_process=1594, csl=0xb75207b0
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_rpc
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): acc
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): auth_db
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): nathelper
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): rtpproxy
 0(1579) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 0(1579) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 0(1579) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 0(1579) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 0(1579) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): debugger
 0(1579) DEBUG: debugger [debugger_mod.c:124]: rank is (0)
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): mi_fifo
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): kex
 7(1586) DEBUG: kex [kex_mod.c:145]: rank is (7)
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): tm
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): mi_fifo
 7(1586) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1586@10.10.10.10'
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): sl
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): usrloc
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): registrar
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): kex
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): ctl
 8(1587) DEBUG: kex [kex_mod.c:145]: rank is (8)
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): tm
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): mi_rpc
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): acc
 8(1587) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1587@10.10.10.10'
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): auth_db
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): sl
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): usrloc
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): registrar
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): ctl
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): mi_rpc
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): acc
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): auth_db
 8(1587) DEBUG: <core> [db.c:308]: connection 0xb751180c not found in pool
 8(1587) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 2(1581) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 2(1581) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 2(1581) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): mi_fifo
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): nathelper
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): rtpproxy
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): kex
 3(1582) DEBUG: kex [kex_mod.c:145]: rank is (3)
 2(1581) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 2(1581) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 2(1581) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 2(1581) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): tm
 2(1581) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (2): debugger
 2(1581) DEBUG: debugger [debugger_mod.c:124]: rank is (2)
 3(1582) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1582@10.10.10.10'
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): sl
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): usrloc
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): registrar
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): ctl
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): mi_rpc
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): acc
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): auth_db
 6(1585) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 6(1585) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 6(1585) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 3(1582) DEBUG: <core> [db.c:308]: connection 0xb751180c not found in pool
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): nathelper
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): rtpproxy
 3(1582) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 6(1585) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 6(1585) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 6(1585) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 6(1585) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 6(1585) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (6): debugger
 6(1585) DEBUG: debugger [debugger_mod.c:124]: rank is (6)
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_fifo
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): kex
12(1594) DEBUG: kex [kex_mod.c:145]: rank is (-2)
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): tm
12(1594) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1594@10.10.10.10'
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): sl
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): usrloc
 0(1579) DEBUG: <core> [main.c:1731]: Expect maximum 2148  open fds
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): registrar
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): ctl
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_rpc
12(1594) DEBUG: mi_rpc [mi_rpc_mod.c:106]: initializing child[-2] for rpc handling
 7(1586) DEBUG: <core> [db.c:308]: connection 0xb751180c not found in pool
12(1594) DEBUG: <core> [db_id.c:302]: identical DB URLs, but different DB connection pid [1594/1579]
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
12(1594) DEBUG: <core> [db.c:308]: connection 0xb751ae68 not found in pool
12(1594) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
10(1589) DEBUG: kex [kex_mod.c:145]: rank is (-1)
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
10(1589) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1589@10.10.10.10'
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
10(1589) DEBUG: <core> [db.c:308]: connection 0xb75193e0 not found in pool
10(1589) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
 9(1588) DEBUG: kex [kex_mod.c:145]: rank is (-1)
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
 9(1588) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1588@10.10.10.10'
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
 7(1586) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 9(1588) DEBUG: <core> [db.c:308]: connection 0xb75193e0 not found in pool
 9(1588) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
11(1590) DEBUG: mi_fifo [mi_fifo.c:223]: new process with pid = 1590 created
11(1590) DEBUG: mi_fifo [fifo_fnc.c:76]: FIFO created @ /tmp/kamailio_fifo
11(1590) DEBUG: mi_fifo [fifo_fnc.c:91]: fifo /tmp/kamailio_fifo opened, mode=660
11(1590) DEBUG: <core> [db.c:308]: connection 0xb751928c not found in pool
11(1590) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
13(1595) DEBUG: kex [kex_mod.c:145]: rank is (-1)
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
13(1595) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1595@10.10.10.10'
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
13(1595) DEBUG: <core> [db_id.c:302]: identical DB URLs, but different DB connection pid [1595/1579]
13(1595) DEBUG: <core> [db.c:308]: connection 0xb751ae68 not found in pool
13(1595) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): mi_fifo
kamailio started.
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): kex
15(1597) DEBUG: kex [kex_mod.c:145]: rank is (10)
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): tm
15(1597) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1597@10.10.10.10'
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): sl
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): usrloc
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): registrar
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): ctl
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): mi_rpc
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): acc
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): auth_db
15(1597) DEBUG: <core> [db_id.c:302]: identical DB URLs, but different DB connection pid [1597/1579]
15(1597) DEBUG: <core> [db.c:308]: connection 0xb75207b0 not found in pool
15(1597) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): mi_fifo
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): kex
 1(1580) DEBUG: kex [kex_mod.c:145]: rank is (1)
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): tm
 1(1580) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1580@10.10.10.10'
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): sl
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): usrloc
 1(1580) DEBUG: <core> [db.c:308]: connection 0xb75193e0 not found in pool
 1(1580) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): mi_fifo
12(1594) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): kex
12(1594) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
17(1601) DEBUG: kex [kex_mod.c:145]: rank is (12)
12(1594) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): acc
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): tm
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): auth_db
17(1601) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1601@10.10.10.10'
12(1594) DEBUG: <core> [db.c:317]: connection 0xb7519e0c found in pool
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): sl
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): nathelper
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): usrloc
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): rtpproxy
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): registrar
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): ctl
12(1594) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): mi_rpc
12(1594) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): acc
12(1594) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
12(1594) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): auth_db
12(1594) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): debugger
12(1594) DEBUG: debugger [debugger_mod.c:124]: rank is (-2)
17(1601) DEBUG: <core> [db_id.c:302]: identical DB URLs, but different DB connection pid [1601/1579]
12(1594) DEBUG: ctl [ctl.c:319]: ctl: mod_child(0), fork_process=0, csl=0xb75207b0
17(1601) DEBUG: <core> [db.c:308]: connection 0xb75207b0 not found in pool
12(1594) DEBUG: ctl [ctl.c:326]: ctl: 0 io_listen_loop(1, 0xb75207b0)
12(1594) INFO: ctl [io_listener.c:224]: io_listen_loop:  using epoll_lt io watch method (config)
17(1601) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
12(1594) DEBUG: ctl [io_listener.c:256]: io_listen_loop: adding socket 4, type 2, transport 3 (/tmp/kamailio_ctl)
12(1594) DEBUG: ctl [../../io_wait.h:390]: DBG: io_watch_add(0xa99020, 4, 2, 0xb75207b0), fd_no=0
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): mi_fifo
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): kex
14(1596) DEBUG: kex [kex_mod.c:145]: rank is (9)
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): tm
14(1596) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1596@10.10.10.10'
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): sl
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): usrloc
10(1589) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): registrar
10(1589) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
10(1589) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): ctl
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): mi_rpc
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): acc
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): auth_db
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
14(1596) DEBUG: <core> [db_id.c:302]: identical DB URLs, but different DB connection pid [1596/1579]
14(1596) DEBUG: <core> [db.c:308]: connection 0xb75207b0 not found in pool
10(1589) DEBUG: <core> [db.c:317]: connection 0xb75207b0 found in pool
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
14(1596) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
10(1589) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
10(1589) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
10(1589) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
10(1589) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
10(1589) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
10(1589) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): mi_fifo
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): kex
16(1598) DEBUG: kex [kex_mod.c:145]: rank is (11)
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): tm
16(1598) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1598@10.10.10.10'
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): sl
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): usrloc
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): registrar
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): ctl
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): mi_rpc
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): acc
 9(1588) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): auth_db
 9(1588) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 9(1588) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
16(1598) DEBUG: <core> [db_id.c:302]: identical DB URLs, but different DB connection pid [1598/1579]
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
 5(1584) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 5(1584) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
 9(1588) DEBUG: <core> [db.c:317]: connection 0xb75207b0 found in pool
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
 9(1588) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 9(1588) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 9(1588) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 9(1588) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 9(1588) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
 9(1588) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
 8(1587) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 3(1582) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 8(1587) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 8(1587) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 3(1582) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 3(1582) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): nathelper
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): nathelper
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): rtpproxy
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): rtpproxy
 8(1587) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 8(1587) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 3(1582) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 8(1587) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 3(1582) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 8(1587) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 3(1582) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 8(1587) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (8): debugger
 3(1582) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 8(1587) DEBUG: debugger [debugger_mod.c:124]: rank is (8)
 3(1582) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (3): debugger
 3(1582) DEBUG: debugger [debugger_mod.c:124]: rank is (3)
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_fifo
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): kex
18(1602) DEBUG: kex [kex_mod.c:145]: rank is (-4)
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): tm
18(1602) DEBUG: tm [callid.c:137]: DEBUG: callid: '7fededbf-1602@10.10.10.10'
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): sl
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): usrloc
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): registrar
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): ctl
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_rpc
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): acc
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): auth_db
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): nathelper
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): rtpproxy
18(1602) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
18(1602) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
18(1602) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
18(1602) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
18(1602) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): debugger
18(1602) DEBUG: debugger [debugger_mod.c:124]: rank is (-4)
18(1602) DEBUG: <core> [local_timer.c:74]: init_local_timer: timer_list between 0x8261104 and 0x8283104
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 9, 1, 0xb74dc958), fd_no=0
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 10, 1, 0xb74dcc74), fd_no=1
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 11, 4, 0xb368579c), fd_no=2
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 12, 4, 0xb3685828), fd_no=3
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 13, 4, 0xb36858b4), fd_no=4
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 14, 4, 0xb3685940), fd_no=5
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 15, 4, 0xb36859cc), fd_no=6
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 16, 4, 0xb3685a58), fd_no=7
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 17, 4, 0xb3685ae4), fd_no=8
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 18, 4, 0xb3685b70), fd_no=9
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 19, 4, 0xb3685bfc), fd_no=10
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 20, 4, 0xb3685c88), fd_no=11
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 21, 4, 0xb3685d14), fd_no=12
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 23, 4, 0xb3685da0), fd_no=13
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 4, 4, 0xb3685e2c), fd_no=14
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 24, 4, 0xb3685eb8), fd_no=15
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 25, 4, 0xb3685f44), fd_no=16
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 27, 4, 0xb3685fd0), fd_no=17
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 29, 4, 0xb368605c), fd_no=18
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 26, 3, 0xb751ae60), fd_no=19
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 28, 3, 0xb751ae74), fd_no=20
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 30, 3, 0xb751ae88), fd_no=21
18(1602) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8260f40, 32, 3, 0xb751ae9c), fd_no=22
master@DualStackCS:~$ 13(1595) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
13(1595) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
13(1595) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
13(1595) DEBUG: <core> [db.c:317]: connection 0xb75207b0 found in pool
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
13(1595) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
13(1595) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
13(1595) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
13(1595) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
13(1595) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
13(1595) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
 7(1586) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 7(1586) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 7(1586) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): nathelper
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): rtpproxy
 7(1586) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 7(1586) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 7(1586) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 7(1586) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 7(1586) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (7): debugger
 7(1586) DEBUG: debugger [debugger_mod.c:124]: rank is (7)
16(1598) DEBUG: <core> [db.c:308]: connection 0xb75207b0 not found in pool
11(1590) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
11(1590) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
11(1590) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
16(1598) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/DualStackDB
17(1601) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
17(1601) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
17(1601) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): nathelper
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): rtpproxy
17(1601) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
17(1601) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
17(1601) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
17(1601) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
17(1601) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (12): debugger
17(1601) DEBUG: debugger [debugger_mod.c:124]: rank is (12)
17(1601) DEBUG: <core> [local_timer.c:74]: init_local_timer: timer_list between 0x8283344 and 0x82a5344
17(1601) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8283180, 33, 1, (nil)), fd_no=0
14(1596) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
 1(1580) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
14(1596) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
14(1596) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
 1(1580) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
 1(1580) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): nathelper
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): rtpproxy
15(1597) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
15(1597) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
15(1597) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
14(1596) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): nathelper
14(1596) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 5(1584) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
14(1596) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
14(1596) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
14(1596) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (9): debugger
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): nathelper
14(1596) DEBUG: debugger [debugger_mod.c:124]: rank is (9)
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): rtpproxy
 5(1584) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 5(1584) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 5(1584) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 5(1584) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 5(1584) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (5): debugger
 5(1584) DEBUG: debugger [debugger_mod.c:124]: rank is (5)
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): rtpproxy
15(1597) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
15(1597) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
15(1597) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
15(1597) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
15(1597) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (10): debugger
14(1596) DEBUG: <core> [local_timer.c:74]: init_local_timer: timer_list between 0x8283344 and 0x82a5344
15(1597) DEBUG: debugger [debugger_mod.c:124]: rank is (10)
14(1596) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8283180, 27, 1, (nil)), fd_no=0
 1(1580) DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb751ae2c
 1(1580) DEBUG: db_mysql [km_res.c:68]: 14 columns returned from the query
 1(1580) DEBUG: <core> [db_res.c:155]: allocate 56 bytes for result names at 0xb751ae78
 1(1580) DEBUG: <core> [db_res.c:165]: allocate 56 bytes for result types at 0xb751aee0
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb751af48
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb751af48)[0]=[username]
 1(1580) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[1] at 0xb751af80
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb751af80)[1]=[contact]
 1(1580) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[2] at 0xb7519d48
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519d48)[2]=[expires]
 1(1580) DEBUG: db_mysql [km_res.c:116]: use DB1_DATETIME result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[3] at 0xb7519d80
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519d80)[3]=[q]
 1(1580) DEBUG: db_mysql [km_res.c:111]: use DB1_DOUBLE result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[4] at 0xb7519db8
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519db8)[4]=[callid]
 1(1580) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[5] at 0xb7519df0
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519df0)[5]=[cseq]
 1(1580) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[6] at 0xb7519e28
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519e28)[6]=[flags]
 1(1580) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
15(1597) DEBUG: <core> [local_timer.c:74]: init_local_timer: timer_list between 0x8283344 and 0x82a5344
15(1597) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8283180, 29, 1, (nil)), fd_no=0
16(1598) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
16(1598) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
16(1598) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.41-3ubuntu12.10
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): nathelper
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): rtpproxy
16(1598) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
16(1598) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
16(1598) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
16(1598) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
16(1598) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (11): debugger
16(1598) DEBUG: debugger [debugger_mod.c:124]: rank is (11)
16(1598) DEBUG: <core> [local_timer.c:74]: init_local_timer: timer_list between 0x8283344 and 0x82a5344
16(1598) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8283180, 31, 1, (nil)), fd_no=0
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[7] at 0xb7519e60
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519e60)[7]=[cflags]
 1(1580) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[8] at 0xb7519e98
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519e98)[8]=[user_agent]
 1(1580) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[9] at 0xb7519ed0
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519ed0)[9]=[received]
 1(1580) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[10] at 0xb7519f08
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519f08)[10]=[path]
 1(1580) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[11] at 0xb7519f40
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519f40)[11]=[socket]
 1(1580) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[12] at 0xb7519f78
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519f78)[12]=[methods]
 1(1580) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
 1(1580) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[13] at 0xb7519fb0
 1(1580) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7519fb0)[13]=[last_modified]
 1(1580) DEBUG: db_mysql [km_res.c:116]: use DB1_DATETIME result type
 1(1580) DEBUG: db_mysql [km_dbase.c:356]: converting row 0 of 3 count 3
 1(1580) DEBUG: <core> [db_row.c:119]: allocate 280 bytes for row values at 0xb751894c
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [200]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [sip:200@30.30.30.1:1117;line=7tlo41sg]
 1(1580) DEBUG: <core> [db_val.c:157]: converting DATETIME [2012-01-04 15:54:55]
 1(1580) DEBUG: <core> [db_val.c:106]: converting DOUBLE [-1.00]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [3c26708ec283-rtzc5odszgxy]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [3]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [0]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [2]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [snom370/7.3-boco-test]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [sip:30.30.30.1:1116]
 1(1580) DEBUG: <core> [db_val.c:56]: converting NULL value 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [udp:10.10.10.10:5060]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [6111]
 1(1580) DEBUG: <core> [db_val.c:157]: converting DATETIME [2012-01-04 14:54:55]
 1(1580) DEBUG: <core> [db_row.c:119]: allocate 280 bytes for row values at 0xb7518a94
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [300]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [sip:300@[3001:0:0:4::4];line=fb0371cc0525bb2]
 1(1580) DEBUG: <core> [db_val.c:157]: converting DATETIME [2012-01-04 15:52:20]
 1(1580) DEBUG: <core> [db_val.c:106]: converting DOUBLE [-1.00]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [30919]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [2]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [0]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [64]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [Linphone/3.4.3 (eXosip2/3.3.0)]
 1(1580) DEBUG: <core> [db_val.c:56]: converting NULL value 1(1580) DEBUG: <core> [db_val.c:56]: converting NULL value 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [udp:[3001:0:0:1:0:0:0:10]:5060]
 1(1580) DEBUG: <core> [db_val.c:56]: converting NULL value 1(1580) DEBUG: <core> [db_val.c:157]: converting DATETIME [2012-01-04 14:52:20]
 1(1580) DEBUG: <core> [db_row.c:119]: allocate 280 bytes for row values at 0xb7517cdc
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [300]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [sip:300@[3001:0:0:4:0:0:0:4]:5060;line=fb0371cc0525bb2]
 1(1580) DEBUG: <core> [db_val.c:157]: converting DATETIME [2012-01-04 16:07:21]
 1(1580) DEBUG: <core> [db_val.c:106]: converting DOUBLE [-1.00]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [30919]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [6]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [0]
 1(1580) DEBUG: <core> [db_val.c:73]: converting INT [64]
 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [Linphone/3.4.3 (eXosip2/3.3.0)]
 1(1580) DEBUG: <core> [db_val.c:56]: converting NULL value 1(1580) DEBUG: <core> [db_val.c:56]: converting NULL value 1(1580) DEBUG: <core> [db_val.c:117]: converting STRING [udp:[3001:0:0:1:0:0:0:10]:5060]
 1(1580) DEBUG: <core> [db_val.c:56]: converting NULL value 1(1580) DEBUG: <core> [db_val.c:157]: converting DATETIME [2012-01-04 15:07:21]
 1(1580) DEBUG: usrloc [udomain.c:416]: loading records - cycle [1]
 1(1580) DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 11==11 &&  [10.10.10.10] == [10.10.10.10]
 1(1580) DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
 1(1580) DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 19==11 &&  [3001:0:0:1:0:0:0:10] == [10.10.10.10]
 1(1580) DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
 1(1580) DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 19==19 &&  [3001:0:0:1:0:0:0:10] == [3001:0:0:1:0:0:0:10]
 1(1580) DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
 1(1580) DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 19==11 &&  [3001:0:0:1:0:0:0:10] == [10.10.10.10]
 1(1580) DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
 1(1580) DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 19==19 &&  [3001:0:0:1:0:0:0:10] == [3001:0:0:1:0:0:0:10]
 1(1580) DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
 1(1580) DEBUG: <core> [db_res.c:54]: freeing 3 rows
 1(1580) DEBUG: <core> [db_row.c:97]: freeing row values at 0xb751894c
 1(1580) DEBUG: <core> [db_row.c:97]: freeing row values at 0xb7518a94
 1(1580) DEBUG: <core> [db_row.c:97]: freeing row values at 0xb7517cdc
 1(1580) DEBUG: <core> [db_res.c:62]: freeing rows at 0xb7519fe8
 1(1580) DEBUG: <core> [db_res.c:81]: freeing 14 columns
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb751af48
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0xb751af80
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0xb7519d48
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0xb7519d80
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[4] at 0xb7519db8
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[5] at 0xb7519df0
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[6] at 0xb7519e28
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[7] at 0xb7519e60
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[8] at 0xb7519e98
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[9] at 0xb7519ed0
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[10] at 0xb7519f08
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[11] at 0xb7519f40
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[12] at 0xb7519f78
 1(1580) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[13] at 0xb7519fb0
 1(1580) DEBUG: <core> [db_res.c:94]: freeing result names at 0xb751ae78
 1(1580) DEBUG: <core> [db_res.c:99]: freeing result types at 0xb751aee0
 1(1580) DEBUG: <core> [db_res.c:136]: freeing result set at 0xb751ae2c
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): registrar
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): ctl
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): mi_rpc
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): acc
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): auth_db
 1(1580) DEBUG: <core> [db.c:317]: connection 0xb751af80 found in pool
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): nathelper
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): rtpproxy
 1(1580) ERROR: rtpproxy [rtpproxy.c:1466]: can't connect to RTP proxy
 1(1580) ERROR: rtpproxy [rtpproxy.c:1538]: proxy <unix:/var/run/rtpproxy/rtpproxy.sock> does not respond, disable it
 1(1580) WARNING: rtpproxy [rtpproxy.c:1395]: can't get version of the RTP proxy
 1(1580) WARNING: rtpproxy [rtpproxy.c:1432]: support for RTP proxy <unix:/var/run/rtpproxy/rtpproxy.sock> has been disabled temporarily
 1(1580) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (1): debugger
 1(1580) DEBUG: debugger [debugger_mod.c:124]: rank is (1)
 6(1585) DEBUG: <core> [parser/msg_parser.c:628]: SIP Request:
 6(1585) DEBUG: <core> [parser/msg_parser.c:630]:  method:  <REGISTER>
 6(1585) DEBUG: <core> [parser/msg_parser.c:632]:  uri:     <sip:[3001:0:0:1::10]>
 6(1585) DEBUG: <core> [parser/msg_parser.c:634]:  version: <SIP/2.0>
 6(1585) DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK27162>; state=16
 6(1585) DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
 6(1585) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
 6(1585) DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
 6(1585) DEBUG: <core> [receive.c:147]: After parse_msg...
 6(1585) DEBUG: <core> [receive.c:188]: preparing to run routing scripts...
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=464 a=6 n=route
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=573 a=17 n=if
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=568 a=26 n=mf_process_maxfwd_header
 6(1585) DEBUG: <core> [parser/parse_to.c:801]: end of header reached, state=10
 6(1585) DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [28]; uri=[sip:300@[3001:0:0:1::10]] 
 6(1585) DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body [<sip:300@[3001:0:0:1::10]>
]
 6(1585) DEBUG: <core> [parser/msg_parser.c:167]: get_hdr_field: cseq <CSeq>: <15> <REGISTER>
 6(1585) DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=578 a=17 n=if
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=573 a=27 n=sanity_check
 6(1585) DEBUG: <core> [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=0
 6(1585) DEBUG: <core> [parser/msg_parser.c:103]: found end of header
 6(1585) DEBUG: <core> [parser/parse_to.c:177]: DEBUG: add_param: tag=22350
 6(1585) DEBUG: <core> [parser/parse_to.c:801]: end of header reached, state=29
 6(1585) DEBUG: sanity [mod_sanity.c:251]: sanity checks result: 1
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=467 a=6 n=route
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=789 a=17 n=if
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=786 a=3 n=return
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=470 a=6 n=route
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=617 a=17 n=if
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=582 a=25 n=has_totag
 6(1585) DEBUG: siputils [checks.c:104]: no totag
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=482 a=17 n=if
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=475 a=26 n=is_method
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=482 a=25 n=t_check_trans
 6(1585) DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffff
 6(1585) DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=52051, isACK=0
 6(1585) DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
 6(1585) DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
 6(1585) DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=1 global id=1 T end=(nil)
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=485 a=6 n=route
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=778 a=17 n=if
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=713 a=26 n=is_method
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=722 a=17 n=if
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=716 a=27 n=www_authorize
 6(1585) DEBUG: auth_db [authorize.c:304]: realm value [[3001:0:0:1::10]]
 6(1585) DEBUG: auth [api.c:85]: auth:pre_auth: Credentials with realm '[3001:0:0:1::10]' not found
 6(1585) DEBUG: auth_db [authorize.c:178]: no credentials
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=718 a=27 n=www_challenge
 6(1585) DEBUG: auth [challenge.c:102]: build_challenge_hf: realm='[3001:0:0:1::10]'
 6(1585) DEBUG: auth [challenge.c:244]: auth: 'WWW-Authenticate: Digest realm="[3001:0:0:1::10]", nonce="TwRloU8EZHWrUX77KyOxRV8XGfFH002X"
'
 6(1585) DEBUG: sl [sl.c:278]: reply in stateless mode (sl)
 6(1585) DEBUG: <core> [msg_translator.c:204]: check_via_address(3001:0:0:4:0:0:0:4, [3001:0:0:4::4], 0)
 6(1585) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=719 a=3 n=exit
 6(1585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 6(1585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 6(1585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 6(1585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 6(1585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 6(1585) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 6(1585) DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
 6(1585) DEBUG: <core> [receive.c:291]: receive_msg: cleaning up
 8(1587) DEBUG: <core> [parser/msg_parser.c:628]: SIP Request:
 8(1587) DEBUG: <core> [parser/msg_parser.c:630]:  method:  <REGISTER>
 8(1587) DEBUG: <core> [parser/msg_parser.c:632]:  uri:     <sip:[3001:0:0:1::10]>
 8(1587) DEBUG: <core> [parser/msg_parser.c:634]:  version: <SIP/2.0>
 8(1587) DEBUG: <core> [parser/parse_via.c:1288]: Found param type 232, <branch> = <z9hG4bK30709>; state=16
 8(1587) DEBUG: <core> [parser/parse_via.c:2563]: end of header reached, state=5
 8(1587) DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: Via found, flags=2
 8(1587) DEBUG: <core> [parser/msg_parser.c:517]: parse_headers: this is the first via
 8(1587) DEBUG: <core> [receive.c:147]: After parse_msg...
 8(1587) DEBUG: <core> [receive.c:188]: preparing to run routing scripts...
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=464 a=6 n=route
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=573 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=568 a=26 n=mf_process_maxfwd_header
 8(1587) DEBUG: <core> [parser/parse_to.c:801]: end of header reached, state=10
 8(1587) DEBUG: <core> [parser/msg_parser.c:187]: DEBUG: get_hdr_field: <To> [28]; uri=[sip:300@[3001:0:0:1::10]] 
 8(1587) DEBUG: <core> [parser/msg_parser.c:189]: DEBUG: to body [<sip:300@[3001:0:0:1::10]>
]
 8(1587) DEBUG: <core> [parser/msg_parser.c:167]: get_hdr_field: cseq <CSeq>: <16> <REGISTER>
 8(1587) DEBUG: maxfwd [mf_funcs.c:85]: value = 70 
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=578 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=573 a=27 n=sanity_check
 8(1587) DEBUG: <core> [parser/msg_parser.c:201]: DEBUG: get_hdr_body : content_length=0
 8(1587) DEBUG: <core> [parser/msg_parser.c:103]: found end of header
 8(1587) DEBUG: <core> [parser/parse_to.c:177]: DEBUG: add_param: tag=22350
 8(1587) DEBUG: <core> [parser/parse_to.c:801]: end of header reached, state=29
 8(1587) DEBUG: sanity [mod_sanity.c:251]: sanity checks result: 1
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=467 a=6 n=route
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=789 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=786 a=3 n=return
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=470 a=6 n=route
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=617 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=582 a=25 n=has_totag
 8(1587) DEBUG: siputils [checks.c:104]: no totag
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=482 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=475 a=26 n=is_method
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=482 a=25 n=t_check_trans
 8(1587) DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=1 global id=0 T start=0xffffffff
 8(1587) DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching: hash=52048, isACK=0
 8(1587) DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
 8(1587) DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction found
 8(1587) DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=1 global id=1 T end=(nil)
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=485 a=6 n=route
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=778 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=713 a=26 n=is_method
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=722 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=716 a=27 n=www_authorize
 8(1587) DEBUG: auth_db [authorize.c:304]: realm value [[3001:0:0:1::10]]
 8(1587) DEBUG: auth [api.c:95]: auth: digest-algo: MD5 parsed value: 1
 8(1587) DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at 0xb7517ff8
 8(1587) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
 8(1587) DEBUG: <core> [db_res.c:155]: allocate 4 bytes for result names at 0xb7518044
 8(1587) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0xb75173d0
 8(1587) DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at 0xb7517404
 8(1587) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb7517404)[0]=[password]
 8(1587) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
 8(1587) DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb751743c
 8(1587) DEBUG: <core> [db_row.c:119]: allocate 20 bytes for row values at 0xb7517474
 8(1587) DEBUG: <core> [db_val.c:117]: converting STRING [300]
 8(1587) DEBUG: auth_db [authorize.c:122]: HA1 string calculated: ef0497074a38cb57e242925705f2d531
 8(1587) DEBUG: auth [api.c:210]: check_response: Our result = '8d49e00fff306bea7e36b616073438de'
 8(1587) DEBUG: auth [api.c:217]: check_response: Authorization is OK
 8(1587) DEBUG: <core> [db_res.c:81]: freeing 1 columns
 8(1587) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb7517404
 8(1587) DEBUG: <core> [db_res.c:94]: freeing result names at 0xb7518044
 8(1587) DEBUG: <core> [db_res.c:99]: freeing result types at 0xb75173d0
 8(1587) DEBUG: <core> [db_res.c:54]: freeing 1 rows
 8(1587) DEBUG: <core> [db_row.c:97]: freeing row values at 0xb7517474
 8(1587) DEBUG: <core> [db_res.c:62]: freeing rows at 0xb751743c
 8(1587) DEBUG: <core> [db_res.c:136]: freeing result set at 0xb7517ff8
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=727 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=780 a=3 n=return
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=489 a=26 n=remove_hf
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=494 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=490 a=26 n=is_method
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=500 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=494 a=26 n=is_method
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=500 a=6 n=route
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=861 a=17 n=if
 8(1587) DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 14==11 &&  [3001:0:0:1::10] == [10.10.10.10]
 8(1587) DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
 8(1587) DEBUG: <core> [socket_info.c:502]: grep_sock_info - checking if host==us: 14==19 &&  [3001:0:0:1::10] == [3001:0:0:1:0:0:0:10]
 8(1587) DEBUG: <core> [socket_info.c:505]: grep_sock_info - checking if port 5060 matches port 5060
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=505 a=6 n=route
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=702 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=677 a=26 n=is_method
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=678 a=3 n=return
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=508 a=6 n=route
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=636 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=621 a=26 n=is_method
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=629 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=623 a=42 n=isflagset
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=631 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=630 a=26 n=setbflag
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=634 a=17 n=if
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=631 a=26 n=save
 8(1587) DEBUG: usrloc [ucontact.c:805]: exists callback for type= UL_CONTACT_UPDATE
 8(1587) DEBUG: registrar [reply.c:192]: created Contact HF: Contact: <sip:300@[3001:0:0:4::4];line=fb0371cc0525bb2>;expires=815, <sip:300@[3001:0:0:4:0:0:0:4]:5060;line=fb0371cc0525bb2>;expires=3600

 8(1587) DEBUG: sl [sl.c:278]: reply in stateless mode (sl)
 8(1587) DEBUG: <core> [msg_translator.c:204]: check_via_address(3001:0:0:4:0:0:0:4, [3001:0:0:4::4], 0)
 8(1587) ERROR: *** cfgtrace: c=[/usr/local/etc/kamailio/kamailio.cfg] l=634 a=3 n=exit
 8(1587) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 8(1587) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 8(1587) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 8(1587) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 8(1587) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 8(1587) DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list (nil)
 8(1587) DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
 8(1587) DEBUG: <core> [receive.c:291]: receive_msg: cleaning up
 7(1586) DEBUG: <core> [udp_server.c:498]: udp_rcv_loop: probing packet received from 3001:0:0:4:0:0:0:4 50195