Authentication issue? Something else?

View: New views
8 Messages — Rating Filter:   Alert me  

Authentication issue? Something else?

by Don Kruse :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

We have been using iCal/CalDAV server along with iCal clients now for about 2-weeks and we have had some of the same issues others on this list have reported but we have either been able to resolve these or at least understand them. Since Monday, 2/16 an issue has started to appear that is new and I believe it is directly iCal/CalDAV related. The problem appears to be with authentication.

On the iCal/CalDAV server the DirectoryServices process is eating up CPU performance and response times for Calendar Server requests have started to become so long they are timing out for most but not all users. This was not an issue before as the DirectoryServices process typically was something under 10% of the CPU load at any given moment. We use an Open Directory (OD) server with Kerberos and this has been working well for the past 6-months. The iCal/CalDAV server is set up as "Connected to a Directory System" to get authentication from the OD. Our internal network appears to be OK, it is not saturated and is performing well. The OD server appears normal too. 

There is an error repeating in the CalDAV log about a single UID not being found, this error repeats so many times I wonder if requests for this calendar are causing the slow down. This error repeats several thousand times per minute or 59 times per second so I can see why this might be it. So how do I make this go away because this may be an issue on the client side right? Users are making requests for a calendar/user that no longer is available in OD. Also would there be any advantage to making the iCal/CalDAV server an OD replica?

Any insight is greatly appreciated.

Here is some of the data:

Calendar Server | Mac OS X 10.5.6

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'

Processes:  72 total, 5 running, 67 sleeping... 271 threads                                                       
Load Avg:  3.37,  4.45,  4.75    CPU usage: 18.99% user,  8.77% sys, 72.24% idle
SharedLibs: num =    7, resident =   45M code, 1388K data, 4448K linkedit.
MemRegions: num =  8365, resident =  889M +   15M private, 1737M shared.
PhysMem:  789M wired,  973M active,   36M inactive, 1806M used, 8434M free.
VM: 3251M + 371M   20873(0) pageins, 0(0) pageouts

  PID COMMAND      %CPU   TIME   #TH #PRTS #MREGS RPRVT  RSHRD  RSIZE  VSIZE
   26 DirectoryS 152.4%  6:48:49  13   115    164 9644K+  584K    12M+   32M+
  202 Python       9.5%  3:29:02   5    44-   453   99M- 1696K   101M+  116M-
  204 Python       7.7%  3:43:18   4    39    402   80M  1696K    82M    97M 
  210 Python       7.0%  7:09:21   5    46    643  148M  1696K   150M   164M 
  200 Python       6.5%  3:13:29   4    39    364   69M  1696K    71M    86M 
 2040 UserNotifi   5.8% 42:09.88   2    87     93 1380K    14M  5136K   148M 
  203 Python       5.8%  2:36:24   5    46    376   73M  1696K    75M    90M 
  201 Python       5.8%  1:45:34   4    39    363   70M  1696K    72M    86M
    0 kernel_tas   4.5% 24:12.76  65     2    405 7376K      0   329M   156M 
  465 top          3.9% 47:49.61   1    21     34 1480K   200K  2072K    19M 
   69 CoreRAIDSe   2.5%  6:05.67   2    67     35  408K  1500K  2196K    30M 
   43 Python       2.2%  8:30.92   1    15    219   11M  1672K    13M    28M

Open Directory Server | Mac OS X 10.5.6

This all seems really typical so I could not find any red-flags here or in any logs I scanned.

Processes:  87 total, 2 running, 85 sleeping... 475 threads                                                       
Load Avg:  1.72,  1.35,  0.93    CPU usage: 12.65% user, 25.71% sys, 61.63% idle
SharedLibs: num =    2, resident =   40M code,     0 data, 3608K linkedit.
MemRegions: num = 11086, resident =  551M +   16M private,   54M shared.
PhysMem:  253M wired,  700M active,  129M inactive, 1083M used,  965M free.
VM: 3486M + 129M   130707(0) pageins, 20334(0) pageouts

  PID COMMAND      %CPU   TIME   #TH #PRTS #MREGS RPRVT  RSHRD  RSIZE  VSIZE
