[CalendarServer-users] Problem when start calendarserver
Andre LaBranche
dre at apple.com
Thu May 12 17:10:01 PDT 2016
Hi,
There is more to installing Calendar Server than just the pip dependencies. Specifically, there are various C-based dependencies you need as well (such as memcache, which appears to be missing in your case).
Unfortunately the task of installing Calendar Server is kind of complicated, and varies by platform. You can try using "bin/package <dest directory>" to attempt to install Calendar Server and all its dependencies into the specified directory. The specific way you start the service also matters, in particular the invoking environment. The 'package' script emits an environment.sh <http://trac.calendarserver.org/browser/CalendarServer/trunk/bin/package#L154> file that should be sourced by the shell that starts CalendarServer.
... and now that I think of it, everything I just said applies to trunk (at least v8 and newer), but possibly not v7... Give it a try and if you're still stuck tomorrow I'll dig deeper :)
(The easiest way to use the service is in 'develop' mode which takes care of all this stuff for you, where you use bin/develop and then bin/run, but that is not really 'installation' per se)
-dre
> On May 12, 2016, at 1:11 PM, Michael Parchet <mparchet at sunrise.ch> wrote:
>
> 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
> _______________________________________________
> calendarserver-users mailing list
> calendarserver-users at lists.macosforge.org
> https://lists.macosforge.org/mailman/listinfo/calendarserver-users
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.macosforge.org/pipermail/calendarserver-users/attachments/20160512/522bc46c/attachment-0001.html>
More information about the calendarserver-users
mailing list