We've implemented iCal here and are having some performance issues. iCal server stats: 162 accounts, 65 calendars, 100 groups, 3000+ events, disk usage is 30kb on Intel Xserve Leopard server. We have all users calendars delegated to all other users, and about half of those users viewing all others calendars. We have a good DNS and resolution. When a user opens their iCal it may take up to 20 minutes to an hour or more for their calendar to sync, and the server pegs out at near 100% cpu usage for all the python processes _Calendar user. Not to mention that it takes forever for iCal to display the available calendars for access in the preferences. All machines are leopard and bound to the OD, single server setup. Has anyone experienced anything similar, and what did you do to correct the problem? Thanks!! Silas Thomas International Affairs Information Technology Consultant 541-346-1790 silas@uoregon.edu
Hi Silas, --On February 13, 2008 9:22:41 AM -0800 Silas Thomas <silas@uoregon.edu> wrote:
We've implemented iCal here and are having some performance issues. iCal server stats: 162 accounts, 65 calendars, 100 groups, 3000+ events, disk usage is 30kb on Intel Xserve Leopard server. We have all users calendars delegated to all other users, and about half of those users viewing all others calendars. We have a good DNS and resolution. When a user opens their iCal it may take up to 20 minutes to an hour or more for their calendar to sync, and the server pegs out at near 100% cpu usage for all the python processes _Calendar user. Not to mention that it takes forever for iCal to display the available calendars for access in the preferences. All machines are leopard and bound to the OD, single server setup.
Has anyone experienced anything similar, and what did you do to correct the problem?
So some user's iCal has ~160 delegate calendars shown in the UI? Can you send all or a portion of your access.log? -- Cyrus Daboo
Hi Silas,
--On February 13, 2008 9:22:41 AM -0800 Silas Thomas <silas@uoregon.edu
wrote:
We've implemented iCal here and are having some performance issues. iCal server stats: 162 accounts, 65 calendars, 100 groups, 3000+ events, disk usage is 30kb on Intel Xserve Leopard server. We have all users calendars delegated to all other users, and about half of those users viewing all others calendars. We have a good DNS and resolution. When a user opens their iCal it may take up to 20 minutes to an hour or more for their calendar to sync, and the server pegs out at near 100% cpu usage for all the python processes _Calendar user. Not to mention that it takes forever for iCal to display the available calendars for access in the preferences. All machines are leopard and bound to the OD, single server setup.
Has anyone experienced anything similar, and what did you do to correct the problem?
So some user's iCal has ~160 delegate calendars shown in the UI?
Can you send all or a portion of your access.log?
-- Cyrus Daboo
Nope... maybe 35, we have about 45 total OD User accounts, iCal server is showing 162 accounts. -Silas
Hi Silas, On Feb 13, 2008, at 9:31 AM, Silas Thomas wrote:
Hi Silas,
--On February 13, 2008 9:22:41 AM -0800 Silas Thomas <silas@uoregon.edu
wrote:
We've implemented iCal here and are having some performance issues. iCal server stats: 162 accounts, 65 calendars, 100 groups, 3000+ events, disk usage is 30kb on Intel Xserve Leopard server. We have all users calendars delegated to all other users, and about half of those users viewing all others calendars. We have a good DNS and resolution. When a user opens their iCal it may take up to 20 minutes to an hour or more for their calendar to sync, and the server pegs out at near 100% cpu usage for all the python processes _Calendar user. Not to mention that it takes forever for iCal to display the available calendars for access in the preferences. All machines are leopard and bound to the OD, single server setup.
Has anyone experienced anything similar, and what did you do to correct the problem?
So some user's iCal has ~160 delegate calendars shown in the UI?
Can you send all or a portion of your access.log?
-- Cyrus Daboo
Nope... maybe 35, we have about 45 total OD User accounts, iCal server is showing 162 accounts.
If you are using caladmin to get these stats I believe there is a bug where it also counts some of the calendar proxy principals. Instead of just user accounts. 162/3 = 54 so my guess is you have 54 total OD accounts and caladmin is counting users plus the 2 proxy principals. -David
Hi Silas, --On February 13, 2008 9:31:53 AM -0800 Silas Thomas <silas@uoregon.edu> wrote:
So some user's iCal has ~160 delegate calendars shown in the UI?
Can you send all or a portion of your access.log?
-- Cyrus Daboo
Nope... maybe 35, we have about 45 total OD User accounts, iCal server is showing 162 accounts.
Can you send a sample of the access.log and error.log when the server is slow? -- Cyrus Daboo
Hi Silas,
--On February 13, 2008 9:31:53 AM -0800 Silas Thomas <silas@uoregon.edu
wrote:
So some user's iCal has ~160 delegate calendars shown in the UI?
Can you send all or a portion of your access.log?
-- Cyrus Daboo
Nope... maybe 35, we have about 45 total OD User accounts, iCal server is showing 162 accounts.
Can you send a sample of the access.log and error.log when the server is slow?
-- Cyrus Daboo
Attached are sample logs during high CPU usage on iCal open. -Silas Thomas Log 39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND /principals/ __uids__/77F38902-743C-4644-927E-2E320CD0E8E5/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2282.0 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/726E9915-9CB5-4F4F-A753-279D33810B9E/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2283.6 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/ECAE46D9-9BE0-4B67-AAC4-B3D3648213F6/ HTTP/1.1" 207 1130 "-" "DAVKit/2.1" [2284.6 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/6779BA66-F1BC-4BA8-A939-71646CA04D9E/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2287.5 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/3992A809-752F-4DC1-BEA0-55BA321C2897/ HTTP/1.1" 207 1195 "-" "DAVKit/2.1" [2289.8 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/ECAE46D9-9BE0-4B67-AAC4-B3D3648213F6/ HTTP/1.1" 207 1130 "-" "DAVKit/2.1" [2291.6 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/9EB16EA5-6D2D-41C3-82D6-0A66EC5FD71F/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2294.1 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/9435EFE9-E40B-411A-AE7F-CD09F9D5D78F/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2296.8 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/95681DC8-EC9B-416D-8008-15B2E8C0FD88/ HTTP/1.1" 207 1109 "-" "DAVKit/2.1" [2299.0 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/9EB16EA5-6D2D-41C3-82D6-0A66EC5FD71F/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2301.7 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/DB478EC4-C872-4F9B-A81C-67E6C8C51B52/ HTTP/1.1" 207 1104 "-" "DAVKit/2.1" [2303.7 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/77F38902-743C-4644-927E-2E320CD0E8E5/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [1821.6 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/82291A06-6DC8-4C81-B606-30FB8144763B/ HTTP/1.1" 207 1188 "-" "DAVKit/2.1" [1816.2 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/DD02C02C-C400-40EB-BA4A-810282DACE21/ HTTP/1.1" 207 1159 "-" "DAVKit/2.1" [1818.3 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/530FAB2E-EEF6-4A0A-BFC0-64783A7D1FC0/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [1821.1 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [1824.6 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/DD02C02C-C400-40EB-BA4A-810282DACE21/ HTTP/1.1" 207 1159 "-" "DAVKit/2.1" [1826.6 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/5F73FA1D-9382-42FD-8698-C2226AE6A834/ HTTP/1.1" 207 1186 "-" "DAVKit/2.1" [1828.9 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/5C77BA87-08BC-4F02-8F28-6DAB25C7B035/ HTTP/1.1" 207 1146 "-" "DAVKit/2.1" [1830.9 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/77F38902-743C-4644-927E-2E320CD0E8E5/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [1831.3 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/8E656C8F-F82C-446A-BBDB-F81FCE5A204D/ HTTP/1.1" 207 1184 "-" "DAVKit/2.1" [1833.2 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/5C7197D7-83E5-4817-95A5-BE792C012C33/ HTTP/1.1" 207 1142 "-" "DAVKit/2.1" [2309.1 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/988B284D-7BAC-4B42-8278-2D664B103DE0/ HTTP/1.1" 207 1180 "-" "DAVKit/2.1" [2307.0 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/95681DC8-EC9B-416D-8008-15B2E8C0FD88/ HTTP/1.1" 207 1109 "-" "DAVKit/2.1" [2307.3 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/6EFBEAC8-50F9-41C9-A6E6-F49D0D2E5E99/ HTTP/1.1" 207 1114 "-" "DAVKit/2.1" [2309.2 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/8E656C8F-F82C-446A-BBDB-F81FCE5A204D/ HTTP/1.1" 207 1184 "-" "DAVKit/2.1" [2311.4 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/8A77EE6D-FC81-4294-A4AF-06343C0BD9AE/ HTTP/1.1" 207 1191 "-" "DAVKit/2.1" [2313.7 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/C2555959-D4A7-4F0A-BC56-437E8898D720/ HTTP/1.1" 207 1184 "-" "DAVKit/2.1" [2316.0 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/1B05AA6E-DD97-4927-BC99-CD1127FE2D1F/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2319.2 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2323.0 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/C7511670-14D1-4F9B-908E-466C7A32A83B/ HTTP/1.1" 207 1194 "-" "DAVKit/2.1" [2325.4 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/BA12721A-868F-40D3-8D43-C3727083DBF1/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2328.1 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/DBEDA4CA-2611-4788-8F53-7E7F2E301EDC/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2330.7 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:52 -0700] "PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2333.5 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/01C05F16-E2C2-4E31-B9A9-1B536BBF2154/ HTTP/1.1" 207 1184 "-" "DAVKit/2.1" [2516.1 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/54487E00-D8C8-4F6C-8646-BA5D3F0E68E4/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2519.3 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/08BC6CF2-580C-40B5-B46A-37C552AF8F51/ HTTP/1.1" 207 1179 "-" "DAVKit/2.1" [2520.4 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/9EB16EA5-6D2D-41C3-82D6-0A66EC5FD71F/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2523.1 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/FC0263B9-4C13-4311-86ED-87D9902B3193/ HTTP/1.1" 207 1179 "-" "DAVKit/2.1" [2525.5 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2529.0 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/28AD338B-F116-4888-B36A-4A2683C26775/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2532.1 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2534.6 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/BA12721A-868F-40D3-8D43-C3727083DBF1/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2537.2 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/6D7F078A-D55A-424E-BE32-746EA1CBA489/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2540.1 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/9435EFE9-E40B-411A-AE7F-CD09F9D5D78F/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2543.5 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/DBEDA4CA-2611-4788-8F53-7E7F2E301EDC/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2546.1 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:53 -0700] "PROPFIND / principals/__uids__/9435EFE9-E40B-411A-AE7F-CD09F9D5D78F/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2549.6 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/0B085F3D-934D-4C6D-A186-D7BAD86A917D/ HTTP/1.1" 207 1187 "-" "DAVKit/2.1" [2222.8 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/12594414-8C69-4D07-9444-6CE6CA59A18D/ HTTP/1.1" 207 1177 "-" "DAVKit/2.1" [2225.2 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/77F38902-743C-4644-927E-2E320CD0E8E5/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2228.4 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2232.2 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2236.2 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1" 207 432 "-" "DAVKit/2.1" [2239.3 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/33B7FF9E-E905-4C8A-A80B-1FE5EA0CC145/ HTTP/1.1" 207 1187 "-" "DAVKit/2.1" [2241.5 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/1B05AA6E-DD97-4927-BC99-CD1127FE2D1F/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2244.4 ms] 128.223.39.208 - lisamick [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/5F73FA1D-9382-42FD-8698-C2226AE6A834/ HTTP/1.1" 207 1186 "-" "DAVKit/2.1" [2246.8 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/BA12721A-868F-40D3-8D43-C3727083DBF1/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2247.5 ms] 128.223.39.61 - silasthomas [03/Mar/2008:11:14:54 -0700] "PROPFIND / principals/__uids__/6D7F078A-D55A-424E-BE32-746EA1CBA489/ HTTP/1.1" 207 321 "-" "DAVKit/2.1" [2250.3 ms] Error Log client] PROPFIND /principals/__uids__/DD33FC4D-3467-4C64- B823-8994E194CEC2/ HTTP/1.1 2008-03-03 11:14:39-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/ECAE46D9-9BE0-4B67-AAC4-B3D3648213F6/ HTTP/1.1 2008-03-03 11:14:39-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/BA12721A-868F-40D3-8D43-C3727083DBF1/ HTTP/1.1 2008-03-03 11:14:39-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/54487E00-D8C8-4F6C-8646-BA5D3F0E68E4/ HTTP/1.1 2008-03-03 11:14:39-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/28AD338B-F116-4888-B36A-4A2683C26775/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/DCBAA723-1D9E-42AB-B939-BB5ECA1B326E/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/DBEDA4CA-2611-4788-8F53-7E7F2E301EDC/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/3C2869B2-65EC-4AEC-9E7A-89F13F0C3301/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/1B05AA6E-DD97-4927-BC99-CD1127FE2D1F/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/1B05AA6E-DD97-4927-BC99-CD1127FE2D1F/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/530FAB2E-EEF6-4A0A-BFC0-64783A7D1FC0/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/9CC28C77-46C9-4CC0-81E1-BC65DC30D4CD/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/CF72B31D-4E82-4E12-9A00-D8F56005F6B1/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/6D7F078A-D55A-424E-BE32-746EA1CBA489/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/726E9915-9CB5-4F4F-A753-279D33810B9E/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/3C2869B2-65EC-4AEC-9E7A-89F13F0C3301/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/9EB16EA5-6D2D-41C3-82D6-0A66EC5FD71F/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/5F73FA1D-9382-42FD-8698-C2226AE6A834/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/DCBAA723-1D9E-42AB-B939-BB5ECA1B326E/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/4A668C97-690A-4D2A-B09B-ECAF8DFCE4FE/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/54487E00-D8C8-4F6C-8646-BA5D3F0E68E4/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/08BC6CF2-580C-40B5-B46A-37C552AF8F51/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/9EB16EA5-6D2D-41C3-82D6-0A66EC5FD71F/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/DBEDA4CA-2611-4788-8F53-7E7F2E301EDC/ HTTP/1.1 2008-03-03 11:14:40-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/1B4CF746-05CE-4648-88AA-0F19BCE89CDA/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/8465E056-8AFC-4C0B-8FDE-FCA6AE528C4A/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/F1ED5BE1-B3B8-41E0-83D6-E2E2A0D7DC12/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/5C77BA87-08BC-4F02-8F28-6DAB25C7B035/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/95681DC8-EC9B-416D-8008-15B2E8C0FD88/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/8465E056-8AFC-4C0B-8FDE-FCA6AE528C4A/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/33B7FF9E-E905-4C8A-A80B-1FE5EA0CC145/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/ECAE46D9-9BE0-4B67-AAC4-B3D3648213F6/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/CDC38E85-0CDC-472A-A9BD-471323C54107/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/726E9915-9CB5-4F4F-A753-279D33810B9E/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/530FAB2E-EEF6-4A0A-BFC0-64783A7D1FC0/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/5C3C541F-A9DF-4169-BCA0-D02DDBBC9085/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/FC0263B9-4C13-4311-86ED-87D9902B3193/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/EA085C42-A5EE-4791-A7CB-54A92EA13BB0/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/5C3C541F-A9DF-4169-BCA0-D02DDBBC9085/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/B830B2E9-1327-4A21-81DB-0ED99C46D81D/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/EEAD2779-0CBD-4B66-BAC7-BFB2CAEF25BA/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/76F47582-4DEC-4550-AD19-F8500AE6C740/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/08BC6CF2-580C-40B5-B46A-37C552AF8F51/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/54487E00-D8C8-4F6C-8646-BA5D3F0E68E4/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/3992A809-752F-4DC1-BEA0-55BA321C2897/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/F1ED5BE1-B3B8-41E0-83D6-E2E2A0D7DC12/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/9CC28C77-46C9-4CC0-81E1-BC65DC30D4CD/ HTTP/1.1 2008-03-03 11:14:41-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/28AD338B-F116-4888-B36A-4A2683C26775/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/DCBAA723-1D9E-42AB-B939-BB5ECA1B326E/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/EEAD2779-0CBD-4B66-BAC7-BFB2CAEF25BA/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/54487E00-D8C8-4F6C-8646-BA5D3F0E68E4/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/9EB16EA5-6D2D-41C3-82D6-0A66EC5FD71F/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/77F38902-743C-4644-927E-2E320CD0E8E5/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/530FAB2E-EEF6-4A0A-BFC0-64783A7D1FC0/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/82291A06-6DC8-4C81-B606-30FB8144763B/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/CDC38E85-0CDC-472A-A9BD-471323C54107/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/08DA3B4D-E0C7-4278-B37F-FDEF727E1363/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/BA12721A-868F-40D3-8D43-C3727083DBF1/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/28AD338B-F116-4888-B36A-4A2683C26775/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/B830B2E9-1327-4A21-81DB-0ED99C46D81D/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/A4F1C0C6-A1FB-4BC0-BFF8-6118F950BC80/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/54487E00-D8C8-4F6C-8646-BA5D3F0E68E4/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/DBEDA4CA-2611-4788-8F53-7E7F2E301EDC/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/54487E00-D8C8-4F6C-8646-BA5D3F0E68E4/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8010] [AMP,client] PROPFIND / principals/__uids__/6EFBEAC8-50F9-41C9-A6E6-F49D0D2E5E99/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8012] [AMP,client] PROPFIND / principals/__uids__/8465E056-8AFC-4C0B-8FDE-FCA6AE528C4A/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8009] [AMP,client] PROPFIND / principals/__uids__/BA12721A-868F-40D3-8D43-C3727083DBF1/ HTTP/1.1 2008-03-03 11:14:42-0800 [-] [caldav-8011] [AMP,client] PROPFIND / principals/__uids__/D006699B-8317-4A34-B35B-A23E04523C31/ HTTP/1.1
Hi Silas, --On March 3, 2008 11:40:07 AM -0800 Silas Thomas <silas@uoregon.edu> wrote:
Attached are sample logs during high CPU usage on iCal open.
Yup looks like iCal is making way too many PROPFINDs and the server is having a hard time keeping up. When the server starts up those PROPFINDs are executing in about 30 ms which is about right. But after a while the time starts to increase a lot, in the worse case taking over 6 seconds. That seems to start happening when the number of requests per second increases (gets as high as 40+ per second). In theory we should be able to cope with that particularly on a 4-core system so we need to analyze this some more. Right now if you want to have a usable system you are going to have to restrict the number of delegates that people setup because that is causing iCal to do all those PROPFINDs. -- Cyrus Daboo
participants (3)
-
Cyrus Daboo
-
David Reid
-
Silas Thomas