[CalendarServer-users] Calendarserver not recognized by Apple Calendar

calendar at bastibe.de calendar at bastibe.de
Sun Aug 11 10:17:59 PDT 2013 

 

Hi, 

I can give you the contents of the error log (only the latest
entry): 

> 2013-08-11 17:10:04+0000 [-] [caldav-1] [-] Unhandled
Error
> 2013-08-11 17:10:05+0000 [-] [caldav-1] Traceback (most recent
call last):
> 2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 402,
in startReactor
> 2013-08-11 17:10:05+0000 [-] [caldav-1] self.config,
oldstdout, oldstderr, self.profiler, reactor)
> 2013-08-11 17:10:05+0000
[-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 323,
in runReactorWithLogging
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
reactor.run()
> 2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1169,
in run
> 2013-08-11 17:10:05+0000 [-] [caldav-1] self.mainLoop()
>
2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1178,
in mainLoop
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
self.runUntilCurrent()
> 2013-08-11 17:10:05+0000 [-] [caldav-1] ---
<exception caught here> ---
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
File "/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line
800, in runUntilCurrent
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
call.func(*call.args, **call.kw)
> 2013-08-11 17:10:05+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 874,
in _startNextRequest
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
self.resumeProducing()
> 2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/protocols/basic.py", line 499,
in resumeProducing
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
self.transport.resumeProducing()
> 2013-08-11 17:10:05+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/abstract.py", line
428, in resumeProducing
> 2013-08-11 17:10:05+0000 [-] [caldav-1] assert
self.connected and not self.disconnecting
> 2013-08-11 17:10:05+0000 [-]
[caldav-1] exceptions.AssertionError:
> 2013-08-11 17:10:05+0000 [-]
[caldav-1]
> 2013-08-11 17:10:15+0000 [-] [caldav-1] [-] Unhandled
Error
> 2013-08-11 17:10:15+0000 [-] [caldav-1] Traceback (most recent
call last):
> 2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 402,
in startReactor
> 2013-08-11 17:10:15+0000 [-] [caldav-1] self.config,
oldstdout, oldstderr, self.profiler, reactor)
> 2013-08-11 17:10:15+0000
[-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 323,
in runReactorWithLogging
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
reactor.run()
> 2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1169,
in run
> 2013-08-11 17:10:15+0000 [-] [caldav-1] self.mainLoop()
>
2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1178,
in mainLoop
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
self.runUntilCurrent()
> 2013-08-11 17:10:15+0000 [-] [caldav-1] ---
<exception caught here> ---
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
File "/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line
800, in runUntilCurrent
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
call.func(*call.args, **call.kw)
> 2013-08-11 17:10:15+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 874,
in _startNextRequest
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
self.resumeProducing()
> 2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/protocols/basic.py", line 499,
in resumeProducing
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
self.transport.resumeProducing()
> 2013-08-11 17:10:15+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/abstract.py", line
428, in resumeProducing
> 2013-08-11 17:10:15+0000 [-] [caldav-1] assert
self.connected and not self.disconnecting
> 2013-08-11 17:10:15+0000 [-]
[caldav-1] exceptions.AssertionError:
> 2013-08-11 17:10:15+0000 [-]
[caldav-1]
> 2013-08-11 17:10:04+0000 [-] [caldav-1] [-] Unhandled
Error
> 2013-08-11 17:10:05+0000 [-] [caldav-1] Traceback (most recent
call last):
> 2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 402,
in startReactor
> 2013-08-11 17:10:05+0000 [-] [caldav-1] self.config,
oldstdout, oldstderr, self.profiler, reactor)
> 2013-08-11 17:10:05+0000
[-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 323,
in runReactorWithLogging
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
reactor.run()
> 2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1169,
in run
> 2013-08-11 17:10:05+0000 [-] [caldav-1] self.mainLoop()
>
2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1178,
in mainLoop
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
self.runUntilCurrent()
> 2013-08-11 17:10:05+0000 [-] [caldav-1] ---
<exception caught here> ---
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
File "/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line
800, in runUntilCurrent
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
call.func(*call.args, **call.kw)
> 2013-08-11 17:10:05+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 874,
in _startNextRequest
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
self.resumeProducing()
> 2013-08-11 17:10:05+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/protocols/basic.py", line 499,
in resumeProducing
> 2013-08-11 17:10:05+0000 [-] [caldav-1]
self.transport.resumeProducing()
> 2013-08-11 17:10:05+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/abstract.py", line
428, in resumeProducing
> 2013-08-11 17:10:05+0000 [-] [caldav-1] assert
self.connected and not self.disconnecting
> 2013-08-11 17:10:05+0000 [-]
[caldav-1] exceptions.AssertionError:
> 2013-08-11 17:10:05+0000 [-]
[caldav-1]
> 2013-08-11 17:10:15+0000 [-] [caldav-1] [-] Unhandled
Error
> 2013-08-11 17:10:15+0000 [-] [caldav-1] Traceback (most recent
call last):
> 2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 402,
in startReactor
> 2013-08-11 17:10:15+0000 [-] [caldav-1] self.config,
oldstdout, oldstderr, self.profiler, reactor)
> 2013-08-11 17:10:15+0000
[-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/application/app.py", line 323,
in runReactorWithLogging
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
reactor.run()
> 2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1169,
in run
> 2013-08-11 17:10:15+0000 [-] [caldav-1] self.mainLoop()
>
2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line 1178,
in mainLoop
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
self.runUntilCurrent()
> 2013-08-11 17:10:15+0000 [-] [caldav-1] ---
<exception caught here> ---
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
File "/usr/lib/python2.7/dist-packages/twisted/internet/base.py", line
800, in runUntilCurrent
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
call.func(*call.args, **call.kw)
> 2013-08-11 17:10:15+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twext/web2/channel/http.py", line 874,
in _startNextRequest
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
self.resumeProducing()
> 2013-08-11 17:10:15+0000 [-] [caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/protocols/basic.py", line 499,
in resumeProducing
> 2013-08-11 17:10:15+0000 [-] [caldav-1]
self.transport.resumeProducing()
> 2013-08-11 17:10:15+0000 [-]
[caldav-1] File
"/usr/lib/python2.7/dist-packages/twisted/internet/abstract.py", line
428, in resumeProducing
> 2013-08-11 17:10:15+0000 [-] [caldav-1] assert
self.connected and not self.disconnecting
> 2013-08-11 17:10:15+0000 [-]
[caldav-1] exceptions.AssertionError:
> 2013-08-11 17:10:15+0000 [-]
[caldav-1]

So that definitely looks like something is wrong. Is this
the issue you had in mind? 

Also, the outputs of netstat -na | egrep
'8008|8443" is the following: 

> tcp 0 0 0.0.0.0:8443 0.0.0.0:* LISTEN

> tcp 0 0 192.168.1.47:8443 192.168.1.1:56194 ESTABLISHED
> tcp 0 0
192.168.1.47:8443 192.168.1.1:56221 ESTABLISHED 

Kind regards, 

Basti


Am 2013-08-11 18:35, schrieb Andre LaBranche: 

> Hi, 
> 
> We'd need
to see the server's /var/log/caldavd/error.log to be sure, but I have a
guess at what might be wrong. On the Pi, run the command: 
> 
> netstat
-na | egrep '8008|8443' 
> 
> This will list all the network connections
associated with the Calendar Server service ports. Calendar Server
limits the amount of total request concurrency, and this limit is by
default [4] based on the server's hw resources, so the setting might be
fairly meager for a Pi. It should still be enough for such a simple
test, however we're tracking an issue right now (in trunk) that's
causing scalability problems that result in the behavior you're seeing.
You are probably affected by this bug if you see that all the
connections slots are full when you really haven't presented much load.
Also, if there are a lot of network connections in FIN_WAIT2, that's
another sign. 
> 
> If instead you see in the error.log that the server
is actually processing the requests which eventually finish (they don't
get logged to access.log until they finish), then maybe the Pi is just
really slow? I'd still expect some bug, because it doesn't take a great
amount of server CPU do a basic CalDAV login. 
> 
> Cheers, 
> -dre 
>

> On Aug 11, 2013, at 7:43 AM, calendar at bastibe.de wrote: 
> 
>> Hi,
>>

>> I set up calendarserver on a Raspberry Pi. I can connect to it using
an iPad and I can see the appropriate data in my web browser. Apple
Calendar (OSX 10.8.4) however works for 30 seconds, then says "can not
create a connection".
>> 
>> The Console is logging
>> 
>>> 11.08.13
16:16:42,633 CalendarAgent[14934]:
[com.apple.calendar.store.log.caldav.queue] [Got error on refresh, but
not rectifying principal because it was manually configured: Error
Domain=NSURLErrorDomain Code=-1001 "Zeitüberschreitung bei der
Anforderung." UserInfo=0x7fb2a3336580 {NSUnderlyingError=0x7fb2a1b2f640
"Zeitüberschreitung bei der Anforderung.",
NSErrorFailingURLStringKey=https://bastibe@cloud.bastibe.de:8443/calendars/__uids__/3e5c3ade-c0ea-5560-8dfb-dce1e4be3c22/calendar/
[1],
NSErrorFailingURLKey=https://bastibe@cloud.bastibe.de:8443/calendars/__uids__/3e5c3ade-c0ea-5560-8dfb-dce1e4be3c22/calendar/
[1], NSLocalizedDescription=Zeitüberschreitung bei der
Anforderung.}]
>> 
>>> 11.08.13 16:16:42,633 CalendarAgent[14934]:
[com.apple.calendar.store.log.caldav.queue] [Account
bastibe at https://cloud.bastibe.de:8443/principals/users/bastibe/ [2]
timed out when executing operation:
<CalDAVAccountRefreshQueueableOperation: 0x7fb2a4a13ce0; Sequence:
0>]
>> 
>> 11.08.13 16:16:42,636 CalendarAgent[14934]:
[com.apple.calendar.store.log.caldav.queue] [Account refresh failed with
error: Error Domain=NSURLErrorDomain Code=-1001 "Zeitüberschreitung bei
der Anforderung." UserI
>> 
>>>
ailingURLStringKey=https://bastibe@cloud.bastibe.de:8443/calendars/__uids__/3e5c3ade-c0ea-5560-8dfb-dce1e4be3c22/calendar/
[1],
NSErrorFailingURLKey=https://bastibe@cloud.bastibe.de:8443/calendars/__uids__/3e5c3ade-c0ea-5560-8dfb-dce1e4be3c22/calendar/
[1], AccountName=cloud.bastibe.de [3], CalDAVErrFromRefresh=YES,
NSLocalizedDescription=Zeitüberschreitung bei der Anforderung.}] 
>>>

