[root@localhost ~]# opensips start Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/signaling.so Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/sl.so Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/tm.so Aug 10 20:20:44 [5061] DBG:core:register_module: register_pv: tm Aug 10 20:20:44 [5061] DBG:core:pv_add_extra: extra items list is not initialized Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/rr.so Aug 10 20:20:44 [5061] DBG:core:register_module: register_pv: rr Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/maxfwd.so Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/usrloc.so Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/registrar.so Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/textops.so Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/mi_fifo.so Aug 10 20:20:44 [5061] DBG:core:yyparse: loading module //lib/opensips/modules/uri.so Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: mi_fifo matches module mi_fifo Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: found in module mi_fifo [//lib/opensips/modules/] Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: rr matches module rr Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: found in module rr [//lib/opensips/modules/] Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: rr matches module rr Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: found in module rr [//lib/opensips/modules/] Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: usrloc matches module usrloc Aug 10 20:20:44 [5061] DBG:core:set_mod_param_regex: found in module usrloc [//lib/opensips/modules/] Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found (1) in module maxfwd [//lib/opensips/modules/] Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found (2) in module sl [//lib/opensips/modules/] Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found (1) in module textops [//lib/opensips/modules/] Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found (2) in module sl [//lib/opensips/modules/] Aug 10 20:20:49 [5061] WARNING:core:fix_socket_list: could not rev. resolve 192.168.1.14 Aug 10 20:20:49 [5061] DBG:core:socket2str: Listening on udp: 192.168.1.14 [192.168.1.14]:5060 Aliases: Aug 10 20:20:49 [5061] WARNING:core:main: no fork mode Aug 10 20:20:49 [5061] DBG:core:fm_malloc_init: F_OPTIMIZE=16384, /ROUNDTO=2048 Aug 10 20:20:49 [5061] DBG:core:fm_malloc_init: F_HASH_SIZE=2067, fm_block size=16568 Aug 10 20:20:49 [5061] DBG:core:fm_malloc_init: params (0xb5f27000, 33554432), start=0xb5f27000 Aug 10 20:20:49 [5061] DBG:core:shm_mem_init_mallocs: success Aug 10 20:20:49 [5061] INFO:core:init_tcp: using epoll_lt as the TCP io watch method (auto detected) Aug 10 20:20:49 [5061] DBG:core:set_core_dump: core dump limits set to 4294967295 Aug 10 20:20:49 [5061] NOTICE:core:main: version: opensips 1.6.3-notls (i386/linux) Aug 10 20:20:49 [5061] INFO:core:main: using 32 Mb shared memory Aug 10 20:20:49 [5061] INFO:core:main: using 1 Mb private memory per process Aug 10 20:20:49 [5061] DBG:core:add_avp_galias: registering for avp id <16725044> Aug 10 20:20:49 [5061] DBG:core:init_stats_collector: statistics manager successfully initialized Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module signaling Aug 10 20:20:49 [5061] NOTICE:signaling:mod_init: initializing module ... Aug 10 20:20:49 [5061] DBG:core:find_cmd_export_t: found (0) in module tm [//lib/opensips/modules/] Aug 10 20:20:49 [5061] DBG:core:find_cmd_export_t: found (0) in module sl [//lib/opensips/modules/] Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module sl Aug 10 20:20:49 [5061] INFO:sl:mod_init: Initializing StateLess engine Aug 10 20:20:49 [5061] DBG:core:MD5StringArray: MD5 calculated: 685fba2b3b1ec9e06d7469b030ef2291 Aug 10 20:20:49 [5061] DBG:core:init_mod: registering stats for sl Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module tm Aug 10 20:20:49 [5061] INFO:tm:mod_init: TM - initializing... Aug 10 20:20:49 [5061] DBG:tm:init_callid: Call-ID initialization: '5f6d77df' Aug 10 20:20:49 [5061] DBG:tm:lock_initialize: lock initialization started Aug 10 20:20:49 [5061] DBG:core:MD5StringArray: MD5 calculated: 67c9d9861bfa0d9856b217ea1a481e88 Aug 10 20:20:49 [5061] DBG:core:MD5StringArray: MD5 calculated: f51d2ba14ed135f0a3e9a5c8ff20db38 Aug 10 20:20:49 [5061] DBG:core:register_pv_context: Registered new context: request / 0x8e4002 Aug 10 20:20:49 [5061] DBG:core:register_pv_context: Context not found Aug 10 20:20:49 [5061] DBG:core:register_pv_context: Registered new context: reply / 0x8e416f Aug 10 20:20:49 [5061] DBG:core:register_pv_context: Context not found Aug 10 20:20:49 [5061] DBG:core:add_pv_context: Registered new context: reply Aug 10 20:20:49 [5061] DBG:core:init_mod: registering stats for tm Aug 10 20:20:49 [5061] DBG:core:init_mod: register MI for tm Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module rr Aug 10 20:20:49 [5061] DBG:rr:mod_init: rr - initializing Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module maxfwd Aug 10 20:20:49 [5061] INFO:maxfwd:mod_init: initializing... Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module usrloc Aug 10 20:20:49 [5061] DBG:usrloc:mod_init: initializing Aug 10 20:20:49 [5061] INFO:usrloc:ul_init_locks: locks array size 512 Aug 10 20:20:49 [5061] DBG:core:init_mod: registering stats for usrloc Aug 10 20:20:49 [5061] DBG:core:init_mod: register MI for usrloc Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module registrar Aug 10 20:20:49 [5061] INFO:registrar:mod_init: initializing... Aug 10 20:20:49 [5061] DBG:core:find_cmd_export_t: found (1) in module signaling [//lib/opensips/modules/] Aug 10 20:20:49 [5061] DBG:core:find_cmd_export_t: found (1) in module usrloc [//lib/opensips/modules/] Aug 10 20:20:49 [5061] DBG:core:init_mod: registering stats for registrar Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module textops Aug 10 20:20:49 [5061] INFO:textops:mod_init: initializing... Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module mi_fifo Aug 10 20:20:49 [5061] DBG:mi_fifo:mi_mod_init: testing fifo existance ... Aug 10 20:20:49 [5061] DBG:core:init_mod: initializing module uri Aug 10 20:20:49 [5061] DBG:uri:mod_init: initializing Aug 10 20:20:49 [5061] DBG:core:init_mod: registering stats for uri Aug 10 20:20:49 [5061] DBG:core:count_module_procs: modules require 1 extra processes Aug 10 20:20:49 [5061] DBG:core:init_pkg_stats: setting stats for 4 processes Aug 10 20:20:49 [5061] DBG:core:fix_actions: fixing mf_process_maxfwd_header, line 193 Aug 10 20:20:49 [5061] DBG:core:fix_actions: fixing sl_send_reply, line 194 Aug 10 20:20:49 [5061] DBG:core:fix_actions: fixing is_method, line 198 Aug 10 20:20:49 [5061] DBG:core:parse_methods: methods 0x80 Aug 10 20:20:49 [5061] DBG:textops:fixup_method: using id for method [REGISTER/128] Aug 10 20:20:49 [5061] DBG:core:fix_actions: fixing sl_send_reply, line 201 Aug 10 20:20:49 [5061] DBG:core:probe_max_receive_buffer: getsockopt SO_RCVBUF is initially 110592 Aug 10 20:20:49 [5061] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 221184 Aug 10 20:20:49 [5061] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=221184,verify=221184 Aug 10 20:20:49 [5061] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 223232 Aug 10 20:20:49 [5061] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=223232,verify=221184 Aug 10 20:20:49 [5061] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF has no effect Aug 10 20:20:49 [5061] INFO:core:probe_max_receive_buffer: using a UDP receive buffer of 216 kb Aug 10 20:20:49 [5061] DBG:core:internal_fork: forking new process "time_keeper" Aug 10 20:20:49 [5062] DBG:core:run_timer_process_jif: tv = 0, 100000 , m=10 Aug 10 20:20:49 [5061] DBG:core:internal_fork: forking new process "timer" Aug 10 20:20:49 [5063] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=tm Aug 10 20:20:49 [5063] DBG:tm:child_init_callid: callid: '5f6d77df-5063@192.168.1.14' Aug 10 20:20:49 [5063] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=usrloc Aug 10 20:20:49 [5063] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=registrar Aug 10 20:20:49 [5061] DBG:core:start_module_procs: forking process "MI FIFO"/0 for module mi_fifo Aug 10 20:20:49 [5061] DBG:core:internal_fork: forking new process "MI FIFO" Aug 10 20:20:49 [5064] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=tm Aug 10 20:20:49 [5064] DBG:tm:child_init_callid: callid: '5f6d77df-5064@192.168.1.14' Aug 10 20:20:49 [5064] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=usrloc Aug 10 20:20:49 [5064] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=registrar Aug 10 20:20:49 [5064] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=mi_fifo Aug 10 20:20:49 [5064] DBG:core:init_mod_child: type=PROC_MODULE, rank=-2, module=uri Aug 10 20:20:49 [5064] DBG:mi_fifo:fifo_process: new process with pid = 5064 created Aug 10 20:20:49 [5061] DBG:core:init_mod_child: type=CHILD, rank=1, module=tm Aug 10 20:20:49 [5061] DBG:tm:child_init_callid: callid: '5f6d77df-5061@192.168.1.14' Aug 10 20:20:49 [5061] DBG:core:init_mod_child: type=CHILD, rank=1, module=usrloc Aug 10 20:20:49 [5061] DBG:core:init_mod_child: type=CHILD, rank=1, module=registrar Aug 10 20:20:49 [5061] DBG:core:init_mod_child: type=CHILD, rank=1, module=mi_fifo Aug 10 20:20:49 [5061] DBG:core:init_mod_child: type=CHILD, rank=1, module=uri Aug 10 20:20:49 [5063] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=mi_fifo Aug 10 20:20:49 [5063] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=uri Aug 10 20:20:49 [5063] DBG:core:run_timer_process: tv = 0, 100000 , m=10 Aug 10 20:20:49 [5064] DBG:mi_fifo:mi_init_fifo_server: FIFO created @ /tmp/opensips_fifo Aug 10 20:20:49 [5064] DBG:mi_fifo:mi_init_fifo_server: fifo /tmp/opensips_fifo opened, mode=660