[CalendarServer-users] Authentication issue? Something else?

[Don Kruse]

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