43853 slapd       18.5% 89:50.06   9    51    124   13M   624K    16M   200M 
 3761 AppleFileS  13.3%  3:25:40 202   492   1454  118M  3060K   120M   324M 
46661 top         12.7%  0:02.60   1    21     33 1244K   200K  1836K    19M 
    0 kernel_tas  10.4%  6:12:47  51     2    833   14M      0   246M   157M 
   25 DirectoryS   2.9%  2:46:42   8    91    113 4744K   636K  7196K    25M 
 8805 SecurityAg   1.8%  3:11:20   3    93    100 1700K  4708K  6244K   120M 
  419 smbd         0.3%  2:20.13   1    17     53  128K  2392K  2136K    23M
   82 named        0.3% 14:37.08   1    14    107   24M   200K    25M    38M
 8797 WindowServ   0.1% 15:46.87   4   105     63 4996K   992K  6628K    81M
   81 mysqld       0.1% 15:42.35   9    40     55   53M   200K    55M   119M
    1 launchd      0.1%  3:29.61   3   140     26  240K   320K   600K    19M
  109 emond        0.1%  8:44.22   1    32     23  364K   464K  1824K    27M



Don Kruse
Information Technology Manager
tel 503-314-7361






_______________________________________________
calendarserver-users mailing list
calendarserver-users@...
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

Re: Authentication issue? Something else?

by Cyrus Daboo-3 :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

Hi Don,

--On February 23, 2009 9:24:23 AM -0800 Don Kruse <don@...> 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?

--
Cyrus Daboo

_______________________________________________
calendarserver-users mailing list
calendarserver-users@...
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

Re: Authentication issue? Something else?

by Don Kruse :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message


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@...> 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
_______________________________________________
calendarserver-users mailing list
calendarserver-users@...
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

Re: Authentication issue? Something else?

by Don Kruse :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

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@...> 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
>

_______________________________________________
calendarserver-users mailing list
calendarserver-users@...
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

Re: Authentication issue? Something else?

by Sean McAvoy-3 :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

Hello Don,
I have had somewhat similar experiences. I was wonder how many users  
you have and and about how many events each do they have? Finally,  
have you had issue where other users attempt to browse another  
calendar (usually with a large amount of entires) and receive a  
timeout error? I have yet to find a solution to my problem :)

On Feb 24, 2009, at 00:02, Don Kruse wrote:

> 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@...> 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
>>
>
> _______________________________________________
> calendarserver-users mailing list
> calendarserver-users@...
> http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users



Sean McAvoy
Systems Engineer, RIPE NCC
smcavoy@...




_______________________________________________
calendarserver-users mailing list
calendarserver-users@...
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

Re: Authentication issue? Something else?

by Don Kruse-2 :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

We have had or are still having the same issues you are having.

61 Users
31,000 events total (I expect that about 18 users have calendars with  
1K - 2K events)

I'm really having trouble with a variety of errors. I fix one or two  
things then something entirely different shows up. It's like playing  
wack-a-mole.

I really wanted this to work because I really wanted my users to have  
the rich feature set of iCal but with shared permission-based  
calendars. But it seems I'm going to have to find another solution.

- Don Kruse

On Feb 24, 2009, at 1:32 AM, Sean McAvoy wrote:

> Hello Don,
> I have had somewhat similar experiences. I was wonder how many users  
> you have and and about how many events each do they have? Finally,  
> have you had issue where other users attempt to browse another  
> calendar (usually with a large amount of entires) and receive a  
> timeout error? I have yet to find a solution to my problem :)
>
> On Feb 24, 2009, at 00:02, Don Kruse wrote:
>
>> 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@...
>>>> > 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
>>>
>>
>> _______________________________________________
>> calendarserver-users mailing list
>> calendarserver-users@...
>> http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users
>
>
>
> Sean McAvoy
> Systems Engineer, RIPE NCC
> smcavoy@...
>
>
>
>
>

_______________________________________________
calendarserver-users mailing list
calendarserver-users@...
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

Re: Authentication issue? Something else?

