search

OpenSIPS / opensips

OpenSIPS is a GPL implementation of a multi-functionality SIP Server that targets to deliver a high-level technical solution (performance, security and quality) to be used in professional SIP server platforms.
https://opensips.org
Other
1.26k stars 576 forks source link

opensips-2.2.2 fresh checkout crashing continuosly #1075

Closed neha31oct closed 7 years ago

neha31oct commented 7 years ago

I just downloaded opensips-2.2.2 and started its service and check the logs i find out that signal 15 is coming again and again which is restarting opensips service repeatedly

Below are the gdb logs :- 

(gdb) c
Continuing.

Program received signal SIGTERM, Terminated.
0x0000003fc88e5ce3 in __epoll_wait_nocancel () from /usr/local/lib64/libc.so.6
(gdb) bt
#0  0x0000003fc88e5ce3 in __epoll_wait_nocancel () from /usr/local/lib64/libc.so.6
#1  0x00000000005380f0 in io_wait_loop_epoll (h=0x83d020, t=1, repeat=0) at net/../io_wait_loop.h:158
#2  0x000000000053a335 in udp_rcv_loop (si=0x7f4fec5936c0) at net/net_udp.c:308
#3  0x000000000053a8d3 in udp_start_processes (chd_rank=0x7ef668, startup_done=0x0) at net/net_udp.c:372
#4  0x0000000000435146 in main_loop () at main.c:671
#5  0x0000000000437c81 in main (argc=11, argv=0x7fff40b617f8) at main.c:1265
(gdb) up
#1  0x00000000005380f0 in io_wait_loop_epoll (h=0x83d020, t=1, repeat=0) at net/../io_wait_loop.h:158
158                     ret=n=epoll_wait(h->epfd, h->ep_array, h->fd_no, t*1000);
(gdb) up
#2  0x000000000053a335 in udp_rcv_loop (si=0x7f4fec5936c0) at net/net_udp.c:308
308             reactor_main_loop( UDP_SELECT_TIMEOUT, error , );
(gdb) up
#3  0x000000000053a8d3 in udp_start_processes (chd_rank=0x7ef668, startup_done=0x0) at net/net_udp.c:372
372                                             udp_rcv_loop( si );
(gdb) p si
$1 = (struct socket_info *) 0x7f4fec5936c0
(gdb) p *si
$2 = {socket = 8, name = {s = 0x7f4fec5937c8 "0.0.0.0", len = 7}, address = {af = 2, len = 4, u = {addrl = {0, 0},
      addr32 = {0, 0, 0, 0}, addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, addr = '\000' <repeats 15 times>}}, address_str = {
    s = 0x7f4fec5c2578 "0.0.0.0", len = 7}, port_no = 5060, port_no_str = {s = 0x7f4fec5c2558 "5060", len = 4},
  flags = SI_NONE, su = {s = {sa_family = 2, sa_data = "\023\304", '\000' <repeats 11 times>}, sin = {sin_family = 2,
      sin_port = 50195, sin_addr = {s_addr = 0}, sin_zero = "\000\000\000\000\000\000\000"}, sin6 = {sin6_family = 2,
      sin6_port = 50195, sin6_flowinfo = 0, sin6_addr = {__in6_u = {__u6_addr8 = '\000' <repeats 15 times>,
          __u6_addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, __u6_addr32 = {0, 0, 0, 0}}}, sin6_scope_id = 0}}, proto = 1,
  sock_str = {s = 0x7f4fec5c2598 "udp:0.0.0.0:50608", len = 16}, adv_sock_str = {s = 0x0, len = 0}, adv_name_str = {
    s = 0x0, len = 0}, adv_port_str = {s = 0x0, len = 0}, adv_address = {af = 0, len = 0, u = {addrl = {0, 0}, addr32 = {
        0, 0, 0, 0}, addr16 = {0, 0, 0, 0, 0, 0, 0, 0}, addr = '\000' <repeats 15 times>}}, adv_port = 0, children = 4,
  next = 0x0, prev = 0x0}
(gdb) up
neha31oct commented 7 years ago

