I ran the proxyclean tool about 15 minutes ago and so far this seems to have cleared out the endlessly repeating errors "Unable to find any...". The DirectoryServices process is no longer eating up all the CPU resources and all 8 Python processes are humming along. I am still experiencing very slow response times to calendar requests (for example [86580.4 ms]) so we're still not getting any love from the iCal/CalDAV server. - Don On Feb 23, 2009, at 11:30 AM, Don Kruse wrote:
On Feb 23, 2009, at 9:28 AM, Cyrus Daboo wrote:
Hi Don,
--On February 23, 2009 9:24:23 AM -0800 Don Kruse <don@stahancyk.com> wrote:
This one entry repeats in the caldav log over and over (in fact there is very little else). I assume it is related to a user deleted from the OD but I'm not sure why this won't go away and it makes me suspicious this is at least partly what is delaying authentication requests. Below the log is the server performance snapshot showing the DirectoryServices PID at the top.
caldav log: 2009-02-18 14:28:51-0800 [-] [caldav-8016] [OpenDirectoryService] Unable to find any record with GUID 77C3A32E-7315-4FD9-ABE4-1F3BDA78EAD0 2009-02-18 14:28:51-0800 [-] [caldav-8016] [-] u'No principal found for UID: 77C3A32E-7315-4FD9-ABE4-1F3BDA78EAD0'
What request in the access.log is causing the above entry in the error.log?
Actually the request for that particular principal is not found in today's logs. What I have currently is similar...
The server is still shows the DirectoryServices process at 80% - 140% constantly (it is rare now that it drops below that).
CalDAV access.log
192.168.168.57 - arthurs [23/Feb/2009:11:19:30 -0700] "PROPFIND / calendars/__uids__/CD2F0C32-85E3-4E8D-A527-5793CA589646/inbox/ HTTP/ 1.1" 207 1188 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [101692.3 ms] 192.168.168.24 - bonnieb [23/Feb/2009:11:19:39 -0700] "PROPFIND / calendars/__uids__/5B7AFCD2-2207-43D6-A152-7C0A5420DCF9/ HTTP/1.1" 207 5112 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/ 3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [109276.6 ms] 192.168.5.65 - - [23/Feb/2009:11:19:32 -0700] "PROPFIND /calendars/ __uids__/7F221082-5962-4BC3-9752-583E10A6EEDF/inbox/ HTTP/1.1" 401 141 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [20862.8 ms] 192.168.2.64 - gordonp [23/Feb/2009:11:19:53 -0700] "PROPFIND / calendars/__uids__/858ACEFE-61B1-493C-B867-C6AED8A1F7B8/ HTTP/1.1" 207 5233 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/ 3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [107731.0 ms] 192.168.5.65 - - [23/Feb/2009:11:20:14 -0700] "MKCALENDAR /calendars/ __uids__/7F221082-5962-4BC3-9752-583E10A6EEDF/68AB66D8-5D0D-42D3- A144-37B3C02CC5C3/ HTTP/1.1" 401 141 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [21517.9 ms] 192.168.168.6 - laurelh [23/Feb/2009:11:20:32 -0700] "PROPFIND / calendars/__uids__/055C6188-33A0-4BA0-BF1E-DEEFF4F1978F/ HTTP/1.1" 207 5137 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/ 3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [172615.0 ms] 192.168.168.17 - melindam [23/Feb/2009:11:20:32 -0700] "PROPFIND / calendars/__uids__/44C8F59C-2838-4357-B8C9-0EF162FE7E8F/ HTTP/1.1" 207 5111 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/ 3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [172624.5 ms] 192.168.168.55 - joelk [23/Feb/2009:11:20:32 -0700] "PROPFIND / calendars/__uids__/292A7290-B215-4521-92A3-93A474C89D83/ HTTP/1.1" 207 5149 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/ 3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [160848.7 ms] 192.168.168.21 - lisaz [23/Feb/2009:11:20:43 -0700] "PROPFIND / principals/__uids__/BACA01DA-43BE-4F04-A7DE-B93E554EAEE5/ HTTP/1.1" 207 1985 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/ 3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [76846.1 ms] 192.168.168.57 - arthurs [23/Feb/2009:11:21:09 -0700] "PROPFIND / calendars/__uids__/CD2F0C32-85E3-4E8D-A527-5793CA589646/inbox/ HTTP/ 1.1" 207 1188 "-" "DAVKit/3.0.6 (653); CalendarStore/3.0.6 (847); iCal/3.0.6 (1273); Mac OS X/10.5.6 (9G55)" [81400.1 ms]
CalDAV error.log
2009-02-23 11:22:37-0800 [-] [caldav-8012] [-] u'No principal found for UID: A68FF97D-00A9-44FD-B0DB-CCA72692C305' 2009-02-23 11:22:37-0800 [-] [caldav-8016] [OpenDirectoryService] Unable to find any record with GUID D0818C28-D37E-487C- A6E3-94C0153FDB51 2009-02-23 11:22:37-0800 [-] [caldav-8014] [OpenDirectoryService] Unable to find any record with GUID A68FF97D-00A9-44FD-B0DB- CCA72692C305 2009-02-23 11:22:37-0800 [-] [caldav-8011] [OpenDirectoryService] Unable to find any record with GUID A68FF97D-00A9-44FD-B0DB- CCA72692C305 2009-02-23 11:22:37-0800 [-] [caldav-8013] [OpenDirectoryService] Unable to find any record with GUID D0818C28-D37E-487C- A6E3-94C0153FDB51 2009-02-23 11:22:37-0800 [-] [caldav-8010] [OpenDirectoryService] Unable to find any record with GUID A68FF97D-00A9-44FD-B0DB- CCA72692C305 2009-02-23 11:22:37-0800 [-] [caldav-8012] [OpenDirectoryService] Unable to find any record with GUID FBB884D1-5BE2-43A6-AE21- B97DC6B0F788 2009-02-23 11:22:37-0800 [-] [caldav-8016] [OpenDirectoryService] Unable to find any record with GUID D0818C28-D37E-487C- A6E3-94C0153FDB51 2009-02-23 11:22:37-0800 [-] [caldav-8014] [OpenDirectoryService] Unable to find any record with GUID A68FF97D-00A9-44FD-B0DB- CCA72692C305 2009-02-23 11:22:37-0800 [-] [caldav-8011] [OpenDirectoryService] Unable to find any record with GUID A68FF97D-00A9-44FD-B0DB- CCA72692C305 2009-02-23 11:22:37-0800 [-] [caldav-8013] [OpenDirectoryService] Unable to find any record with GUID D0818C28-D37E-487C- A6E3-94C0153FDB51 2009-02-23 11:22:37-0800 [-] [caldav-8013] [-] u'No principal found for UID: D0818C28-D37E-487C-A6E3-94C0153FDB51' 2009-02-23 11:22:37-0800 [-] [caldav-8010] [OpenDirectoryService] Unable to find any record with GUID A68FF97D-00A9-44FD-B0DB- CCA72692C305 2009-02-23 11:22:38-0800 [-] [caldav-8012] [OpenDirectoryService] Unable to find any record with GUID FBB884D1-5BE2-43A6-AE21- B97DC6B0F788
Thanks.
- Don Kruse