Calendar Server Reported Error: Operation CalDAVAccountRefreshQueueableOperation

Any help is greatly appreciated...

I have followed these steps:

(Shut down all services first)

sudo serveradmin stop postgres

sudo mv /var/pgsql /var/pgsql.broken

suco cp -r /var/pgsql.pre-restore-[my .pre-restore file] /var/pgsql

chown -R _postgres:_postgres pgsql

sudo serveradmin start postgres

sudo -u _postgres psql -U caldav caldav

postgres starts and I get a shell with the last command but iCal is still broken. This is a fairly new server and I only have 1 pre-restore -- nothing prior to just before I installed the 10.7.2 update.

I have looked at my Time Machine backups but can't seem to find any files prior to the 10.7.2 update that are "pgsql.pre-restore*"

I have re-started the server a few times, stoped/started services, let sit for a couple days, but iCal doesn't suddenly or magically start working in my case.

If the calendar server does not appear to start, then please always post details from /var/log/caldavd/error.log so we can try and diagnose the problem.

Thank you.

Here is the last 10 minutes of /car/log/caldavd/error.log -- and just before I copied this, I tried to go online with an iCal account from a client machine:

2011-10-27 08:19:24-0700 [-] [mailgateway] 2011-10-27 08:19:24-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:19:24-0700 [-] [mailgateway] 2011-10-27 08:19:24-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-27 08:19:54-0700 [-] [mailgateway] 2011-10-27 08:19:54-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:19:54-0700 [-] [mailgateway] 2011-10-27 08:19:54-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

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

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

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

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

2011-10-27 08:21:25-0700 [-] [mailgateway] 2011-10-27 08:21:25-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:21:25-0700 [-] [mailgateway] 2011-10-27 08:21:25-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-27 08:21:55-0700 [-] [mailgateway] 2011-10-27 08:21:55-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:21:55-0700 [-] [mailgateway] 2011-10-27 08:21:55-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

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

2011-10-27 08:21:55-0700 [-] [caldav-2] ]

2011-10-27 08:22:25-0700 [-] [mailgateway] 2011-10-27 08:22:25-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:22:25-0700 [-] [mailgateway] 2011-10-27 08:22:25-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-27 08:22:55-0700 [-] [mailgateway] 2011-10-27 08:22:55-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:22:55-0700 [-] [mailgateway] 2011-10-27 08:22:55-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-27 08:23:25-0700 [-] [mailgateway] 2011-10-27 08:23:25-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:23:25-0700 [-] [mailgateway] 2011-10-27 08:23:25-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-27 08:23:56-0700 [-] [mailgateway] 2011-10-27 08:23:56-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:23:56-0700 [-] [mailgateway] 2011-10-27 08:23:56-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-27 08:24:26-0700 [-] [mailgateway] 2011-10-27 08:24:26-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:24:26-0700 [-] [mailgateway] 2011-10-27 08:24:26-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

2011-10-27 08:24:56-0700 [-] [mailgateway] 2011-10-27 08:24:56-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:24:56-0700 [-] [mailgateway] 2011-10-27 08:24:56-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

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

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

2011-10-27 08:25:57-0700 [-] [mailgateway] 2011-10-27 08:25:57-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:25:57-0700 [-] [mailgateway] 2011-10-27 08:25:57-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

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

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

2011-10-27 08:26:42-0700 [-] [caldav-2] [PooledMemCacheProtocol,client] [twistedcaldav.directory.principal#error] No principal found for UID: 8383CE17-684C-4E27-BEE6-556E8953462B

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

2011-10-27 08:26:53-0700 [-] [caldav-1] ]

2011-10-27 08:26:56-0700 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.principal#error] No principal found for UID: 5636C23E-CF0B-4781-A94D-5B430A956654

2011-10-27 08:26:56-0700 [-] [caldav-2] [PooledMemCacheProtocol,client] [twistedcaldav.directory.principal#error] No principal found for UID: 3B949A35-D1D0-4BFC-AEA2-449CB300F322

2011-10-27 08:26:57-0700 [-] [mailgateway] 2011-10-27 08:26:57-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-10-27 08:26:57-0700 [-] [mailgateway] 2011-10-27 08:26:57-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

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

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

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

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

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

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

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

2011-10-27 08:28:40-0700 [-] [caldav-3] ]

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

2011-10-27 08:28:46-0700 [-] [caldav-2] ]

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

2011-10-27 08:28:46-0700 [-] [caldav-3] ]

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

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

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

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

Anybody? Anything?

I've disabled iCal for users, restarted, re-enabled iCal, removed iCal accounts from all clients, restarted client machines and the server -- same errors as above.

Was there a resolution to the bug that was filed with Apple?

