[CalendarServer-users] Error: 'Server' object has no attribute 'protocol'
Ulrich Fourier
ulrich.fourier at rockyourlife.de
Thu Sep 11 07:29:56 PDT 2014
On 11.09.14 15:22, Cyrus Daboo wrote:
> Hi Ulrich,
>
> --On September 11, 2014 at 3:15:04 PM +0200 Ulrich Fourier
> <ulrich.fourier at rockyourlife.de> wrote:
>
>> Could it be a storage problem, I want to store the calendar on the
>> filesystem not in a postgresql?
>
> Don't do that. The file store is now only supported as a means of
> migrating from legacy file store to the SQL store. All the new
> functionality we build is going into the SQL store. As a result the
> file store may well be broken for operations other than the migration
> step we use it for.
>
> That said the problem you are seeing may not be related to that. A
> browser should definitely work - in fact that is probably the first
> and easiest thing to try. The errors you saw with the browser
> suggestion some problem with perhaps using SSL when that isn't
> enabled. So I suggest you check that again. If you have no luck, turn
> the server off, clear out the error log file, then turn it on again
> and try again with the browser. Send the log file and we can look at
> the whole startup process and maybe spot the issue.
>
Hi Cyrus,
thank you, in that case I will setup a database.
I attached both log files, I hope that they are helpful.
Uli
-------------- next part --------------
Log opened - server start: [Thu Sep 11 16:13:33 2014].
88.217.236.30 - - [11/Sep/2014:16:13:45 +0200] "GET / HTTP/1.1" 304 0 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.120 Safari/537.36" i=0 t=21.5 or=1
88.217.236.30 - - [11/Sep/2014:16:13:45 +0200] "GET /favicon.ico HTTP/1.1" 401 141 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.120 Safari/537.36" i=0 t=6.0 or=1
88.217.236.30 - - [11/Sep/2014:16:13:47 +0200] "GET /calendars/ HTTP/1.1" 401 141 "https://rockyourlife.de:8843/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.120 Safari/537.36" i=0 t=5.3 or=1
88.217.236.30 - - [11/Sep/2014:16:13:56 +0200] "GET /calendars/ HTTP/1.1" 401 330 "https://rockyourlife.de:8843/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.120 Safari/537.36" i=0 t=8.1 or=1
88.217.236.30 - - [11/Sep/2014:16:14:39 +0200] "GET /calendars/ HTTP/1.1" 401 141 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.120 Safari/537.36" i=0 t=3.9 or=1
88.217.236.30 - - [11/Sep/2014:16:14:47 +0200] "GET /calendars/ HTTP/1.1" 401 330 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.120 Safari/537.36" i=0 t=6.3 or=1
88.217.236.30 - - [11/Sep/2014:16:15:05 +0200] "GET /calendars/ HTTP/1.1" 401 141 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_5) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/37.0.2062.120 Safari/537.36" i=0 t=3.6 or=1
Log closed - server stop: [Thu Sep 11 16:15:28 2014].
-------------- next part --------------
2014-09-11 16:13:33+0200 [-] Log opened.
2014-09-11 16:13:33+0200 [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-11 16:13:33+0200 [-] reactor class: twisted.internet.pollreactor.PollReactor.
2014-09-11 16:13:33+0200 [-] AMPLoggingFactory starting on '/tmp/caldavd.sock'
2014-09-11 16:13:33+0200 [-] [calendarserver.accesslog#debug] rotateGlobalHitHistoryStats: [{'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}, {'hits': 0, 'time': 1410444813}]
2014-09-11 16:13:33+0200 [-] CalDAVStatisticsServer starting on '/tmp/caldavd-stats.sock'
2014-09-11 16:13:33+0200 [-] LimitingInheritingProtocolFactory starting on 8008
2014-09-11 16:13:33+0200 [-] LimitingInheritingProtocolFactory starting on 8443
2014-09-11 16:13:33+0200 [-] LimitingInheritingProtocolFactory starting on 8843
2014-09-11 16:13:33+0200 [-] [calendarserver.tap.caldav.CalDAVServiceMaker#info] Adding group caching service
2014-09-11 16:13:33+0200 [-] [memcached-Default] can't run as root without the -u switch
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] Log opened.
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] reactor class: twisted.internet.selectreactor.SelectReactor.
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacherService#warn] Starting group membership cacher service
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] is group-cacher-populated
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Getting Cache Token for 'group-cacher-populated'
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Initating new client connection to: IPv4Address(TCP, '127.0.0.1', 11211)
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Updating group membership cache
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Group membership snapshot file exists: /var/www/caldavd/Data/memberships_cache
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Attempting to acquire group membership cache lock
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] add group-cacher-lock
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Adding Cache Token for 'group-cacher-lock'
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Acquired lock
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Retrieving list of all proxies
2014-09-11 16:13:34+0200 [-] [caldav-1] Reading configuration from file: /etc/caldavd/caldavd.plist
2014-09-11 16:13:34+0200 [-] [caldav-1] [-] Log opened.
2014-09-11 16:13:34+0200 [-] [caldav-1] [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-11 16:13:34+0200 [-] [caldav-1] [-] reactor class: twisted.internet.selectreactor.SelectReactor.
2014-09-11 16:13:34+0200 [-] [caldav-0] Reading configuration from file: /etc/caldavd/caldavd.plist
2014-09-11 16:13:34+0200 [-] [caldav-0] [-] Log opened.
2014-09-11 16:13:34+0200 [-] [caldav-0] [-] twistd 12.0.0 (/usr/bin/python2.7 2.7.3) starting up.
2014-09-11 16:13:34+0200 [-] [caldav-0] [-] reactor class: twisted.internet.selectreactor.SelectReactor.
2014-09-11 16:13:34+0200 [calendarserver.accesslog.AMPLoggingFactory] AMPLoggingProtocol connection established (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
2014-09-11 16:13:34+0200 [calendarserver.accesslog.AMPLoggingFactory] AMPLoggingProtocol connection established (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
2014-09-11 16:13:34+0200 [-] [caldav-0] [-] AMP connection established (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-11 16:13:34+0200 [-] [caldav-1] [-] AMP connection established (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.database#debug] Starting ADBAPI thread: 139860540520192
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] There are 0 proxies
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Retrieving group hierarchy from directory
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] 0 groups retrieved from the directory
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] 0 groups are proxies
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] There are 0 users delegated-to via groups
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Taking snapshot of group memberships to /var/www/caldavd/Data/memberships_cache
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Storing 0 group memberships in memcached
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] set group-cacher-populated
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Setting Cache Token for 'group-cacher-populated'
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Releasing lock
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] delete group-cacher-lock
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCache#debug] Deleting Cache Token for 'group-cacher-lock'
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x4309680>
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacheUpdater#info] Group memberships cache updated
2014-09-11 16:13:34+0200 [-] [groupcacher] 2014-09-11 16:13:34+0200 [PooledMemCacheProtocol,client] [twistedcaldav.directory.directory.GroupMembershipCacherService#info] Scheduling next group membership update
2014-09-11 16:13:34+0200 [-] [memcached-Default] can't run as root without the -u switch
2014-09-11 16:13:36+0200 [-] [memcached-Default] can't run as root without the -u switch
2014-09-11 16:13:40+0200 [-] [memcached-Default] can't run as root without the -u switch
2014-09-11 16:13:45+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twext.web2.server#info] GET / HTTP/1.1
2014-09-11 16:13:45+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twext.web2.server#info] GET /favicon.ico HTTP/1.1
2014-09-11 16:13:45+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '784053266912630745292173428306800341612334858145875887617'
2014-09-11 16:13:45+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Initating new client connection to: IPv4Address(TCP, '127.0.0.1', 11211)
2014-09-11 16:13:45+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:45+0200 [-] [caldav-0] [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:45+0200 [-] [caldav-0] [Uninitialized] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:45+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:45+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:45+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Setting Cache Token for '784053266912630745292173428306800341612334858145875887617'
2014-09-11 16:13:45+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:45+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:45+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:45+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:47+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twext.web2.server#info] GET /calendars/ HTTP/1.1
2014-09-11 16:13:47+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '63031744201064215908719688380183090337702875212563411934'
2014-09-11 16:13:47+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:47+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Setting Cache Token for '63031744201064215908719688380183090337702875212563411934'
2014-09-11 16:13:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:48+0200 [-] [memcached-Default] can't run as root without the -u switch
2014-09-11 16:13:56+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twext.web2.server#info] GET /calendars/ HTTP/1.1
2014-09-11 16:13:56+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '63031744201064215908719688380183090337702875212563411934'
2014-09-11 16:13:56+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:56+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: checking dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|shortname|UlrichFourier|users|groups
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: miss dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|shortname|UlrichFourier|users|groups
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Faulting record for attribute 'shortname' with value 'UlrichFourier'
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP query for types ['users'], indexType shortname and indexKey UlrichFourier
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Retrieving ldap record with base ou=people,dc=rockyourlife,dc=de and filter (&(&(!(objectClass=organizationalUnit))(objectClass=inetOrgPerson))(dn=UlrichFourier)).
2014-09-11 16:13:56+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.extensions#info] Could not find the principal resource for user id: UlrichFourier
2014-09-11 16:14:04+0200 [-] [memcached-Default] can't run as root without the -u switch
2014-09-11 16:14:36+0200 [-] [memcached-Default] can't run as root without the -u switch
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twext.web2.server#info] GET /calendars/ HTTP/1.1
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '286579884750953364325102881126432113315786597146508329742'
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,1,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Setting Cache Token for '286579884750953364325102881126432113315786597146508329742'
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#info] Exception rendering:
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AttributeError'>: 'Server' object has no attribute 'protocol'
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/server.py:651:_cbFinishRender
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:368:callback
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:464:_startRunCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:125:writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/http.py:505:writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/stream.py:749:beginProducing
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:712:registerProducer
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/_newtls.py:231:registerProducer
2014-09-11 16:14:39+0200 [-] [caldav-0] ]
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#info] Exception rendering error page:
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.ValueError'>: registering producer <twext.web2.stream.StreamProducer object at 0x3c6f090> before previous one (<twext.web2.stream.StreamProducer object at 0x3c6abd0>) was unregistered
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/server.py:651:_cbFinishRender
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:368:callback
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:464:_startRunCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:125:writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/http.py:505:writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/stream.py:749:beginProducing
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:705:registerProducer
2014-09-11 16:14:39+0200 [-] [caldav-0] ]
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#info] Original exception:
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AttributeError'>: 'Server' object has no attribute 'protocol'
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/server.py:651:_cbFinishRender
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:368:callback
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:464:_startRunCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:125:writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/http.py:505:writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/stream.py:749:beginProducing
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:712:registerProducer
2014-09-11 16:14:39+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/_newtls.py:231:registerProducer
2014-09-11 16:14:39+0200 [-] [caldav-0] ]
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] Unhandled error in Deferred:
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] Unhandled Error
2014-09-11 16:14:39+0200 [-] [caldav-0] Traceback (most recent call last):
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 551, in _runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] current.result = callback(current.result, *args, **kw)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/server.py", line 619, in _processingFailed
2014-09-11 16:14:39+0200 [-] [caldav-0] d.addErrback(self._processingReallyFailed, reason)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 312, in addErrback
2014-09-11 16:14:39+0200 [-] [caldav-0] errbackKeywords=kw)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 290, in addCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] self._runCallbacks()
2014-09-11 16:14:39+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 551, in _runCallbacks
2014-09-11 16:14:39+0200 [-] [caldav-0] current.result = callback(current.result, *args, **kw)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/server.py", line 635, in _processingReallyFailed
2014-09-11 16:14:39+0200 [-] [caldav-0] self.writeResponse(response)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 125, in writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] return super(SSLRedirectRequest, self).writeResponse(response)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/http.py", line 505, in writeResponse
2014-09-11 16:14:39+0200 [-] [caldav-0] d = stream.StreamProducer(response.stream).beginProducing(self.chanRequest)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/stream.py", line 749, in beginProducing
2014-09-11 16:14:39+0200 [-] [caldav-0] self.consumer.registerProducer(self, True)
2014-09-11 16:14:39+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 705, in registerProducer
2014-09-11 16:14:39+0200 [-] [caldav-0] raise ValueError, "registering producer %s before previous one (%s) was unregistered" % (producer, self.producer)
2014-09-11 16:14:39+0200 [-] [caldav-0] exceptions.ValueError: registering producer <twext.web2.stream.StreamProducer object at 0x3c6f2d0> before previous one (<twext.web2.stream.StreamProducer object at 0x3c6abd0>) was unregistered
2014-09-11 16:14:39+0200 [-] [caldav-0]
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twext.web2.server#info] GET /calendars/ HTTP/1.1
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '862554574725466381433499852097166246014720303348863535510'
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Setting Cache Token for '862554574725466381433499852097166246014720303348863535510'
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:39+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:14:47+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twext.web2.server#info] GET /calendars/ HTTP/1.1
2014-09-11 16:14:47+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '862554574725466381433499852097166246014720303348863535510'
2014-09-11 16:14:47+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:47+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: checking dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|shortname|UlrichFourier|users|groups
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Memcache: miss dir|5A871574-0C86-44EE-B11B-B9440C3DC4DD|shortname|UlrichFourier|users|groups
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Faulting record for attribute 'shortname' with value 'UlrichFourier'
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] LDAP query for types ['users'], indexType shortname and indexKey UlrichFourier
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.ldapdirectory.LdapDirectoryService#debug] Retrieving ldap record with base ou=people,dc=rockyourlife,dc=de and filter (&(&(!(objectClass=organizationalUnit))(objectClass=inetOrgPerson))(dn=UlrichFourier)).
2014-09-11 16:14:47+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.extensions#info] Could not find the principal resource for user id: UlrichFourier
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twext.web2.server#info] GET /calendars/ HTTP/1.1
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '349544046306628998052557274982693993346640269403649950367'
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,2,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Setting Cache Token for '349544046306628998052557274982693993346640269403649950367'
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#info] Exception rendering:
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AttributeError'>: 'Server' object has no attribute 'protocol'
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/server.py:651:_cbFinishRender
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:368:callback
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:464:_startRunCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:125:writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/http.py:505:writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/stream.py:749:beginProducing
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:712:registerProducer
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/_newtls.py:231:registerProducer
2014-09-11 16:15:05+0200 [-] [caldav-0] ]
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#info] Exception rendering error page:
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.ValueError'>: registering producer <twext.web2.stream.StreamProducer object at 0x3c74990> before previous one (<twext.web2.stream.StreamProducer object at 0x3c74650>) was unregistered
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/server.py:651:_cbFinishRender
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:368:callback
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:464:_startRunCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:125:writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/http.py:505:writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/stream.py:749:beginProducing
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:705:registerProducer
2014-09-11 16:15:05+0200 [-] [caldav-0] ]
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#info] Original exception:
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twext.web2.server#error] [Failure instance: Traceback: <type 'exceptions.AttributeError'>: 'Server' object has no attribute 'protocol'
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/server.py:651:_cbFinishRender
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:368:callback
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:464:_startRunCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:551:_runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:125:writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/http.py:505:writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/stream.py:749:beginProducing
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twext/web2/channel/http.py:712:registerProducer
2014-09-11 16:15:05+0200 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/_newtls.py:231:registerProducer
2014-09-11 16:15:05+0200 [-] [caldav-0] ]
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] Unhandled error in Deferred:
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] Unhandled Error
2014-09-11 16:15:05+0200 [-] [caldav-0] Traceback (most recent call last):
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 551, in _runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] current.result = callback(current.result, *args, **kw)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/server.py", line 619, in _processingFailed
2014-09-11 16:15:05+0200 [-] [caldav-0] d.addErrback(self._processingReallyFailed, reason)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 312, in addErrback
2014-09-11 16:15:05+0200 [-] [caldav-0] errbackKeywords=kw)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 290, in addCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] self._runCallbacks()
2014-09-11 16:15:05+0200 [-] [caldav-0] --- <exception caught here> ---
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twisted/internet/defer.py", line 551, in _runCallbacks
2014-09-11 16:15:05+0200 [-] [caldav-0] current.result = callback(current.result, *args, **kw)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/server.py", line 635, in _processingReallyFailed
2014-09-11 16:15:05+0200 [-] [caldav-0] self.writeResponse(response)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 125, in writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] return super(SSLRedirectRequest, self).writeResponse(response)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/http.py", line 505, in writeResponse
2014-09-11 16:15:05+0200 [-] [caldav-0] d = stream.StreamProducer(response.stream).beginProducing(self.chanRequest)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/stream.py", line 749, in beginProducing
2014-09-11 16:15:05+0200 [-] [caldav-0] self.consumer.registerProducer(self, True)
2014-09-11 16:15:05+0200 [-] [caldav-0] File "/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 705, in registerProducer
2014-09-11 16:15:05+0200 [-] [caldav-0] raise ValueError, "registering producer %s before previous one (%s) was unregistered" % (producer, self.producer)
2014-09-11 16:15:05+0200 [-] [caldav-0] exceptions.ValueError: registering producer <twext.web2.stream.StreamProducer object at 0x3c74bd0> before previous one (<twext.web2.stream.StreamProducer object at 0x3c74650>) was unregistered
2014-09-11 16:15:05+0200 [-] [caldav-0]
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,3,88.217.236.30] [twext.web2.server#info] GET /calendars/ HTTP/1.1
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,3,88.217.236.30] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Getting Cache Token for '170798008080117901036634924262781803562131911468485813676'
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,3,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [HTTPChannel,3,88.217.236.30] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.directory.digest.DigestCredentialsMemcache#debug] Setting Cache Token for '170798008080117901036634924262781803562131911468485813676'
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x3b7add0>
2014-09-11 16:15:05+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0
2014-09-11 16:15:28+0200 [-] Received SIGINT, shutting down.
2014-09-11 16:15:28+0200 [AMPLoggingProtocol,0,] AMPLoggingProtocol connection lost (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
2014-09-11 16:15:28+0200 [-] [caldav-0] [-] Received SIGINT, shutting down.
2014-09-11 16:15:28+0200 [-] [caldav-0] [PooledMemCacheProtocol,client] Received SIGTERM, shutting down.
2014-09-11 16:15:28+0200 [-] [caldav-0] [AMP,client] AMP connection lost (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-11 16:15:28+0200 [-] [caldav-0] [-] Main loop terminated.
2014-09-11 16:15:28+0200 [-] [caldav-1] [-] Received SIGINT, shutting down.
2014-09-11 16:15:28+0200 [-] [caldav-1] [AMP,client] Received SIGTERM, shutting down.
2014-09-11 16:15:28+0200 [-] [caldav-1] [AMP,client] AMP connection lost (HOST:UNIXAddress(None) PEER:UNIXAddress('/tmp/caldavd.sock'))
2014-09-11 16:15:28+0200 [-] [caldav-1] [-] Main loop terminated.
2014-09-11 16:15:28+0200 [-] [caldav-1] [-] Server Shut Down.
2014-09-11 16:15:28+0200 [-] [groupcacher] 2014-09-11 16:15:28+0200 [-] Received SIGINT, shutting down.
2014-09-11 16:15:28+0200 [-] [groupcacher] 2014-09-11 16:15:28+0200 [-] [twistedcaldav.directory.directory.GroupMembershipCacherService#warn] Stopping group membership cacher service
2014-09-11 16:15:28+0200 [-] [groupcacher] 2014-09-11 16:15:28+0200 [-] Received SIGTERM, shutting down.
2014-09-11 16:15:28+0200 [-] [groupcacher] 2014-09-11 16:15:28+0200 [-] [twistedcaldav.database#debug] Closing ADBAPI thread: 139860540520192
2014-09-11 16:15:28+0200 [-] [groupcacher] 2014-09-11 16:15:28+0200 [-] Main loop terminated.
2014-09-11 16:15:28+0200 [-] [groupcacher] 2014-09-11 16:15:28+0200 [-] Server Shut Down.
2014-09-11 16:15:28+0200 [-] (TCP Port 8843 Closed)
2014-09-11 16:15:28+0200 [-] (TCP Port 8443 Closed)
2014-09-11 16:15:28+0200 [-] (TCP Port 8008 Closed)
2014-09-11 16:15:28+0200 [-] (UNIX Port '/tmp/caldavd-stats.sock' Closed)
2014-09-11 16:15:28+0200 [-] (UNIX Port '/tmp/caldavd.sock' Closed)
2014-09-11 16:15:28+0200 [AMPLoggingProtocol,1,] AMPLoggingProtocol connection lost (HOST:UNIXAddress('/tmp/caldavd.sock') PEER:UNIXAddress(''))
2014-09-11 16:15:28+0200 [-] [caldav-0] [-] Server Shut Down.
More information about the calendarserver-users
mailing list