[CalendarServer-users] Authentication issue? Something else?

[Don Kruse]

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 at 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
>