So you caldavd log appears to show a calendar service that is running. On that machine, run Safari and try one of this urls:

If you use SSL: https://<<yourhost>>:8443

Without SSL: http://<<yourhost>>:8008

Do those work? If so, try the same from a client machine.

I do use SSL.

https://<myhost>:8443 takes me a "Collection Listing" page:

http://<myhost>:8008 redirects me to https://<myhost>:8443

same result whether from the server or client machine.

OK, so calendar server is functioning at least as far as that fron page. Now try:

https://<myhost>:8443/principals/users/<<uid>>

where <<uid>> is the user login id of a user on the system. You should get prompted to authenticate in Safari - do so. Does that page come up? Make sure to do that from a client machine, not the server.

Yes, that page comes up as well, similar to the Collection Listing, but starts with "Principal Details" and a bunch of info about the user account.

OK, so configure an account in iCal for that user. Enter <<uid>>@<<yourhost> as the EMail Address for the account.

I get the same error with an existing user:

The server responded with

“500”

to operation CalDAVAccountRefreshQueueableOperation.

weird thing - I made a test user account through server.app and then connected that account to iCal on a client machine and it does NOT give the error.

All of the user accounts that are on the system (for my entire company) prior to 10.7.2 update give the error.

OK, so now post the part of the /var/log/caldavd/error.log that covers that error. There ought to be a traceback in there showing what happened. Also check /var/log/caldavd/access.log to see if there is an entry in there from that client with a 500 status code.

Ok, here is part of the error.log:

2011-11-02 09:08:31-0700 [-] [mailgateway] 2011-11-02 09:08:31-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-11-02 09:08:31-0700 [-] [mailgateway] 2011-11-02 09:08:31-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

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

2011-11-02 09:08:36-0700 [-] [caldav-1] ]

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

2011-11-02 09:08:39-0700 [-] [caldav-3] ]

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

2011-11-02 09:08:39-0700 [-] [caldav-1] ]

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

2011-11-02 09:08:45-0700 [-] [caldav-2] ]

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

2011-11-02 09:08:46-0700 [-] [caldav-3] ]

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

2011-11-02 09:08:48-0700 [-] [caldav-2] ]

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

2011-11-02 09:08:48-0700 [-] [caldav-3] ]

2011-11-02 09:09:01-0700 [-] [mailgateway] 2011-11-02 09:09:01-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1'], 'Highest']

2011-11-02 09:09:01-0700 [-] [mailgateway] 2011-11-02 09:09:01-0700 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['URLMECH', 'INTERNAL'], 'Mechanisms', 'supported']

...I'll post part of the access.log in a minute...

...and here is the access log:

192.168.188.120 - brandon [02/Nov/2011:09:08:35 -0700] "PROPFIND / HTTP/1.1" 207 593 "-" "CoreDAV/115.19 (11C74)" i=1 t=16.3 or=1

127.0.0.1 - - [02/Nov/2011:09:08:35 -0700] "PROPFIND /principals/users/brandon/ HTTP/1.1" 401 141 "-" "CoreDAV/115.19 (11C74)" i=2 t=3.8 or=1 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:35 -0700] "PROPFIND /principals/users/brandon/ HTTP/1.1" 207 641 "-" "CoreDAV/115.19 (11C74)" i=3 t=15.5 or=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - - [02/Nov/2011:09:08:35 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 401 141 "-" "CoreDAV/115.19 (11C74)" i=3 t=4.8 or=1 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:35 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 3673 "-" "CoreDAV/115.19 (11C74)" i=2 t=20.6 or=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:35 -0700] "OPTIONS /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 200 0 "-" "CoreDAV/115.19 (11C74)" i=2 t=11.5 or=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:35 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 3673 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=14.4 or=1 cached=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:35 -0700] "OPTIONS /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 200 0 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=12.8 or=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - - [02/Nov/2011:09:08:35 -0700] "REPORT /principals/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=3.8 or=1 fwd=192.168.188.120

127.0.0.1 - - [02/Nov/2011:09:08:35 -0700] "PROPFIND /calendars/__uids__/42E46199-09C7-419F-823C-5CF39AE30270/inbox/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=1 t=3.4 or=2 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:35 -0700] "REPORT(DAV:principal-search-property-set) /principals/ HTTP/1.1" 200 1362 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=11.2 or=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - testtesting [02/Nov/2011:09:08:35 -0700] "PROPFIND /calendars/__uids__/42E46199-09C7-419F-823C-5CF39AE30270/inbox/ HTTP/1.1" 207 1164 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=1 t=25.3 or=2 responses=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - - [02/Nov/2011:09:08:35 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=1 t=3.5 or=2 fwd=192.168.188.120

