[CalendarServer-users] snow leopard woes

Sat Jan 2 00:03:02 PST 2010

The major error I encountered was fixed by removing the Twisted folder and re-running ./run -s to replace that directory, etc. 

Now I am back to where I was before - up and running, but wholly unable to send or receive invites via email.  Invites are not getting out of the server and going to postfix (the email handler on the same box).  from iCal, I get a notification that I have invited someone who is not recognized by the server, and the invite never goes out via email (or any other method).  

I have the iMIP settings in place in my caldavd-dev.plist, and in order to dispense with the "no iMip injection principal" error, I found that adding an address pattern to the scheduling > calDAV array eliminated that error (I started with mailto:.*, and then tried just .* - both eliminate the error message, but neither seems to help convince the server to send an emailed invite).

The logs during these emailed invite attempts look like this:

2010-01-02 01:45:28-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.directory.sudo.SudoDirectoryService#info] Directory service <SudoDirectoryService 'wasmerhome': FilePath('/Library/calserv/conf/sudoers.plist')> has no GUID; generating service GUID from realm name.
2010-01-02 01:45:29-0600 [-] [caldav-0]  [LimitingHTTPChannel,1,10.0.1.1] [twistedcaldav.extensions#info] POST /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/outbox/ HTTP/1.1
2010-01-02 01:45:29-0600 [-] [caldav-0]  [-] [twistedcaldav.directory.xmlfile.XMLDirectoryService#info] Directory service <XMLDirectoryService 'wasmerhome': FilePath('/Library/calserv/conf/auth/accounts.xml')> has no GUID; generating service GUID from realm name.
2010-01-02 01:45:29-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler#error] Unknown calendar user address: mailto:Conrad.wasmer at gmail.com
2010-01-02 01:45:29-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler#info] METHOD: REQUEST, Component: VFREEBUSY
2010-01-02 01:45:29-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler.ScheduleResponseQueue#error] Error during POST for mailto:Conrad.wasmer at gmail.com: None
2010-01-02 01:45:40-0600 [-] [mailgateway] 2010-01-02 01:45:40-0600 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1']]
2010-01-02 01:45:47-0600 [-] [caldav-1]  [LimitingHTTPChannel,3,10.0.1.1] [twistedcaldav.extensions#info] PUT /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/calendar/2CD93AE6-5AA3-489E-8248-A3A67F902B5D.ics HTTP/1.1
2010-01-02 01:45:47-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.implicit#info] Implicit REQUEST - organizer: 'urn:uuid:E2F8C7D5-DB6B-4809-80D5-53363D54267C' to attendee: 'mailto:Conrad.wasmer at gmail.com', UID: '2CD93AE6-5AA3-489E-8248-A3A67F902B5D'
2010-01-02 01:45:47-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler#error] Unknown calendar user address: mailto:Conrad.wasmer at gmail.com
2010-01-02 01:45:47-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler#info] METHOD: REQUEST, Component: VEVENT
2010-01-02 01:45:47-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler.ScheduleResponseQueue#error] Error during PUT for mailto:Conrad.wasmer at gmail.com: None

Any ideas on this one?  

Thanks in advance!

- Conrad 

On Jan 2, 2010, at 12:57 AM, Conrad Wasmer wrote:

> Happy New Year!
> 
> I just upgraded to SL from Leopard and I am having a bear of a time getting Calendar Server running again.
> 
> In an effort to resolve my issues, I have deleted my old install and re-installed from trunk (build 4892), but even though the log seems to indicate that everything is working, when I try to connect I get no love - web access is denied due to an "internal service error," as is iCal access.
> 
> When I run ./run, I get the following, which seems to show me that all is fine in CalServ land:
> 
> 2010-01-02 00:42:31-0600 [-] [caldav-1]  [-] [twext.internet.tcp#info] twext.web2.channel.http.LimitingHTTPFactory starting on 8443
> 2010-01-02 00:42:31-0600 [-] [caldav-1]  [-] [twext.internet.tcp#info] twext.web2.channel.http.LimitingHTTPFactory starting on 8008
> 2010-01-02 00:42:31-0600 [twistedcaldav.accesslog.AMPLoggingFactory] AMPLoggingProtocol connection established (HOST:UNIXSocket('logs/caldavd.sock') PEER:UNIXSocket(''))
> 2010-01-02 00:42:31-0600 [twistedcaldav.accesslog.AMPLoggingFactory] AMPLoggingProtocol connection established (HOST:UNIXSocket('logs/caldavd.sock') PEER:UNIXSocket(''))
> 2010-01-02 00:42:31-0600 [-] [caldav-1]  [-] AMP connection established (HOST:UNIXSocket(None) PEER:UNIXSocket('logs/caldavd.sock'))
> 2010-01-02 00:42:31-0600 [-] [caldav-0]  [-] [twext.internet.tcp#info] twext.web2.channel.http.LimitingHTTPFactory starting on 8443
> 2010-01-02 00:42:31-0600 [-] [caldav-0]  [-] [twext.internet.tcp#info] twext.web2.channel.http.LimitingHTTPFactory starting on 8008
> 2010-01-02 00:42:31-0600 [-] [caldav-0]  [-] AMP connection established (HOST:UNIXSocket(None) PEER:UNIXSocket('logs/caldavd.sock'))
> 
> 
> But then I try to connect, and get logs like this, showing what appear to be two different failures and a third issue with the mail handling for iTIP:
> 
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	Traceback (most recent call last):
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	  File "/library/Twisted/twisted/internet/defer.py", line 184, in addCallbacks
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	    self._runCallbacks()
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	  File "/library/Twisted/twisted/internet/defer.py", line 326, in _runCallbacks
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	    self.result = callback(self.result, *args, **kw)
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	  File "/library/Twisted/twisted/web2/dav/method/get.py", line 57, in <lambda>
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	    d.addCallback(lambda parent: parent.authorize(request, (davxml.Bind(),)))
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	  File "/library/Twisted/twisted/internet/defer.py", line 832, in unwindGenerator
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	    return _inlineCallbacks(None, f(*args, **kwargs), Deferred())
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	--- <exception caught here> ---
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	  File "/library/Twisted/twisted/internet/defer.py", line 749, in _inlineCallbacks
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	    result = g.send(result)
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	exceptions.AttributeError: Deferred instance has no attribute 'send'
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	
> 2010-01-02 00:42:45-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] Unhandled error in Deferred:
> 2010-01-02 00:42:45-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] Unhandled Error
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	Traceback (most recent call last):
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	Failure: twisted.web2.http.HTTPError: <UnauthorizedResponse 401 You are not authorized to access this resource.>
> 2010-01-02 00:42:45-0600 [-] [caldav-0] 	
> 2010-01-02 00:43:01-0600 [-] [mailgateway] 2010-01-02 00:43:01-0600 [IMAP4DownloadProtocol,client] Unhandled unsolicited response: ['OK', ['HIGHESTMODSEQ', '1']]
> 
> Any advice would be greatly appreciated!
> 
> _______________________________________________
> calendarserver-users mailing list
> calendarserver-users at lists.macosforge.org
> http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users

--
Conrad Wasmer
612-598-0301