[CalendarServer-users] Error: 'Server' object has no attribute 'protocol'
Ulrich Fourier
ulrich.fourier at rockyourlife.de
Fri Sep 12 02:43:12 PDT 2014
On 11.09.14 18:08, Andre LaBranche wrote:
> On Sep 11, 2014, at 8:21 AM, Cyrus Daboo <cdaboo at apple.com> wrote:
>> Turn off digest authentication and use basic instead. The LDAP directory service only supports basic.
> By default, basic auth is only allowed when using SSL, so make sure SSL is happy. Obviously you want SSL in this case :) It's possible to override this policy, but it's not a good idea.
>
> -dre
Thank you guys, now everything is working fine except the calendarsharing.
I cannot select the user with who I want to share my calendar with in
the logs there is an exception although he logs that he finds users. See
log files attached. Is it a LDAP misconfiguration I made?
-------------- next part --------------
Log opened - server start: [Fri Sep 12 11:34:56 2014].
88.217.236.30 - - [12/Sep/2014:11:35:07 +0200] "REPORT(DAV:expand-property) /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-write/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=10.2 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:08 +0200] "REPORT(DAV:expand-property) /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-write/ HTTP/1.1" 207 339 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=9.2 or=1
88.217.236.30 - - [12/Sep/2014:11:35:08 +0200] "REPORT(DAV:expand-property) /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-read/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=4.1 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:08 +0200] "REPORT(DAV:expand-property) /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-read/ HTTP/1.1" 207 338 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=5.3 or=1
88.217.236.30 - - [12/Sep/2014:11:35:14 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=15.9 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:14 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=54.3 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:18 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=31.6 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:19 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 207 66 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=38.4 or=1
88.217.236.30 - - [12/Sep/2014:11:35:19 +0200] "POST /calendars/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/outbox/ HTTP/1.1" 401 141 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=13.8 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:20 +0200] "POST /calendars/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/outbox/ HTTP/1.1" 200 1710 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=109.4 or=1 fb-cached=2 recipients=2 cl=490
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:23 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=77.3 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:25 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=64.8 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:28 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=41.1 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:28 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=37.2 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:30 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=36.1 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:31 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=58.4 or=1
88.217.236.30 - RYLFamily [12/Sep/2014:11:35:32 +0200] "REPORT(DAV:principal-property-search) /principals/ HTTP/1.1" 500 201 "-" "CalendarStore/5.0.3 (1204.2); iCal/5.0.3 (1605.4); Mac OS X/10.7.5 (11G63)" i=0 t=28.8 or=1
Log closed - server stop: [Fri Sep 12 11:35:43 2014].
-------------- next part --------------
2014-09-12 11:34:56+0200 [-] Log opened.
2014-09-12 11:34:56+0200 [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-12 11:34:56+0200 [-] reactor class: twisted.internet.pollreactor.PollReactor.
2014-09-12 11:34:56+0200 [-] AMPLoggingFactory starting on '/tmp/caldavd.sock'
2014-09-12 11:34:56+0200 [-] [calendarserver.accesslog#debug] rotateGlobalHitHistoryStats: [{'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}, {'hits': 0, 'time': 1410514496}]
2014-09-12 11:34:56+0200 [-] CalDAVStatisticsServer starting on '/tmp/caldavd-stats.sock'
2014-09-12 11:34:56+0200 [-] LimitingInheritingProtocolFactory starting on 8008
2014-09-12 11:34:56+0200 [-] LimitingInheritingProtocolFactory starting on 8443
2014-09-12 11:34:56+0200 [-] LimitingInheritingProtocolFactory starting on 8843
2014-09-12 11:34:56+0200 [-] [calendarserver.tap.caldav.CalDAVServiceMaker#info] Adding group caching service
2014-09-12 11:34:56+0200 [-] [memcached-Default] failed to listen on TCP port 11211: Address already in use
2014-09-12 11:34:57+0200 [-] [caldav-1] Reading configuration from file: /etc/caldavd/caldavd.plist
2014-09-12 11:34:57+0200 [-] [caldav-1] [-] Log opened.
2014-09-12 11:34:57+0200 [-] [caldav-1] [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-12 11:34:57+0200 [-] [caldav-1] [-] reactor class: twisted.internet.selectreactor.SelectReactor.
2014-09-12 11:34:57+0200 [calendarserver.accesslog.AMPLoggingFactory] AMPLoggingProtocol connection established (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
2014-09-12 11:34:57+0200 [-] [caldav-1] [-] AMP connection established (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] Log opened.
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] reactor class: twisted.internet.selectreactor.SelectReactor.
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacherService#warn] Starting group membership cacher service
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] is group-cacher-populated
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Getting Cache Token for 'group-cacher-populated'
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Initating new client connection to: IPv4Address(TCP, '127.0.0.1', 11211)
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [caldav-0] Reading configuration from file: /etc/caldavd/caldavd.plist
2014-09-12 11:34:57+0200 [-] [caldav-0] [-] Log opened.
2014-09-12 11:34:57+0200 [-] [caldav-0] [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-12 11:34:57+0200 [-] [caldav-0] [-] reactor class: twisted.internet.selectreactor.SelectReactor.
2014-09-12 11:34:57+0200 [calendarserver.accesslog.AMPLoggingFactory] AMPLoggingProtocol connection established (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [caldav-0] [-] AMP connection established (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Updating group membership cache
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Group membership snapshot file exists: /var/www/caldavd/Data/memberships_cache
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Attempting to acquire group membership cache lock
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] add group-cacher-lock
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Adding Cache Token for 'group-cacher-lock'
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Acquired lock
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Retrieving list of all proxies
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.database#debug] Starting ADBAPI thread: 140671829960448
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] There are 0 proxies
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Retrieving group hierarchy from directory
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] 0 groups retrieved from the directory
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] 0 groups are proxies
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] There are 0 users delegated-to via groups
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Taking snapshot of group memberships to /var/www/caldavd/Data/memberships_cache
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Storing 0 group memberships in memcached
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] set group-cacher-populated
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Setting Cache Token for 'group-cacher-populated'
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Releasing lock
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] delete group-cacher-lock
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Deleting Cache Token for 'group-cacher-lock'
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x44275f0>
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Group memberships cache updated
2014-09-12 11:34:57+0200 [-] [groupcacher] 2014-09-12 11:34:57+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacherService#info] Scheduling next group membership update
2014-09-12 11:34:57+0200 [-] [memcached-Default] failed to listen on TCP port 11211: Address already in use
2014-09-12 11:34:59+0200 [-] [memcached-Default] failed to listen on TCP port 11211: Address already in use
2014-09-12 11:35:03+0200 [-] [memcached-Default] failed to listen on TCP port 11211: Address already in use
2014-09-12 11:35:07+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twext.web2.server#info] REPORT /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-write/ HTTP/1.1
2014-09-12 11:35:07+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.directory.sudo.SudoDirectoryService#info] Directory service <SudoDirectoryService 'ldap://localhost/': FilePath('/etc/caldavd/sudoers.plist')> has no GUID; generating service GUID from realm name.
2014-09-12 11:35:07+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: checking dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|guid|cff936ac-6330-1033-826f-ed7950cb7b75|users|groups
2014-09-12 11:35:07+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: hit dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|guid|cff936ac-6330-1033-826f-ed7950cb7b75|users|groups
2014-09-12 11:35:08+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twext.web2.server#info] REPORT /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-write/ HTTP/1.1
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: checking dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|shortname|RYLFamily|users|groups
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: hit dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|shortname|RYLFamily|users|groups
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Authenticating cn=ryl family,ou=people,dc=rockyourlife,dc=de
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Creating authentication connection to LDAP
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Authentication succeeded for cn=ryl family,ou=people,dc=rockyourlife,dc=de
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.directory.calendaruserproxy.ProxyDBMemcacher#debug] Getting Cache Token for 'members:cff936ac-6330-1033-826f-ed7950cb7b75#calendar-proxy-write'
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Initating new client connection to: IPv4Address(TCP, '127.0.0.1', 11211)
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:35:08+0200 [-] [caldav-0] [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:08+0200 [-] [caldav-0] [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:35:08+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:08+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:35:08+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twext.web2.server#info] REPORT /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-read/ HTTP/1.1
2014-09-12 11:35:08+0200 [-] [caldav-0] [HTTPChannel,3,88.217.236.30] [twext.web2.server#info] REPORT /principals/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/calendar-proxy-read/ HTTP/1.1
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.directory.calendaruserproxy.ProxyDBMemcacher#debug] Getting Cache Token for 'members:cff936ac-6330-1033-826f-ed7950cb7b75#calendar-proxy-read'
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:08+0200 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:35:08+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:08+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:35:11+0200 [-] [memcached-Default] failed to listen on TCP port 11211: Address already in use
2014-09-12 11:35:14+0200 [-] [caldav-0] [HTTPChannel,3,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:14+0200 [-] [caldav-0] [HTTPChannel,4,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:14+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('lastName', 'ulrich', True, 'starts-with'), ('emailAddresses', 'ulrich', True, 'starts-with'), ('firstName', 'ulrich', True, 'starts-with'), ('fullName', 'ulrich', True, 'starts-with')]
2014-09-12 11:35:14+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(sn=ulrich*)(mail=ulrich*)(givenName=ulrich*)(cn=ulrich*))
2014-09-12 11:35:14+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 4 results
2014-09-12 11:35:14+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:14+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:14+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:14+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:14+0200 [-] [caldav-0] ]
2014-09-12 11:35:18+0200 [-] [caldav-0] [HTTPChannel,4,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:18+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('emailAddresses', 'ulrich', True, 'starts-with'), ('firstName', 'ulrich', True, 'starts-with'), ('lastName', 'ulrich', True, 'starts-with'), ('fullName', 'ulrich', True, 'starts-with')]
2014-09-12 11:35:18+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(mail=ulrich*)(givenName=ulrich*)(sn=ulrich*)(cn=ulrich*))
2014-09-12 11:35:18+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 4 results
2014-09-12 11:35:18+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:18+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:18+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:18+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:18+0200 [-] [caldav-0] ]
2014-09-12 11:35:19+0200 [-] [caldav-0] [HTTPChannel,5,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:19+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('emailAddresses', 'ulrichfourier', True, 'starts-with'), ('firstName', 'ulrichfourier', True, 'starts-with'), ('lastName', 'ulrichfourier', True, 'starts-with'), ('fullName', 'ulrichfourier', True, 'starts-with')]
2014-09-12 11:35:19+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(mail=ulrichfourier*)(givenName=ulrichfourier*)(sn=ulrichfourier*)(cn=ulrichfourier*))
2014-09-12 11:35:19+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 0 results
2014-09-12 11:35:19+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Principal property search matched 0 records
2014-09-12 11:35:19+0200 [-] [caldav-0] [HTTPChannel,5,88.217.236.30] [twext.web2.server#info] POST /calendars/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/outbox/ HTTP/1.1
2014-09-12 11:35:20+0200 [-] [caldav-0] [HTTPChannel,6,88.217.236.30] [twext.web2.server#info] POST /calendars/__uids__/cff936ac-6330-1033-826f-ed7950cb7b75/outbox/ HTTP/1.1
2014-09-12 11:35:20+0200 [-] [caldav-0] [HTTPChannel,6,88.217.236.30] [twistedcaldav.directory.calendaruserproxy.ProxyDBMemcacher#debug] Getting Cache Token for 'members:cff936ac-6330-1033-826f-ed7950cb7b75#calendar-proxy-write'
2014-09-12 11:35:20+0200 [-] [caldav-0] [HTTPChannel,6,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:20+0200 [-] [caldav-0] [HTTPChannel,6,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: checking dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|guid|1634da1e-0f07-1033-8766-2708925ed8ba|users|groups
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: hit dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|guid|1634da1e-0f07-1033-8766-2708925ed8ba|users|groups
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: checking dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|cua|mailto:ryl-family at rockyourlife.de|users|groups
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: hit dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|cua|mailto:ryl-family at rockyourlife.de|users|groups
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.scheduling.scheduler#info] METHOD: REQUEST, Component: VFREEBUSY
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.memcacher.Memcacher#debug] Getting Cache Token for 'urn:uuid:acd090a1-6b4a-480b-8c79-227d39975534/cff936ac-6330-1033-826f-ed7950cb7b75'
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:20+0200 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcacher.Memcacher#debug] Getting Cache Token for 'urn:uuid:4aa69fd5-8102-48d9-9341-e61a48de5d7b/1634da1e-0f07-1033-8766-2708925ed8ba'
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x2550cf8>
2014-09-12 11:35:20+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-12 11:35:23+0200 [-] [caldav-0] [HTTPChannel,6,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:23+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('firstName', 'ulrich.fourier', True, 'starts-with'), ('lastName', 'ulrich.fourier', True, 'starts-with'), ('fullName', 'ulrich.fourier', True, 'starts-with'), ('emailAddresses', 'ulrich.fourier', True, 'starts-with')]
2014-09-12 11:35:23+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(givenName=ulrich.fourier*)(sn=ulrich.fourier*)(cn=ulrich.fourier*)(mail=ulrich.fourier*))
2014-09-12 11:35:23+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 2 results
2014-09-12 11:35:23+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:23+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:23+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:23+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:23+0200 [-] [caldav-0] ]
2014-09-12 11:35:25+0200 [-] [caldav-0] [HTTPChannel,6,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:25+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('firstName', 'ulrich.fourie', True, 'starts-with'), ('fullName', 'ulrich.fourie', True, 'starts-with'), ('emailAddresses', 'ulrich.fourie', True, 'starts-with'), ('lastName', 'ulrich.fourie', True, 'starts-with')]
2014-09-12 11:35:25+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(givenName=ulrich.fourie*)(cn=ulrich.fourie*)(mail=ulrich.fourie*)(sn=ulrich.fourie*))
2014-09-12 11:35:25+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 2 results
2014-09-12 11:35:25+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:25+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:25+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:25+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:25+0200 [-] [caldav-0] ]
2014-09-12 11:35:27+0200 [-] [memcached-Default] failed to listen on TCP port 11211: Address already in use
2014-09-12 11:35:28+0200 [-] [caldav-0] [HTTPChannel,6,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('lastName', 'ulr', True, 'starts-with'), ('emailAddresses', 'ulr', True, 'starts-with'), ('fullName', 'ulr', True, 'starts-with'), ('firstName', 'ulr', True, 'starts-with')]
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(sn=ulr*)(mail=ulr*)(cn=ulr*)(givenName=ulr*))
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 5 results
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:28+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:28+0200 [-] [caldav-0] ]
2014-09-12 11:35:28+0200 [-] [caldav-0] [HTTPChannel,7,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('firstName', 'ulri', True, 'starts-with'), ('fullName', 'ulri', True, 'starts-with'), ('lastName', 'ulri', True, 'starts-with'), ('emailAddresses', 'ulri', True, 'starts-with')]
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(givenName=ulri*)(cn=ulri*)(sn=ulri*)(mail=ulri*))
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 5 results
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:28+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:28+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:28+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:28+0200 [-] [caldav-0] ]
2014-09-12 11:35:30+0200 [-] [caldav-0] [HTTPChannel,7,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:30+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('firstName', 'ulrich', True, 'starts-with'), ('fullName', 'ulrich', True, 'starts-with'), ('emailAddresses', 'ulrich', True, 'starts-with'), ('lastName', 'ulrich', True, 'starts-with')]
2014-09-12 11:35:30+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(givenName=ulrich*)(cn=ulrich*)(mail=ulrich*)(sn=ulrich*))
2014-09-12 11:35:30+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 4 results
2014-09-12 11:35:30+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:30+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:30+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:30+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:30+0200 [-] [caldav-0] ]
2014-09-12 11:35:31+0200 [-] [caldav-0] [HTTPChannel,7,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:31+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('emailAddresses', 'ulrich.', True, 'starts-with'), ('lastName', 'ulrich.', True, 'starts-with'), ('fullName', 'ulrich.', True, 'starts-with'), ('firstName', 'ulrich.', True, 'starts-with')]
2014-09-12 11:35:31+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(mail=ulrich.*)(sn=ulrich.*)(cn=ulrich.*)(givenName=ulrich.*))
2014-09-12 11:35:31+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 2 results
2014-09-12 11:35:31+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:31+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:31+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:31+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:31+0200 [-] [caldav-0] ]
2014-09-12 11:35:32+0200 [-] [caldav-0] [HTTPChannel,9,88.217.236.30] [twext.web2.server#info] REPORT /principals/ HTTP/1.1
2014-09-12 11:35:32+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Peforming principal property search for [('emailAddresses', 'ulrich.fourier', True, 'starts-with'), ('firstName', 'ulrich.fourier', True, 'starts-with'), ('fullName', 'ulrich.fourier', True, 'starts-with'), ('lastName', 'ulrich.fourier', True, 'starts-with')]
2014-09-12 11:35:32+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search ou=people,dc=rockyourlife,dc=de 2 (|(mail=ulrich.fourier*)(givenName=ulrich.fourier*)(cn=ulrich.fourier*)(sn=ulrich.fourier*))
2014-09-12 11:35:32+0200 [-] [caldav-0] [-] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP search returned 2 results
2014-09-12 11:35:32+0200 [-] [caldav-0] [-] [twext.web2.server#info] Exception rendering:
2014-09-12 11:35:32+0200 [-] [caldav-0] [-] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AssertionError'>:
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/extensions.py:410:report_DAV__principal_property_search
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:224:recordsMatchingFieldsWithCUType
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1187:unwindGenerator
2014-09-12 11:35:32+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1045:_inlineCallbacks
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/aggregate.py:162:recordsMatchingFields
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:951:recordsMatchingFields
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:749:_ldapResultToRecord
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/ldapdirectory.py:1146:__init__
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/cachingdirectory.py:395:__init__
2014-09-12 11:35:32+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twistedcaldav/directory/directory.py:938:__init__
2014-09-12 11:35:32+0200 [-] [caldav-0] ]
2014-09-12 11:35:43+0200 [-] Received SIGINT, shutting down.
2014-09-12 11:35:43+0200 [AMPLoggingProtocol,0,] AMPLoggingProtocol connection lost (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
2014-09-12 11:35:43+0200 [-] [caldav-0] [-] Received SIGINT, shutting down.
2014-09-12 11:35:43+0200 [-] [caldav-0] [-] Received SIGTERM, shutting down.
2014-09-12 11:35:43+0200 [-] [caldav-0] [AMP,client] AMP connection lost (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-12 11:35:43+0200 [-] [caldav-0] [-] Main loop terminated.
2014-09-12 11:35:43+0200 [-] [caldav-0] [-] Server Shut Down.
2014-09-12 11:35:43+0200 [-] [caldav-1] [-] Received SIGINT, shutting down.
2014-09-12 11:35:43+0200 [-] [caldav-1] [AMP,client] AMP connection lost (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-12 11:35:43+0200 [-] [caldav-1] [-] Main loop terminated.
2014-09-12 11:35:43+0200 [-] [caldav-1] [-] Received SIGTERM, shutting down.
2014-09-12 11:35:43+0200 [-] [caldav-1] [-] Server Shut Down.
2014-09-12 11:35:43+0200 [-] [groupcacher] 2014-09-12 11:35:43+0200 [-] Received SIGINT, shutting down.
2014-09-12 11:35:43+0200 [-] [groupcacher] 2014-09-12 11:35:43+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacherService#warn] Stopping group membership cacher service
2014-09-12 11:35:43+0200 [-] [groupcacher] 2014-09-12 11:35:43+0200 [-] [twistedcaldav.database#debug] Closing ADBAPI thread: 140671829960448
2014-09-12 11:35:43+0200 [-] [groupcacher] 2014-09-12 11:35:43+0200 [-] Received SIGTERM, shutting down.
2014-09-12 11:35:43+0200 [-] [groupcacher] 2014-09-12 11:35:43+0200 [-] Main loop terminated.
2014-09-12 11:35:43+0200 [-] [groupcacher] 2014-09-12 11:35:43+0200 [-] Server Shut Down.
2014-09-12 11:35:43+0200 [-] (TCP Port 8843 Closed)
2014-09-12 11:35:43+0200 [-] (TCP Port 8443 Closed)
2014-09-12 11:35:43+0200 [-] (TCP Port 8008 Closed)
2014-09-12 11:35:43+0200 [-] (UNIX Port '/tmp/caldavd-stats.sock' Closed)
2014-09-12 11:35:43+0200 [-] (UNIX Port '/tmp/caldavd.sock' Closed)
2014-09-12 11:35:43+0200 [AMPLoggingProtocol,1,] AMPLoggingProtocol connection lost (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
More information about the calendarserver-users
mailing list