[CalendarServer-dev] Fw: Strange Rotation of Log files in caldavd

Lisana Berberi lisanaberberi at yahoo.com
Tue Jun 21 06:25:50 PDT 2011





----- Forwarded Message ----
From: Lisana Berberi <lisanaberberi at yahoo.com>
To: Andre LaBranche <dre at apple.com>; calendarserver-dev at lists.macosforge.org; 
calendarserver-users at lists.macosforge.org
Sent: Mon, June 20, 2011 9:42:52 AM
Subject: Re: [CalendarServer-dev] Strange Rotation of Log files in caldavd




this is the content of error.log:

2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/bin/twistd", line 21, in <module>
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
run()
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/scripts/t
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
app.run(runApp, ServerOptions)
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File  
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/applicati
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
runApp(config)
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/scripts/t
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
_SomeApplicationRunner(config).run()
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/applicati
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
self.application = self.createOrGetApplication()
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File  
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/applicati
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     ser 
= plg.makeService(self.config.subOptions)
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/plugins/c
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
return self._serviceMaker.makeService(options)
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/mai
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
mailer = MailHandler()
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File  
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/mai
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
datetime.timedelta(days=days))
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/mai
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     """, 
before
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/sql
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-]     
q.execute(sql, query_params)
2011-06-20 09:30:44+0200 [-] [mailgateway] 2011-06-20 09:30:44+0200 [-] 
sqlite3.OperationalError: database is locked
2011-06-20 09:30:45+0200 [-] [mailgateway]  
/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/mail/smtp.py:10:
 DeprecationWarning: the MimeW
2011-06-20 09:30:45+0200 [-] [mailgateway]   import MimeWriter, tempfile, rfc822
2011-06-20 09:30:45+0200 [-] [mailgateway] 2011-06-20 09:30:45+0200 [-] Log 
opened.
2011-06-20 09:30:45+0200 [-] [mailgateway] 2011-06-20 09:30:45+0200 [-] twistd 
8.2.0 (/usr/bin/python 2.6.6) starting up.
2011-06-20 09:30:45+0200 [-] [mailgateway] 2011-06-20 09:30:45+0200 [-] reactor 
class: twisted.internet.selectreactor.SelectReactor.
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-] 
[twistedcaldav.sql#error] Exception while executing SQL on DB 
<MailGatewayTokensDatab
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-] 
Traceback (most recent call last):
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File  
"/usr/lib/twisted-calendarserver/bin/twistd", line 21, in <module>
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     
run()
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/scripts/t
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     
app.run(runApp, ServerOptions)
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/applicati
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     
runApp(config)
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/scripts/t
2011-06-20 09:30:47+0200 [-]  [mailgateway] 2011-06-20 09:30:47+0200 [-]     
_SomeApplicationRunner(config).run()
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/applicati
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     
self.application = self.createOrGetApplication()
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/applicati
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     ser 
= plg.makeService(self.config.subOptions)
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/plugins/c
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20  09:30:47+0200 [-]     
return self._serviceMaker.makeService(options)
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/mai
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     
mailer = MailHandler()
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/mai
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     
datetime.timedelta(days=days))
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/mai
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     """,  
before
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]   File 
"/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twistedcaldav/sql
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]     
q.execute(sql, query_params)
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-] 
sqlite3.OperationalError: database is locked
2011-06-20 09:30:47+0200 [-] [mailgateway] 
/usr/lib/twisted-calendarserver/lib/python2.6/site-packages/twisted/mail/smtp.py:10:
 DeprecationWarning: the MimeW
