On Thu, Mar 19, 2009 at 12:18 PM, Henning Westerholt <span dir="ltr"><<a href="mailto:henning.westerholt@1und1.de">henning.westerholt@1und1.de</a>></span> wrote:<br>
<div class="gmail_quote"><blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;"><div class="im"><br>
</div>Hi Sara,<br>
<br>
ok, take a look to the plain output from strace, without the grep command.<br>
Does it shows any errors from a file open, or a stat? Probably stupid<br>
question, but do you've the strace command installed?<br>
<br>
Cheers,<br>
<font color="#888888"><br>
Henning</font></blockquote><div><br> </div></div><br>Hello Henning, <br>
<br>
I do have strace :-)<br>
Yesterday i tried it with and without the grep part, and there was no
error. So i did some IMS presence service tests... And Kamailio
couldn't process a "SIP PUBLISH" because of an error "<b>ERROR 600 : Busy everywhere - Forwarding to S-CSCF failed</b>".
For your information, i'm using the Fokus OpenIms Core as an ims
server, and Kamailio as a presence server. An IFC (Initial Filter
Criteria) is set up in OpenIms to route properly the presence messages
to Kamailio.<br>
And by the way, i saw some lines in the logs about /tmp/kamailio_fifo opening successfully...<br>
<br>
Today, i launch the strace command and gess what... i'm getting the
/tmp/kamailio_fifo opening error. Still a new kamailio_fifo is created
indeed in my /tmp directory.<br>
And well, i am encountering the "busy everywhere" error again...<br>
<br>
Have anyone ever noticed the error 600?<br>
And what about the mi_fifo error that appears half the time?<br>
<br>PS: Here you are some parts of the logs, and you can have a look at the attached file for the entire trace.<br><br>
====LOG PARTS====<br><br>
1/ Error 600 : busy everywhere (in kamailio start)<br>
<br>
<b>Mar 19 10:46:28 [11850] DBG:core:parse_msg: SIP Request:<br>
Mar 19 10:46:28 [11850] DBG:core:parse_msg: method: <PUBLISH><br>
Mar 19 10:46:28 [11850] DBG:core:parse_msg: uri: <<a href="mailto:sip%3Abob@test.ims.com">sip:bob@test.ims.com</a>><br>
Mar 19 10:46:28 [11850] DBG:core:parse_msg: version: <SIP/2.0><br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: flags=2<br>
Mar 19 10:46:28 [11850] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bKa813.b002d4e4.0>; state=16<br>
Mar 19 10:46:28 [11850] DBG:core:parse_via: end of header reached, state=5<br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: via found, flags=2<br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: this is the first via<br>
Mar 19 10:46:28 [11850] DBG:core:receive_msg: After parse_msg...<br>
Mar 19 10:46:28 [11850] DBG:core:receive_msg: preparing to run routing scripts...<br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: flags=100<br>
Mar 19 10:46:28 [11850] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bKa813.41769183.0>; state=16<br>
Mar 19 10:46:28 [11850] DBG:core:parse_via: end of header reached, state=5<br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: via found, flags=100<br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: parse_headers: this is the second via<br>
Mar 19 10:46:28 [11850] DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=6<br>
Mar 19 10:46:28 [11850] DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK315224847>; state=16<br>
Mar 19 10:46:28 [11850] DBG:core:parse_via: end of header reached, state=5<br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: via found, flags=100<br>
Mar 19 10:46:28 [11850] DBG:core:parse_to: end of header reached, state=10<br>
Mar 19 10:46:28 [11850] DBG:core:parse_to: display={}, ruri={<a href="mailto:sip%3Abob@test.ims.com">sip:bob@test.ims.com</a>}<br>
Mar 19 10:46:28 [11850] DBG:core:get_hdr_field: <To> [25]; uri=[</b><b><a href="mailto:sip%3Abob@test.ims.com">sip:bob@test.ims.com</a></b><b>] <br>
Mar 19 10:46:28 [11850] DBG:core:get_hdr_field: to body [<</b><b><a href="mailto:sip%3Abob@test.ims.com">sip:bob@test.ims.com</a></b><b>><br>
]<br>
Mar 19 10:46:28 [11850] DBG:core:get_hdr_field: cseq <CSeq>: <20> <PUBLISH><br>
Mar 19 10:46:28 [11850] DBG:maxfwd:is_maxfwd_present: value = 15 <br>
Mar 19 10:46:28 [11850] DBG:uri:has_totag: no totag<br>
Mar 19 10:46:28 [11850] DBG:core:parse_headers: flags=78<br>
Mar 19 10:46:28 [11850] DBG:tm:t_lookup_request: start searching: hash=12682, isACK=0<br>
Mar 19 10:46:28 [11850] DBG:tm:matching_3261: RFC3261 transaction matched, tid=a813.b002d4e4.0<br>
Mar 19 10:46:28 [11850] DBG:tm:t_lookup_request: REF_UNSAFE: after is 1<br>
Mar 19 10:46:28 [11850] DBG:tm:t_lookup_request: transaction found (T=0xb5a0d430)<br>
Mar 19 10:46:28 [11850] DBG:tm:t_retransmit_reply: nothing to retransmit<br>
Mar 19 10:46:28 [11850] DBG:tm:t_check_trans: UNREF_UNSAFE: after is 0<br>
Mar 19 10:46:28 [11850] DBG:core:destroy_avp_list: destroying list (nil)<br>
Mar 19 10:46:28 [11850] DBG:core:receive_msg: cleaning up<br>
Mar 19 10:46:31 [11852] DBG:core:parse_msg: SIP Reply (status):<br>
Mar 19 10:46:31 [11852] DBG:core:parse_msg: version: <SIP/2.0><br>
Mar 19 10:46:31 [11852] DBG:core:parse_msg: status: <600><br>
Mar 19 10:46:31 [11852] DBG:core:parse_msg: reason: <Busy everywhere - Forwarding to S-CSCF failed><br>
Mar 19 10:46:31 [11852] DBG:core:parse_headers: flags=2<br>
Mar 19 10:46:31 [11852] DBG:core:parse_via_param: found param type 232,
<branch> = <z9hG4bKa813.192cfef5.0>; state=16<br>
Mar 19 10:46:31 [11852] DBG:core:parse_via: end of header reached, state=5</b><br>
<br>
<br>
<br>
2/ /tmp/kamailio_fifo opening error (with strace kamctl)<br>
<br>
<b>rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
stat64("/tmp/kamailio_fifo", {st_mode=S_IFIFO|0660, st_size=0, ...}) = 0<br>
geteuid32() = 1000<br>
getegid32() = 1000<br>
getuid32() = 1000<br>
getgid32() = 1000<br>
access("/tmp/kamailio_fifo", W_OK) = -1 EACCES (Permission denied)<br>
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0<br>
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, {B38400 opost isig icanon echo ...}) = 0<br>
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0<br>
write(1, "\33[37;31m\33[1mERROR: Error opening"..., 72ERROR: Error opening Kamailio's FIFO /tmp/kamailio_fifo<br>
) = 72<br>
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, {B38400 opost isig icanon echo ...}) = 0<br>
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0<br>
brk(0x9302000) = 0x9302000<br>
write(1, "\33[37;31m\33[1mERROR: Make sure you"..., 123ERROR: Make sure
you have the line 'modparam("mi_fifo", "fifo_name",
"/tmp/kamailio_fifo")' in your config<br>
) = 123<br>
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, {B38400 opost isig icanon echo ...}) = 0<br>
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0<br>
write(1, "\33[37;31m\33[1mERROR: and also have"..., 64ERROR: and also have loaded the mi_fifo module.<br>
) = 64<br>
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0<br>
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0<br>
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0<br>
exit_group(2) = ?<br>
Process 12215 detached</b><br>
<br>
<br>
==================<br>
<br clear="all"><br>-- <br>S.<br>