[SR-Users] Problem with my kamailio installation
Ramazan Yilmaz
ramazan.cs at gmail.com
Thu Nov 1 14:16:12 CET 2012
In my kamailio configuration, I already have "#!define WITH_TLS". And some
more about my configuration:
listen=tls:XX.XX.XXX.XX:5061
#!ifdef WITH_TLS
enable_tls=yes
#!endif
#!ifdef WITH_TLS
loadmodule "tls.so"
#!endif
#!ifdef WITH_TLS
# ----- tls params -----
modparam("tls", "config", "/usr/local/kamailio-3.3/etc/kamailio/tls.cfg")
#!endif
And my tls.cfg is,
[server:default]
method = SSLv23
verify_certificate = no
require_certificate = no
private_key = /usr/local/kamailio-3.3/etc/kamailio/kamailio.key
certificate = /usr/local/kamailio-3.3/etc/kamailio/kamailio.pem
[client:default]
verify_certificate = yes
require_certificate = yes
I have just installed kamailio 3.2.4 on some other server to see whether
the problem is with my configuration/my system or with kamailio release. I
again installed Ubuntu, and I installed the requested packages via apt-get,
as I had done on problematic system. I used exactly the same configuration
file, except changing the domain/ip values. And it worked. Then I used the
same configuration file on some other versions of Ubuntu server, and it
worked again. So, it really seems as a bug in kamailio.
It seems the worker children cannot be forked for some reason at startup,
so I enabled WITH_DEBUG directive and restarted the kamailio. The output is
attached to this mail. I hope it helps.
Best,
On Thu, Nov 1, 2012 at 2:44 PM, Daniel-Constantin Mierla
<miconda at gmail.com>wrote:
> What is the listen parameter for tls?
>
> IIRC, UDP is mandatory, you should have some workers for it, even you
> reject the traffic on it.
>
> The default config file has TLS support inside, just enable:
>
> #!define WITH_TLS
>
> and then test with it to see if you can connect. If yes, compare with your
> config to see what you did wrong.
>
> Cheers,
> Daniel
>
>
> On 10/31/12 6:12 PM, Ramazan Yilmaz wrote:
>
> I swithced again to tcp, and 'kamctl ps' now gives the following:
>
> Process:: ID=0 PID=5913 Type=attendant
> Process:: ID=1 PID=5914 Type=slow timer
> Process:: ID=2 PID=5915 Type=timer
> Process:: ID=3 PID=5916 Type=MI FIFO
> Process:: ID=4 PID=5917 Type=ctl handler
> Process:: ID=5 PID=5918 Type=MI DATAGRAM
> Process:: ID=6 PID=5919 Type=TIMER NH
> Process:: ID=7 PID=5920 Type=tcp receiver (generic) child=0
> Process:: ID=8 PID=5921 Type=tcp receiver (generic) child=1
> Process:: ID=9 PID=5922 Type=tcp receiver (generic) child=2
> Process:: ID=10 PID=5923 Type=tcp receiver (generic) child=3
> Process:: ID=11 PID=5924 Type=tcp main process
>
> So it seems ok for TCP, right?
>
> The only modification I made while switching from TLS to TCP is to change
> the listen=... directive (and disabling tls module parameters, of course)
> in kamailio.cfg file. So I do not make any change on children parameter
> value while switching to TCP, i.e. I have the following lines in
> kamailio.cfg
>
> fork=yes
> children=4
>
> Any suggestion?
>
> On Wed, Oct 31, 2012 at 6:18 PM, Daniel-Constantin Mierla <
> miconda at gmail.com> wrote:
>
>> Is this all the output of kamctl ps? Because there is no udp or tcp
>> worker process. If yes, then is something wrong with your config, be sure
>> you set proper children parameter value.
>>
>> Cheers,
>> Daniel
>>
>>
>> On 10/31/12 4:53 PM, Ramazan Yilmaz wrote:
>>
>> And the output of 'kamctl ps' is as following (after changing back to
>> tls):
>>
>> Process:: ID=0 PID=5621 Type=attendant
>> Process:: ID=1 PID=5622 Type=slow timer
>> Process:: ID=2 PID=5623 Type=timer
>> Process:: ID=3 PID=5624 Type=MI FIFO
>> Process:: ID=4 PID=5625 Type=ctl handler
>> Process:: ID=5 PID=5626 Type=MI DATAGRAM
>> Process:: ID=6 PID=5627 Type=TIMER NH
>> Process:: ID=7 PID=5628 Type=tcp main process
>>
>> And the new error lines in syslog for the processes above are,
>> Oct 31 16:52:14 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[5628]: :
>> <core> [tcp_main.c:3578]: BUG: handle_ser_child: fd -1 for 0 (pid 5621)
>> Oct 31 16:52:14 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[5628]: :
>> <core> [pass_fd.c:103]: ERROR: recv_all: 1st recv on 56 failed: Bad file
>> descriptor
>> Oct 31 16:52:14 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[5628]: :
>> <core> [tcp_main.c:3363]: ERROR: handle_tcp_child: read from tcp child 0
>> (pid 0, no 0) Bad file descriptor [9]
>> Oct 31 16:52:14 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[5628]: :
>> <core> [pass_fd.c:209]: ERROR: send_fd: sendmsg failed sending 17 on 56:
>> Bad file descriptor (9)
>> Oct 31 16:52:14 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[5628]:
>> ERROR: <core> [tcp_main.c:4006]: ERROR: send2child: send_fd failed for
>> 0x7f215351b588 (flags 0x4018), fd 17
>>
>>
>> On Wed, Oct 31, 2012 at 5:04 PM, Ramazan Yilmaz <ramazan.cs at gmail.com>wrote:
>>
>>> These messages are for tls. When switched to TCP, it works fine. What
>>> can be the problem?
>>>
>>>
>>> On Wed, Oct 31, 2012 at 3:39 PM, Daniel-Constantin Mierla <
>>> miconda at gmail.com> wrote:
>>>
>>>> Hello,
>>>>
>>>> does registration over UDP work fine? Are these messages for tcp or tls
>>>> connections?
>>>>
>>>> Can you paste the output of 'kamctl ps'?
>>>>
>>>> Cheers,
>>>> Daniel
>>>>
>>>>
>>>> On 10/30/12 5:32 PM, Ramazan Yilmaz wrote:
>>>>
>>>> Any ideas? It really seems as a bug, as the logs say. But do you have
>>>> any workaround suggestion?
>>>>
>>>> On Mon, Oct 29, 2012 at 11:09 PM, Ramazan Yilmaz <ramazan.cs at gmail.com>wrote:
>>>>
>>>>> Hi,
>>>>> I installed kamailio 3.2.x a few times before and they were really
>>>>> fine. I decided to install a fresh one and followed the instructions on
>>>>> http://www.kamailio.org/wiki/install/3.3.x/git. However there is a
>>>>> problem: clients cannot register to kamailio. Each registration attempt
>>>>> produces the following error in the syslog:
>>>>>
>>>>> Oct 29 20:07:38 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[32421]:
>>>>> : <core> [tcp_main.c:3578]: BUG: handle_ser_child: fd -1 for 0 (pid 32414)
>>>>> Oct 29 20:07:38 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[32421]: :
>>>>> <core> [pass_fd.c:103]: ERROR: recv_all: 1st recv on 56 failed: Bad file
>>>>> descriptor
>>>>> Oct 29 20:07:38 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[32421]: :
>>>>> <core> [tcp_main.c:3363]: ERROR: handle_tcp_child: read from tcp child 0
>>>>> (pid 0, no 0) Bad file descriptor [9]
>>>>> Oct 29 20:07:38 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[32421]: :
>>>>> <core> [pass_fd.c:209]: ERROR: send_fd: sendmsg failed sending 17 on 56:
>>>>> Bad file descriptor (9)
>>>>> Oct 29 20:07:38 ubuntu /usr/local/kamailio-3.3/sbin/kamailio[32421]:
>>>>> ERROR: <core> [tcp_main.c:4006]: ERROR: send2child: send_fd failed for
>>>>> 0x7f510bc41588 (flags 0x4018), fd 17
>>>>>
>>>>> What could be the problem? Any suggestion?
>>>>>
>>>>> In case you need, the server is ubuntu server 11.10, and I installed
>>>>> including mysql and tls modules.
>>>>>
>>>>>
>>>>
>>>>
>>>> _______________________________________________
>>>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing listsr-users at lists.sip-router.orghttp://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>>>
>>>>
>>>> --
>>>> Daniel-Constantin Mierla - http://www.asipto.comhttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>>>> Kamailio Advanced Training, Berlin, Nov 5-8, 2012 - http://asipto.com/u/kat
>>>> Kamailio Advanced Training, Miami, USA, Nov 12-14, 2012 - http://asipto.com/u/katu
>>>>
>>>>
>>>
>>
>> --
>> Daniel-Constantin Mierla - http://www.asipto.comhttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>> Kamailio Advanced Training, Berlin, Nov 5-8, 2012 - http://asipto.com/u/kat
>> Kamailio Advanced Training, Miami, USA, Nov 12-14, 2012 - http://asipto.com/u/katu
>>
>>
>
> --
> Daniel-Constantin Mierla - http://www.asipto.comhttp://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
> Kamailio Advanced Training, Berlin, Nov 5-8, 2012 - http://asipto.com/u/kat
> Kamailio Advanced Training, Miami, USA, Nov 12-14, 2012 - http://asipto.com/u/katu
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20121101/77ab6b5d/attachment-0001.htm>
-------------- next part --------------
Restarting Kamailio:
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
0(9076) DEBUG: <core> [cfg.y:1785]: loading modules under /usr/local/kamailio-3.3/lib64/kamailio/modules_k/:/usr/local/kamailio-3.3/lib64/kamailio/modules/
0(9076) DEBUG: <core> [cfg.y:1772]: loading module db_mysql.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/db_mysql.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/db_mysql/db_mysql.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so>
0(9076) DEBUG: <core> [db_query.c:375]: About to allocate sql_buf size = 65535
0(9076) DEBUG: <core> [cfg.y:1772]: loading module mi_fifo.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_fifo.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module kex.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: kex
0(9076) DEBUG: <core> [cfg.y:1772]: loading module tm.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tm.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tm/tm.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module tmx.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tmx.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: tmx
0(9076) DEBUG: <core> [cfg.y:1772]: loading module sl.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sl.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sl/sl.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module rr.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: rr
0(9076) DEBUG: <core> [cfg.y:1772]: loading module pv.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/pv.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: pv
0(9076) DEBUG: <core> [cfg.y:1772]: loading module maxfwd.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/maxfwd.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module usrloc.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module registrar.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: registrar
0(9076) DEBUG: <core> [cfg.y:1772]: loading module textops.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module siputils.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module xlog.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: xlog
0(9076) DEBUG: <core> [cfg.y:1772]: loading module sanity.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sanity.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/sanity/sanity.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/sanity.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module ctl.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/ctl.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/ctl/ctl.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module cfg_rpc.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/cfg_rpc.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/cfg_rpc/cfg_rpc.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/cfg_rpc.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module mi_rpc.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_rpc.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_rpc/mi_rpc.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/mi_rpc.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module acc.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module mi_datagram.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_datagram.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module auth.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth/auth.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module auth_db.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module permissions.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module nathelper.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: nathelper
0(9076) DEBUG: <core> [cfg.y:1772]: loading module rtpproxy.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/rtpproxy.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/rtpproxy/rtpproxy.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: rtpproxy
0(9076) DEBUG: <core> [cfg.y:1772]: loading module tls.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tls.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/tls/tls.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/tls.so>
0(9076) DEBUG: <core> [mem/f_malloc.c:293]: fm_malloc_init: F_OPTIMIZE=16384, /ROUNDTO=2048
0(9076) DEBUG: <core> [mem/f_malloc.c:295]: fm_malloc_init: F_HASH_SIZE=2099, fm_block size=33896
0(9076) DEBUG: <core> [mem/f_malloc.c:296]: fm_malloc_init(0x7f9d866b8000, 67108864), start=0x7f9d866b8000
0(9076) DEBUG: <core> [mem/shm_mem.c:203]: shm_mem_init: success
0(9076) INFO: tls [tls_init.c:377]: tls: init_tls: disabling compression...
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: tls
0(9076) DEBUG: <core> [cfg.y:1772]: loading module htable.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/htable.so>
0(9076) DEBUG: <core> [sr_module.c:343]: register PV from: htable
0(9076) DEBUG: <core> [cfg.y:1772]: loading module pike.so
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so>
0(9076) DEBUG: <core> [cfg.y:1772]: loading module debugger.so
0(9076) DEBUG: <core> [sr_module.c:499]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/debugger.so>
0(9076) DEBUG: <core> [sr_module.c:522]: load_module: module file not found </usr/local/kamailio-3.3/lib64/kamailio/modules_k/debugger/debugger.so>
0(9076) DEBUG: <core> [sr_module.c:564]: load_module: trying to load </usr/local/kamailio-3.3/lib64/kamailio/modules/debugger.so>
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'ctl' matches module 'ctl'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <user> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <user> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'ctl' matches module 'ctl'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <group> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <group> in module ctl [/usr/local/kamailio-3.3/lib64/kamailio/modules/ctl.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'mi_fifo' matches module 'mi_fifo'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fifo_name> in module mi_fifo [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_fifo.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fifo_name> in module mi_fifo [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_fifo.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <failure_reply_mode> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <failure_reply_mode> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fr_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fr_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tm' matches module 'tm'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <fr_inv_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <fr_inv_timer> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rr' matches module 'rr'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <enable_full_lr> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <enable_full_lr> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rr' matches module 'rr'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <append_fromtag> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <append_fromtag> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'registrar' matches module 'registrar'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <method_filtering> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <method_filtering> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'registrar' matches module 'registrar'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <max_expires> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <max_expires> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'mi_datagram' matches module 'mi_datagram'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <socket_name> in module mi_datagram [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_datagram.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <socket_name> in module mi_datagram [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/mi_datagram.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <early_media> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <early_media> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <report_ack> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <report_ack> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <report_cancels> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <report_cancels> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <detect_direction> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <detect_direction> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACC] value [1]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACCMISSED] value [2]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_missed_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_missed_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <log_extra> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <log_extra> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACCFAILED] value [3]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'acc' matches module 'acc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <failed_transaction_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <failed_transaction_flag> in module acc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/acc.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [DBURL] value ["mysql://kamailio_rw:openserrw@localhost/kamailio"]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_mode> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_mode> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MULTIDOMAIN] value [0]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <use_domain> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <use_domain> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [DBURL] value ["mysql://kamailio_rw:openserrw@localhost/kamailio"]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <calculate_ha1> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <calculate_ha1> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <password_column> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <password_column> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <load_credentials> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <load_credentials> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MULTIDOMAIN] value [0]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'auth_db' matches module 'auth_db'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <use_domain> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <use_domain> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [DBURL] value ["mysql://kamailio_rw:openserrw@localhost/kamailio"]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'permissions' matches module 'permissions'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_url> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_url> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'permissions' matches module 'permissions'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <db_mode> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <db_mode> in module permissions [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/permissions.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'rtpproxy' matches module 'rtpproxy'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <rtpproxy_sock> in module rtpproxy [/usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <rtpproxy_sock> in module rtpproxy [/usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <natping_interval> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <natping_interval> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <ping_nated_only> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <ping_nated_only> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATSIPPING] value [7]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sipping_bflag> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sipping_bflag> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper' matches module 'nathelper'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sipping_from> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sipping_from> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper|registrar' matches module 'nathelper'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <received_avp> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <received_avp> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'nathelper|registrar' matches module 'registrar'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <received_avp> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <received_avp> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'usrloc' matches module 'usrloc'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <nat_bflag> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <nat_bflag> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'tls' matches module 'tls'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <config> in module tls [/usr/local/kamailio-3.3/lib64/kamailio/modules/tls.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <config> in module tls [/usr/local/kamailio-3.3/lib64/kamailio/modules/tls.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'pike' matches module 'pike'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <sampling_time_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <sampling_time_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'pike' matches module 'pike'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <reqs_density_per_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <reqs_density_per_unit> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'pike' matches module 'pike'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <remove_latency> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <remove_latency> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'htable' matches module 'htable'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <htable> in module htable [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/htable.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <htable> in module htable [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/htable.so]
0(9076) DEBUG: htable [ht_api.c:762]: htable [ipban] - expire [300]
0(9076) DEBUG: htable [ht_api.c:767]: htable [ipban] - size [8]
0(9076) DEBUG: <core> [modparam.c:96]: set_mod_param_regex: 'debugger' matches module 'debugger'
0(9076) DEBUG: <core> [sr_module.c:770]: find_param_export: found <cfgtrace> in module debugger [/usr/local/kamailio-3.3/lib64/kamailio/modules/debugger.so]
0(9076) DEBUG: <core> [modparam.c:113]: set_mod_param_regex: found <cfgtrace> in module debugger [/usr/local/kamailio-3.3/lib64/kamailio/modules/debugger.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90341fa8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90342128
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d903422a8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90342428
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90343020
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90343780
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903427b8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90343870
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <remove_hf> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <record_route> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903447b0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90344f60
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90345180
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903458e0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <options_reply> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903461c0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90345f18
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d903469d0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90347430
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90346d60
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903474c0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903485c8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90348d28
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d903497e8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90349968
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_branch> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_reply> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_on_failure> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90349cd8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90349028
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_reply_error> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034b148
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034a758
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: htable [ht_var.c:162]: htable [ipban] - key [$si]
0(9076) DEBUG: htable [ht_api.c:211]: htable found [ipban]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xdbg> in module xlog [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <pike_check_req> in module pike [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/pike.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xlog> in module xlog [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so]
0(9076) DEBUG: htable [ht_var.c:162]: htable [ipban] - key [$si]
0(9076) DEBUG: htable [ht_api.c:211]: htable found [ipban]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #0/2: 21(15)/ 0x7f9d9034f438
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #1/2: 22(16)/ 0x7f9d9034ed68
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034e698
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034dbb0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034cfd0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034d730
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d9034f688
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9034b928
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9034f778
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <mf_process_maxfwd_header> in module maxfwd [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/maxfwd.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90350358
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90350ab8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sanity_check> in module sanity [/usr/local/kamailio-3.3/lib64/kamailio/modules/sanity.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <xlog> in module xlog [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/xlog.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90350c38
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903518c0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <loose_route> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACC] value [1]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x1
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_ACCFAILED] value [3]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x3
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903537b0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90353e80
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90354150
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_check_trans> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90354d30
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90355490
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903544e0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90355580
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90352ff0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90354060
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d903557f0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90352920
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903561c0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_NATS] value [5]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#42 #0/1: 3(3)/ 0x5
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATSIPPING] value [7]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90355a60
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90356730
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <save> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_reply_error> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903581b8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90358918
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <lookup> in module registrar [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/registrar.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #0/2: 21(15)/ 0x7f9d90359dc0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#65 #1/2: 22(16)/ 0x7f9d90359e30
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#18 #0/2: 22(16)/ 0x7f9d9035a800
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#18 #1/2: 24(18)/ 0x7f9d90359d18
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903595b8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9035a500
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <www_authorize> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <www_challenge> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9035ffb0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9035f4f8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9035daa0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9035e910
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d90360de0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <consume_credentials> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <proxy_authorize> in module auth_db [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/auth_db.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [MYDOMAIN] value ["XX.XX.XX.XX"]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <proxy_challenge> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <sl_send_reply> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90363190
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903626d8
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90360680
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90361af0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d90363fc0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <consume_credentials> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9035cd00
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90360ed0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d903640b0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <nat_uac_test> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_method> in module textops [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/textops.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_register> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_contact> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90364cd0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90363db0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 8(8)/ 0x7f9d90365430
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_NATS] value [5]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#40 #0/1: 3(3)/ 0x5
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90364600
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90365520
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_request> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <check_route_param> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <setbflag> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90368030
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90368790
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLT_NATS] value [5]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#42 #0/1: 3(3)/ 0x5
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x2
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9036adf0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9036b4c0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_request> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <has_totag> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <add_rr_param> in module rr [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/rr.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9036ce10
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9036d570
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <is_reply> in module siputils [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/siputils.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [cfg.lex:1878]: ### returning define ID [FLB_NATB] value [6]
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <isbflagset> in module kex [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/kex.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <fix_nated_contact> in module nathelper [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/nathelper.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d9036f250
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d9036db70
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <rtpproxy_manage> in module rtpproxy [/usr/local/kamailio-3.3/lib64/kamailio/modules/rtpproxy.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d9036fb00
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90370bc0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d903704f0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d90370c50
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#6 #0/1: 1(1)/ 0x7f9d90370f00
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #0/2: 9(9)/ (nil)
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#25 #1/2: 3(3)/ (nil)
0(9076) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_is_canceled> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #0/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#3 #1/2: 3(3)/ 0x1
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #0/3: 22(16)/ 0x7f9d90371200
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #1/3: 8(8)/ 0x7f9d903718d0
0(9076) DEBUG: <core> [route_struct.c:166]: ACTION_#17 #2/3: 0(0)/ 0x7f9d00000000
0(9076) DEBUG: <core> [route.c:2207]: routing table 0:
0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "ANTIFLOOD" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "REQINIT" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATDETECT" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "WITHINDLG" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332080> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332978> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90337e68> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333aa0> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "604" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903385a8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "AUTH" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "REGISTRAR" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "484" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "LOCATION" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "RELAY" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 1:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903323c8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "INVITE_BRANCH" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332390> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "INVITE_REPLY" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332358> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "INVITE_FAILURE" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903337e8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 2:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90338d58> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "request from blocked IP - $rm from $fu (IP:$si:$sp)
" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90338ce8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "L_ALERT" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:485]: assign( 0(9076) DEBUG: <core> [route_struct.c:526]: type<21> 0(9076) DEBUG: <core> [route_struct.c:560]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 3:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "483" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90338cb0> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , "Malformed SIP message from $si:$sp
" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 4:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:411]: setflag( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:411]: setflag( 0(9076) DEBUG: <core> [route_struct.c:498]: 3 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "RELAY" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90332080> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "404" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 5:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331660> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331660> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d903337e8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 6:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:485]: assign( 0(9076) DEBUG: <core> [route_struct.c:526]: type<21> 0(9076) DEBUG: <core> [route_struct.c:560]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331c58> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:491]: UNKNOWN( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:560]: , type<24> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 7:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b080> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "XX.XX.XX.XX" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "403" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b048> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b0b8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "XX.XX.XX.XX" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333740> 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:567]: , "403" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033b048> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 8:
0(9076) DEBUG: <core> [route_struct.c:464]: force_rport( 0(9076) DEBUG: <core> [route_struct.c:526]: type<0> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033bd18> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033bbc8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:411]: setflag( 0(9076) DEBUG: <core> [route_struct.c:498]: 5 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: routing table 9:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90331660> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:586]: , type<22> 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 2 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d90333bb8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:567]: , ";nat=yes" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033bbc8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route_struct.c:461]: external_module_call( 0(9076) DEBUG: <core> [route_struct.c:510]: f_ptr<0x7f9d9033c3f8> 0(9076) DEBUG: <core> [route_struct.c:536]: , 0 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: onreply routing table 1:
0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: failure routing table 1:
0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:444]: if ( 0(9076) DEBUG: <core> [route_struct.c:526]: type<22> 0(9076) DEBUG: <core> [route_struct.c:528]: ) { 0(9076) DEBUG: <core> [route_struct.c:381]: drop( 0(9076) DEBUG: <core> [route_struct.c:498]: 1 0(9076) DEBUG: <core> [route_struct.c:536]: , 1 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route_struct.c:562]: } else { 0(9076) DEBUG: <core> [route_struct.c:588]: }; 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [route.c:2207]: branch routing table 1:
0(9076) DEBUG: <core> [route_struct.c:390]: route( 0(9076) DEBUG: <core> [route_struct.c:495]: "NATMANAGE" 0(9076) DEBUG: <core> [route_struct.c:589]: ); 0(9076) DEBUG: <core> [route.c:2209]:
0(9076) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'core' (num=49, size=196)
0(9076) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'tcp' (num=26, size=104)
0(9076) DEBUG: <core> [timer.c:272]: init_timer: starting with *ticks=1909742625
0(9076) DEBUG: <core> [timer.c:314]: init_timer: timer_list between 0x7f9d866c0978 and 0x7f9d86704978
0(9076) INFO: <core> [tcp_main.c:4832]: init_tcp: using epoll_lt as the io watch method (auto detected)
loading modules under /usr/local/kamailio-3.3/lib64/kamailio/modules_k/:/usr/local/kamailio-3.3/lib64/kamailio/modules/
Listening on
tls: XX.XX.XX.XX [XX.XX.XX.XX]:5061
Aliases:
*: XX.XX.XX.XX:*
0(9078) DEBUG: <core> [daemonize.c:219]: trying enable core dumping...
0(9078) DEBUG: <core> [daemonize.c:237]: core dumping is enabled now (1)...
0(9078) DEBUG: <core> [daemonize.c:575]: core dump limits set to 18446744073709551615
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: db_mysql
0(9078) DEBUG: db_mysql [km_db_mysql.c:92]: MySQL client version is 5.1.63
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: mi_fifo
0(9078) DEBUG: mi_fifo [mi_fifo.c:124]: testing mi_fifo existance ...
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: kex
0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2376-] (0 / 19)
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: tm
0(9078) DEBUG: tm [tm.c:738]: TM - (sizeof cell=4776, sip_msg=1600) initializing...
0(9078) DEBUG: tm [callid.c:105]: Call-ID initialization: '746bd51a16d56563'
0(9078) DEBUG: tm [lock.c:87]: DEBUG: lock_initialize: lock initialization started
0(9078) DEBUG: tm [timer.c:226]: tm: tm_init_timers: fr=160 fr_inv=960 wait=80 delete=4 t1=500 t2=4000 max_inv_lifetime=2880 max_noninv_lifetime=512
0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'tm' (num=35, size=168)
0(9078) DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: 6fbeced333041219cc0e266155e5d989
0(9078) DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: 7321ae68013f6a299ac7e32ffb4f2546
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: tmx
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: sl
0(9078) DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated: 8f06e0b537f8292d6acbb4c9371a7e8e
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: rr
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: pv
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: maxfwd
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: usrloc
0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [ulcx-50927432-2376-] (0 / 19)
0(9078) INFO: usrloc [hslot.c:53]: locks array size 512
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: registrar
0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2376-] (0 / 19)
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'registrar' (num=10, size=56)
0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 'RECEIVED'
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <ul_bind_usrloc> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: textops
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: siputils
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 's:rpid'
0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'siputils' (num=1, size=4)
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: xlog
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: sanity
0(9078) DEBUG: sanity [mod_sanity.c:103]: sanity initializing
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9078) DEBUG: sanity [mod_sanity.c:111]: parsing proxy requires string:
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: ctl
0(9078) DEBUG: ctl [ctl.c:242]: listening on:
0(9078) DEBUG: ctl [ctl.c:252]: [binrpc:unix stream] /tmp/kamailio_ctl
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: cfg_rpc
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: mi_rpc
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: acc
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: mi_datagram
0(9078) DEBUG: mi_datagram [mi_datagram.c:158]: testing socket existance...
0(9078) DEBUG: mi_datagram [mi_datagram.c:165]: the socket's name/addres is udp:127.0.0.1:8033
0(9078) DEBUG: mi_datagram [mi_datagram.c:172]: we have an udp socket
0(9078) DEBUG: mi_datagram [mi_datagram.c:180]: the remaining address after separating the protocol is 127.0.0.1:8033
0(9078) DEBUG: mi_datagram [mi_datagram.c:192]: the port string is 8033
0(9078) DEBUG: mi_datagram [mi_datagram.c:208]: the ip is 127.0.0.1
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: auth
0(9078) DEBUG: auth [auth_mod.c:257]: auth module - initializing
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_sl> in module sl [/usr/local/kamailio-3.3/lib64/kamailio/modules/sl.so]
0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 'digest_challenge'
0(9078) INFO: auth [auth_mod.c:329]: auth: qop set, but nonce-count (nc_enabled) support disabled
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: auth_db
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <bind_auth_s> in module auth [/usr/local/kamailio-3.3/lib64/kamailio/modules/auth.so]
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: permissions
0(9078) INFO: permissions [parse_config.c:251]: file not found: /usr/local/kamailio-3.3/etc/kamailio/permissions.allow
0(9078) INFO: permissions [permissions.c:610]: default allow file (/usr/local/kamailio-3.3/etc/kamailio/permissions.allow) not found => empty rule set
0(9078) INFO: permissions [parse_config.c:251]: file not found: /usr/local/kamailio-3.3/etc/kamailio/permissions.deny
0(9078) INFO: permissions [permissions.c:619]: default deny file (/usr/local/kamailio-3.3/etc/kamailio/permissions.deny) not found => empty rule set
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d90378070 not found in pool
0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d903786c8
0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d903786e0
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d903786f8
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903786f8)[0]=[table_version]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90378718
0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90378738
0(9078) DEBUG: <core> [db_val.c:73]: converting INT [5]
0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d903786f8
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d903786c8
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d903786e0
0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90378738
0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90378718
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
0(9078) DEBUG: db_mysql [km_res.c:68]: 4 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 32 bytes for result names at 0x7f9d90378738
0(9078) DEBUG: <core> [db_res.c:165]: allocate 16 bytes for result types at 0x7f9d90378718
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d903786f8
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903786f8)[0]=[src_ip]
0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[1] at 0x7f9d90378768
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378768)[1]=[proto]
0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[2] at 0x7f9d90378788
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378788)[2]=[from_pattern]
0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[3] at 0x7f9d903787a8
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903787a8)[3]=[tag]
0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
0(9078) DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
0(9078) DEBUG: permissions [trusted.c:102]: number of rows in trusted table: 0
0(9078) DEBUG: <core> [db_res.c:81]: freeing 4 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d903786f8
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0x7f9d90378768
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0x7f9d90378788
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0x7f9d903787a8
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90378738
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90378718
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
0(9078) DEBUG: permissions [trusted.c:145]: trusted table reloaded successfully.
0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <db_bind_api> in module db_mysql [/usr/local/kamailio-3.3/lib64/kamailio/modules/db_mysql.so]
0(9078) DEBUG: <core> [db.c:212]: using db bind api for db_mysql
0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d90378028 not found in pool
0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d903786c8
0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d9032aa88
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90378718
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378718)[0]=[table_version]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d903787a8
0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90378070
0(9078) DEBUG: <core> [db_val.c:73]: converting INT [5]
0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90378718
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d903786c8
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d9032aa88
0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90378070
0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d903787a8
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90378688
0(9078) DEBUG: db_mysql [km_res.c:68]: 5 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 40 bytes for result names at 0x7f9d90378070
0(9078) DEBUG: <core> [db_res.c:165]: allocate 20 bytes for result types at 0x7f9d903787c8
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d903787a8
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d903787a8)[0]=[grp]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[1] at 0x7f9d9032aa88
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d9032aa88)[1]=[ip_addr]
0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[2] at 0x7f9d90378718
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378718)[2]=[mask]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[3] at 0x7f9d90378788
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378788)[3]=[port]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[4] at 0x7f9d90378768
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378768)[4]=[tag]
0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
0(9078) DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
0(9078) DEBUG: permissions [address.c:134]: Number of rows in address table: 0
0(9078) DEBUG: <core> [db_res.c:81]: freeing 5 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d903787a8
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[1] at 0x7f9d9032aa88
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[2] at 0x7f9d90378718
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[3] at 0x7f9d90378788
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[4] at 0x7f9d90378768
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90378070
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d903787c8
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90378688
0(9078) DEBUG: permissions [address.c:200]: address table reloaded successfully.
0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: nathelper
0(9078) DEBUG: <core> [usr_avp.c:895]: Parsing 'RECEIVED'
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <ul_bind_usrloc> in module usrloc [/usr/local/kamailio-3.3/lib64/kamailio/modules_k/usrloc.so]
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: rtpproxy
0(9078) DEBUG: rtpproxy [rtpproxy.c:535]: url is udp:127.0.0.1:7722, len is 18
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <load_tm> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_newtran> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_tcp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay_to_udp> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_relay> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_forward_nonack> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:674]: find_export_record: found <t_release> in module tm [/usr/local/kamailio-3.3/lib64/kamailio/modules/tm.so]
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: tls
0(9078) DEBUG: <core> [cfg/cfg.c:178]: DEBUG: register_cfg_def(): new config group has been registered: 'tls' (num=27, size=216)
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: htable
0(9078) DEBUG: htable [htable.c:175]: starting auto-expire timer
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: pike
0(9078) INFO: pike [ip_tree.c:88]: probing 256 set size
0(9078) DEBUG: <core> [sr_module.c:935]: DEBUG: init_mod: debugger
0(9078) INFO: tls [tls_init.c:541]: tls: _init_tls_h: compiled with openssl version "OpenSSL 1.0.0e 6 Sep 2011" (0x1000005f), kerberos support: off, compression: on
0(9078) INFO: tls [tls_init.c:549]: tls: init_tls_h: installed openssl library version "OpenSSL 1.0.0e 6 Sep 2011" (0x1000005f), kerberos support: off, zlib compression: on
compiler: cc -fPIC -DOPENSSL_PIC -DZLIB -DOPENSSL_THREADS -D_REENTRANT -DDSO_DLFCN -DHAVE_DLFCN_H -m64 -DL_ENDIAN -DTERMIO -O3 -Wa,--noexecstack -g -Wall -DMD32_REG_T=int -DOPENSSL_IA32_SSE2 -DOPENSSL_BN_ASM_MONT -DSHA1_ASM -DSHA256_ASM -DSHA512_ASM -DMD5_ASM -DAES_ASM -DWHIRLPOOL_ASM
0(9078) WARNING: tls [tls_init.c:603]: tls: openssl bug #1491 (crash/mem leaks on low memory) workaround enabled (on low memory tls operations will fail preemptively) with free memory thresholds 4194304 and 2097152 bytes
0(9078) INFO: <core> [cfg/cfg_ctx.c:613]: INFO: cfg_set_now(): tls.low_mem_threshold1 has been changed to 4194304
0(9078) INFO: <core> [cfg/cfg_ctx.c:613]: INFO: cfg_set_now(): tls.low_mem_threshold2 has been changed to 2097152
0(9078) DEBUG: <core> [main.c:2519]: Expect (at least) 8 SER processes in your process list
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [CANCEL/2]
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing t_check_trans()
0(9078) DEBUG: <core> [route.c:929]: fixing t_relay()
0(9078) DEBUG: <core> [route.c:929]: fixing t_check_trans()
0(9078) DEBUG: <core> [route.c:929]: fixing remove_hf()
0(9078) DEBUG: textops [textops.c:1669]: using hdr type (9) instead of <Route>
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1746]: using id for methods [INVITE,SUBSCRIBE/65]
0(9078) DEBUG: <core> [route.c:929]: fixing record_route()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [OPTIONS/512]
0(9078) DEBUG: <core> [route.c:929]: fixing options_reply()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [REGISTER/32]
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [INVITE/1]
0(9078) DEBUG: <core> [route.c:929]: fixing t_on_branch()
0(9078) DEBUG: <core> [route.c:929]: fixing t_on_reply()
0(9078) DEBUG: <core> [route.c:929]: fixing t_on_failure()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing t_relay()
0(9078) DEBUG: <core> [route.c:929]: fixing sl_reply_error()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [route.c:929]: fixing xdbg()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing pike_check_req()
0(9078) DEBUG: <core> [route.c:929]: fixing xlog()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 1
0(9078) DEBUG: <core> [rvalue.c:2861]: RV is int: 1
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing mf_process_maxfwd_header()
0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing sanity_check()
0(9078) DEBUG: <core> [route.c:929]: fixing xlog()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing has_totag()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing loose_route()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [BYE/8]
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [ACK/4]
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing t_check_trans()
0(9078) DEBUG: <core> [route.c:929]: fixing t_relay()
0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing setbflag()
0(9078) DEBUG: <core> [route.c:929]: fixing setbflag()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing save()
0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d903571e0 not found in pool
0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356908
0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356920
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356938
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356938)[0]=[table_version]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90356958
0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90356978
0(9078) DEBUG: <core> [db_val.c:73]: converting INT [5]
0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356938
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356908
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356920
0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90356978
0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90356958
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356920
0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356908
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356958
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356958)[0]=[username]
0(9078) DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
0(9078) DEBUG: db_mysql [km_res.c:169]: no rows returned from the query
0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356958
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356920
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356908
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
0(9078) DEBUG: <core> [route.c:929]: fixing sl_reply_error()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing lookup()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [route.c:929]: fixing t_newtran()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [route.c:929]: fixing send_reply()
0(9078) DEBUG: <core> [route.c:929]: fixing send_reply()
0(9078) DEBUG: <core> [switch.c:232]: SWITCH: 3 cases, 0 default
0(9078) DEBUG: <core> [switch.c:402]: SWITCH: optimized to condtable (3) default: no
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [REGISTER/32]
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing www_authorize()
0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d90357420 not found in pool
0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356908
0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356920
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90378738
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90378738)[0]=[table_version]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90356978
0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d903574b0
0(9078) DEBUG: <core> [db_val.c:73]: converting INT [6]
0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90378738
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356908
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356920
0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d903574b0
0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90356978
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
0(9078) DEBUG: <core> [route.c:929]: fixing www_challenge()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
0(9078) DEBUG: <core> [route.c:929]: fixing consume_credentials()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing proxy_authorize()
0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d903575f0 not found in pool
0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
0(9078) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d903568c8
0(9078) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
0(9078) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356920
0(9078) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356908
0(9078) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356978
0(9078) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356978)[0]=[table_version]
0(9078) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
0(9078) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d903574b0
0(9078) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90378738
0(9078) DEBUG: <core> [db_val.c:73]: converting INT [6]
0(9078) DEBUG: <core> [db_res.c:81]: freeing 1 columns
0(9078) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356978
0(9078) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356920
0(9078) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356908
0(9078) DEBUG: <core> [db_res.c:54]: freeing 1 rows
0(9078) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90378738
0(9078) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d903574b0
0(9078) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d903568c8
0(9078) DEBUG: <core> [db_pool.c:102]: removing connection from the pool
0(9078) DEBUG: <core> [route.c:929]: fixing proxy_challenge()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 7
0(9078) DEBUG: <core> [route.c:929]: fixing sl_send_reply()
0(9078) DEBUG: <core> [route.c:929]: fixing consume_credentials()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing nat_uac_test()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_method()
0(9078) DEBUG: textops [textops.c:1753]: using id for method [REGISTER/32]
0(9078) DEBUG: <core> [route.c:929]: fixing fix_nated_register()
0(9078) DEBUG: <core> [route.c:929]: fixing fix_nated_contact()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_request()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing has_totag()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing check_route_param()
0(9078) DEBUG: <core> [route.c:929]: fixing setbflag()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing isbflagset()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_request()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing has_totag()
0(9078) DEBUG: <core> [route.c:929]: fixing add_rr_param()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing is_reply()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing isbflagset()
0(9078) DEBUG: <core> [route.c:929]: fixing fix_nated_contact()
0(9078) DEBUG: <core> [route.c:929]: fixing rtpproxy_manage()
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 3
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 2
0(9078) DEBUG: <core> [rvalue.c:2865]: RV is str: "[12][0-9][0-9]"
0(9078) DEBUG: <core> [route.c:506]: right EXP optimized: op11(O52, _ST22_) => op11(O52, _ST1_)
0(9078) DEBUG: <core> [rvalue.c:2857]: RV fixing type 4
0(9078) DEBUG: <core> [route.c:929]: fixing t_is_canceled()
0(9078) DEBUG: <core> [tcp_main.c:2840]: tcp_init: added XX.XX.XX.XX:5061
0(9078) DEBUG: <core> [daemonize.c:219]: trying enable core dumping...
0(9078) DEBUG: <core> [daemonize.c:221]: core dumping is disabled now...
0(9078) DEBUG: <core> [daemonize.c:225]: core dumping has just been enabled...
0(9078) DEBUG: <core> [daemonize.c:232]: current core file limit: 18446744073709551615 (max: 18446744073709551615)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_fifo
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): kex
0(9078) DEBUG: kex [kex_mod.c:160]: rank is (-127)
0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2376-] (0 / 19)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): tm
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): sl
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): usrloc
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): registrar
0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2376-] (0 / 19)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): ctl
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_rpc
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): acc
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): mi_datagram
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): auth_db
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): permissions
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): nathelper
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): rtpproxy
0(9078) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): tls
0(9078) INFO: tls [tls_domain.c:187]: TLSs<default>: tls_method=12
0(9078) INFO: tls [tls_domain.c:199]: TLSs<default>: certificate='/usr/local/kamailio-3.3/etc/kamailio/kamailio.pem'
0(9078) INFO: tls [tls_domain.c:206]: TLSs<default>: ca_list='(null)'
0(9078) INFO: tls [tls_domain.c:213]: TLSs<default>: crl='(null)'
0(9078) INFO: tls [tls_domain.c:217]: TLSs<default>: require_certificate=0
0(9078) INFO: tls [tls_domain.c:224]: TLSs<default>: cipher_list='(null)'
0(9078) INFO: tls [tls_domain.c:231]: TLSs<default>: private_key='/usr/local/kamailio-3.3/etc/kamailio/kamailio.key'
0(9078) INFO: tls [tls_domain.c:235]: TLSs<default>: verify_certificate=0
0(9078) INFO: tls [tls_domain.c:238]: TLSs<default>: verify_depth=9
0(9078) DEBUG: tls [tls_domain.c:438]: TLSs<default>: No CA list configured
0(9078) DEBUG: tls [tls_domain.c:475]: TLSs<default>: No CRL configured
0(9078) INFO: tls [tls_domain.c:576]: TLSs<default>: No client certificate required and no checks performed
0(9078) INFO: tls [tls_domain.c:187]: TLSc<default>: tls_method=9
0(9078) INFO: tls [tls_domain.c:199]: TLSc<default>: certificate='(null)'
0(9078) INFO: tls [tls_domain.c:206]: TLSc<default>: ca_list='(null)'
0(9078) INFO: tls [tls_domain.c:213]: TLSc<default>: crl='(null)'
0(9078) INFO: tls [tls_domain.c:217]: TLSc<default>: require_certificate=1
0(9078) INFO: tls [tls_domain.c:224]: TLSc<default>: cipher_list='(null)'
0(9078) INFO: tls [tls_domain.c:231]: TLSc<default>: private_key='(null)'
0(9078) INFO: tls [tls_domain.c:235]: TLSc<default>: verify_certificate=1
0(9078) INFO: tls [tls_domain.c:238]: TLSc<default>: verify_depth=9
0(9078) DEBUG: tls [tls_domain.c:408]: TLSc<default>: No certificate configured
0(9078) DEBUG: tls [tls_domain.c:438]: TLSc<default>: No CA list configured
0(9078) DEBUG: tls [tls_domain.c:475]: TLSc<default>: No CRL configured
0(9078) INFO: tls [tls_domain.c:561]: TLSc<default>: Server MUST present valid certificate
0(9078) DEBUG: tls [tls_domain.c:917]: TLSs<default>: Key '/usr/local/kamailio-3.3/etc/kamailio/kamailio.key' successfuly loaded
0(9078) DEBUG: tls [tls_domain.c:877]: TLSc<default>: No private key specified
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): htable
0(9078) DEBUG: htable [htable.c:194]: rank is (-127)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-127): debugger
0(9078) DEBUG: debugger [debugger_mod.c:124]: rank is (-127)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_fifo
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): kex
0(9078) DEBUG: kex [kex_mod.c:160]: rank is (0)
0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2376-] (0 / 19)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): tm
0(9078) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9078 at XX.XX.XX.XX'
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): sl
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): usrloc
0(9078) DEBUG: <core> [db.c:316]: connection 0x7f9d9036fb00 not found in pool
0(9078) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
0(9078) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
0(9078) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
0(9078) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): registrar
0(9078) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2376-] (0 / 19)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): ctl
0(9078) DEBUG: ctl [ctl.c:311]: ctl: mod_child(0), ctrl_sock_lst=0x1a661b0
0(9078) DEBUG: ctl [ctl.c:319]: ctl: mod_child(0), fork_process=9082, csl=0x1a661b0
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_rpc
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): acc
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): mi_datagram
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): auth_db
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): permissions
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): nathelper
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): rtpproxy
3(9081) DEBUG: mi_fifo [mi_fifo.c:223]: new process with pid = 9081 created
3(9081) DEBUG: mi_fifo [fifo_fnc.c:76]: FIFO created @ /tmp/kamailio_fifo
3(9081) DEBUG: mi_fifo [fifo_fnc.c:91]: fifo /tmp/kamailio_fifo opened, mode=660
3(9081) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
3(9081) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
2(9080) DEBUG: kex [kex_mod.c:160]: rank is (-1)
2(9080) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2378-] (0 / 19)
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
2(9080) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9080 at XX.XX.XX.XX'
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
2(9080) DEBUG: <core> [db.c:316]: connection 0x7f9d9036fb00 not found in pool
2(9080) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
5(9083) INFO: mi_datagram [mi_datagram.c:329]: a new child 0/9083
5(9083) DEBUG: <core> [db_id.c:292]: identical DB URLs, but different DB connection pid [9083/9078]
5(9083) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
5(9083) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_fifo
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): kex
4(9082) DEBUG: kex [kex_mod.c:160]: rank is (-2)
4(9082) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-237a-] (0 / 19)
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): tm
4(9082) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9082 at XX.XX.XX.XX'
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): sl
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): usrloc
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): registrar
4(9082) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-237a-] (0 / 19)
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): ctl
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_rpc
4(9082) DEBUG: mi_rpc [mi_rpc_mod.c:106]: initializing child[-2] for rpc handling
4(9082) DEBUG: <core> [db_id.c:292]: identical DB URLs, but different DB connection pid [9082/9078]
4(9082) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
4(9082) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
6(9084) DEBUG: kex [kex_mod.c:160]: rank is (-1)
6(9084) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-237c-] (0 / 19)
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
6(9084) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9084 at XX.XX.XX.XX'
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
6(9084) DEBUG: <core> [db_id.c:292]: identical DB URLs, but different DB connection pid [9084/9078]
6(9084) DEBUG: <core> [db.c:316]: connection 0x7f9d90370f00 not found in pool
6(9084) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
3(9081) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
3(9081) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
3(9081) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
3(9081) DEBUG: <core> [db.c:325]: connection 0x7f9d90370bc0 found in pool
3(9081) DEBUG: <core> [db.c:325]: connection 0x7f9d90356b90 found in pool
5(9083) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
5(9083) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
5(9083) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
5(9083) DEBUG: <core> [db.c:325]: connection 0x7f9d90371c60 found in pool
5(9083) DEBUG: <core> [db.c:325]: connection 0x7f9d90371d68 found in pool
2(9080) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
2(9080) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
2(9080) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
2(9080) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2378-] (0 / 19)
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_datagram
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
2(9080) DEBUG: <core> [db.c:325]: connection 0x7f9d90370f00 found in pool
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): permissions
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
4(9082) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
4(9082) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
4(9082) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371c60 found in pool
4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371d68 found in pool
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): acc
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): mi_datagram
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): auth_db
4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371e70 found in pool
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): permissions
4(9082) DEBUG: <core> [db.c:325]: connection 0x7f9d90371f78 found in pool
6(9084) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
6(9084) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
6(9084) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
6(9084) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-237c-] (0 / 19)
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_datagram
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
6(9084) DEBUG: <core> [db.c:325]: connection 0x7f9d90371c60 found in pool
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): permissions
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
4(9082) DEBUG: <core> [db_res.c:118]: allocate 48 bytes for result set at 0x7f9d90356b48
4(9082) DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
4(9082) DEBUG: <core> [db_res.c:155]: allocate 8 bytes for result names at 0x7f9d90356b88
4(9082) DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at 0x7f9d90356ba0
4(9082) DEBUG: db_mysql [km_res.c:85]: allocate 16 bytes for RES_NAMES[0] at 0x7f9d90356bb8
4(9082) DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0x7f9d90356bb8)[0]=[table_version]
4(9082) DEBUG: db_mysql [km_res.c:100]: use DB1_INT result type
4(9082) DEBUG: <core> [db_res.c:184]: allocate 16 bytes for rows at 0x7f9d90356bd8
4(9082) DEBUG: <core> [db_row.c:119]: allocate 32 bytes for row values at 0x7f9d90356bf8
4(9082) DEBUG: <core> [db_val.c:73]: converting INT [5]
4(9082) DEBUG: <core> [db_res.c:81]: freeing 1 columns
4(9082) DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0x7f9d90356bb8
4(9082) DEBUG: <core> [db_res.c:94]: freeing result names at 0x7f9d90356b88
4(9082) DEBUG: <core> [db_res.c:99]: freeing result types at 0x7f9d90356ba0
4(9082) DEBUG: <core> [db_res.c:54]: freeing 1 rows
4(9082) DEBUG: <core> [db_row.c:97]: freeing row values at 0x7f9d90356bf8
4(9082) DEBUG: <core> [db_res.c:62]: freeing rows at 0x7f9d90356bd8
4(9082) DEBUG: <core> [db_res.c:136]: freeing result set at 0x7f9d90356b48
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): nathelper
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): rtpproxy
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_fifo
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): kex
1(9079) DEBUG: kex [kex_mod.c:160]: rank is (-1)
1(9079) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-2377-] (0 / 19)
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tm
1(9079) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9079 at XX.XX.XX.XX'
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): sl
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): usrloc
1(9079) DEBUG: <core> [db.c:316]: connection 0x7f9d9036fb00 not found in pool
1(9079) DEBUG: db_mysql [km_my_con.c:94]: opening connection: mysql://xxxx:xxxx@localhost/kamailio
1(9079) DEBUG: db_mysql [km_my_con.c:121]: connection type is Localhost via UNIX socket
1(9079) DEBUG: db_mysql [km_my_con.c:122]: protocol version is 10
1(9079) DEBUG: db_mysql [km_my_con.c:123]: server version is 5.1.58-1ubuntu1
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): registrar
1(9079) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-2377-] (0 / 19)
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): ctl
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_rpc
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): acc
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): mi_datagram
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): auth_db
1(9079) DEBUG: <core> [db.c:325]: connection 0x7f9d90370f00 found in pool
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): permissions
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): nathelper
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): rtpproxy
0(9078) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
4(9082) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
6(9084) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
2(9080) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
1(9079) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): tls
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): htable
0(9078) DEBUG: htable [htable.c:194]: rank is (0)
0(9078) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (0): debugger
0(9078) DEBUG: debugger [debugger_mod.c:124]: rank is (0)
0(9078) DEBUG: <core> [main.c:1752]: Expect maximum 2091 open fds
kamailio started.
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_fifo
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): kex
7(9085) DEBUG: kex [kex_mod.c:160]: rank is (-4)
7(9085) DEBUG: <core> [sruid.c:100]: root for sruid is [srid-50927432-237d-] (0 / 19)
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): tm
7(9085) DEBUG: tm [callid.c:137]: DEBUG: callid: '746bd51a16d56563-9085 at XX.XX.XX.XX'
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): sl
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): usrloc
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): registrar
7(9085) DEBUG: <core> [sruid.c:100]: root for sruid is [uloc-50927432-237d-] (0 / 19)
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): ctl
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_rpc
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): acc
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): mi_datagram
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): auth_db
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): permissions
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): nathelper
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): rtpproxy
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tls
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): htable
6(9084) DEBUG: htable [htable.c:194]: rank is (-1)
6(9084) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
6(9084) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): tls
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): htable
4(9082) DEBUG: htable [htable.c:194]: rank is (-2)
4(9082) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-2): debugger
4(9082) DEBUG: debugger [debugger_mod.c:124]: rank is (-2)
4(9082) DEBUG: ctl [ctl.c:319]: ctl: mod_child(0), fork_process=0, csl=0x1a661b0
4(9082) DEBUG: ctl [ctl.c:326]: ctl: 0 io_listen_loop(1, 0x1a661b0)
4(9082) INFO: ctl [io_listener.c:225]: io_listen_loop: using epoll_lt io watch method (config)
4(9082) DEBUG: ctl [io_listener.c:257]: io_listen_loop: adding socket 4, type 2, transport 3 (/tmp/kamailio_ctl)
4(9082) DEBUG: ctl [../../io_wait.h:390]: DBG: io_watch_add(0x7f9d8ce5e440, 4, 2, 0x1a661b0), fd_no=0
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tls
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): htable
2(9080) DEBUG: htable [htable.c:194]: rank is (-1)
2(9080) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
2(9080) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): tls
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): htable
1(9079) DEBUG: htable [htable.c:194]: rank is (-1)
1(9079) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-1): debugger
1(9079) DEBUG: debugger [debugger_mod.c:124]: rank is (-1)
7(9085) INFO: rtpproxy [rtpproxy.c:1413]: rtp proxy <udp:127.0.0.1:7722> found, support for it enabled
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): tls
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): htable
7(9085) DEBUG: htable [htable.c:194]: rank is (-4)
7(9085) DEBUG: <core> [sr_module.c:892]: DEBUG: init_mod_child (-4): debugger
7(9085) DEBUG: debugger [debugger_mod.c:124]: rank is (-4)
7(9085) DEBUG: <core> [local_timer.c:74]: init_local_timer: timer_list between 0x86c228 and 0x8b0228
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 6, 1, 0x7f9d90330ba0), fd_no=0
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 8, 4, 0x7f9d86923074), fd_no=1
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 9, 4, 0x7f9d86923100), fd_no=2
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 10, 4, 0x7f9d8692318c), fd_no=3
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 12, 4, 0x7f9d86923218), fd_no=4
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 14, 4, 0x7f9d869232a4), fd_no=5
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 4, 4, 0x7f9d86923330), fd_no=6
7(9085) DEBUG: <core> [ip_addr.c:247]: tcpconn_new: new tcp connection: 188.57.131.155
7(9085) DEBUG: <core> [tcp_main.c:1089]: tcpconn_new: on port 32404, type 3
7(9085) DEBUG: <core> [tcp_main.c:1403]: tcpconn_add: hashes: 3324:1057:1987, 1
7(9085) DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x86c020, 16, 2, 0x7f9d869656a8), fd_no=7
7(9085) DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x86c020, 16, -1, 0x0) fd_no=8 called
7(9085) DEBUG: <core> [tcp_main.c:4298]: tcp: DBG: sending to child, events 1
7(9085) DEBUG: <core> [tcp_main.c:3969]: selected tcp worker 0 0(0) for activity on [tls:XX.XX.XX.XX:5061], 0x7f9d869656a8
7(9085) : <core> [tcp_main.c:3578]: BUG: handle_ser_child: fd -1 for 0 (pid 9078)
7(9085) : <core> [pass_fd.c:103]: ERROR: recv_all: 1st recv on 56 failed: Bad file descriptor
7(9085) : <core> [tcp_main.c:3363]: ERROR: handle_tcp_child: read from tcp child 0 (pid 0, no 0) Bad file descriptor [9]
7(9085) : <core> [pass_fd.c:209]: ERROR: send_fd: sendmsg failed sending 16 on 56: Bad file descriptor (9)
7(9085) ERROR: <core> [tcp_main.c:4006]: ERROR: send2child: send_fd failed for 0x7f9d869656a8 (flags 0x4018), fd 16
7(9085) DEBUG: tls [tls_server.c:601]: Closing SSL connection (nil)
More information about the sr-users
mailing list