<div dir="ltr"><div>We had this issue in the past. We moved the core database to ODBC over MySQL and this solved this issue.</div>
<div> </div>
<div> __yehavi:<br><br></div>
<div class="gmail_quote">2010/9/27 Ashley B <span dir="ltr"><<a href="mailto:ashley@midletearth.com">ashley@midletearth.com</a>></span><br>
<blockquote style="BORDER-LEFT: #ccc 1px solid; MARGIN: 0px 0px 0px 0.8ex; PADDING-LEFT: 1ex" class="gmail_quote">Hi,<br><br>I was experiencing the same issue all of a sudden on FreeSWITCH (Windows -<br>17048M). FS was running without a hitch for 2 months, until I started seeing<br>
the below on our console log whenever receiving an inbound call (the call<br>never made it through to the client obviously). After restarting FS, the<br>problem went away but I saw it happen once about 5 months ago as well.<br>
<br>We make no external calls whatsoever to the SQLite db's.<br><br>Has anyone identified why this could happen and how to prevent future<br>occurrences of it?<br><br>Thanks<br>Ashley<br><br><br>...see log:<br><br>2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:722 SQL ERR: [select<br>
call_id,sip_user,sip_host,contact,status,rpid,expires,user_agent,server_user<br>,server_host,profile_name,network_ip,0 from sip_registrations where expires<br>> 0 and expires <= 1285417376] library routine called out of sequence<br>
2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>routine called out of sequence]<br>delete from sip_registrations where expires > 0 and expires <= 1285417376<br>and hostname='bet'<br>
2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:722 SQL ERR: [select<br>call_id from sip_shared_appearance_dialogs where hostname='bet' and<br>profile_name='internal' and expires <= 1285417376] library routine called<br>
out of sequence<br>2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>routine called out of sequence]<br>delete from sip_registrations where expires > 0 and hostname='bet' and<br>expires <= 1285417376<br>
2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>routine called out of sequence]<br>delete from sip_presence where expires > 0 and expires <= 1285417376 and<br>hostname='bet'<br>2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>delete from sip_authentication where expires > 0 and expires <= 1285417376<br>and hostname='bet'<br>2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:722 SQL ERR: [select<br>
call_id from sip_subscriptions where (expires = -1 or (expires > 0 and<br>expires <= 1285417376)) and hostname='bet'] library routine called out of<br>sequence<br>2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>delete from sip_subscriptions where (expires = -1 or (expires > 0 and<br>expires <= 1285417376)) and hostname='bet'<br>2010-09-25 13:22:56.136423 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>delete from sip_dialogs where (expires = -1 or (expires > 0 and expires <=<br>1285417376)) and hostname='bet'<br>2010-09-25 13:23:20.970384 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:20.970384 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.069997 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.069997 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.171564 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.171564 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.271177 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.271177 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.372743 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.372743 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.472357 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.472357 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.573923 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.573923 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.673536 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.673536 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.775103 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br>BEGIN<br>2010-09-25 13:23:21.775103 [DEBUG] switch_core_sqldb.c:643 SQL Retry<br>[library routine called out of sequence]<br>2010-09-25 13:23:21.874716 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>
routine called out of sequence]<br><br><br><br>-----Original Message-----<br>From: Peter Olsson [mailto: peter.olsson at <a href="http://visionutveckling.se/" target="_blank">visionutveckling.se</a>]<br>Sent: 3 May 2010 12:58 PM<br>
To: FreeSWITCH Users Help<br>Subject: RE: [Freeswitch-users] Strange errors from sqlite DB<br><br>Brian,<br><br>This is the log when it first occured. I'm not sure if I'll ever recreate<br>the issue, so do you think I should open a jira anyway? This log is more or<br>
less all I have.<br><br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:119<br>(sofia/internal/50711 at 10.10.1.35) State Change CS_ROUTING -> CS_EXECUTE<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_session.c:1021 Send signal<br>
sofia/internal/50711 at 10.10.1.35 [BREAK]<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:341<br>(sofia/internal/50711 at 10.10.1.35) State ROUTING going to sleep<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:314<br>
(sofia/internal/50711 at 10.10.1.35) Running State Change CS_EXECUTE<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:348<br>(sofia/internal/50711 at 10.10.1.35) State EXECUTE<br>2010-05-03 20:25:32.673126 [DEBUG] mod_sofia.c:226 sofia/internal/50711 at<br>
10.10.1.35 SOFIA EXECUTE<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:157<br>sofia/internal/50711 at 10.10.1.35 Standard EXECUTE<br>EXECUTE sofia/internal/50711 at 10.10.1.35 set(hangup_after_bridge=true)<br>
2010-05-03 20:25:32.673126 [DEBUG] mod_dptools.c:816 sofia/internal/50711 at<br>10.10.1.35 SET [hangup_after_bridge]=[true]<br>EXECUTE sofia/internal/50711 at 10.10.1.35<br>bridge(sofia/gateway/vip2000-trunk/00709275071)<br>
2010-05-03 20:25:32.673126 [NOTICE] switch_channel.c:669 New Channel<br>sofia/internal/00709275071 [41b995f9-df54-4053-a521-ae804c6c1c6b]<br>2010-05-03 20:25:32.673126 [DEBUG] mod_sofia.c:3386<br>(sofia/internal/00709275071) State Change CS_NEW -> CS_INIT<br>
2010-05-03 20:25:32.673126 [DEBUG] switch_core_session.c:1021 Send signal<br>sofia/internal/00709275071 [BREAK]<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:314<br>(sofia/internal/00709275071) Running State Change CS_INIT<br>
2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:338<br>(sofia/internal/00709275071) State INIT<br>2010-05-03 20:25:32.673126 [DEBUG] mod_sofia.c:83 sofia/internal/00709275071<br>SOFIA INIT<br>2010-05-03 20:25:32.673126 [DEBUG] mod_sofia.c:117<br>
(sofia/internal/00709275071) State Change CS_INIT -> CS_ROUTING<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_session.c:1021 Send signal<br>sofia/internal/00709275071 [BREAK]<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:338<br>
(sofia/internal/00709275071) State INIT going to sleep<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:314<br>(sofia/internal/00709275071) Running State Change CS_ROUTING<br>2010-05-03 20:25:32.673126 [DEBUG] sofia.c:4153 Channel<br>
sofia/internal/00709275071 entering state [calling][0]<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:341<br>(sofia/internal/00709275071) State ROUTING<br>2010-05-03 20:25:32.673126 [DEBUG] mod_sofia.c:140<br>
sofia/internal/00709275071 SOFIA ROUTING<br>2010-05-03 20:25:32.673126 [DEBUG] switch_ivr_originate.c:66<br>(sofia/internal/00709275071) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_session.c:1021 Send signal<br>
sofia/internal/00709275071 [BREAK]<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:341<br>(sofia/internal/00709275071) State ROUTING going to sleep<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:314<br>
(sofia/internal/00709275071) Running State Change CS_CONSUME_MEDIA<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:360<br>(sofia/internal/00709275071) State CONSUME_MEDIA<br>2010-05-03 20:25:32.673126 [DEBUG] switch_core_state_machine.c:360<br>
(sofia/internal/00709275071) State CONSUME_MEDIA going to sleep<br>2010-05-03 20:25:32.688751 [INFO] sofia.c:662 Update Callee ID to<br>"00709275071" <00709275071><br>2010-05-03 20:25:32.751251 [ERR] switch_core_sqldb.c:722 SQL ERR: [select<br>
sip_subscriptions.proto,sip_subscriptions.sip_user,sip_subscriptions.sip_hos<br>t,sip_subscriptions.sub_to_user,sip_subscriptions.sub_to_host,sip_subscripti<br>ons.event,sip_subscriptions.contact,sip_subscriptions.call_id,sip_subscripti<br>
ons.full_from,sip_subscriptions.full_via,sip_subscriptions.expires,sip_subsc<br>riptions.user_agent,sip_subscriptions.accept,sip_subscriptions.profile_name,<br>'CS_ROUTING','unknown','10.10.1.35',sip_presence.status,sip_presence.rpid<br>
from sip_subscriptions left join sip_presence on<br>(sip_subscriptions.sub_to_user=sip_presence.sip_user and<br>sip_subscriptions.sub_to_host=sip_presence.sip_host and<br>sip_subscriptions.profile_name=sip_presence.profile_name) where<br>
sip_subscriptions.expires > -1 and (event='presence' or event='dialog') and<br>sub_to_user='50711' and (sub_to_host='10.10.1.35' or presence_hosts like<br>'%10.10.1.35%') and (sip_subscriptions.profile_name = 'internal' or<br>
sip_subscriptions.presence_hosts != sip_subscriptions.sub_to_host)] library<br>routine called out of sequence<br>2010-05-03 20:25:32.782501 [DEBUG] sofia.c:4153 Channel<br>sofia/internal/00709275071 entering state [proceeding][180]<br>
2010-05-03 20:25:32.782501 [NOTICE] sofia.c:4223 Ring-Ready<br>sofia/internal/00709275071!<br>2010-05-03 20:25:32.782501 [NOTICE] mod_sofia.c:1837 Ring-Ready<br>sofia/internal/50711 at 10.10.1.35!<br>2010-05-03 20:25:32.782501 [ERR] switch_core_sqldb.c:525 Statement Error<br>
[select 'appearance-index=1' from sip_subscriptions where expires > -1 and<br>hostname='teller' and event='call-info' and sub_to_user='00709275071' and<br>sub_to_host='192.168.1.55']!<br>
2010-05-03 20:25:32.782501 [DEBUG] switch_core_session.c:641 Send signal<br>sofia/internal/50711 at 10.10.1.35 [BREAK]<br>2010-05-03 20:25:32.782501 [NOTICE] switch_ivr_originate.c:437 Ring Ready<br>sofia/internal/50711 at 10.10.1.35!<br>
2010-05-03 20:25:32.782501 [DEBUG] sofia.c:4153 Channel<br>sofia/internal/00709275071 entering state [proceeding][183]<br>2010-05-03 20:25:32.782501 [DEBUG] sofia.c:4164 Remote SDP:<br><br>________________________________________<br>
Från: freeswitch-users-bounces at <a href="http://lists.freeswitch.org/" target="_blank">lists.freeswitch.org</a><br>[freeswitch-users-bounces at <a href="http://lists.freeswitch.org/" target="_blank">lists.freeswitch.org</a>] f&#246;r Brian West<br>
[brian at <a href="http://freeswitch.org/" target="_blank">freeswitch.org</a>]<br>Skickat: den 3 maj 2010 21:33<br>Till: freeswitch-users at <a href="http://lists.freeswitch.org/" target="_blank">lists.freeswitch.org</a><br>
Ämne: Re: [Freeswitch-users] Strange errors from sqlite DB<br><br>Happen to be touching core.db from outside of FreeSWITCH? Also what SVN/GIT<br>are you on?<br><br>/b<br><br>On May 3, 2010, at 2:27 PM, Peter Olsson wrote:<br>
<br>> ----<br>><br>> 2010-05-03 20:29:21.032501 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>routine called out of sequence]<br>> insert into sip_authentication (nonce,expires,profile_name,hostname,<br>last_nc) values('6e5df620-d388-4f93-b0b0-8da4cf880ef1', 1272911421,<br>
'(NULL)', 'internal', 0)<br>> 2010-05-03 20:29:21.048126 [ERR] switch_core_sqldb.c:722 SQL ERR: [select<br>nonce,last_nc from sip_authentication where<br>nonce='6e5df620-d388-4f93-b0b0-8da4cf880ef1' and last_nc < 1] library<br>
routine called out of sequence<br>> 2010-05-03 20:29:21.048126 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>routine called out of sequence]<br>> update sip_authentication set expires='1272911421',last_nc=1 where<br>
nonce='6e5df620-d388-4f93-b0b0-8da4cf880ef1'<br>> 2010-05-03 20:29:21.048126 [ERR] switch_core_sqldb.c:404 SQL ERR [library<br>routine called out of sequence]<br>><br>> Regards,<br>><br>> Peter<br><br>
<br>_______________________________________________<br>FreeSWITCH-users mailing list<br><a href="mailto:FreeSWITCH-users@lists.freeswitch.org">FreeSWITCH-users@lists.freeswitch.org</a><br><a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br><a href="http://www.freeswitch.org/" target="_blank">http://www.freeswitch.org</a><br>
</blockquote></div></div>