by Sean McAvoy-3 :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message


On Feb 25, 2009, at 01:48, Don Kruse wrote:

> We have had or are still having the same issues you are having.
>
> 61 Users
> 31,000 events total (I expect that about 18 users have calendars  
> with 1K - 2K events)
we have about 110 users with calendars ranging from 100 events to  
nearly 10k.
>
>
> I'm really having trouble with a variety of errors. I fix one or two  
> things then something entirely different shows up. It's like playing  
> wack-a-mole.
I've tried several different tests with our current OD setup and  
calendar server on different machines, I plan a final set of tests on  
a completely fresh install of Mac OS X Server and OD to completely  
rule out the possibility of a misconfiguration.
>
>
> I really wanted this to work because I really wanted my users to  
> have the rich feature set of iCal but with shared permission-based  
> calendars. But it seems I'm going to have to find another solution.
Pending the tests I run, I believe I will find myself in the same  
situation.

>
>
> - Don Kruse
>
> On Feb 24, 2009, at 1:32 AM, Sean McAvoy wrote:
>
>> Hello Don,
>> I have had somewhat similar experiences. I was wonder how many  
>> users you have and and about how many events each do they have?  
>> Finally, have you had issue where other users attempt to browse  
>> another calendar (usually with a large amount of entires) and  
>> receive a timeout error? I have yet to find a solution to my  
>> problem :)
>>
>> On Feb 24, 2009, at 00:02, Don Kruse wrote:
>>
>>> 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@...
>>>>> > 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
>>>>
>>>
>>> _______________________________________________
>>> calendarserver-users mailing list
>>> calendarserver-users@...
>>> http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver- 
>>> users
>>
>>
>>
>> Sean McAvoy
>> Systems Engineer, RIPE NCC
>> smcavoy@...
>>
>>
>>
>>
>>
>
> _______________________________________________
> calendarserver-users mailing list
> calendarserver-users@...
> http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

_______________________________________________
calendarserver-users mailing list
calendarserver-users@...
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

Re: Authentication issue? Something else?

by hjenkins :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

I'm having the exact same issue, same set up iCal server connected to my ODM.  I cannot even run the python script as I get this response:

CalendarServer proxy DB clean-up tool
=====================================

Parsed:              /etc/caldavd/caldavd.plist
Found DS Node:       /Search
Found proxy DB path: /Library/CalendarServer/Calendar Files/principals/.db.calendaruserproxy

Loading /Users records from OD: /Search
Found 104 /Users records

Reading proxy DB: /Library/CalendarServer/Calendar Files/principals/.db.calendaruserproxy
Found 1 proxy DB records

Failed: need more than 1 value to unpack



Don Kruse wrote:
We have been using iCal/CalDAV server along with iCal clients now for  
about 2-weeks and we have had some of the same issues others on this  
list have reported but we have either been able to resolve these or at  
least understand them. Since Monday, 2/16 an issue has started to  
appear that is new and I believe it is directly iCal/CalDAV related.  
The problem appears to be with authentication.

On the iCal/CalDAV server the DirectoryServices process is eating up  
CPU performance and response times for Calendar Server requests have  
started to become so long they are timing out for most but not all  
users. This was not an issue before as the DirectoryServices process  
typically was something under 10% of the CPU load at any given moment.  
We use an Open Directory (OD) server with Kerberos and this has been  
working well for the past 6-months. The iCal/CalDAV server is set up  
as "Connected to a Directory System" to get authentication from the  
OD. Our internal network appears to be OK, it is not saturated and is  
performing well. The OD server appears normal too.

There is an error repeating in the CalDAV log about a single UID not  
being found, this error repeats so many times I wonder if requests for  
this calendar are causing the slow down. This error repeats several  
thousand times per minute or 59 times per second so I can see why this  
might be it. So how do I make this go away because this may be an  
issue on the client side right? Users are making requests for a  
calendar/user that no longer is available in OD. Also would there be  
any advantage to making the iCal/CalDAV server an OD replica?

Any insight is greatly appreciated.

Here is some of the data:

Calendar Server | Mac OS X 10.5.6

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'