logs m getting mentioned below: - INFO:core:sig_usr: signal 15 received

Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17303]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17305]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17295]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17302]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17304]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17296]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17301]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17297]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17300]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17299]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17298]: INFO:core:sig_usr: signal 15 received
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17293]: INFO:core:cleanup: cleanup
Mar 15 12:14:15 server_42 opensips: WARNING:core:parse_avp_spec: Deprecated AVP name format "s:ld" - use "ld" instead
Mar 15 12:14:15 server_42 opensips: INFO:core:fix_poll_method: using epoll_lt as the IO watch method (auto detected)
Mar 15 12:14:15 server_42 /usr/local/sbin/opensips[17387]: NOTICE:core:main: version: opensips 2.2.2 (x86_64/linux
razvancrainea commented 7 years ago

This is not a crash, it's just somebody tries to kill it for whatever reason. Are you sure you are not manually killing it, either over opensipctl or CTR-C if it runs in foreground? Can you increase the log_level to get more info?

neha31oct commented 7 years ago

I am not killing this manually . Increased log level logs mentioned below:-

Mar 15 15:29:44 server /usr/local/sbin/opensips[28580]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:core:bitmask_to_flag_list: bitmask -> 0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_do_prepared_query: conn=0x7f6f2f2a42f0 (tail=140115509443360) MC=0x7f6f2f2afb98
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_do_prepared_query: new query=|update location set expires=?,q=?,cseq=?,flags=?,cflags=?,user_agent=?,received=?,path=?,socket=?,methods=?,last_modified=?,attr=?,callid=? where contact_id=?|
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:re_init_statement:  query  is <update location set expires=?,q=?,cseq=?,flags=?,cflags=?,user_agent=?,received=?,path=?,socket=?,methods=?,last_modified=?,attr=?,callid=? where contact_id=?>, ptr=(nil)
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_do_prepared_query: new statement(0x7f6f2f2bba90) on connection: (0x7f6f2f2a42f0) 0x7f6f2f2afb20
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_do_prepared_query: set values for the statement run
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (0): len=40; type=12; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (1): len=8; type=5; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (2): len=4; type=3; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (3): len=4; type=3; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (4): len=0; type=254; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (5): len=16; type=254; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (8): len=20; type=254; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (10): len=40; type=12; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (12): len=44; type=254; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_val2bind: added val (13): len=8; type=8; is_null=0
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:db_mysql_do_prepared_query: doing BIND_PARAM in...
Mar 15 15:29:47 server /usr/local/sbin/opensips[28580]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:29:55 server /usr/local/sbin/opensips[28579]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:30:05 server /usr/local/sbin/opensips[28580]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:30:15 server /usr/local/sbin/opensips[28581]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:handle_sigs: SIGTERM received, program terminates
Mar 15 15:30:20 server /usr/local/sbin/opensips[28575]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28585]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28579]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28581]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28576]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28583]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28578]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28586]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28577]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28582]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28580]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28584]: INFO:core:sig_usr: signal 15 received
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: INFO:core:cleanup: cleanup
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:pool_remove: connection still kept in the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:pool_remove: connection still kept in the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:pool_remove: connection still kept in the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:pool_remove: connection still kept in the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:tm:tm_shutdown: tm_shutdown : start
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:tm:unlink_timer_lists: emptying DELETE list for set 0
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:tm:tm_shutdown: emptying hash table
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:tm:tm_shutdown: releasing timers
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:tm:tm_shutdown: removing semaphores
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:tm:tm_shutdown: destroying callback lists
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:tm:tm_shutdown: tm_shutdown : done
Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:shm_mem_destroy: destroying the shared memory lock
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/db_mysql.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/signaling.so
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 0 dependency signaling - (module tm)
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 0 dependency signaling - (module sl)
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/sl.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/tm.so
Mar 15 15:30:20 server opensips: DBG:core:register_module: register_pv: tm
Mar 15 15:30:20 server opensips: DBG:core:pv_add_extra: extra items list is not initialized
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/rr.so
Mar 15 15:30:20 server opensips: DBG:core:register_module: register_pv: rr
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/maxfwd.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/sipmsgops.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/usrloc.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/registrar.so
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency registrar - (module usrloc)
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency registrar - (module signaling)
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 0 dependency registrar - (module tm)
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/textops.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/dialog.so
Mar 15 15:30:20 server opensips: DBG:core:register_module: register_pv: dialog
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency dialog - (module tm)
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency dialog - (module rr)
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/mi_datagram.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/uri.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/acc.so
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency acc - (module tm)
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/auth.so
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency auth - (module signaling)
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/auth_db.so
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency auth_db - (module auth)
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency auth_db - (sqldb module (null))
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/alias_db.so
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency alias_db - (sqldb module (null))
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/avpops.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/load_balancer.so
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency load_balancer - (module dialog)
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency load_balancer - (sqldb module (null))
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/domain.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/perl.so
Mar 15 15:30:20 server opensips: DBG:core:sr_load_module: reloading module /usr/local/lib64/opensips/modules/perl.so with flags 258
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency perl - (module signaling)
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/nathelper.so
Mar 15 15:30:20 server opensips: DBG:core:load_module: loading module /usr/local/lib64/opensips/modules/siptrace.so
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: tm matches module tm
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <fr_timeout> in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: tm matches module tm
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <fr_inv_timeout> in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: mi_datagram matches module mi_datagram
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <socket_name> in module mi_datagram [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: mi_datagram matches module mi_datagram
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <unix_socket_mode> in module mi_datagram [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: dialog matches module dialog
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_mode> in module dialog [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: adding modparam dependencies:
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: dependency found: dialog ---> ( sqldb module (null) )
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency dialog - (sqldb module (null))
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: dialog matches module dialog
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_url> in module dialog [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: siptrace matches module siptrace
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <trace_on> in module siptrace [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: siptrace matches module siptrace
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <trace_id> in module siptrace [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server opensips: DBG:core:db_do_init: connection 0x7f9ac87f2b10 not found in pool
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:20 server opensips: DBG:core:db_do_init: connection 0x7f9ac87f2b10 inserted in pool as 0x7f9ac87f2cb8
Mar 15 15:30:20 server opensips: DBG:db_mysql:mysql_raise_event: event not registered -1
Mar 15 15:30:20 server opensips: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac87f32f8
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:20 server opensips: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac87f3340
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac87f3348)[0]=[table_version]
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server opensips: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac87f3378
Mar 15 15:30:20 server opensips: DBG:db_mysql:db_mysql_str2val: converting INT [5]
Mar 15 15:30:20 server opensips: DBG:core:db_free_columns: freeing result columns at 0x7f9ac87f3340
Mar 15 15:30:20 server opensips: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:20 server opensips: DBG:core:db_free_row: freeing row values at 0x7f9ac87f3388
Mar 15 15:30:20 server opensips: DBG:core:db_free_rows: freeing rows at 0x7f9ac87f3378
Mar 15 15:30:20 server opensips: DBG:core:db_free_result: freeing result set at 0x7f9ac87f32f8
Mar 15 15:30:20 server opensips: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: adding modparam dependencies:
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: dependency found: siptrace ---> ( sqldb module (null) )
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency siptrace - (sqldb module (null))
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: rr matches module rr
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <enable_double_rr> in module rr [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: rr matches module rr
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <append_fromtag> in module rr [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: registrar matches module registrar
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <max_contacts> in module registrar [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: registrar matches module registrar
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <max_expires> in module registrar [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: usrloc matches module usrloc
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_mode> in module usrloc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: usrloc matches module usrloc
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_mode> in module usrloc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: adding modparam dependencies:
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: dependency found: usrloc ---> ( sqldb module (null) )
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency usrloc - (sqldb module (null))
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: usrloc matches module usrloc
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_url> in module usrloc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: uri matches module uri
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <use_uri_table> in module uri [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: acc matches module acc
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <early_media> in module acc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: acc matches module acc
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <report_cancels> in module acc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: acc matches module acc
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <detect_direction> in module acc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: auth_db matches module auth_db
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <calculate_ha1> in module auth_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: auth_db matches module auth_db
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <password_column> in module auth_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: auth_db matches module auth_db
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_url> in module auth_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: auth_db matches module auth_db
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <load_credentials> in module auth_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: alias_db matches module alias_db
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_url> in module alias_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: domain matches module domain
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_url> in module domain [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: domain matches module domain
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_mode> in module domain [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: avpops matches module avpops
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_url> in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: adding modparam dependencies:
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: dependency found: avpops ---> ( sqldb module (null) )
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 1 dependency avpops - (sqldb module (null))
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: avpops matches module avpops
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <avp_table> in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: load_balancer matches module load_balancer
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_url> in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: load_balancer matches module load_balancer
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <db_table> in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: load_balancer matches module load_balancer
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <probing_interval> in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: adding modparam dependencies:
Mar 15 15:30:20 server opensips: DBG:core:add_modparam_dependencies: dependency found: load_balancer ---> ( module tm )
Mar 15 15:30:20 server opensips: DBG:core:add_module_dependency: adding type 2 dependency load_balancer - (module tm)
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: load_balancer matches module load_balancer
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <probing_method> in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: load_balancer matches module load_balancer
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <probing_reply_codes> in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: usrloc matches module usrloc
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <nat_bflag> in module usrloc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: nathelper matches module nathelper
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <sipping_bflag> in module nathelper [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: nathelper matches module nathelper
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <ping_nated_only> in module nathelper [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: nathelper matches module nathelper
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <received_avp> in module nathelper [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: perl matches module perl
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <modpath> in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: perl matches module perl
Mar 15 15:30:20 server opensips: DBG:core:set_mod_param_regex: found <filename> in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [priority] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias priority with id 1
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [g_id] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias g_id with id 2
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [source_ip] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias source_ip with id 3
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [did] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias did with id 4
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [c_id] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias c_id with id 5
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <mf_process_maxfwd_header>(1) in module maxfwd [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <has_totag>(0) in module uri [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <loose_route>(0) in module rr [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <record_route>(0) in module rr [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_from_local>(0) in module domain [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <proxy_authorize>(2) in module auth_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <proxy_challenge>(2) in module auth [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <db_check_from>(0) in module uri [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <consume_credentials>(0) in module auth [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <loose_route>(0) in module rr [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <append_hf>(2) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <append_hf>(2) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <append_hf>(2) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [ret] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias ret with id 6
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [ret] avp  - found 6
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [t_ip] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias t_ip with id 7
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [ret] avp  - found 6
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [ret1] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias ret1 with id 8
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [ret1] avp  - found 8
Mar 15 15:30:20 server opensips: WARNING:core:parse_avp_spec: Deprecated AVP name format "s:ld" - use "ld" instead
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [s:ld] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias s:ld with id 9
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <append_hf>(2) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [a_live] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias a_live with id 10
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [a_live] avp  - found 10
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <perl_exec>(1) in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [t_ip] avp  - found 7
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [res] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias res with id 11
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [did] avp  - found 4
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [c_id] avp  - found 5
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <append_hf>(2) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [cloud] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias cloud with id 12
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [res] avp  - found 11
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [res] avp  - found 11
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <perl_exec>(1) in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [cloud] avp  - found 12
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [t_ip] avp  - found 7
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [res] avp  - found 11
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <lookup>(1) in module registrar [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_uri_host_local>(0) in module domain [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_from_local>(0) in module domain [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <is_method>(1) in module sipmsgops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <www_authorize>(2) in module auth_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <www_challenge>(2) in module auth [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <db_check_to>(0) in module uri [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <save>(1) in module registrar [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_reply_error>(0) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <alias_db_lookup>(1) in module alias_db [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <lookup>(2) in module registrar [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_newtran>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_reply>(2) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <sl_reply_error>(0) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_was_cancelled>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_check_status>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <load_balance>(2) in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <perl_exec>(1) in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [r_ip] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias r_ip with id 13
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [r_ip] avp  - found 13
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [a_ret] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias a_ret with id 14
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <load_balance>(2) in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [a_ret] avp  - found 14
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [p_ip] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias p_ip with id 15
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_was_cancelled>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_check_status>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <avp_db_query>(2) in module avpops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [p_ip] avp  - found 15
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_was_cancelled>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_check_status>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <perl_exec>(1) in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [t_ip] avp  - found 7
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <subst>(1) in module textops [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <perl_exec>(1) in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [g_ip] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias g_ip with id 16
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [t_ret] avp  - found -1
Mar 15 15:30:20 server opensips: DBG:core:new_avp_alias: added alias t_ret with id 17
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <load_balance>(2) in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:__search_avp_map: looking for [t_ret] avp  - found 17
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_was_cancelled>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_check_status>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <load_balance>(2) in module load_balancer [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <send_reply>(2) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:find_cmd_export_t: found <perl_exec>(1) in module perl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency load_balancer -> module tm
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency avpops -> sqldb module
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency usrloc -> sqldb module
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency siptrace -> sqldb module
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency dialog -> sqldb module
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency perl -> module signaling
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency load_balancer -> sqldb module
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency load_balancer -> module dialog
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency alias_db -> sqldb module
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency auth_db -> sqldb module
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency auth_db -> module auth
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency auth -> module signaling
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency acc -> module tm
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency dialog -> module rr
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency dialog -> module tm
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency registrar -> module tm
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency registrar -> module signaling
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency registrar -> module usrloc
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency signaling -> module sl
Mar 15 15:30:20 server opensips: DBG:core:solve_module_dependencies: solving dependency signaling -> module tm
Mar 15 15:30:20 server opensips: INFO:core:fix_poll_method: using epoll_lt as the IO watch method (auto detected)
Mar 15 15:30:20 server opensips: DBG:core:main: Loaded 1 transport protocols
Mar 15 15:30:20 server opensips: DBG:core:fix_socket_list: listening on
Mar 15 15:30:20 server opensips: DBG:core:socket2str: <udp:192.16.2.28:5060>
Mar 15 15:30:20 server opensips: DBG:core:socket2str: <udp:10.1.1.28:5060>
Mar 15 15:30:20 server opensips: DBG:core:create_status_pipe: pipe created ? rc = 0, errno = Success
Mar 15 15:30:20 server opensips: DBG:core:daemonize: waiting for status code from children
Mar 15 15:30:20 server opensips: DBG:core:count_init_children: 11 children are going to be inited
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:set_core_dump: core dump limits set to 18446744073709551615
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: NOTICE:core:main: version: opensips 2.2.2 (x86_64/linux)
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:main: using 32 Mb shared memory
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:main: using 2 Mb private memory per process
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_reactor_size: using reactor size 1024
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [serial_branch] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias serial_branch with id 18
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_CORE_THRESHOLD(0)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_CORE_SHM_THRESHOLD(1)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_CORE_PKG_THRESHOLD(2)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_mod_init: mysql: MySQL client version is 5.1.52
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_MYSQL_CONNECTION(3)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module tm
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:tm:mod_init: TM - initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:get_flag_id_by_name: Flag name is null!
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:tm:init_callid: Call-ID initialization: '728d33254202ca56'
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:tm:lock_initialize: lock initialization started
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:tm:tm_init_timers: creating 0 parallel timer structures
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:tm:mod_init: timer set shift is 3
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:MD5StringArray: MD5 calculated: 7dfe5e26e906647c0cbddf8266a7bfa2
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:MD5StringArray: MD5 calculated: 634c347852aae54cab467e8552221c31
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:register_pv_context: Registered new context: request / 0x7f9ac5af2f88
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:register_pv_context: Context not found
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:register_pv_context: Registered new context: reply / 0x7f9ac5af2e2b
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:register_pv_context: Context not found
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:add_pv_context: Registered new context: reply
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [uac_ctx] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias uac_ctx with id 19
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: registering stats for tm
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for tm
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module sl
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:sl:mod_init: Initializing StateLess engine
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:MD5StringArray: MD5 calculated: 59d9cb8a0c4ea292637b899612757b45
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: registering stats for sl
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module signaling
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: NOTICE:signaling:mod_init: initializing module ...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_sl>(0) in module sl [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module rr
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:rr:mod_init: rr - initializing
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module maxfwd
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:maxfwd:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module sipmsgops
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:sipmsgops:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module usrloc
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:usrloc:mod_init: initializing
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:usrloc:ul_init_locks: locks array size 512
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: WARNING:usrloc:mod_init: Integer flags are now deprecated! Use unique quoted strings!
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:get_flag_id_by_name: New flag: [ 6 : 0 ][1]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_UL_AOR_INSERT(4)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_UL_AOR_DELETE(5)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_UL_CONTACT_INSERT(6)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_UL_CONTACT_DELETE(7)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_UL_CONTACT_UPDATE(8)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: registering stats for usrloc
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for usrloc
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module registrar
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:registrar:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_sig>(1) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <ul_bind_usrloc>(1) in module usrloc [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:get_flag_id_by_name: Flag name is null!
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: registering stats for registrar
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module textops
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:textops:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module dialog
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:dialog:mod_init: Dialog module - initializing
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_DLG_STATE_CHANGED(9)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_rr>(0) in module rr [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac8820818 not found in pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac8820818 inserted in pool as 0x7f9ac88209c0
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8821000
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac8821048
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821050)[0]=[table_version]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac8821080
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_str2val: converting INT [10]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac8821048
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_row: freeing row values at 0x7f9ac8821090
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing rows at 0x7f9ac8821080
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac8821000
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8821000
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 26 columns returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 728 bytes for result columns at 0x7f9ac88210c8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821198)[0]=[dlg_id]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_BIGINT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88211a8)[1]=[callid]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88211b8)[2]=[from_uri]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88211c8)[3]=[from_tag]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88211d8)[4]=[to_uri]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88211e8)[5]=[to_tag]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88211f8)[6]=[start_time]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821208)[7]=[state]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821218)[8]=[timeout]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821228)[9]=[caller_cseq]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821238)[10]=[callee_cseq]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821248)[11]=[caller_route_set]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821258)[12]=[callee_route_set]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821268)[13]=[caller_contact]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821278)[14]=[callee_contact]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821288)[15]=[caller_sock]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821298)[16]=[callee_sock]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88212a8)[17]=[vars]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88212b8)[18]=[profiles]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_BLOB result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88212c8)[19]=[script_flags]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88212d8)[20]=[caller_ping_cseq]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88212e8)[21]=[callee_ping_cseq]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88212f8)[22]=[flags]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821308)[23]=[mangled_from_uri]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821318)[24]=[mangled_to_uri]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821328)[25]=[module_flags]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_fetch_result: no rows returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:dialog:load_dialog_info_from_db: loading information from database for 0 dialogs
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac88210c8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 0 rows
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac8821000
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: registering stats for dialog
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for dialog
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module mi_datagram
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:mi_datagram:mi_mod_init: testing socket existence...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:mi_datagram:mi_mod_init: the socket's name/address is /tmp/opensips.sock
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:mi_datagram:mi_mod_init: we have an UNIX socket
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module uri
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:uri:mod_init: initializing
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: registering stats for uri
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module acc
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:acc:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_ACC_EVENT(10)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_ACC_CDR(11)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_ACC_MISSED_EVENT(12)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module auth
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:auth:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_sig>(1) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [rpid] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias rpid with id 20
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module auth_db
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:auth_db:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <bind_auth>(0) in module auth [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_sig>(1) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module alias_db
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:alias_db:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module avpops
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:avpops:avpops_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:is_mod_func_used: function avp_db_query found to be used in script
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:is_mod_func_used: function avp_db_query found to be used in script
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module load_balancer
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:load_balancer:mod_init: Load-Balancer module - initializing
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_dlg>(0) in module dialog [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:load_balancer:init_lb_bls: Initialising lb blacklists
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac88212f8 not found in pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac88212f8 inserted in pool as 0x7f9ac8821450
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8821580
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac88215c8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88215d0)[0]=[table_version]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac8821600
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_str2val: converting INT [2]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac88215c8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_row: freeing row values at 0x7f9ac8821610
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing rows at 0x7f9ac8821600
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac8821580
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8821580
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 5 columns returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 140 bytes for result columns at 0x7f9ac8821648
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821670)[0]=[id]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821680)[1]=[group_id]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8821690)[2]=[dst_uri]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88216a0)[3]=[resources]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac88216b0)[4]=[probe_mode]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_convert_rows: no rows returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: WARNING:load_balancer:lb_db_load_data: table "load_balancer" empty
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:load_balancer:populate_lb_bls: Updating lb blacklists...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [lb_grp] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias lb_grp with id 21
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [lb_flg] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias lb_flg with id 22
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [lb_mask] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias lb_mask with id 23
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [lb_id] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias lb_id with id 24
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [lb_res] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias lb_res with id 25
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:evi_publish_event: Registered event <E_LOAD_BALANCER_STATUS(13)>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for load_balancer
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module domain
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:domain:mod_init: Initializing
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_mod_export: found <db_bind_api> in module db_mysql [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_bind_mod: using db bind api for db_mysql
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac881eca0 not found in pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac881eca0 inserted in pool as 0x7f9ac881ee20
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac881ef50
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac881ef98
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac881efa0)[0]=[table_version]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac881efd0
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_str2val: converting INT [3]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac881ef98
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_row: freeing row values at 0x7f9ac881efe0
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing rows at 0x7f9ac881efd0
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac881ef50
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac881ef50
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 2 columns returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 56 bytes for result columns at 0x7f9ac881f018
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac881f028)[0]=[domain]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac881f038)[1]=[attrs]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_convert_rows: no rows returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:domain:reload_domain_table: Number of rows in domain table: 0
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac881f018
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 0 rows
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac881ef50
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for domain
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module perl
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:perl:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:find_cmd_export_t: found <load_sig>(1) in module signaling [/usr/local/lib64/opensips/modules/]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:perl:parser_init: setting lib path: '/usr/local/lib64/opensips/perl/'
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:perl:parser_init: successfully loaded perl file "/usr/local/etc/opensips/manualrouting.pl"
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for perl
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module nathelper
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [received_nh] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_alias: added alias received_nh with id 26
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for nathelper
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module siptrace
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:siptrace:mod_init: initializing...
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: registering stats for siptrace
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: register MI for siptrace
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod: initializing module proto_udp
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: INFO:core:mod_init: initializing UDP-plain protocol
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:count_module_procs: modules require 1 extra processes
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:init_pkg_stats: setting stats for 13 processes
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing mf_process_maxfwd_header, /usr/local/etc/opensips/opensips.cfg:151
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:152
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing has_totag, /usr/local/etc/opensips/opensips.cfg:156
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing loose_route, /usr/local/etc/opensips/opensips.cfg:159
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:160
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [BYE/8]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:get_flag_id_by_name: New flag: [ 1 : 0 ][0]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:get_flag_id_by_name: New flag: [ 3 : 1 ][0]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:164
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [INVITE/1]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:168
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x4
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [ACK/4]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_check_trans, /usr/local/etc/opensips/opensips.cfg:169
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_relay, /usr/local/etc/opensips/opensips.cfg:170
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:176
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing record_route, /usr/local/etc/opensips/opensips.cfg:180
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:183
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x2
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [CANCEL/2]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_check_trans, /usr/local/etc/opensips/opensips.cfg:185
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_relay, /usr/local/etc/opensips/opensips.cfg:186
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_check_trans, /usr/local/etc/opensips/opensips.cfg:189
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:190
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x80
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [REGISTER/128]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_from_local, /usr/local/etc/opensips/opensips.cfg:190
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing proxy_authorize, /usr/local/etc/opensips/opensips.cfg:191
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac87f0768 not found in pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac87f0768 inserted in pool as 0x7f9ac87f2cb8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac87f0dc8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac87f0d78
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac87f0d80)[0]=[table_version]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac87f10b8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_str2val: converting INT [7]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac87f0d78
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_row: freeing row values at 0x7f9ac87f10c8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing rows at 0x7f9ac87f10b8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac87f0dc8
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing proxy_challenge, /usr/local/etc/opensips/opensips.cfg:192
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing db_check_from, /usr/local/etc/opensips/opensips.cfg:195
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:196
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing consume_credentials, /usr/local/etc/opensips/opensips.cfg:199
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing loose_route, /usr/local/etc/opensips/opensips.cfg:203
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:205
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x4
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [ACK/4]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:206
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:210
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x180
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for methods [REGISTER,MESSAGE/384]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:213
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x20
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [OPTIONS/32]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:216
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [INVITE/1]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing append_hf, /usr/local/etc/opensips/opensips.cfg:220
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:hname_fixup: using hdr type (27) instead of <User-Agent>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing append_hf, /usr/local/etc/opensips/opensips.cfg:232
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:hname_fixup: using hdr type (27) instead of <User-Agent>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:238
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:244
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:get_flag_id_by_name: New flag: [ 5 : 2 ][0]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:get_flag_id_by_name: New flag: [ 7 : 3 ][0]
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing append_hf, /usr/local/etc/opensips/opensips.cfg:252
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:hname_fixup: using hdr type (27) instead of <User-Agent>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:255
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [t_ip] avp  - found 7
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [t_ip] avp  - found 7
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:259
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:265
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: WARNING:core:parse_avp_spec: Deprecated AVP name format "s:ld" - use "ld" instead
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [s:ld] avp  - found 9
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_id] avp  - found 2
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:275
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:280
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing append_hf, /usr/local/etc/opensips/opensips.cfg:286
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:hname_fixup: using hdr type (27) instead of <User-Agent>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:288
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [node_id] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [node_id] avp in shm - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:new_avp_extra_alias: added extra alias node_id with id 27
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing perl_exec, /usr/local/etc/opensips/opensips.cfg:290
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [t_ip] avp  - found 7
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:296
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:302
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [res] avp  - found 11
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:304
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_id] avp  - found 2
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:308
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing append_hf, /usr/local/etc/opensips/opensips.cfg:322
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:hname_fixup: using hdr type (27) instead of <User-Agent>
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:325
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [cloud] avp  - found 12
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [node_id] avp  - found -1
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [node_id] avp in shm - found 27
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing perl_exec, /usr/local/etc/opensips/opensips.cfg:327
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [cloud] avp  - found 12
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [cloud] avp  - found 12
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:334
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:338
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:343
Mar 15 15:30:20 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing lookup, /usr/local/etc/opensips/opensips.cfg:346
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac8826130 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac8826130 inserted in pool as 0x7f9ac88261c0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8826800
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac8826848
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8826850)[0]=[table_version]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac8826880
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_str2val: converting INT [1011]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac8826848
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_row: freeing row values at 0x7f9ac8826890
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing rows at 0x7f9ac8826880
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac8826800
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8826800
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac8826848
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8826850)[0]=[username]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_convert_rows: no rows returned from the query
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac8826848
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 0 rows
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac8826800
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_uri_host_local, /usr/local/etc/opensips/opensips.cfg:353
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_from_local, /usr/local/etc/opensips/opensips.cfg:355
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:363
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x2000
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [PUBLISH/8192]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:365
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:368
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x200
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [SUBSCRIBE/512]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:370
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing is_method, /usr/local/etc/opensips/opensips.cfg:373
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:parse_methods: methods 0x80
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:sipmsgops:fixup_method: using id for method [REGISTER/128]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing www_authorize, /usr/local/etc/opensips/opensips.cfg:376
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac8826130 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac8826130 inserted in pool as 0x7f9ac88268c8
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8826f08
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac88261f8
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8826200)[0]=[table_version]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac8826f50
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_str2val: converting INT [7]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac88261f8
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_row: freeing row values at 0x7f9ac8826f60
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_rows: freeing rows at 0x7f9ac8826f50
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_free_result: freeing result set at 0x7f9ac8826f08
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:pool_remove: removing connection from the pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing www_challenge, /usr/local/etc/opensips/opensips.cfg:379
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing db_check_to, /usr/local/etc/opensips/opensips.cfg:382
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:384
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing save, /usr/local/etc/opensips/opensips.cfg:387
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_reply_error, /usr/local/etc/opensips/opensips.cfg:388
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:397
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing alias_db_lookup, /usr/local/etc/opensips/opensips.cfg:402
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing lookup, /usr/local/etc/opensips/opensips.cfg:404
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_newtran, /usr/local/etc/opensips/opensips.cfg:408
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_reply, /usr/local/etc/opensips/opensips.cfg:409
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_send_reply, /usr/local/etc/opensips/opensips.cfg:412
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_relay, /usr/local/etc/opensips/opensips.cfg:423
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing sl_reply_error, /usr/local/etc/opensips/opensips.cfg:424
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing load_balance, /usr/local/etc/opensips/opensips.cfg:554
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:load_balancer:parse_resources_list: discovered 1 resources
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:557
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:560
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:561
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing perl_exec, /usr/local/etc/opensips/opensips.cfg:453
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [r_ip] avp  - found 13
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:458
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:462
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:463
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_id] avp  - found 2
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing load_balance, /usr/local/etc/opensips/opensips.cfg:471
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_id] avp  - found 2
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:load_balancer:parse_resources_list: discovered 1 resources
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:474
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:477
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:478
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:487
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_id] avp  - found 2
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [p_ip] avp  - found 15
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [p_ip] avp  - found 15
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:492
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:493
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing perl_exec, /usr/local/etc/opensips/opensips.cfg:585
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing perl_exec, /usr/local/etc/opensips/opensips.cfg:546
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_ip] avp  - found 16
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing subst, /usr/local/etc/opensips/opensips.cfg:541
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:textops:fixup_substre: textops module -- fixing /^Contact: <sip:([0-9]+)@(.*)$/Contact: <sip:$fU@$si:5060>\r/ig
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:subst_parser: ok, se is 0x7f9ac88281e0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_was_cancelled, /usr/local/etc/opensips/opensips.cfg:430
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_check_status, /usr/local/etc/opensips/opensips.cfg:433
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing load_balance, /usr/local/etc/opensips/opensips.cfg:436
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_id] avp  - found 2
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:load_balancer:parse_resources_list: discovered 1 resources
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:440
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:443
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:444
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_was_cancelled, /usr/local/etc/opensips/opensips.cfg:499
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_check_status, /usr/local/etc/opensips/opensips.cfg:502
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing avp_db_query, /usr/local/etc/opensips/opensips.cfg:504
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [g_id] avp  - found 2
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [priority] avp  - found 1
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [p_ip] avp  - found 15
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:__search_avp_map: looking for [p_ip] avp  - found 15
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:509
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:510
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_was_cancelled, /usr/local/etc/opensips/opensips.cfg:518
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_check_status, /usr/local/etc/opensips/opensips.cfg:521
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing perl_exec, /usr/local/etc/opensips/opensips.cfg:525
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:529
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:530
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_was_cancelled, /usr/local/etc/opensips/opensips.cfg:566
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_check_status, /usr/local/etc/opensips/opensips.cfg:569
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing load_balance, /usr/local/etc/opensips/opensips.cfg:571
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:load_balancer:parse_resources_list: discovered 1 resources
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing send_reply, /usr/local/etc/opensips/opensips.cfg:575
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_failure, /usr/local/etc/opensips/opensips.cfg:578
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:fix_actions: fixing t_on_reply, /usr/local/etc/opensips/opensips.cfg:579
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: getsockopt: rcv is initially 65536
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: trying : 131072
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: setting rcv: set=131072,verify=262144
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: trying : 262144
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: setting rcv: set=262144,verify=524288
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: INFO:core:probe_max_sock_buff: using rcv buffer of 512 kb
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: getsockopt: rcv is initially 65536
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: trying : 131072
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: setting rcv: set=131072,verify=262144
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: trying : 262144
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:probe_max_sock_buff: setting rcv: set=262144,verify=524288
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: INFO:core:probe_max_sock_buff: using rcv buffer of 512 kb
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:mi_datagram:mi_init_datagram_server: we have a unix socket: /tmp/opensips.sock
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:start_module_procs: forking process "MI Datagram"/0 for module mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "MI Datagram"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "time_keeper"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28675@192.16.2.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "timer"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28676]: DBG:core:run_timer_process_jif: tv = 0, 10000 , m=10, mu=10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28677]: DBG:core:run_timer_process: tv = 0, 100000 , m=10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28678@192.16.2.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=usrloc
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: INFO:mi_datagram:datagram_process: a new child 0/28675
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac882a928 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: NOTICE:usrloc:preload_udomain: load start time [1489572021]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac882aae8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28679@192.16.2.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28675]: DBG:core:db_do_init: connection 0x7f9ac882aca8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac882a160
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: 17 columns returned from the query
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_allocate_columns: allocate 476 bytes for result columns at 0x7f9ac882a1a8
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a230)[0]=[username]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a240)[1]=[contact_id]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_BIGINT result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a250)[2]=[contact]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a260)[3]=[expires]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_DATETIME result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a270)[4]=[q]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_DOUBLE result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a280)[5]=[callid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a290)[6]=[cseq]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a2a0)[7]=[flags]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a2b0)[8]=[cflags]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a2c0)[9]=[user_agent]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a2d0)[10]=[received]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a2e0)[11]=[path]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a2f0)[12]=[socket]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a300)[13]=[methods]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_INT result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a310)[14]=[last_modified]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_DATETIME result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a320)[15]=[sip_instance]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac882a330)[16]=[attr]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_fetch_result: converting row 0 of 1 count 1
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_allocate_rows: allocate 560 bytes for result rows and values at 0x7f9ac882a3a0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting STRING [2002]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting INT BIG[1892848849634996835]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting STRING [sip:2002@172.16.2.64:5060]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting DATETIME [2017-03-15 15:31:02]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting DOUBLE [-1.00]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting STRING [D4D5E3AAE557431EB7FD5D9576F22698@192.16.2.28]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting INT [57240]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting INT [0]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting STRING []
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting STRING [X-PRO build 1101]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting STRING [udp:192.16.2.28:5060]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:db_mysql:db_mysql_str2val: converting DATETIME [2017-03-15 15:29:22]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:usrloc:preload_udomain: loading records - cycle [1]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:usrloc:dbrow2info: flag str: ''
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:flag_list_to_bitmask: flag_list -> ''
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:usrloc:dbrow2info: set flags: 0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:evi_param_set: adding string param
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:destroy_avp_list: destroying list (nil)
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<172.16.2.64>(11)#012 port=<5060>(4): 5060#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<172.16.2.64>(11)#012 port=<5060>(4): 5060#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:evi_param_set: adding string param
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:evi_param_set: adding string param
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:evi_param_set: adding string param
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:evi_param_set: adding string param
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:evi_param_set: adding int param
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:destroy_avp_list: destroying list (nil)
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_free_row: freeing row values at 0x7f9ac882a3b0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:init_mod_child: type=CHILD, rank=2, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28683@10.1.1.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "UDP receiver"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28682@10.1.1.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28679]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (8 on 6) (0x83d020, 8, 17, 0x7f9ac881e878,1), fd_no=1/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_free_rows: freeing rows at 0x7f9ac882a3a0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:internal_fork: forking new process "Timer handler"
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac882a1a8
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_free_rows: freeing 0 rows
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_free_result: freeing result set at 0x7f9ac882a160
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: NOTICE:usrloc:preload_udomain: load end time [1489572021]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28684@10.1.1.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_do_init: connection 0x7f9ac882a240 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_do_init: connection 0x7f9ac882a320 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28674@192.16.2.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_do_init: connection 0x7f9ac882a5e8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_do_init: connection 0x7f9ac882a7a8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28685@10.1.1.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:init_mod_child: type=CHILD, rank=1, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:db_do_init: connection 0x7f9ac882a968 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28680@192.16.2.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28678]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (8 on 6) (0x83d020, 8, 17, 0x7f9ac881e878,1), fd_no=1/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:init_mod_child: type=CHILD, rank=5, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:init_mod_child: type=CHILD, rank=8, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28682]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (9 on 6) (0x83d020, 9, 17, 0x7f9ac881e9a8,1), fd_no=1/1024
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28681@192.16.2.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:init_mod_child: type=CHILD, rank=6, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28685]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (9 on 6) (0x83d020, 9, 17, 0x7f9ac881e9a8,1), fd_no=1/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:init_mod_child: type=CHILD, rank=3, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=tm
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=alias_db
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28683]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (9 on 6) (0x83d020, 9, 17, 0x7f9ac881e9a8,1), fd_no=1/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:tm:child_init_callid: callid: '728d33254202ca56-28686@192.16.2.28'
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=dialog
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=domain
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:init_mod_child: type=CHILD, rank=7, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=usrloc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28674]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 not found in pool
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:db_mysql:db_mysql_connect: opening connection: mysql://xxxx:xxxx@localhost/czentrix_campaign_manager
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28684]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (9 on 6) (0x83d020, 9, 17, 0x7f9ac881e9a8,1), fd_no=1/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:init_mod_child: type=CHILD, rank=4, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:db_mysql:db_mysql_connect: connection type is Localhost via UNIX socket
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:db_mysql:db_mysql_connect: protocol version is 10
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:db_mysql:db_mysql_connect: server version is 5.1.52-log
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:db_do_init: connection 0x7f9ac881e0e0 inserted in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=registrar
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=dialog
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:db_do_init: connection 0x7f9ac882a1f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=mi_datagram
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=uri
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=acc
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=auth_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28681]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (8 on 6) (0x83d020, 8, 17, 0x7f9ac881e878,1), fd_no=1/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:db_do_init: connection 0x7f9ac882a3b8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=alias_db
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:db_do_init: connection 0x7f9ac882a578 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28680]: DBG:core:io_watch_add: [UDP_worker] io_watch_add op (8 on 6) (0x83d020, 8, 17, 0x7f9ac881e878,1), fd_no=1/1024
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=avpops
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:db_do_init: connection 0x7f9ac882a738 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=load_balancer
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=domain
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=perl
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:init_mod_child: type=CHILD, rank=9, module=siptrace
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:siptrace:child_init: Initializing trace id [tid]
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:db_do_init: connection 0x7f9ac882a8f8 found in pool as 0x7f9ac8829b20
Mar 15 15:30:21 server opensips: DBG:core:wait_status_code: read code 0 ? rc = 1, errno=Success
Mar 15 15:30:21 server opensips: INFO:core:daemonize: pre-daemon process exiting with 0
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:send_status_code: send 0 ? rc = 1 , errno=Success
Mar 15 15:30:21 server /usr/local/sbin/opensips[28686]: DBG:core:io_watch_add: [Timer_extra] io_watch_add op (4 on 6) (0x83d020, 4, 1, (nil),1), fd_no=0/1024
Mar 15 15:30:25 server /usr/local/sbin/opensips[28679]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:30:35 server /usr/local/sbin/opensips[28680]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:30:46 server /usr/local/sbin/opensips[28680]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:30:56 server /usr/local/sbin/opensips[28680]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg: SIP Request:
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  method:  <REGISTER>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  uri:     <sip:192.16.2.28>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  version: <SIP/2.0>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=2
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=6
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKCB208239456344D48E8EEDE9D52D92D3>; state=16
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_via: end of header reached, state=5
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: via found, flags=2
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: this is the first via
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/UDP 172.16.2.64:5060;rport;branch=z9hG4bKCB208239456344D48E8EEDE9D52D92D3>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  first  via: <SIP/2.0/UDP> <172.16.2.64:5060(5060)>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg: ;<rport;branch=z9hG4bKCB208239456344D48E8EEDE9D52D92D3>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg: exiting
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:receive_msg: After parse_msg...
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:receive_msg: preparing to run routing scripts...
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=100
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 4, name=<From>, body=<2002 <sip:2002@192.16.2.28>>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: end of header reached, state=10
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: display={2002}, ruri={sip:2002@192.16.2.28}
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: <To> [29]; uri=[sip:2002@192.16.2.28]
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: to body [2002 <sip:2002@192.16.2.28>#015#012]
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 3, name=<To>, body=<2002 <sip:2002@192.16.2.28>>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 7, name=<Contact>, body=<"2002" <sip:2002@172.16.2.64:5060>>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 6, name=<Call-ID>, body=<D4D5E3AAE557431EB7FD5D9576F22698@192.16.2.28>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: cseq <CSeq>: <57241> <REGISTER>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 5, name=<CSeq>, body=<57241 REGISTER>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 15, name=<Expires>, body=<100>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 8, name=<Max-Forwards>, body=<70>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:maxfwd:is_maxfwd_present: value = 70
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:buf_init: initializing...
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<>(0)#012 passwd=<>(0)#012 host=<192.16.2.28>(11)#012 port=<>(0): 0#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 27, name=<User-Agent>, body=<X-PRO build 1101>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: content_length=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 13, name=<Content-Length>, body=<0>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: found end of header
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: end of header reached, state=10
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: display={2002}, ruri={sip:2002@192.16.2.28}
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<192.16.2.28>(11)#012 port=<>(0): 0#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:pv_printf: final buffer length 213
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: CZENTRIX PACKET RECEIVED time [Wed Mar 15 15:31:02 2017] method (REGISTER) r-uri (<null> 192.16.2.28) 2nd via (<null>)src_user=2002 src_domain=192.16.2.28 dst_user=<null> dst_domain=192.16.2.28 src_ip=172.16.2.64
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:uri:has_totag: no totag
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=78
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:tm:t_lookup_request: start searching: hash=3804, isACK=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:tm:t_lookup_request: no transaction found
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=200
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:rr:find_first_route: No Route headers found
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:rr:loose_route: There is no Route HF
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:pv_printf: final buffer length 26
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: Register message recieved
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<192.16.2.28>(11)#012 port=<>(0): 0#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=4000
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:pre_auth: credentials with given realm not found
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:pv_printf: final buffer length 27
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: In autharize message block
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:reserve_nonce_index: second= 11, sec_monit= -1,  index= 0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:build_auth_hf: nonce index= 0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:build_auth_hf: 'WWW-Authenticate: Digest realm="192.16.2.28", nonce="58c910fc00000000b14501ade8cbbb41f312cb736bb57b26"#015#012'
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:destroy_avp_list: destroying list 0x7f9ac689a6a8
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:receive_msg: cleaning up
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg: SIP Request:
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  method:  <REGISTER>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  uri:     <sip:192.16.2.28>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  version: <SIP/2.0>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=2
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=6
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK92F69F292405401FB9DE561F03DC8940>; state=16
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_via: end of header reached, state=5
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: via found, flags=2
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: this is the first via
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 1, name=<Via>, body=<SIP/2.0/UDP 172.16.2.64:5060;rport;branch=z9hG4bK92F69F292405401FB9DE561F03DC8940>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:  first  via: <SIP/2.0/UDP> <172.16.2.64:5060(5060)>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg: ;<rport;branch=z9hG4bK92F69F292405401FB9DE561F03DC8940>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg:
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_msg: exiting
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:receive_msg: After parse_msg...
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:receive_msg: preparing to run routing scripts...
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=100
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 4, name=<From>, body=<2002 <sip:2002@192.16.2.28>>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: end of header reached, state=10
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: display={2002}, ruri={sip:2002@192.16.2.28}
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: <To> [29]; uri=[sip:2002@192.16.2.28]
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: to body [2002 <sip:2002@192.16.2.28>#015#012]
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 3, name=<To>, body=<2002 <sip:2002@192.16.2.28>>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 7, name=<Contact>, body=<"2002" <sip:2002@172.16.2.64:5060>>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 6, name=<Call-ID>, body=<D4D5E3AAE557431EB7FD5D9576F22698@192.16.2.28>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: cseq <CSeq>: <57242> <REGISTER>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 5, name=<CSeq>, body=<57242 REGISTER>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 15, name=<Expires>, body=<100>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 14, name=<Authorization>, body=<Digest username="2002",realm="192.16.2.28",nonce="58c910fc00000000b14501ade8cbbb41f312cb736bb57b26",response="13a6064fb80324110904dbb4094923a8",uri="sip:192.16.2.28">
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 8, name=<Max-Forwards>, body=<70>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:maxfwd:is_maxfwd_present: value = 70
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<>(0)#012 passwd=<>(0)#012 host=<192.16.2.28>(11)#012 port=<>(0): 0#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 27, name=<User-Agent>, body=<X-PRO build 1101>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: content_length=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: header field type 13, name=<Content-Length>, body=<0>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:get_hdr_field: found end of header
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: end of header reached, state=10
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_to: display={2002}, ruri={sip:2002@192.16.2.28}
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<192.16.2.28>(11)#012 port=<>(0): 0#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:pv_printf: final buffer length 213
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: CZENTRIX PACKET RECEIVED time [Wed Mar 15 15:31:02 2017] method (REGISTER) r-uri (<null> 192.16.2.28) 2nd via (<null>)src_user=2002 src_domain=192.16.2.28 dst_user=<null> dst_domain=192.16.2.28 src_ip=172.16.2.64
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:uri:has_totag: no totag
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=78
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:tm:t_lookup_request: start searching: hash=3801, isACK=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:tm:t_lookup_request: no transaction found
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=200
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:rr:find_first_route: No Route headers found
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:rr:loose_route: There is no Route HF
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:pv_printf: final buffer length 26
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: Register message recieved
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<192.16.2.28>(11)#012 port=<>(0): 0#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:check_nonce: comparing [58c910fc00000000b14501ade8cbbb41f312cb736bb57b26] and [58c910fc00000000b14501ade8cbbb41f312cb736bb57b26]
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_do_prepared_query: conn=0x7f9ac882a320 (tail=140302765693728) MC=0x7f9ac8829b98
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_do_prepared_query: new query=|select password from subscriber where username=?|
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:re_init_statement:  query  is <select password from subscriber where username=?>, ptr=(nil)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_do_prepared_query: new statement(0x7f9ac8838928) on connection: (0x7f9ac882a320) 0x7f9ac8829b20
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_do_prepared_query: set values for the statement run
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_val2bind: added val (0): len=4; type=254; is_null=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_do_prepared_query: doing BIND_PARAM in...
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_do_prepared_query: prepared statement has 1 columns in result
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_do_prepared_query: doing to BIND_PARAM out ...
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_new_result: allocate 48 bytes for result set at 0x7f9ac8838688
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_get_columns: 1 columns returned from the query
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_allocate_columns: allocate 28 bytes for result columns at 0x7f9ac8838ed0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_get_columns: RES_NAMES(0x7f9ac8838ed8)[0]=[password]
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_get_columns: use DB_STRING result type
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_allocate_rows: allocate 48 bytes for result rows and values at 0x7f9ac8838f08
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:db_mysql:db_mysql_str2val: converting STRING [2002]
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth_db:get_ha1: HA1 string calculated: c93333360d3bdc16486bcee0a1e40521
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:check_response: our result = '13a6064fb80324110904dbb4094923a8'
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:check_response: authorization is OK
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:auth:post_auth: nonce index= 0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_free_columns: freeing result columns at 0x7f9ac8838ed0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_free_rows: freeing 1 rows
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_free_row: freeing row values at 0x7f9ac8838f18
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_free_rows: freeing rows at 0x7f9ac8838f08
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:db_free_result: freeing result set at 0x7f9ac8838688
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:uri:check_username: Digest username and URI username match
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<192.16.2.28>(11)#012 port=<>(0): 0#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=8000000
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri: parsed uri:#012 type=1 user=<2002>(4)#012 passwd=<>(0)#012 host=<172.16.2.64>(11)#012 port=<5060>(4): 5060#012 params=<>(0)#012 headers=<>(0)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:  uri params:#012   transport=<>, val=<>, proto=0
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    user-param=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    method=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    ttl=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    maddr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    lr=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_uri:    r2=<>, val=<>
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:evi_param_set: adding string param
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:evi_param_set: adding string param
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:evi_param_set: adding string param
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:evi_param_set: adding string param
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:evi_param_set: adding int param
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:destroy_avp_list: destroying list (nil)
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:registrar:build_contact: created Contact HF: Contact: <sip:2002@172.16.2.64:5060>;expires=100#015#012
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:destroy_avp_list: destroying list 0x7f9ac689a6a8
Mar 15 15:31:02 server /usr/local/sbin/opensips[28680]: DBG:core:receive_msg: cleaning up
Mar 15 15:31:06 server /usr/local/sbin/opensips[28679]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:31:16 server /usr/local/sbin/opensips[28681]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:core:bitmask_to_flag_list: bitmask -> 0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_do_prepared_query: conn=0x7f9ac881e2f0 (tail=140302765693728) MC=0x7f9ac8829b98
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_do_prepared_query: new query=|update location set expires=?,q=?,cseq=?,flags=?,cflags=?,user_agent=?,received=?,path=?,socket=?,methods=?,last_modified=?,attr=?,callid=? where contact_id=?|
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:re_init_statement:  query  is <update location set expires=?,q=?,cseq=?,flags=?,cflags=?,user_agent=?,received=?,path=?,socket=?,methods=?,last_modified=?,attr=?,callid=? where contact_id=?>, ptr=(nil)
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_do_prepared_query: new statement(0x7f9ac8835a90) on connection: (0x7f9ac881e2f0) 0x7f9ac8829b20
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_do_prepared_query: set values for the statement run
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (0): len=40; type=12; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (1): len=8; type=5; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (2): len=4; type=3; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (3): len=4; type=3; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (4): len=0; type=254; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (5): len=16; type=254; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (8): len=20; type=254; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (10): len=40; type=12; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (12): len=44; type=254; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_val2bind: added val (13): len=8; type=8; is_null=0
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:db_mysql_do_prepared_query: doing BIND_PARAM in...
Mar 15 15:31:21 server /usr/local/sbin/opensips[28679]: DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 15:31:27 server /usr/local/sbin/opensips[28680]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:31:37 server /usr/local/sbin/opensips[28680]: DBG:core:udp_read_req: probing packet received len = 2
Mar 15 15:31:47 server /usr/local/sbin/opensips[28680]: DBG:core:udp_read_req: probing packet received len = 2
razvancrainea commented 7 years ago

In the logs you showed, it looks like the attendant is receiving SIGTERM. The attendant is literally doing nothing but waiting for signals, so it doesn't crash by itself or something. So it must be something from outside. How are you starting your opensips server?

neha31oct commented 7 years ago

/usr/local/sbin/opensips -u opensips -g opensips -P /var/run/opensips.pid -f /usr/local/etc/opensips/opensips.cfg -w /tmp/opensips_core

On Wed, Mar 15, 2017 at 3:49 PM, Răzvan Crainea notifications@github.com wrote:

In the logs you showed, it looks like the attendant is receiving SIGTERM. The attendant is literally doing nothing but waiting for signals, so it doesn't crash by itself or something. So it must be something from outside. How are you starting your opensips server?

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/OpenSIPS/opensips/issues/1075#issuecomment-286699623, or mute the thread https://github.com/notifications/unsubscribe-auth/AO58ANzDf9FtxY83kfGq4QvEov5geYYIks5rl7tEgaJpZM4Mdh8W .

bogdan-iancu commented 7 years ago

The relevant log is : Mar 15 15:30:20 server /usr/local/sbin/opensips[28574]: DBG:core:handle_sigs: SIGTERM received, program terminates

Some one or some app is sending a SIGTERM to your OpenSIPS. Maybe your init.d script ?

bogdan-iancu commented 7 years ago

btw, do you see any pattern for the restarts ? like time based, or traffic based ?

neha31oct commented 7 years ago

i think it is time based . opensips is restarting within every 1 minute 30 seconds around. there is no traffic at all now. i killed my soft phone.

On Tue, Mar 21, 2017 at 5:03 PM, Bogdan Andrei IANCU < notifications@github.com> wrote:

btw, do you see any pattern for the restarts ? like time based, or traffic based ?

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/OpenSIPS/opensips/issues/1075#issuecomment-288051781, or mute the thread https://github.com/notifications/unsubscribe-auth/AO58AN9v7l43Te1h13X9XYY0grGqvmxLks5rn7WigaJpZM4Mdh8W .

bogdan-iancu commented 7 years ago

Do you start opensips by hand, from shell, or via init.d or some other system ?

neha31oct commented 7 years ago

via init.d script

On Tue, Mar 21, 2017 at 7:36 PM, Bogdan Andrei IANCU < notifications@github.com> wrote:

Do you start opensips by hand, from shell, or via init.d or some other system ?

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/OpenSIPS/opensips/issues/1075#issuecomment-288088609, or mute the thread https://github.com/notifications/unsubscribe-auth/AO58AIM6uVgFy4gJKIeRqDzfh8quyN0Gks5rn9lygaJpZM4Mdh8W .

bogdan-iancu commented 7 years ago

I suspect there is something strange with your init.d.....Try to manually start OpenSIPS from shell:

/usr/local/sbin/opensips -u opensips -g opensips -P /var/run/opensips.pid -f /usr/local/etc/opensips/opensips.cfg -w /tmp/opensips_core

and see if it still stops in 1.5 mins.

bogdan-iancu commented 7 years ago

Any update here ?

neha31oct commented 7 years ago

It suddenly stop recieving signal 15. I am checking why it has stopped . i will update you whenever i find the problem

On Wed, Apr 5, 2017 at 2:11 AM, Bogdan Andrei IANCU < notifications@github.com> wrote:

Any update here ?

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/OpenSIPS/opensips/issues/1075#issuecomment-291625491, or mute the thread https://github.com/notifications/unsubscribe-auth/AO58AEJGQy0hkC-0z8sLyW0ok_lVSDPcks5rsqsEgaJpZM4Mdh8W .

bogdan-iancu commented 7 years ago

OK, let's have this ticket close for now as there is no issue related to OpenSIPS itself.