>>> where "Zeitüberschreitung bei der Anforderung" is German for
"timeout for request".
>>> 
>>> During those 30 seconds, the Raspberry
Pi is running a Python process for the caldavd user at full CPU
utilization.
>>> 
>>> So I'm guessing that Apple Calendar has an
internal timeout of 30 second
>> pberry Pi is not fast enough to send
the calendar data in that time frame.
>> 
>> Do you have any idea how I
could fix that? Any help would be greatly appreciated!
>> 
>> Kind
regards,
>> Basti
>> _______________________________________________
>>
calendarserver-users mailing list
>>
calendarserver-users at lists.macosforge.org
>>
https://lists.macosforge.org/mailman/listinfo/calendarserver-users




Links:
------
[1]
https://bastibe@cloud.bastibe.de:8443/calendars/__uids__/3e5c3ade-c0ea-5560-8dfb-dce1e4be3c22/calendar/
[2]
https://cloud.bastibe.de:8443/principals/users/bastibe/
[3]
http://cloud.bastibe.de
[4]
https://trac.calendarserver.org/browser/CalendarServer/trunk/twistedcaldav/stdconfig.py#L504
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.macosforge.org/pipermail/calendarserver-users/attachments/20130811/b12a4b31/attachment-0001.html>

More information about the calendarserver-users mailing list