[horde] CalDAV Mac OS large number of sessions

Tue Jun 25 10:42:23 UTC 2013

Zitat von Mark Holmes <mark at mark.net>:

> I'm using Mac OS Lion 10.8.4 and have configured Horde 5.1.1 with  
> Caldav support.
>
> When using IOS I see one session in the Horde list of current  
> sessions and this remains at 1 for each calendar refresh.
>
> When using the Desktop calendar on the Mac It uses 12 sessions per  
> refresh of the calendar. So the total session count
> increases by 12 for each refresh, on and on until there are  
> thousands and thousands of sessions. I could certainly see this
> being problematic with many users refreshing their calendars  
> automatically every minute. :-)
>
> Now this may very well be an Apple problem but I'd like to know if  
> anyone else sees this same behavior.
>
> I'm posting the log entries for an IOS calendar refresh and Mac  
> desktop calendar refresh below
>
> os refresh 1 opens 1 session in horde session list
>
> xx.x.xxx.xxx - - [24/Jun/2013:16:05:05 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 401 213
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:05:06 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 757
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:05:07 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 305
> xx.x.xxx.xxx - - [24/Jun/2013:16:05:08 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 401 213
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:05:09 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 310
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:05:10 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:05:11 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
> xx.x.xxx.xxx - - [24/Jun/2013:16:05:12 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 401 213
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:05:12 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 237
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:05:13 -0400] "REPORT  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 437
>
> 2nd session uses same session as first
>
> xx.x.xxx.xxx - - [24/Jun/2013:16:06:47 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 401 213
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:06:47 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 757
> xx.x.xxx.xxx - - [24/Jun/2013:16:06:48 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 401 213
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:06:49 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 310
> xx.x.xxx.xxx - - [24/Jun/2013:16:06:50 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 401 213
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:06:51 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 305
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:06:51 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 237
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:06:52 -0400] "REPORT  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 436
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:06:53 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
> xx.x.xxx.xxx - testuser [24/Jun/2013:16:06:54 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
>
> Mac calendar desktop uses 12 sessions to horde
>
> yy.yy.yy.yyy - - [24/Jun/2013:16:09:38 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 401 213
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:39 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 757
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:40 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 269
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:42 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 271
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:43 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 310
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:44 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 295
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:45 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 305
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:46 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 291
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:47 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:49 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:50 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 237
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:51 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 441
> yy.yy.yy.yyy - - [24/Jun/2013:16:09:52 -0400] "REPORT  
> /rpc.php/principals/testuser/ HTTP/1.1" 401 213
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:09:53 -0400] "REPORT  
> /rpc.php/principals/testuser/ HTTP/1.1" 207 284
>
> Next mac desktop calendar refresh uses an additional 12 sessions so  
> 24 sessions total for the mac desktop calendar
>
> yy.yy.yy.yyy - - [24/Jun/2013:16:11:46 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 401 213
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:47 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 757
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:48 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 269
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:49 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/ HTTP/1.1" 207 271
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:50 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 310
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:51 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 295
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:53 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 305
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:54 -0400] "PROPPATCH  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 291
> yy.yy.yy.yyy - - [24/Jun/2013:16:11:54 -0400] "POST  
> /services/ajax.php/horde/topbarUpdate HTTP/1.1" 200 216
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:55 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:57 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/tasks%3aNM_fJkfwrKaZFHEbQrHwiA1/  
> HTTP/1.1" 207 241
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:58 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 237
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:11:59 -0400] "PROPFIND  
> /rpc.php/calendars/testuser/calendar%3aRC4DknCsRJR7hkh4ETP-2g1/  
> HTTP/1.1" 207 441
> yy.yy.yy.yyy - - [24/Jun/2013:16:12:01 -0400] "REPORT  
> /rpc.php/principals/testuser/ HTTP/1.1" 401 213
> yy.yy.yy.yyy - testuser [24/Jun/2013:16:12:02 -0400] "REPORT  
> /rpc.php/principals/testuser/ HTTP/1.1" 207 284
> --
> Horde mailing list
> Frequently Asked Questions: http://horde.org/faq/
> To unsubscribe, mail: horde-unsubscribe at lists.horde.org

Hi Mark,

same here. The session count increased from 27 to 231 during the last  
20 minutes.

With iOS or Mac OS Caldav (I used both at the same time) I witnessed  
also some strange behavior. Suddenly my iPhone reminded me of a daily  
recurring event... but not for the same day, no, for July 1, 2050! The  
reminder said something like "you wanted to be reminded 13.560 days 4  
hours and 16 minutes prior to this event."

Volker