2011-06-20 09:30:47+0200 [-] [mailgateway]   import MimeWriter, tempfile, rfc822
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-] Log 
opened.
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-] twistd 
8.2.0 (/usr/bin/python 2.6.6) starting up.
2011-06-20 09:30:47+0200 [-] [mailgateway] 2011-06-20 09:30:47+0200 [-]  reactor 
class: twisted.internet.selectreactor.SelectReactor.
2011-06-20 09:30:50+0200 [-] [mailgateway] 2011-06-20 09:30:50+0200 [-] 
[twistedcaldav.sql#error] Exception while executing SQL on DB 
<MailGatewayTokensDatab
2011-06-20 09:30:50+0200 [-] [mailgateway] 2011-06-20 09:30:50+0200 [-] 
Traceback (most recent call last):
...............................

as i can see there is an error about Sqlite Db:  sqlite3.OperationalError: 
database is locked

in run/caldavd i've this:

-rw------- 1 root    root        5 Jun 17 22:31 caldavd.pid
srw-rw---- 1 root    caldavd     0 Jun 17 22:31 caldavd.sock
srw-rw---- 1 root    caldavd     0 Jun 17 22:31 caldavd-stats.sock
-rw-r----- 1 caldavd caldavd 27648 Jun 20 09:33 calendaruserproxy.sqlite
-rw------- 1  caldavd caldavd  6144 Jun  8 19:16 mailgatewaytokens.sqlite
-rw------- 1 caldavd caldavd  1544 Jun 10 12:14 mailgatewaytokens.sqlite-journal
-rw-r----- 1 caldavd caldavd  6144 Jun 20 09:33 resourceinfo.sqlite
drwx------ 4 caldavd caldavd  4096 Feb  3 15:14 tasks

i opened mailgatewaytokens.sqlite and the db is not locked.., maybe there is sth 
unusual with it's journal db file???

thnx





________________________________
From: Andre LaBranche <dre at apple.com>
To: Lisana Berberi <lisanaberberi at yahoo.com>
Cc: Calendar Server <calendarserver-users at lists.macosforge.org>
Sent: Thu, June 16, 2011  7:00:28 PM
Subject: Re: [CalendarServer-dev] Strange Rotation of Log files in caldavd



On Jun 16, 2011, at 7:05 AM, Lisana Berberi wrote:

hello,
>
>i deleted the error log rotated files and kept just error.log and access.log, i 
>changed
>to "warn" the DefaultLogLevel, restart caldavd, and still in a very short time i 
>had this:
>
>-rw------- 1 root    root    2255213 Jun 16 15:59 access.log
>-rwxrwxrwx 1 root    root     668190 Jun 16 15:59 error.log
>-rwxrwxrwx 1  root    root    1000008 Jun 16 15:45 error.log.1
>-rwxrwxrwx 1 root    root    1000046 Jun 16 15:23 error.log.2
>-rwxrwxrwx 1 root    root    1000078 Jun 16 15:01 error.log.3
>-rwxrwxrwx 1 root    root    1000062 Jun 16 14:39 error.log.4
>-rwxrwxrwx 1 root    root    1000149 Jun 16 14:17 error.log.5
>-rwxrwxrwx 1 root    root    1000048 Jun 16 13:55 error.log.6
>
>what to do????
>

Well... what is being logged so furiously? :) I'm guessing there is some 
chronically bad condition that's causing this much logging, so having a peek 
into the error.logs would be useful at this point.

-dre


>thnx,
>lisa
>
>
>
>
>
________________________________
From: Morgen Sagen <sagen at apple.com>
>To: Lisana Berberi <lisanaberberi at yahoo.com>
>Cc: Calendar Server 
><calendarserver-users at lists.macosforge.org>; calendarserver-dev at lists.macosforge.org
>
>Sent: Thu, June 16, 2011 1:05:08 AM
>Subject: Re: [CalendarServer-users] Strange  Rotation of Log files in caldavd
>
>
>On Jun 15, 2011, at 4:04 PM, Morgen Sagen wrote:
>> What do you have for "LogLevel" setting in caldavd.plist?  "debug" is a very 
>>verbose level.  Try "warn", and that should keep your error.logs from growing 
>>quickly.  As for your access.logs, is your server very busy?  :-)
>
>Sorry, I meant "DefaultLogLevel", not "LogLevel"
>_______________________________________________
>calendarserver-dev mailing list
>calendarserver-dev at lists.macosforge.org
>http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-dev
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.macosforge.org/pipermail/calendarserver-dev/attachments/20110621/dcce87a1/attachment-0001.html>


More information about the calendarserver-dev mailing list