[CalendarServer-users] Problem when start calendarserver

Michael Parchet mparchet at sunrise.ch
Thu May 12 13:11:11 PDT 2016


Hello,

I have install the calendarserver dependence throw pip with this command,

pip install -r requirements-stable.txt




but when I start the calendarserver, I have a log similar to

Starting server...
Reading configuration from file: 
/Applications/MAMP/htdocs/CalendarServer-7.0/conf/caldavd-dev.plist
2016-05-12 21:40:08+0200 [-] Log opened.
2016-05-12 21:40:08+0200 [-] [twisted.application.app#info] twistd 
15.2.1 
(/System/Library/Frameworks/Python.framework/Versions/2.7/Resources/Python.app/Contents/MacOS/Python 
2.7.10) starting up.
2016-05-12 21:40:08+0200 [-] [twisted.application.app#info] reactor 
class: twisted.internet.selectreactor.SelectReactor.
2016-05-12 21:40:08+0200 [-] [twisted.internet.tcp#info] 
AMPPushNotifierFactory starting on 62311
2016-05-12 21:40:08+0200 [-] ControlSocket starting on 
'./data/Logs/state/caldavd.sock'
2016-05-12 21:40:08+0200 [-] [txdav.base.datastore.subpostgres#info] 
Creating /tmp/ccs_postgres_b58a648cbe50bc0e4ac7cd29028ed59e
2016-05-12 21:40:08+0200 [-] [txdav.base.datastore.subpostgres#info] 
Cluster already exists at 
/Applications/MAMP/htdocs/CalendarServer-7.0/data/Data/Database/cluster
2016-05-12 21:40:08+0200 [-] [txdav.base.datastore.subpostgres#info] 
Requesting postgres start via: ['/usr/local/bin/pg_ctl', 'start', 
'--log=/Applications/MAMP/htdocs/CalendarServer-7.0/data/Logs/postgres.log', 
'--timeout=86400', '-w', '-o', "-c listen_addresses='' -c 
unix_socket_directories=/tmp/ccs_postgres_b58a648cbe50bc0e4ac7cd29028ed59e 
-c shared_buffers=139 -c max_connections=93 -c 
standard_conforming_strings=on -c unix_socket_permissions=0770 -c 
log_line_prefix=%t"]
2016-05-12 21:40:08+0200 [-] [memcached-Default] Upon execvpe memcached 
['memcached', '-U', '0', '-s', './data/Logs/state/memcache.sock'] in 
environment id 4647634656
2016-05-12 21:40:08+0200 [-] [memcached-Default] :Traceback (most recent 
call last):
2016-05-12 21:40:08+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
424, in _fork
2016-05-12 21:40:08+0200 [-] [memcached-Default]     environment)
2016-05-12 21:40:08+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
502, in _execChild
2016-05-12 21:40:08+0200 [-] [memcached-Default] os.execvpe(executable, 
args, environment)
2016-05-12 21:40:08+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 355, in execvpe
2016-05-12 21:40:08+0200 [-] [memcached-Default]     _execvpe(file, 
args, env)
2016-05-12 21:40:08+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 382, in _execvpe
2016-05-12 21:40:08+0200 [-] [memcached-Default]     func(fullname, 
*argrest)
2016-05-12 21:40:08+0200 [-] [memcached-Default] OSError: [Errno 2] No 
such file or directory
2016-05-12 21:40:08+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#info] waiting for 
server to start....
2016-05-12 21:40:09+0200 [-] [memcached-Default] Upon execvpe memcached 
['memcached', '-U', '0', '-s', './data/Logs/state/memcache.sock'] in 
environment id 4647571816
2016-05-12 21:40:09+0200 [-] [memcached-Default] :Traceback (most recent 
call last):
2016-05-12 21:40:09+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
424, in _fork
2016-05-12 21:40:09+0200 [-] [memcached-Default]     environment)
2016-05-12 21:40:09+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
502, in _execChild
2016-05-12 21:40:09+0200 [-] [memcached-Default] os.execvpe(executable, 
args, environment)
2016-05-12 21:40:09+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 355, in execvpe
2016-05-12 21:40:09+0200 [-] [memcached-Default]     _execvpe(file, 
args, env)
2016-05-12 21:40:09+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 382, in _execvpe
2016-05-12 21:40:09+0200 [-] [memcached-Default]     func(fullname, 
*argrest)
2016-05-12 21:40:09+0200 [-] [memcached-Default] OSError: [Errno 2] No 
such file or directory
2016-05-12 21:40:09+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#info]  done
2016-05-12 21:40:09+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#info] server started
2016-05-12 21:40:09+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#info] pg_ctl process 
ended with status=0
2016-05-12 21:40:09+0200 [-] [txdav.base.datastore.subpostgres#info] 
/usr/local/bin/pg_ctl exited
2016-05-12 21:40:09+0200 [-] [txdav.who.util#info] Configuring augment 
service of type: <class 'twistedcaldav.directory.augment.AugmentXMLDB'>
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseSchemaStep#warn] 
Beginning database schema check.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseSchemaStep#warn] 
Required database key VERSION: 58.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseSchemaStep#warn] 
Actual database key VERSION: 58.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseSchemaStep#warn] 
Schema version check complete: no upgrade needed.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseSchemaStep#warn] 
Database schema check complete.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseAddressBookDataStep#warn] 
Beginning database addressbook data check.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseAddressBookDataStep#warn] 
Required database key ADDRESSBOOK-DATAVERSION: 2.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseAddressBookDataStep#warn] 
Actual database key ADDRESSBOOK-DATAVERSION: 2.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseAddressBookDataStep#warn] 
Addressbook data version check complete: no upgrade needed.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseAddressBookDataStep#warn] 
Database addressbook data check complete.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseCalendarDataStep#warn] 
Beginning database calendar data check.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseCalendarDataStep#warn] 
Required database key CALENDAR-DATAVERSION: 6.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseCalendarDataStep#warn] 
Actual database key CALENDAR-DATAVERSION: 6.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseCalendarDataStep#warn] 
Calendar data version check complete: no upgrade needed.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseCalendarDataStep#warn] 
Database calendar data check complete.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseNotificationDataStep#warn] 
Beginning database notification data check.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseNotificationDataStep#warn] 
Required database key NOTIFICATION-DATAVERSION: 1.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseNotificationDataStep#warn] 
Actual database key NOTIFICATION-DATAVERSION: 1.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseNotificationDataStep#warn] 
Notification data version check complete: no upgrade needed.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseNotificationDataStep#warn] 
Database notification data check complete.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseOtherStep#warn] 
Beginning database other upgrades check.
2016-05-12 21:40:09+0200 [-] 
[txdav.common.datastore.upgrade.sql.upgrade.UpgradeDatabaseOtherStep#warn] 
Database other upgrades check complete.
2016-05-12 21:40:09+0200 [-] [twistedcaldav.upgrade#warn] Loading 
delegate assignments from XML
2016-05-12 21:40:09+0200 [-] 
[twistedcaldav.memcachepool.MemCacheClientFactory#error] MemCache 
connection failed: [Failure instance: Traceback (failure with no 
frames): <class 'twisted.internet.error.ConnectError'>: An error 
occurred while connecting: 2: No such file or directory.
     ]
2016-05-12 21:40:11+0200 [-] [memcached-Default] Upon execvpe memcached 
['memcached', '-U', '0', '-s', './data/Logs/state/memcache.sock'] in 
environment id 4651329528
2016-05-12 21:40:11+0200 [-] [memcached-Default] :Traceback (most recent 
call last):
2016-05-12 21:40:11+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
424, in _fork
2016-05-12 21:40:11+0200 [-] [memcached-Default]     environment)
2016-05-12 21:40:11+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
502, in _execChild
2016-05-12 21:40:11+0200 [-] [memcached-Default] os.execvpe(executable, 
args, environment)
2016-05-12 21:40:11+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 355, in execvpe
2016-05-12 21:40:11+0200 [-] [memcached-Default]     _execvpe(file, 
args, env)
2016-05-12 21:40:11+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 382, in _execvpe
2016-05-12 21:40:11+0200 [-] [memcached-Default]     func(fullname, 
*argrest)
2016-05-12 21:40:11+0200 [-] [memcached-Default] OSError: [Errno 2] No 
such file or directory
2016-05-12 21:40:12+0200 [-] 
[twistedcaldav.memcachepool.MemCacheClientFactory#error] MemCache 
connection failed: [Failure instance: Traceback (failure with no 
frames): <class 'twisted.internet.error.ConnectError'>: An error 
occurred while connecting: 2: No such file or directory.
     ]
2016-05-12 21:40:15+0200 [-] [memcached-Default] Upon execvpe memcached 
['memcached', '-U', '0', '-s', './data/Logs/state/memcache.sock'] in 
environment id 4651329528
2016-05-12 21:40:15+0200 [-] [memcached-Default] :Traceback (most recent 
call last):
2016-05-12 21:40:15+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
424, in _fork
2016-05-12 21:40:15+0200 [-] [memcached-Default]     environment)
2016-05-12 21:40:15+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
502, in _execChild
2016-05-12 21:40:15+0200 [-] [memcached-Default] os.execvpe(executable, 
args, environment)
2016-05-12 21:40:15+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 355, in execvpe
2016-05-12 21:40:15+0200 [-] [memcached-Default]     _execvpe(file, 
args, env)
2016-05-12 21:40:15+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 382, in _execvpe
2016-05-12 21:40:15+0200 [-] [memcached-Default]     func(fullname, 
*argrest)
2016-05-12 21:40:15+0200 [-] [memcached-Default] OSError: [Errno 2] No 
such file or directory
2016-05-12 21:40:21+0200 [-] 
[twistedcaldav.memcachepool.MemCacheClientFactory#error] MemCache 
connection failed: [Failure instance: Traceback (failure with no 
frames): <class 'twisted.internet.error.ConnectError'>: An error 
occurred while connecting: 2: No such file or directory.
     ]
2016-05-12 21:40:23+0200 [-] [memcached-Default] Upon execvpe memcached 
['memcached', '-U', '0', '-s', './data/Logs/state/memcache.sock'] in 
environment id 4651329528
2016-05-12 21:40:23+0200 [-] [memcached-Default] :Traceback (most recent 
call last):
2016-05-12 21:40:23+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
424, in _fork
2016-05-12 21:40:23+0200 [-] [memcached-Default]     environment)
2016-05-12 21:40:23+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
502, in _execChild
2016-05-12 21:40:23+0200 [-] [memcached-Default] os.execvpe(executable, 
args, environment)
2016-05-12 21:40:23+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 355, in execvpe
2016-05-12 21:40:23+0200 [-] [memcached-Default]     _execvpe(file, 
args, env)
2016-05-12 21:40:23+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 382, in _execvpe
2016-05-12 21:40:23+0200 [-] [memcached-Default]     func(fullname, 
*argrest)
2016-05-12 21:40:23+0200 [-] [memcached-Default] OSError: [Errno 2] No 
such file or directory
2016-05-12 21:40:39+0200 [-] [memcached-Default] Upon execvpe memcached 
['memcached', '-U', '0', '-s', './data/Logs/state/memcache.sock'] in 
environment id 4651330368
2016-05-12 21:40:39+0200 [-] [memcached-Default] :Traceback (most recent 
call last):
2016-05-12 21:40:39+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
424, in _fork
2016-05-12 21:40:39+0200 [-] [memcached-Default]     environment)
2016-05-12 21:40:39+0200 [-] [memcached-Default]   File 
"/Library/Python/2.7/site-packages/twisted/internet/process.py", line 
502, in _execChild
2016-05-12 21:40:39+0200 [-] [memcached-Default] os.execvpe(executable, 
args, environment)
2016-05-12 21:40:39+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 355, in execvpe
2016-05-12 21:40:39+0200 [-] [memcached-Default]     _execvpe(file, 
args, env)
2016-05-12 21:40:39+0200 [-] [memcached-Default]   File 
"/System/Library/Frameworks/Python.framework/Versions/2.7/lib/python2.7/os.py", 
line 382, in _execvpe
2016-05-12 21:40:39+0200 [-] [memcached-Default]     func(fullname, 
*argrest)
2016-05-12 21:40:39+0200 [-] [memcached-Default] OSError: [Errno 2] No 
such file or directory
2016-05-12 21:40:45+0200 [-] 
[twistedcaldav.memcachepool.MemCacheClientFactory#error] MemCache 
connection failed: [Failure instance: Traceback (failure with no 
frames): <class 'twisted.internet.error.ConnectError'>: An error 
occurred while connecting: 2: No such file or directory.
     ]
^C2016-05-12 21:40:51+0200 [-] [twisted.internet.base#info] Received 
SIGINT, shutting down.
2016-05-12 21:40:51+0200 [-] (UNIX Port './data/Logs/state/caldavd.sock' 
Closed)
2016-05-12 21:40:51+0200 [-] [twisted.internet.tcp#info] (TCP Port 62311 
Closed)
2016-05-12 21:40:51+0200 [-] [txdav.base.datastore.subpostgres#info] 
Requesting postgres stop via: ['/usr/local/bin/pg_ctl', 'stop', 
'--log=/Applications/MAMP/htdocs/CalendarServer-7.0/data/Logs/postgres.log']
2016-05-12 21:40:51+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#error] pg_ctl: PID 
file 
"/Applications/MAMP/htdocs/CalendarServer-7.0/data/Data/Database/cluster/postmaster.pid" 
does not exist
2016-05-12 21:40:51+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#error] Is server running?
2016-05-12 21:40:51+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#info] pg_ctl process 
ended with status=256
2016-05-12 21:40:51+0200 [-] 
[txdav.base.datastore.subpostgres.PostgresMonitor#error] Could not start 
postgres; see postgres.log
2016-05-12 21:41:43+0200 [-] 
[twistedcaldav.memcachepool.MemCacheClientFactory#error] MemCache 
connection failed: [Failure instance: Traceback (failure with no 
frames): <class 'twisted.internet.error.ConnectError'>: An error 
occurred while connecting: 2: No such file or directory.
     ]
2016-05-12 21:44:44+0200 [-] 
[twistedcaldav.memcachepool.MemCacheClientFactory#error] MemCache 
connection failed: [Failure instance: Traceback (failure with no 
frames): <class 'twisted.internet.error.ConnectError'>: An error 
occurred while connecting: 2: No such file or directory.
     ]
2016-05-12 21:45:09+0200 [-] [txdav.common.datastore.sql#error] 
Transaction abort too long: PG-TXN<xmlDelegatesToStore>, Statements: 0, 
IUDs: 0, Statement: None
2016-05-12 21:45:09+0200 [-] Unhandled Error
     Traceback (most recent call last):
       File 
"/Library/Python/2.7/site-packages/twisted/application/app.py", line 
405, in startReactor
         self.config, oldstdout, oldstderr, self.profiler, reactor)
       File 
"/Library/Python/2.7/site-packages/twisted/application/app.py", line 
326, in runReactorWithLogging
         reactor.run()
       File 
"/Library/Python/2.7/site-packages/twisted/internet/base.py", line 1194, 
in run
         self.mainLoop()
       File 
"/Library/Python/2.7/site-packages/twisted/internet/base.py", line 1203, 
in mainLoop
         self.runUntilCurrent()
     --- <exception caught here> ---
       File 
"/Library/Python/2.7/site-packages/twisted/internet/base.py", line 825, 
in runUntilCurrent
         call.func(*call.args, **call.kw)
       File 
"/Applications/MAMP/htdocs/CalendarServer-7.0/txdav/common/datastore/sql.py", 
line 546, in _forceAbort
         self.txn.timeout()
       File 
"/Applications/MAMP/htdocs/CalendarServer-7.0/txdav/common/datastore/sql.py", 
line 1019, in timeout
         return self.abort()
       File 
"/Applications/MAMP/htdocs/CalendarServer-7.0/txdav/common/datastore/sql.py", 
line 1011, in abort
         return self._sqlTxn.abort()
       File 
"/Applications/MAMP/htdocs/CalendarServer-7.0/src/twextpy/twext/enterprise/adbapi2.py", 
line 736, in abort
         result = super(_SingleTxn, self).abort()
       File 
"/Applications/MAMP/htdocs/CalendarServer-7.0/src/twextpy/twext/enterprise/adbapi2.py", 
line 376, in abort
         return self._end(self._connection.rollback).addErrback(log.err)
       File 
"/Applications/MAMP/htdocs/CalendarServer-7.0/src/twextpy/twext/enterprise/adbapi2.py", 
line 368, in _end
         raise AlreadyFinishedError(self._completed)
     twext.enterprise.ienterprise.AlreadyFinishedError: released

2016-05-12 21:54:46+0200 [-] 
[twistedcaldav.memcachepool.MemCacheClientFactory#error] MemCache 
connection failed: [Failure instance: Traceback (failure with no 
frames): <class 'twisted.internet.error.ConnectError'>: An error 
occurred while connecting: 2: No such file or directory.
     ]


I have found this on the apple community forum


https://discussions.apple.com/thread/6494327?start=0&tstart=0


Could you help me please to fix this issue ?

Thanks for your support

Best regards

mparchet


More information about the calendarserver-users mailing list