[CalendarServer-users] Calendar Server

Robert Bruce rbruce at celsiusinc.com
Thu Mar 7 09:04:56 PST 2013


Hi

Failed Delete

::ffff:192.168.1.38 - - [07/Mar/2013:12:03:02 -0400] "DELETE /calendars/__uids__/admin/calendar/AE195B52-8AD1-4F43-BB6B-2298EC72E889.ics HTTP/1.1" 412 157 "-" "DAVKit/4.0.3 (732.2); CalendarStore/4.0.4 (997.7); iCal/4.0.4 (1395.7); Mac OS X/10.6.8 (10K549)" i=1 or=1 t=12.7
::ffff:192.168.1.38 - admin [07/Mar/2013:12:03:02 -0400] "GET /calendars/__uids__/admin/calendar/AE195B52-8AD1-4F43-BB6B-2298EC72E889.ics HTTP/1.1" 200 705 "-" "DAVKit/4.0.3 (732.2); CalendarStore/4.0.4 (997.7); iCal/4.0.4 (1395.7); Mac OS X/10.6.8 (10K549)" i=1 or=1 t=22.4


Second try delete works...

::ffff:192.168.1.38 - admin [07/Mar/2013:12:03:51 -0400] "DELETE /calendars/__uids__/admin/calendar/AE195B52-8AD1-4F43-BB6B-2298EC72E889.ics HTTP/1.1" 204 0 "-" "DAVKit/4.0.3 (732.2); CalendarStore/4.0.4 (997.7); iCal/4.0.4 (1395.7); Mac OS X/10.6.8 (10K549)" i=1 or=1 t=511.1


The deletes that work are not followed by a GET... 



On 2013-03-07, at 11:55 AM, Andre LaBranche wrote:

> Hi,
> 
> Please also include the relevant portion of access.log. I'm very curious to see the http status code of the DELETE you're sending.
> 
> -dre
> 
> On Mar 7, 2013, at 8:45 AM, Robert Bruce <rbruce at celsiusinc.com> wrote:
> 
>> Hi thank you for your time... 
>> 
>> This is the same for creating, moving, delete    Calendarsever 2.4 does not have the same problem only 3.2, 4.2 on this system
>> 
>> Here is the delete operation failing...
>> 2013-03-07 11:41:32-0500 [-] [caldav-1]  [HTTPChannel,23,::ffff:192.168.1.38] [twext.web2.server#info] DELETE /calendars/__uids__/admin/calendar/4BB3FD49-05EC-46FE-A958-66C790F462B9.ics HTTP/1.1
>> 2013-03-07 11:41:32-0500 [-] [caldav-1]  [HTTPChannel,24,::ffff:192.168.1.38] [twext.web2.server#info] GET /calendars/__uids__/admin/calendar/4BB3FD49-05EC-46FE-A958-66C790F462B9.ics HTTP/1.1
>> 2013-03-07 11:41:32-0500 [-] [caldav-1]  [HTTPChannel,24,::ffff:192.168.1.38] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '23068977201759756079232744263502251584903490272716206165'
>> 2013-03-07 11:41:32-0500 [-] [caldav-1]  [HTTPChannel,24,::ffff:192.168.1.38] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x34df5a8>
>> 2013-03-07 11:41:32-0500 [-] [caldav-1]  [HTTPChannel,24,::ffff:192.168.1.38] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 2, #busy: 1, #pending: 0, #queued: 0
>> 2013-03-07 11:41:32-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x34df5a8>
>> 2013-03-07 11:41:32-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 3, #busy: 0, #pending: 0, #queued: 0
>> 
>> Now the second try works... 
>> 
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [HTTPChannel,25,::ffff:192.168.1.38] [twext.web2.server#info] DELETE /calendars/__uids__/admin/calendar/4BB3FD49-05EC-46FE-A958-66C790F462B9.ics HTTP/1.1
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [HTTPChannel,25,::ffff:192.168.1.38] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '23068977201759756079232744263502251584903490272716206165'
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [HTTPChannel,25,::ffff:192.168.1.38] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x339fdd0>
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [HTTPChannel,25,::ffff:192.168.1.38] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 2, #busy: 1, #pending: 0, #queued: 0
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x339fdd0>
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 3, #busy: 0, #pending: 0, #queued: 0
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.notify.Notifier#debug] Notifications are enabled: update default CalDAV|admin
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.notify.NotifierFactory#debug] Sending to notification server: update CalDAV|admin
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.notify.Notifier#debug] Notifications are enabled: update collection CalDAV|admin/calendar
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.notify.NotifierFactory#debug] Sending to notification server: update CalDAV|admin/calendar
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.cache.MemcacheChangeNotifier#debug] Changing Cache Token for '/calendars/__uids__/admin/'
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x366ea28>
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 2, #busy: 1, #pending: 0, #queued: 0
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.cache.MemcacheChangeNotifier#debug] Changing Cache Token for '/calendars/__uids__/admin/calendar/'
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x34df5a8>
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 2, #pending: 0, #queued: 0
>> 2013-03-07 11:42:57-0500 [-] [notifications] 2013-03-07 11:42:57-0500 [InternalNotificationProtocol,0,127.0.0.1] [twistedcaldav.notify.Coalescer#debug] Scheduling: CalDAV|admin
>> 2013-03-07 11:42:57-0500 [-] [notifications] 2013-03-07 11:42:57-0500 [InternalNotificationProtocol,0,127.0.0.1] [twistedcaldav.notify.Coalescer#debug] Scheduling: CalDAV|admin/calendar
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x34df5a8>
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 2, #busy: 1, #pending: 0, #queued: 0
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x366ea28>
>> 2013-03-07 11:42:57-0500 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 3, #busy: 0, #pending: 0, #queued: 0
>> 2013-03-07 11:43:00-0500 [-] [notifications] 2013-03-07 11:43:00-0500 [-] [twistedcaldav.notify.Coalescer#debug] Time to send: CalDAV|admin
>> 2013-03-07 11:43:00-0500 [-] [notifications] 2013-03-07 11:43:00-0500 [-] [twistedcaldav.notify.Coalescer#debug] Time to send: CalDAV|admin/calendar
>> 
>> 
>> On 2013-03-07, at 10:34 AM, Andre LaBranche wrote:
>> 
>>> 
>>> On Mar 7, 2013, at 6:20 AM, Robert Bruce <rbruce at celsiusinc.com> wrote:
>>> 
>>>> ...
>>>> Then I delete the event at 4:00 but it come right back 
>>>>  
>>>> 2013-03-06 14:53:31-0500 [-] [caldav-0]  [HTTPChannel,23,24.201.74.72] [twext.web2.server#info] DELETE /calendars/__uids__/1cc63f8f-744a-4941-88c8-8ab121d03be9/calendar/D49F6977-1BCA-4EB3-A602-510B2037DC81.ics HTTP/1.1
>>>> 2013-03-06 14:53:31-0500 [-] [caldav-0]  [HTTPChannel,23,24.201.74.72] [twext.web2.server#info] GET /calendars/__uids__/1cc63f8f-744a-4941-88c8-8ab121d03be9/calendar/D49F6977-1BCA-4EB3-A602-510B2037DC81.ics HTTP/1.1
>>>> 2013-03-06 14:53:31-0500 [-] [caldav-0]  [HTTPChannel,24,24.201.74.72] [twext.web2.server#info] GET /calendars/__uids__/1cc63f8f-744a-4941-88c8-8ab121d03be9/calendar/D49F6977-1BCA-4EB3-A602-510B2037DC81.ics HTTP/1.1
>>> 
>>> Either the delete is failing or the GETs are failing; they can't both be succeeding, but we can't see why. Can you turn your log level up to Debug (in caldavd.plist) then restart the service and reproduce these steps, then reply including the relevant portions of error.log and access.log?
>>> 
>>> Thx,
>>> -dre
>>> 
>>>>  
>>>> Then I delete the event at 4:00 again and this time it is gone
>>>> 
>>>> 2013-03-06 14:55:01-0500 [-] [caldav-0]  [HTTPChannel,25,24.201.74.72] [twext.web2.server#info] DELETE /calendars/__uids__/1cc63f8f-744a-4941-88c8-8ab121d03be9/calendar/D49F6977-1BCA-4EB3-A602-510B2037DC81.ics HTTP/1.1
>>>> 2013-03-06 14:55:01-0500 [-] [caldav-0]  [HTTPChannel,26,24.201.74.72] [twext.web2.server#info] DELETE /calendars/__uids__/1cc63f8f-744a-4941-88c8-8ab121d03be9/calendar/D49F6977-1BCA-4EB3-A602-510B2037DC81.ics HTTP/1.1
>>>> 
>>>> It seems to me the Following GET operations are reading back the old data?  
>>>> 
>>>> 
>>>> On 2013-03-06, at 4:31 PM, Glyph wrote:
>>>> 
>>>>> 
>>>>> On Mar 6, 2013, at 11:57 AM, Robert Bruce <rbruce at celsiusinc.com> wrote:
>>>>> 
>>>>>> I can't get 4.x releases to work on Ubuntu ( 12.10 in my case ) x86… and there are no packages for 4.x releases so far?  relevant info is hard to find on the Net about how to set it all up.   I managed to compile from SVN successfully but the logs fill with errors,
>>>>> 
>>>>> I'm a little more interested in these errors than the ones you're having with 3.2, since 4.x is a lot closer to what we're currently working on in trunk.  Can you send them on?  Not to say that I wouldn't like you to fix your current errors, but if we do find a bug and fix it, you'll still have to upgrade :).
>>>>> 
>>>>>> and after so many unpaid hours I chose 3.2 because the packages exist.
>>>>> 
>>>>> I think that someone's working on 4.2 packages, and in fact I believe they're on this mailing list.
>>>>> 
>>>>> -glyph
>>>>> 
>>>>> P.S.: Please remember to "reply all" so others on the list will see the reply.
>>>> 
>>>> _______________________________________________
>>>> calendarserver-users mailing list
>>>> calendarserver-users at lists.macosforge.org
>>>> https://lists.macosforge.org/mailman/listinfo/calendarserver-users
>>> 
>> 
> 

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.macosforge.org/pipermail/calendarserver-users/attachments/20130307/0795d99d/attachment-0001.html>


More information about the calendarserver-users mailing list