Package: calendarserver Version: 7.0+dfsg-1 Severity: normal Dear Maintainer,
There are errors in logs : 2016-02-21 12:58:35+0100 [-] [caldav-0] [BinaryBoxProtocol,client] [twext.enterprise.jobs.jobitem#debug] JobItem: 208 starting to run 2016-02-21 12:58:35+0100 [-] [twext.enterprise.jobs.queue#debug] workCheck: processed 1 jobs in one loop 2016-02-21 12:58:35+0100 [-] [twext.enterprise.jobs.queue#debug] workCheckLoop: interval set to 0.1s 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twext.enterprise.jobs.jobitem#debug] JobItem: 208 loaded CALENDAR_OBJECT_UPGRADE_WORK t=3.148 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [txdav.caldav.datastore.sql#debug] Data upgrade calendar object with resource-id: 337 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [txdav.base.datastore.util.QueryCacher#debug] Getting Cache Token for 'homeWithID:0:None:83' 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [txdav.base.datastore.util.QueryCacher#debug] Getting Cache Token for 'homeMetaData:83' 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcacher.Memcacher#debug] Getting Cache Token for '83' 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcacher.Memcacher#debug] Getting Cache Token for '83/guillaume' 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [txdav.base.datastore.util.QueryCacher#debug] Getting Cache Token for 'objectWithResourceID:83:196' 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [txdav.base.datastore.util.QueryCacher#debug] Getting Cache Token for 'homeChildMetaData:196' 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcacher.Memcacher#debug] Getting Cache Token for '196' 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcacher.Memcacher#debug] Getting Cache Token for '196/guillaume' 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Busied client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 0, #busy: 1, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Freed client: <twistedcaldav.memcachepool.PooledMemCacheProtocol instance at 0x7f1318ac9518> 2016-02-21 12:58:35+0100 [-] [caldav-0] [PooledMemCacheProtocol,client] [twistedcaldav.memcachepool.MemCachePool#debug] Clients #free: 1, #busy: 0, #pending: 0, #queued: 0 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [txdav.who.cache#debug] Directory cache miss: emailAddress guillaume 2016-02-21 12:58:35+0100 [-] [directoryproxy] 2016-02-21 12:58:35+0100 [DirectoryProxyAMPProtocol,0,] [txdav.dps.server#debug] RecordsWithEmailAddress: guillaume 2016-02-21 12:58:35+0100 [-] [directoryproxy] 2016-02-21 12:58:35+0100 [DirectoryProxyAMPProtocol,0,] [txdav.who.cache#debug] Directory cache miss: emailAddress guillaume 2016-02-21 12:58:35+0100 [-] [directoryproxy] 2016-02-21 12:58:35+0100 [DirectoryProxyAMPProtocol,0,] [txdav.who.cache#debug] Directory cache miss: emailAddress guillaume 2016-02-21 12:58:35+0100 [-] [caldav-0] [AMP,client] [txdav.dps.client#debug] DPS call <class 'txdav.dps.commands.RecordsWithEmailAddressCommand'> duration=2.12ms, results=0 2016-02-21 12:58:35+0100 [-] [caldav-0] [AMP,client] [twext.enterprise.jobs.jobitem#error] JobItem: 208, WorkItem: 47855 failed: [Failure instance: Traceback: <type 'exceptions.UnicodeDecodeError'>: 'ascii' codec can't decode byte 0xc3 in position 217: ordinal not in range(128) 2016-02-21 12:58:35+0100 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1184:gotResult 2016-02-21 12:58:35+0100 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1128:_inlineCallbacks 2016-02-21 12:58:35+0100 [-] [caldav-0] /usr/lib/python2.7/dist-packages/txdav/caldav/datastore/sql.py:4336:upgradeData 2016-02-21 12:58:35+0100 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1274:unwindGenerator 2016-02-21 12:58:35+0100 [-] [caldav-0] --- <exception caught here> --- 2016-02-21 12:58:35+0100 [-] [caldav-0] /usr/lib/python2.7/dist-packages/twisted/internet/defer.py:1128:_inlineCallbacks 2016-02-21 12:58:35+0100 [-] [caldav-0] /usr/lib/python2.7/dist-packages/txdav/caldav/datastore/sql.py:4032:updateDatabase 2016-02-21 12:58:35+0100 [-] [caldav-0] /usr/lib/python2.7/dist-packages/txdav/caldav/datastore/sql.py:3906:_generateEtag 2016-02-21 12:58:35+0100 [-] [caldav-0] ] 2016-02-21 12:58:35+0100 [-] [caldav-0] [AMP,client] [twext.enterprise.jobs.jobitem#debug] JobItem: 208 failed CALENDAR_OBJECT_UPGRADE_WORK t=38.215 2016-02-21 12:58:35+0100 [-] [caldav-0] [-] [twext.enterprise.jobs.jobitem#debug] JobItem: 208 marking as failed 67 t=41.165 These error appear without a client is connected. -- System Information: Debian Release: stretch/sid APT prefers testing APT policy: (990, 'testing'), (500, 'stable'), (100, 'unstable'), (10, 'experimental') Architecture: amd64 (x86_64) Foreign Architectures: i386 Kernel: Linux 4.3.0-1-amd64 (SMP w/6 CPU cores) Locale: LANG=fr_FR.utf8, LC_CTYPE=fr_FR.utf8 (charmap=UTF-8) Shell: /bin/sh linked to /bin/dash Init: systemd (via /run/systemd/system) Versions of packages calendarserver depends on: ii adduser 3.113+nmu3 ii memcached 1.4.25-2 ii python-crypto 2.6.1-6 ii python-dateutil 2.4.2-1 ii python-kerberos 1.1.5-2+b1 ii python-openssl 0.15.1-2 ii python-pg8000 1.10.2-1 ii python-psutil 3.4.2-1 ii python-pycalendar 2.1~svn15020-1 ii python-service-identity 14.0.0-1 ii python-setproctitle 1.1.8-1+b1 ii python-sqlparse 0.1.18-1 ii python-twext 0.1.b2.dev15059-1 ii python-twisted 15.5.0-4 ii python-tz 2012c+dfsg-0.1 ii python-xattr 0.7.8-3 ii python-zope.interface 4.1.3-1 pn python:any <none> ii ssl-cert 1.0.37 Versions of packages calendarserver recommends: ii python-pam 0.4.2-13.2 calendarserver suggests no packages. -- Configuration Files: /etc/caldavd/accounts.xml [Errno 13] Permission non accordée: u'/etc/caldavd/accounts.xml' /etc/caldavd/augments.xml changed [not included] /etc/caldavd/caldavd.plist changed [not included] /etc/caldavd/resources.xml changed [not included] /etc/default/calendarserver changed [not included] -- no debconf information
