Calendar Server Reported Error: Operation CalDAVAccountRefreshQueueableOperation

Question

Level 1

6 points

Calendar Server Reported Error: Operation CalDAVAccountRefreshQueueableOperation

I have setup several Users on a MAC OS X Server Lion with the sole purpose to use their Calendar and Addressbook as repository for calendar and address information for the Mac, Macbook and iPhone and shared calendars for all users of the OD. This worked quite well for a time. It seems to have stopped working a while ago (noone realised it because the individual device did not show an obvious problem and had all the required dates), definitively after the Update to 10.7.2, but presumably a couple of weeks before. To my knowledge I did not change anything beyond applying the apple updates.

One of the clients shows the dates of a shared calendar multiple times (depending on date up to 7 times, but mostly 3 times), the mobile devices and the other iMac only once.

Upon looking at the calendars (even as Admin on the Mac Server itself) the error message displayed is

Calendar Server Reported Error

Operation CalDAVAccountRefreshQueueableOperation

I hope there is a solution as we lost qiute a few dates on the iPhones during update to iOS 5 (the shared calendar data are apparently not backed-up).

Mac OS X (10.7.1), Server

Posted on Oct 16, 2011 10:10 AM

Reply

Answer 1

mappler

Level 1

0 points

Oct 17, 2011 10:42 AM in response to aef5023

same problem here!

CalDAVAccountRefreshQueueableOperation after 10.7.2 update.

problem exists with os x and ios clients.

please help!

Reply

Answer 2

Oct 17, 2011 3:31 PM in response to aef5023

this same thing happened to me.

when the server restarted after the update, I could log in and open the Server app (and the Server Admin app) -- checked iCal settings and appeared that nothing had changed. I stopped and restarted iCal with the same results.

I decided to restart the server and now I cannot log into any server apps - I just get the shaking window for bad username/password. everyhting seems to be working except iCal - doesnt work from clients or from the wiki

Reply

Answer 3

mappler

Level 1

0 points

Oct 19, 2011 10:21 AM in response to bsibrandon

I decided to restart the server and now I cannot log into any server apps - I just get the shaking window for bad username/password.

possible solutions:

But the iCal problem remains... any help?

Reply

Answer 4

Oct 20, 2011 7:07 AM in response to mappler

Please post the contents of the /var/log/caldavd/error.log covering the time when the error is seen on the client. Hopefully that will show some kind of traceback or an error message that might suggest what is going on here.

Reply

Answer 5

Oct 20, 2011 8:24 AM in response to Cyrus Daboo

Here is the last part of the log...

...on a side note, since the update to 10.7.2, along with iCal being broken, the server's fans kick into high gear intermittently, at least once an hour - this was not the case prior to the update.

I refreshed all calendars in iCal then took the following from the error log:

2011-10-20 08:14:28-0700 [-] [mailgateway] 2011-10-20 08:14:28-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-20 08:14:28-0700 [-] [mailgateway] 2011-10-20 08:14:28-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-20 08:14:58-0700 [-] [mailgateway] 2011-10-20 08:14:58-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-20 08:14:58-0700 [-] [mailgateway] 2011-10-20 08:14:58-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-20 08:15:01-0700 [-] [caldav-0] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:01-0700 [-] [caldav-0] ]

2011-10-20 08:15:02-0700 [-] [caldav-2] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:02-0700 [-] [caldav-2] ]

2011-10-20 08:15:03-0700 [-] [caldav-0] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:03-0700 [-] [caldav-0] ]

2011-10-20 08:15:04-0700 [-] [caldav-2] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:04-0700 [-] [caldav-2] ]

2011-10-20 08:15:05-0700 [-] [caldav-0] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:05-0700 [-] [caldav-0] ]

2011-10-20 08:15:05-0700 [-] [caldav-2] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:05-0700 [-] [caldav-2] ]

2011-10-20 08:15:06-0700 [-] [caldav-0] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:06-0700 [-] [caldav-0] ]

2011-10-20 08:15:06-0700 [-] [caldav-2] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:06-0700 [-] [caldav-2] ]

2011-10-20 08:15:07-0700 [-] [caldav-0] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:07-0700 [-] [caldav-0] ]

