[Devel] OpenSER sometimes goes down.

Sebastian Zaprzalski s.zaprzalski at inotel.pl
Fri Feb 10 21:34:25 CET 2006 

Hi Bogdan

I think that it's not typical crash, becouse usually the last message is: 
"/usr/local/sbin/openser[19521]: WARNING: destroy_fifo: cannot delete fifo 
(/var/tmp/ser_fifo): Operation not permitted", and I don't have any core 
dumps. It looks like almost normal stopping the application. You can see all 
logs in my first e-mail (at the end).
I've added xlog, almost at the beginning of my config, and after next crash 
I've seen the last Invite request. It was quite strange becouse the From 
header was empty
(

...
From:
To: "xxxxxxxxx" <sip:xxxxxxxxxx at xxx.xxx.23.35>;tag=as7e678c30
Contact: <sip:xxxxxxxxxxxx at 192.168.31.2>
...

)
You can see whole request below, in my previous e-mail. I think that crash 
was coused by this request.
I have a lot of error logs from openser, coused by incorrect requests, but 
only this one can crash application.
If you want I can add any xlogs in my config and try to simulate this 
situation next week.

regards

Sebastian


----- Original Message ----- 
From: "Bogdan-Andrei Iancu" <bogdan at voice-system.ro>
To: "Sebastian Zaprzalski" <s.zaprzalski at inotel.pl>
Cc: <daniel at voice-system.ro>; <devel at openser.org>
Sent: Friday, February 10, 2006 5:20 PM
Subject: Re: [Devel] OpenSER sometimes goes down.


