Hello Brett,
Thanks for you quick reply,
I did check the binding with netstat -nlp, this is correct.
I did not find a firewall.
Hard to tell is opensips is running, I see no action what so ever. I only knnow
the process is there.
there is section in the main route to handle the register message:
"
route{
if (!mf_process_maxfwd_header("10")) {
sl_send_reply("483","Too Many Hops");
exit;
}
if (is_method("REGISTER"))
{
xlog("L_ERR","received an register");
sl_send_reply("200","OK");
exit;
}
}
"
I attached the start-up log, after this nothing happens any more, debug=9
I dont have the packages you suggested to trace, but I'm sure of the output of
tcpdump
"tcpdump -i eth0 -X -s 2048 port 5060"
I'm lost and I know there is too little info to help, i looks like or opensips
is not running or it does not receive
anything. Is there any way to have a little log info on the udp_server? On a
solaris machine I would use dtrace
to see what it happening but for. The linux version of it systemtap seems a bit
too complex.
Any suggestions on how i could proceed?
Kind regards,
Erik Buelens
----- Original Message -----
From: Brett Nemeroff
To: OpenSIPS users mailling list
Sent: Tuesday, August 10, 2010 4:30 PM
Subject: Re: [OpenSIPS-Users] how to debug new opensips setup
On Tue, Aug 10, 2010 at 9:23 AM, < [email protected] > wrote:
Hello,
I'm new to opensips, i've sip experience but little programming skills.
I've installed the opensips module on a virtual machine running centos.
I've not seen compiler errors. Port 5060 is listend.
When I'm sending a register message, which also arrives on the interface,
verified with tcpdump
I don't see any respons.
I tought when i would compile with mode=debug i would get some logging but
neither in the terminal nor on in
the syslog i get any info.
Couple of comments:
To turn on debug, set:
debug=3 (or higher)
in opensips.cfg, then restart (opensipsctl restart)
instead of using tcpdump, try using a tool like ngrep or tshark:
ngrep -qW byline port 5060
tshark -i any port 5060
It's hard to diagnose the problem you are having without more information. I
guess what you need to check on:
1. Is opensips listening on the same interface you are receiving the message?
(netstat -nlp)
2. Is there a firewall blocking the traffic?
3. Is opensips actually running (seriously!)
4. Does your opensips.cfg actually handle REGISTERs? if you have a bunch of if
(is_method("INVITE")) but nothing for REGISTER, it might end up just ignoring
it altogether.
normal "debugging" for the transactions isn't something you need to set during
compile, it's a configuration parameter.
Ultimately, that should help out. The opensips debug log will point you in the
right direction, but honestly, I think while learning opensips, that debug log
might be a little intimidating for this sort of problem. I'd check the other
points first. :)
-Brett
_______________________________________________
Users mailing list
[email protected]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
[r...@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 <fifo_name> 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 <enable_full_lr> 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 <append_fromtag> 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 <db_mode> in module
usrloc [//lib/opensips/modules/]
Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found
<mf_process_maxfwd_header>(1) in module maxfwd [//lib/opensips/modules/]
Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in
module sl [//lib/opensips/modules/]
Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found <is_method>(1) in
module textops [//lib/opensips/modules/]
Aug 10 20:20:44 [5061] DBG:core:find_cmd_export_t: found <sl_send_reply>(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: <udp:192.168.1.14:5060>
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 <serial_branch> 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 <load_tm>(0) in module
tm [//lib/opensips/modules/]
Aug 10 20:20:49 [5061] DBG:core:find_cmd_export_t: found <load_sl>(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 <load_sig>(1) in
module signaling [//lib/opensips/modules/]
Aug 10 20:20:49 [5061] DBG:core:find_cmd_export_t: found <ul_bind_usrloc>(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:
'[email protected]'
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:
'[email protected]'
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:
'[email protected]'
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
_______________________________________________
Users mailing list
[email protected]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