127.0.0.1 - testtesting [02/Nov/2011:09:08:35 -0700] "PROPFIND /calendars/__uids__/42E46199-09C7-419F-823C-5CF39AE30270/inbox/ HTTP/1.1" 207 1164 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=20.6 or=2 responses=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:36 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=1 t=670.4 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:36 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 551 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=19.0 or=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:36 -0700] "PROPFIND /principals/ HTTP/1.1" 207 505 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=12.6 or=1 responses=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:39 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=77.5 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:39 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 3673 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=11.7 or=2 cached=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:39 -0700] "OPTIONS /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 200 0 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=14.2 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:39 -0700] "REPORT(DAV:principal-search-property-set) /principals/ HTTP/1.1" 200 1362 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=12.4 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:39 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=1 t=70.0 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:39 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 551 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=4.8 or=1 cached=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:39 -0700] "PROPFIND /principals/ HTTP/1.1" 207 505 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=5.6 or=1 responses=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - - [02/Nov/2011:09:08:45 -0700] "PROPFIND /calendars/__uids__/42E46199-09C7-419F-823C-5CF39AE30270/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=5.7 or=2 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:45 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=76.4 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:45 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 3673 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=5.0 or=2 cached=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:45 -0700] "OPTIONS /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 200 0 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=11.2 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:45 -0700] "REPORT(DAV:principal-search-property-set) /principals/ HTTP/1.1" 200 1362 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=10.7 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:46 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=70.8 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:46 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 551 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=0 t=12.8 or=2 cached=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - testtesting [02/Nov/2011:09:08:46 -0700] "PROPFIND /calendars/__uids__/42E46199-09C7-419F-823C-5CF39AE30270/ HTTP/1.1" 207 37975 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=1 t=314.2 or=2 responses=7 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:46 -0700] "PROPFIND /principals/ HTTP/1.1" 207 505 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=0 t=12.6 or=2 responses=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - - [02/Nov/2011:09:08:46 -0700] "REPORT /principals/__uids__/42E46199-09C7-419F-823C-5CF39AE30270/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=4.0 or=2 fwd=192.168.188.120

127.0.0.1 - testtesting [02/Nov/2011:09:08:46 -0700] "REPORT(DAV:expand-property) /principals/__uids__/42E46199-09C7-419F-823C-5CF39AE30270/ HTTP/1.1" 207 438 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=158.4 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:48 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=71.4 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:48 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 3673 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=11.9 or=2 cached=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:48 -0700] "OPTIONS /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 200 0 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=11.0 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:48 -0700] "REPORT(DAV:principal-search-property-set) /principals/ HTTP/1.1" 200 1362 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=2 t=4.4 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:48 -0700] "PROPFIND /calendars/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=3 t=70.0 or=2 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:48 -0700] "PROPFIND /principals/__uids__/A2781586-17BD-467B-8718-E5F1194863F8/ HTTP/1.1" 207 551 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=0 t=5.1 or=3 cached=1 xff=192.168.188.120 fwd=192.168.188.120

127.0.0.1 - brandon [02/Nov/2011:09:08:48 -0700] "PROPFIND /principals/ HTTP/1.1" 207 505 "-" "CalendarStore/5.0.1 (1139.14); iCal/5.0.1 (1547.4); Mac OS X/10.7.2 (11C74)" i=0 t=6.0 or=3 responses=1 xff=192.168.188.120 fwd=192.168.188.120

OK,

So the new test user I added (after the 10.7.2 update) can access iCal on client machines without any problems. Events added and deleted and it works fine.

Existing users, prior to the 10.7.2 update still get the following error:

The server responded with

“500”

to operation CalDAVAccountRefreshQueueableOperation.

Neither the new test accounts or existing user accounts can access the calendar service in existing or new wikis, regardless whether the existing or new test accounts are in existing or new groups. Every time the calendar is accessed in a wiki, the pop up message stays at "Getting events from server..." -- but it never does.

iCal still not working.

I can't seem to figure out why a new account doesnt get the "operation CalDAVAccountRefreshQueueableOperation" error but existing users do -- but the wiki calendar still doesn't work for anyone.

On a wiki page, the "Upcoming Events" side bar just hangs at "getting events" and the calendar itself still stays at "Getting events from server..."

If I am quick enough, I can click the Settings button on the wiki calendar before it trys to get the events from the server:

but whether I leave the same or change the settings, it will hang at "saving settings..."

So, what would be the next step? Is this a database thing? Instead of trying to restore from a previous point before the 10.7.2 update (which I did, but didn't work), is there a way to just have os x lion create a new fresh calendar db?

At this point, I care more about getting it to work than losing all the calendar events.

Anyone have suggestions?