2011-10-20 08:15:07-0700 [-] [caldav-2] [QueryProtocol,client] [twext.web2.server#error] [Failure instance: Traceback (failure with no frames): <type 'exceptions.ValueError'>: ('302', 'Found')

2011-10-20 08:15:07-0700 [-] [caldav-2] ]

2011-10-20 08:15:28-0700 [-] [mailgateway] 2011-10-20 08:15:28-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-20 08:15:28-0700 [-] [mailgateway] 2011-10-20 08:15:28-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-20 08:15:55-0700 [-] [caldav-3] [PooledMemCacheProtocol,client] [twistedcaldav.directory.principal#error] No principal found for UID: 8383CE17-684C-4E27-BEE6-556E8953462B

2011-10-20 08:15:58-0700 [-] [mailgateway] 2011-10-20 08:15:58-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-20 08:15:58-0700 [-] [mailgateway] 2011-10-20 08:15:58-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

Reply

Answer 6

Oct 20, 2011 8:58 AM in response to bsibrandon

Please try this: pull up a Terminal window and do:

sudo webappctl status -

grep webcal /var/log/caldavd/server*

Please post the output of both of those. At this point I suspect a problem with the apache reverse proxy which is supposed to redirect requests on port 80/443 to the actual calendar server's 8008/8443 ports. Without that proxy redirecting, you typically get a 405 response in the client.

Reply

Answer 7

Oct 20, 2011 9:16 AM in response to Cyrus Daboo

Here are the results from both those commands:

web:webAppState:_array_index:0:state = "RUNNING"

web:webAppState:_array_index:0:virtualHostName = ""

web:webAppState:_array_index:0:webAppName = "com.apple.webapp.ACSServer"

web:webAppState:_array_index:1:state = "RUNNING"

web:webAppState:_array_index:1:virtualHostName = ""

web:webAppState:_array_index:1:webAppName = "com.apple.webapp.auth"

web:webAppState:_array_index:2:state = "RUNNING"

web:webAppState:_array_index:2:virtualHostName = ""

web:webAppState:_array_index:2:webAppName = "com.apple.webapp.devicemgr"

web:webAppState:_array_index:3:state = "RUNNING"

web:webAppState:_array_index:3:virtualHostName = ""

web:webAppState:_array_index:3:webAppName = "com.apple.webapp.collab"

web:webAppState:_array_index:4:state = "RUNNING"

web:webAppState:_array_index:4:virtualHostName = ""

web:webAppState:_array_index:4:webAppName = "com.apple.webapp.webmailserver"

web:webAppState:_array_index:5:state = "RUNNING"

web:webAppState:_array_index:5:virtualHostName = ""

web:webAppState:_array_index:5:webAppName = "com.apple.webapp.php"

web:webAppState:_array_index:6:state = "RUNNING"

web:webAppState:_array_index:6:virtualHostName = ""

web:webAppState:_array_index:6:webAppName = "com.apple.webapp.webcalssl"

web:webAppState:_array_index:7:state = "RUNNING"

web:webAppState:_array_index:7:virtualHostName = ""

web:webAppState:_array_index:7:webAppName = "org.calendarserver"

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:16:20 PM PDT : setWebAppState com.apple.webapp.webcalssl STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:16:20 PM PDT : Current com.apple.webapp.webcalssl WebAppState: {

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:16:20 PM PDT : Changing com.apple.webapp.webcalssl WebAppState to STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:16:44 PM PDT : setWebAppState com.apple.webapp.webcal STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:16:44 PM PDT : Current com.apple.webapp.webcal WebAppState: {

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:17:19 PM PDT : setWebAppState com.apple.webapp.webcal STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:17:19 PM PDT : Current com.apple.webapp.webcal WebAppState: {

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:17:19 PM PDT : setWebAppState com.apple.webapp.webcalssl START

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:17:19 PM PDT : Current com.apple.webapp.webcalssl WebAppState: {

/var/log/caldavd/servermgr_calendar.log:[cal] 9/21/11 4:17:19 PM PDT : Changing com.apple.webapp.webcalssl WebAppState to START

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 1:59:00 PM PDT : setWebAppState setting com.apple.webapp.webcalssl to STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 1:59:03 PM PDT : setWebAppState setting com.apple.webapp.webcal to STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 1:59:23 PM PDT : setWebAppState setting com.apple.webapp.webcal to STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 1:59:25 PM PDT : setWebAppState setting com.apple.webapp.webcalssl to START

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 8:12:49 PM PDT : setWebAppState setting com.apple.webapp.webcalssl to STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 8:12:53 PM PDT : setWebAppState setting com.apple.webapp.webcal to STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 8:12:54 PM PDT : setWebAppState setting com.apple.webapp.webcal to STOP

/var/log/caldavd/servermgr_calendar.log:[cal] 10/17/11 8:12:55 PM PDT : setWebAppState setting com.apple.webapp.webcalssl to START

Reply

Answer 8

Oct 20, 2011 10:11 AM in response to bsibrandon

Sorry, more information needed: if you grep your system.log for 'CalDAVAccountRefreshQueueableOperation' on the affected client machines, can you post aal the iCal: entries around that error?

Reply

Answer 9

Oct 20, 2011 11:24 AM in response to Cyrus Daboo

I didn't find anything specific to CalDAVAccountRefreshQueueableOperation in the system.log on the client machines, but here is the part from the system.log on the client when I try to refresh the calendar:

Oct 20 10:56:55 Brandon AddressBookSourceSync[18199]: Subscription request completed

Oct 20 10:58:57: --- last message repeated 3 times ---

Oct 20 10:58:57 Brandon iCal[16495]: Account refresh failed with error: Error Domain=CoreDAVHTTPStatusErrorDomain Code=500 "The operation couldn’t be completed. (CoreDAVHTTPStatusErrorDomain error 500.)" UserInfo=0x7f98fea83ee0 {CalDAVErrFromRefresh=YES, CoreDAVHTTPHeaders=<CFBasicHash 0x7f98ffbb00c0 [0x7fff79290f40]>{type = immutable dict, count = 9,

entries =>

0 : Case Insensitive Key: Server = <CFString 0x7f98feacce60 [0x7fff79290f40]>{contents = "CalendarServer/3.1(iCalServerv14.80.52) Twisted/11.0.0 TwistedWeb/9.0.0"}

1 : Case Insensitive Key: Content-Type = <CFString 0x7fff79287b60 [0x7fff79290f40]>{contents = "text/html"}

2 : Case Insensitive Key: MS-Author-Via = <CFString 0x7f98fea50cd0 [0x7fff79290f40]>{contents = "DAV"}

3 : Case Insensitive Key: Connection = <CFString 0x7f98fea14ed0 [0x7fff79290f40]>{contents = "close"}

6 : Case Insensitive Key: Date = <CFString 0x7f98fb3d9dd0 [0x7fff79290f40]>{contents = "Thu, 20 Oct 2011 17:58:58 GMT"}

7 : Case Insensitive Key: X-Frame-Options = <CFString 0x7f98fb3e2560 [0x7fff79290f40]>{contents = "SameOrigin"}

8 : Case Insensitive Key: Content-Encoding = <CFString 0x7f98fea5ce30 [0x7fff79290f40]>{contents = "gzip"}

10 : Case Insensitive Key: Content-Length = <CFString 0x7f98fea33680 [0x7fff79290f40]>{contents = "152"}

12 : Case Insensitive Key: Vary = <CFString 0x7f98feac0b00 [0x7fff79290f40]>{contents = "Accept-Encoding,User-Agent"}

}

, AccountName=[MYACCOUNTNAME]}

Oct 20 10:59:00 Brandon iCal[16495]: Account refresh failed with error: Error Domain=CoreDAVHTTPStatusErrorDomain Code=500 "The operation couldn’t be completed. (CoreDAVHTTPStatusErrorDomain error 500.)" UserInfo=0x7f98ff9abc80 {CalDAVErrFromRefresh=YES, CoreDAVHTTPHeaders=<CFBasicHash 0x7f98ffa048a0 [0x7fff79290f40]>{type = immutable dict, count = 9,

entries =>

0 : Case Insensitive Key: Server = <CFString 0x7f9900a19160 [0x7fff79290f40]>{contents = "CalendarServer/3.1(iCalServerv14.80.52) Twisted/11.0.0 TwistedWeb/9.0.0"}

1 : Case Insensitive Key: Content-Type = <CFString 0x7fff79287b60 [0x7fff79290f40]>{contents = "text/html"}

2 : Case Insensitive Key: MS-Author-Via = <CFString 0x7f99003ddf50 [0x7fff79290f40]>{contents = "DAV"}

3 : Case Insensitive Key: Connection = <CFString 0x7f98ffa6a440 [0x7fff79290f40]>{contents = "close"}

6 : Case Insensitive Key: Date = <CFString 0x7f98ffa81270 [0x7fff79290f40]>{contents = "Thu, 20 Oct 2011 17:59:00 GMT"}

7 : Case Insensitive Key: X-Frame-Options = <CFString 0x7f98fd66b370 [0x7fff79290f40]>{contents = "SameOrigin"}

8 : Case Insensitive Key: Content-Encoding = <CFString 0x7f98ffa6e300 [0x7fff79290f40]>{contents = "gzip"}

10 : Case Insensitive Key: Content-Length = <CFString 0x7f9900376400 [0x7fff79290f40]>{contents = "152"}

12 : Case Insensitive Key: Vary = <CFString 0x7f990030f8f0 [0x7fff79290f40]>{contents = "Accept-Encoding,User-Agent"}

}

, AccountName=[MYACCOUNTNAME]}

Oct 20 10:59:01 Brandon iCal[16495]: Account refresh failed with error: Error Domain=CoreDAVHTTPStatusErrorDomain Code=500 "The operation couldn’t be completed. (CoreDAVHTTPStatusErrorDomain error 500.)" UserInfo=0x7f98ff9366e0 {CalDAVErrFromRefresh=YES, CoreDAVHTTPHeaders=<CFBasicHash 0x7f98fd675bc0 [0x7fff79290f40]>{type = immutable dict, count = 9,

entries =>

0 : Case Insensitive Key: Server = <CFString 0x7f98fd646a70 [0x7fff79290f40]>{contents = "CalendarServer/3.1(iCalServerv14.80.52) Twisted/11.0.0 TwistedWeb/9.0.0"}

1 : Case Insensitive Key: Content-Type = <CFString 0x7fff79287b60 [0x7fff79290f40]>{contents = "text/html"}

2 : Case Insensitive Key: MS-Author-Via = <CFString 0x7f990035b8c0 [0x7fff79290f40]>{contents = "DAV"}

3 : Case Insensitive Key: Connection = <CFString 0x7f98fd6723d0 [0x7fff79290f40]>{contents = "close"}

6 : Case Insensitive Key: Date = <CFString 0x7f99003bc050 [0x7fff79290f40]>{contents = "Thu, 20 Oct 2011 17:59:01 GMT"}

7 : Case Insensitive Key: X-Frame-Options = <CFString 0x7f98fb60e070 [0x7fff79290f40]>{contents = "SameOrigin"}

8 : Case Insensitive Key: Content-Encoding = <CFString 0x7f9900358a90 [0x7fff79290f40]>{contents = "gzip"}

10 : Case Insensitive Key: Content-Length = <CFString 0x7f99003e6750 [0x7fff79290f40]>{contents = "152"}

12 : Case Insensitive Key: Vary = <CFString 0x7f98ff9faf30 [0x7fff79290f40]>{contents = "Accept-Encoding,User-Agent"}

}

, AccountName=[MYACCOUNTNAME]}

Oct 20 10:59:02 Brandon iCal[16495]: Account refresh failed with error: Error Domain=CoreDAVHTTPStatusErrorDomain Code=500 "The operation couldn’t be completed. (CoreDAVHTTPStatusErrorDomain error 500.)" UserInfo=0x7f98ffd6b910 {CalDAVErrFromRefresh=YES, CoreDAVHTTPHeaders=<CFBasicHash 0x7f98fea8b3b0 [0x7fff79290f40]>{type = immutable dict, count = 9,

entries =>

0 : Case Insensitive Key: Server = <CFString 0x7f98fea810f0 [0x7fff79290f40]>{contents = "CalendarServer/3.1(iCalServerv14.80.52) Twisted/11.0.0 TwistedWeb/9.0.0"}

1 : Case Insensitive Key: Content-Type = <CFString 0x7fff79287b60 [0x7fff79290f40]>{contents = "text/html"}

2 : Case Insensitive Key: MS-Author-Via = <CFString 0x7f98fea8cf80 [0x7fff79290f40]>{contents = "DAV"}

3 : Case Insensitive Key: Connection = <CFString 0x7f98fea9c780 [0x7fff79290f40]>{contents = "close"}

6 : Case Insensitive Key: Date = <CFString 0x7f98fea7dc70 [0x7fff79290f40]>{contents = "Thu, 20 Oct 2011 17:59:02 GMT"}

7 : Case Insensitive Key: X-Frame-Options = <CFString 0x7f98fea6dad0 [0x7fff79290f40]>{contents = "SameOrigin"}

8 : Case Insensitive Key: Content-Encoding = <CFString 0x7f98fea9f9f0 [0x7fff79290f40]>{contents = "gzip"}

10 : Case Insensitive Key: Content-Length = <CFString 0x7f98fea92850 [0x7fff79290f40]>{contents = "152"}

12 : Case Insensitive Key: Vary = <CFString 0x7f98fea67ee0 [0x7fff79290f40]>{contents = "Accept-Encoding,User-Agent"}

}

, AccountName=[MYACCOUNTNAME]}

Reply

Answer 10

aef5023 Author

Level 1

6 points

Oct 20, 2011 12:03 PM in response to Cyrus Daboo

If I do your questions on my system, this is the out of your various questions. I eliminated repetitive stuff and things clearly not connected with the issue

significant Output from: grep CalDAV /var/log/system.log (there are many repetitions for this, only one copied. As bsibrandon mentions, the complete message term does not yield anything. Strictly speaking I use the iCAL client on the server itself, but the output on the client is sort of identical)

Oct 20 20:38:47 SharkServer iCal[1462]: Account refresh failed with error: Error Domain=NSURLErrorDomain Code=-1004 "Could not connect to the server." UserInfo=0x10b3e7600 {NSLocalizedDescription=Could not connect to the server., NSErrorFailingURLStringKey=https://Shared@sharkserver.local:8443/principals/__uids__/88DAB90C-BD13-430D-BD5 3-640295033DF9/, NSErrorFailingURLKey=https://Shared@sharkserver.local:8443/principals/__uids__/88DAB90C-BD13-430D-BD5 3-640295033DF9/, AccountName=Familienkalender, CalDAVErrFromRefresh=YES, NSUnderlyingError=0x7fd7d3c22ce0 "Could not connect to the server."}

significant output from: grep webcal /var/log/caldavd/server*

[cal] 10/20/11 8:11:32 PM GMT+02:00 : setWebAppState setting com.apple.webapp.webcalssl to STOP

[cal] 10/20/11 8:11:37 PM GMT+02:00 : setWebAppState setting com.apple.webapp.webcal to STOP

[cal] 10/20/11 8:15:38 PM GMT+02:00 : setWebAppState setting com.apple.webapp.webcal to STOP

[cal] 10/20/11 8:15:39 PM GMT+02:00 : setWebAppState setting com.apple.webapp.webcalssl to START

significant output from: cat /var/log/caldavd/error.log (first entry after server boot, no client interaction. Server can be stopped, log deleted, same error log is created while still stopped. Starting and connecting the server local iCal Client Calendar does not change anything, the repetition part below is just repeated, the caldav server start is listed below the repetition part)

2011-10-20 19:59:39+0200 [-] Log opened.

2011-10-20 19:59:39+0200 [-] twistd 11.0.0 (/System/Library/Frameworks/Python.framework/Versions/2.7/Resources/Python.app/ Contents/MacOS/Python 2.7.1) starting up.

2011-10-20 19:59:39+0200 [-] reactor class: twext.internet.kqreactor.KQueueReactor.

2011-10-20 19:59:39+0200 [-] calendarserver.accesslog.AMPLoggingFactory starting on "'/var/run/caldavd/caldavd.sock'"

2011-10-20 19:59:39+0200 [-] calendarserver.tap.caldav.CalDAVStatisticsServer starting on "'/var/run/caldavd-stats.sock'"

2011-10-20 19:59:39+0200 [-] [txdav.common.datastore.util.UpgradeDatabaseSchemaService#warn] Beginning database schema check.

2011-10-20 19:59:39+0200 [-] [txdav.common.datastore.util.UpgradeDatabaseSchemaService#warn] Required schema version: 3.

2011-10-20 19:59:39+0200 [-] Re-trying connection due to connection failure

Traceback (most recent call last):

File "/System/Library/Frameworks/Python.framework/Versions/2.7/Extras/lib/python/twi sted/python/context.py", line 118, in callWithContext

return self.currentContext().callWithContext(ctx, func, *args, **kw)

File "/System/Library/Frameworks/Python.framework/Versions/2.7/Extras/lib/python/twi sted/python/context.py", line 81, in callWithContext

return func(*args,**kw)

File "/usr/share/caldavd/lib/python/twext/internet/threadutils.py", line 49, in _run

work = self._q.get()

File "/usr/share/caldavd/lib/python/twext/internet/threadutils.py", line 72, in _oneWorkUnit

self._reactor.callFromThread(deferred.callback, result)

--- <exception caught here> ---

File "/usr/share/caldavd/lib/python/twext/internet/threadutils.py", line 64, in _oneWorkUnit

result = instruction()

File "/usr/share/caldavd/lib/python/twext/enterprise/adbapi2.py", line 806, in initCursor

connection = self.connectionFactory()

File "/usr/share/caldavd/lib/python/txdav/base/datastore/dbapiclient.py", line 196, in connect

connection = self.dbModule.connect(*self.connectArgs, **self.connectKw)

File "/usr/share/caldavd/lib/python/pgdb.py", line 482, in connect

dbtty, dbuser, dbpasswd)

pg.InternalError: could not connect to server: No such file or directory

Is the server running locally and accepting

connections on Unix domain socket "/var/pgsql_socket/.s.PGSQL.5432"?

/* this repeats itself umpteen times, he log grows steadily */

2011-10-20 19:59:49+0200 [-] Re-trying connection due to connection failure

Traceback (most recent call last):

File "/System/Library/Frameworks/Python.framework/Versions/2.7/Extras/lib/python/twi sted/python/context.py", line 118, in callWithContext

return self.currentContext().callWithContext(ctx, func, *args, **kw)

File "/System/Library/Frameworks/Python.framework/Versions/2.7/Extras/lib/python/twi sted/python/context.py", line 81, in callWithContext

return func(*args,**kw)

File "/usr/share/caldavd/lib/python/twext/internet/threadutils.py", line 49, in _run

work = self._q.get()

File "/usr/share/caldavd/lib/python/twext/internet/threadutils.py", line 72, in _oneWorkUnit

self._reactor.callFromThread(deferred.callback, result)

--- <exception caught here> ---

File "/usr/share/caldavd/lib/python/twext/internet/threadutils.py", line 64, in _oneWorkUnit

result = instruction()

File "/usr/share/caldavd/lib/python/twext/enterprise/adbapi2.py", line 806, in initCursor

connection = self.connectionFactory()

File "/usr/share/caldavd/lib/python/txdav/base/datastore/dbapiclient.py", line 196, in connect

connection = self.dbModule.connect(*self.connectArgs, **self.connectKw)

File "/usr/share/caldavd/lib/python/pgdb.py", line 482, in connect

dbtty, dbuser, dbpasswd)

pg.InternalError: could not connect to server: No such file or directory

Is the server running locally and accepting

connections on Unix domain socket "/var/pgsql_socket/.s.PGSQL.5432"?

the Log part where the CalDAV server is started (manually from Server applet)

2011-10-20 20:15:47+0200 [-] Received SIGTERM, shutting down.

2011-10-20 20:15:47+0200 [-] Unhandled error in Deferred:

2011-10-20 20:15:47+0200 [-] Unhandled Error

Traceback (most recent call last):

Failure: twext.enterprise.ienterprise.ConnectionError:

2011-10-20 20:15:47+0200 [-] (UNIX Port '/var/run/caldavd-stats.sock' Closed)

2011-10-20 20:15:47+0200 [-] (UNIX Port '/var/run/caldavd/caldavd.sock' Closed)

2011-10-20 20:15:47+0200 [-] Main loop terminated.

2011-10-20 20:15:47+0200 [-] Server Shut Down.

2011-10-20 20:15:52+0200 [-] Log opened.

2011-10-20 20:15:52+0200 [-] twistd 11.0.0 (/System/Library/Frameworks/Python.framework/Versions/2.7/Resources/Python.app/ Contents/MacOS/Python 2.7.1) starting up.

2011-10-20 20:15:52+0200 [-] reactor class: twext.internet.kqreactor.KQueueReactor.

2011-10-20 20:15:52+0200 [-] calendarserver.accesslog.AMPLoggingFactory starting on "'/var/run/caldavd/caldavd.sock'"

2011-10-20 20:15:52+0200 [-] calendarserver.tap.caldav.CalDAVStatisticsServer starting on "'/var/run/caldavd-stats.sock'"

2011-10-20 20:15:52+0200 [-] [txdav.common.datastore.util.UpgradeDatabaseSchemaService#warn] Beginning database schema check.

2011-10-20 20:15:52+0200 [-] [txdav.common.datastore.util.UpgradeDatabaseSchemaService#warn] Required schema version: 3.

2011-10-20 20:15:52+0200 [-] Re-trying connection due to connection failure

I hope this helps

Reply

Answer 11

aef5023 Author

Level 1

6 points

Oct 20, 2011 12:08 PM in response to aef5023

Significant output for:sudo webappctl status -

web:webAppState:_array_index:0:state = "RUNNING"

web:webAppState:_array_index:0:virtualHostName = ""

web:webAppState:_array_index:0:webAppName = "com.apple.webapp.collab"

web:webAppState:_array_index:1:state = "RUNNING"

web:webAppState:_array_index:1:virtualHostName = ""

web:webAppState:_array_index:1:webAppName = "com.apple.webapp.auth"

web:webAppState:_array_index:2:state = "RUNNING"

web:webAppState:_array_index:2:virtualHostName = ""

web:webAppState:_array_index:2:webAppName = "com.apple.webapp.ACSServer"

web:webAppState:_array_index:3:state = "RUNNING"

web:webAppState:_array_index:3:virtualHostName = ""

web:webAppState:_array_index:3:webAppName = "com.apple.webapp.webdavsharing"

web:webAppState:_array_index:4:state = "RUNNING"

web:webAppState:_array_index:4:virtualHostName = ""

web:webAppState:_array_index:4:webAppName = "com.apple.webapp.webcalssl"

web:webAppState:_array_index:5:state = "RUNNING"

web:webAppState:_array_index:5:virtualHostName = ""

web:webAppState:_array_index:5:webAppName = "org.calendarserver"

Reply

Answer 12

Oct 20, 2011 12:11 PM in response to aef5023

pg.InternalError: could not connect to server - that implies postgres is not running on your system. Try:

sudo serveradmin status postgres

Does it show as "RUNNING"? If not, do:

sudo serveradmin start postgres

If that does not fix it you need to look at systems logs for postgres spseicifc startup problems (though I have no idea what those could be).

Reply

Answer 13

aef5023 Author

Level 1

6 points

Oct 20, 2011 12:15 PM in response to Cyrus Daboo

In my case postgres is running

Reply

Answer 14

Oct 20, 2011 12:18 PM in response to bsibrandon

If you are seeing 500 errors, then they should be showing up in the /var/log/caldavd/error.log file on the server. Or perhaps in /var/log/apache2/error_log. Is there any evidence of that?

What happens if in Safari you go to:

https://<<your os x server hostname>>:8443

http://<<your os x server hostname>>:8008

https://<<your os x server hostname>>

http://<<your os x server hostname>>

If you look at the account details in iCal, does the server hostname match what you expect? What is the port set to?

Reply

Answer 15

Oct 20, 2011 12:24 PM in response to aef5023

What do these show:

ps aux | grep postgres

ps aux | grep caldavd

sudo ls -l /var/pgsql_socket/

Reply