Processes:  72 total, 5 running, 67 sleeping... 271 threads
Load Avg:  3.37,  4.45,  4.75    CPU usage: 18.99% user,  8.77% sys,  
72.24% idle
SharedLibs: num =    7, resident =   45M code, 1388K data, 4448K  
linkedit.
MemRegions: num =  8365, resident =  889M +   15M private, 1737M shared.
PhysMem:  789M wired,  973M active,   36M inactive, 1806M used, 8434M  
free.
VM: 3251M + 371M   20873(0) pageins, 0(0) pageouts

   PID COMMAND      %CPU   TIME   #TH #PRTS #MREGS RPRVT  RSHRD  
RSIZE  VSIZE
    26 DirectoryS 152.4%  6:48:49  13   115    164 9644K+  584K    12M
+   32M+
   202 Python       9.5%  3:29:02   5    44-   453   99M- 1696K   101M
+  116M-
   204 Python       7.7%  3:43:18   4    39    402   80M  1696K    
82M    97M
   210 Python       7.0%  7:09:21   5    46    643  148M  1696K    
150M   164M
   200 Python       6.5%  3:13:29   4    39    364   69M  1696K    
71M    86M
  2040 UserNotifi   5.8% 42:09.88   2    87     93 1380K    14M  
5136K   148M
   203 Python       5.8%  2:36:24   5    46    376   73M  1696K    
75M    90M
   201 Python       5.8%  1:45:34   4    39    363   70M  1696K    
72M    86M
     0 kernel_tas   4.5% 24:12.76  65     2    405 7376K      0    
329M   156M
   465 top          3.9% 47:49.61   1    21     34 1480K   200K  
2072K    19M
    69 CoreRAIDSe   2.5%  6:05.67   2    67     35  408K  1500K  
2196K    30M
    43 Python       2.2%  8:30.92   1    15    219   11M  1672K    
13M    28M

Open Directory Server | Mac OS X 10.5.6

This all seems really typical so I could not find any red-flags here  
or in any logs I scanned.

Processes:  87 total, 2 running, 85 sleeping... 475 threads
Load Avg:  1.72,  1.35,  0.93    CPU usage: 12.65% user, 25.71% sys,  
61.63% idle
SharedLibs: num =    2, resident =   40M code,     0 data, 3608K  
linkedit.
MemRegions: num = 11086, resident =  551M +   16M private,   54M shared.
PhysMem:  253M wired,  700M active,  129M inactive, 1083M used,  965M  
free.
VM: 3486M + 129M   130707(0) pageins, 20334(0) pageouts

   PID COMMAND      %CPU   TIME   #TH #PRTS #MREGS RPRVT  RSHRD  
RSIZE  VSIZE
43853 slapd       18.5% 89:50.06   9    51    124   13M   624K    
16M   200M
  3761 AppleFileS  13.3%  3:25:40 202   492   1454  118M  3060K    
120M   324M
46661 top         12.7%  0:02.60   1    21     33 1244K   200K  
1836K    19M
     0 kernel_tas  10.4%  6:12:47  51     2    833   14M      0    
246M   157M
    25 DirectoryS   2.9%  2:46:42   8    91    113 4744K   636K  
7196K    25M
  8805 SecurityAg   1.8%  3:11:20   3    93    100 1700K  4708K  
6244K   120M
   419 smbd         0.3%  2:20.13   1    17     53  128K  2392K  
2136K    23M
    82 named        0.3% 14:37.08   1    14    107   24M   200K    
25M    38M
  8797 WindowServ   0.1% 15:46.87   4   105     63 4996K   992K  
6628K    81M
    81 mysqld       0.1% 15:42.35   9    40     55   53M   200K    
55M   119M
     1 launchd      0.1%  3:29.61   3   140     26  240K   320K    
600K    19M
   109 emond        0.1%  8:44.22   1    32     23  364K   464K  
1824K    27M



Don Kruse
Information Technology Manager
tel 503-314-7361






_______________________________________________
calendarserver-users mailing list
calendarserver-users@lists.macosforge.org
http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users