> Hi Sebastian,
>
> just to bring more light - by going down you mean crashing? if crashing 
> what are the logs during the shutdown (any signal reported)...was core 
> dumped ? any other logs following the messages you already posted?
>
> thanks and regards,
> bogdan
>
>
> Sebastian Zaprzalski wrote:
>
>> Hello
>>
>> It's not easy to reproduce the situation, cause we have about 4000 
>> registered users on server, but I've add a xlog in config file for 
>> logging INVITE requests.
>> Today we had a problem with openser once again. This is a log with INVITE 
>> (I've replaced some data by 'x' sign):
>>
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: ERROR: parse_to : 
>> unexpected char ["] in status 8: <<To: >> .
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: 
>> ERROR:parse_from_header: bad from header
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: insert_RR(): From 
>> parsing failed
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: record_route(): 
>> Error while inserting Record-Route line
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: is_preloaded: To 
>> header field not found
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]:  [INVITE_LOG] Src 
>> IP: xxx.xxx.223.123  Message: INVITE sip:xxxxxxxxx at xxx.xxx.xxx SIP/2.0^M 
>> Via: SIP/2.0/UDP 192.168.31.2;branch=z9hG4bK11693e0bd74ccf18^M Route: 
>> <sip:xxx.xxx.23.34;ftag=as7e678c30;lr=on>^M From: To: "xxxxxxxxx" 
>> <sip:xxxxxxxxxx at xxx.xxx.23.35>;tag=as7e678c30^M Contact: 
>> <sip:xxxxxxxxxxxx at 192.168.31.2>^M Supported: replaces, timer^M Call-ID: 
>> 6a4e552729e7bb6f205745d75c6ee6b2 at xxx.xxx.23.35^M CSeq: 20280 INVITE^M 
>> User-Agent: Grandstream HT487 1.0.7.11^M Max-Forwards: 69^M Allow: 
>> INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE,UPDATE^M 
>> Content-Type: application/sdp^M Content-Length: 290^M ^M v=0^M 
>> o=xxxxxxxxxxxxxx 8000 8001 IN IP4 192.168.31.2^M s=SIP Call^M c=IN IP4 
>> 192.168.31.2^M t=0 0^M m=audio 5004 RTP/AVP 2 107 8 0 18^M a=sendrecv^M 
>> a=rtpmap:2 G726-32/8000^M a=rtpmap:107 iLBC/8000^M a=fmtp:107 mode=30^M 
>> a=rtpmap:8 PCMA/8000^M a=rtpmap:0 PCMU/8000^M a=rtpmap:18 G729/8000^M 
>> a=ptime:20^M  [/INVITE_LOG]
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: ERROR: parse_to : 
>> unexpected char ["] in status 8: <<To: >> .
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: 
>> ERROR:parse_from_header: bad from header
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: 
>> ERROR:avpops:get_source_uri: failed to parse from
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: 
>> ERROR:avpops:parse_source_uri: cannot get uri
>> Feb 10 09:04:58 aster2 /usr/local/sbin/openser[28679]: 
>> ERROR:avpops:write_avp: cannot parse uri
>>
>>
>> INVITE_LOG is placed just before avp_write("$from/username","s:subA");. I 
>> think that there is a problem with From header, which is empty but this 
>> shouldn't turn an openser off.
>>
>> Sebastian
>>
>>
>>
>>
>> ----- Original Message ----- From: "Daniel-Constantin Mierla" 
>> <daniel at voice-system.ro>
>> To: "Sebastian Zaprzalski" <s.zaprzalski at inotel.pl>
>> Cc: <devel at openser.org>
>> Sent: Thursday, February 02, 2006 10:46 AM
>> Subject: Re: [Devel] OpenSER sometimes goes down.
>>
>>
>>> Hello,
>>>
>>> could you reproduce easily the situation? It seems that the SIP message 
>>> is malformed, at least the To and From headers. Could you take the 
>>> network trace for this case (ngrep -qt port 5060)?
>>>
>>> After the message "ERROR:avpops:write_avp: cannot parse uri" the 
>>> function avp_write() returns to script with false. What is the next 
>>> function you call in the script after avp_write() (somewhere after 
>>> record_route()).
>>>
>>> It will be great if you can generate a core file, use "ulimit -c 
>>> unlimited" before starting the sip server. When you get the core, send 
>>> us the backtrace.
>>>
>>> Cheers,
>>> Daniel
>>>
>>>
>>> On 02/01/06 13:39, Sebastian Zaprzalski wrote:
>>>
>>>> Hi
>>>>  A few weeks ago, on our VoIP platform, we migrate from SER to OpenSER 
>>>> (ver. 1.0.0). SER has been worked very stable long time, but
>>>> OpenSER has some problems with stability. From time to time it goes 
>>>> down. I always recive the same logs:
>>>>  Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: ERROR: parse_to 
>>>> : unexpected char ["] in status 8: <<To: >> .
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: 
>>>> ERROR:parse_from_header: bad from header
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: insert_RR(): 
>>>> From parsing failed
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: record_route(): 
>>>> Error while inserting Record-Route line
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: is_preloaded: To 
>>>> header field not found
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: ERROR: parse_to 
>>>> : unexpected char ["] in status 8: <<To: >> .
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: 
>>>> ERROR:parse_from_header: bad from header
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: 
>>>> ERROR:avpops:get_source_uri: failed to parse from
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: 
>>>> ERROR:avpops:parse_source_uri: cannot get uri
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[25967]: 
>>>> ERROR:avpops:write_avp: cannot parse uri
>>>> Jan 31 11:20:58 aster2 /usr/local/sbin/openser[27417]: WARNING: 
>>>> destroy_fifo: cannot delete fifo (/var/tmp/ser_fifo): Operation not 
>>>> permitted
>>>>
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: ERROR: parse_to 
>>>> : unexpected char ["] in status 8: <<To: >> .
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: 
>>>> ERROR:parse_from_header: bad from header
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: insert_RR():
>>>> >From parsing failed
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: record_route(): 
>>>> Error while inserting Record-Route line
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: is_preloaded: To 
>>>> header field not found
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: ERROR: parse_to 
>>>> : unexpected char ["] in status 8: <<To: >> .
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: 
>>>> ERROR:parse_from_header: bad from header
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: 
>>>> ERROR:avpops:get_source_uri: failed to parse from
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: 
>>>> ERROR:avpops:parse_source_uri: cannot get uri
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[14777]: 
>>>> ERROR:avpops:write_avp: cannot parse uri
>>>> Feb  1 11:12:43 aster2 /usr/local/sbin/openser[19521]: WARNING: 
>>>> destroy_fifo: cannot delete fifo (/var/tmp/ser_fifo): Operation not 
>>>> permitted
>>>> Does somebody have any idea?
>>>>  Regards
>>>>  Sebastian
>>>>  ------------------------------------------------------------------------
>>>>
>>>>
>>>> _______________________________________________
>>>> Devel mailing list
>>>> Devel at openser.org
>>>> http://openser.org/cgi-bin/mailman/listinfo/devel
>>>>
>>>
>>
>>
>>
>> _______________________________________________
>> Devel mailing list
>> Devel at openser.org
>> http://openser.org/cgi-bin/mailman/listinfo/devel
>>
>

More information about the Devel mailing list