[CalendarServer-users] iMIP settings

Wed Dec 2 09:18:29 PST 2009

I have created a user for the iMIP injection and entered that user also in the sudoers.xml file. 

However, I still got an exception whenever an email reply should be imported into the DCS again. I fixed that by changing in "twistedcaldav/scheduling/implicit.py" the line "if self.originatorPrincipal:" into "if self.originatorPrincipal and hasattr(self.originatorPrincipal,'calendarUserAddresses'):". 

I do not know if the whole approach is the way to go, unfortunately there is hardly any documentation available. It took me very long to set up the installation, and I also modified some parts to get Lightning, Mulberry, Evolution (and of course iCal.app) working and enable Kontact and Horde to at least subscribe calendars. 

Regards,
Markus

Am 02.12.2009 um 17:01 schrieb Conrad Wasmer:

> Okay - I had wholly failed to provide the debug error log because I had not restarted the server.  Too little sleep.
> 
> 
> I apologize now for the fairly lengthy log files included with this email...
> 
> Here's a set of log entries regarding the mailgateway, which seems to indicate (as far as I can tell) that CalendarServer thinks everything is just fine, though it remains that postfix is not getting (nor processing) anything from calendarserver.:
> 
> 2009-12-01 23:35:32-0600 [-] [mailgateway] 2009-12-01 23:35:32-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0002 AUTHENTICATE PLAIN
> 2009-12-01 23:35:32-0600 [-] [mailgateway] 2009-12-01 23:35:32-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: + 
> 2009-12-01 23:35:32-0600 [-] [mailgateway] 2009-12-01 23:35:32-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: AGNhbGVuZGFyc2VydmVyAGNhbHNlcnY=
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0002 OK Logged in.
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP logged in [unauth]
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0003 SELECT Inbox
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted.
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * 0 EXISTS
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * 0 RECENT
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * OK [UIDVALIDITY 1259640498] UIDs valid
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * OK [UIDNEXT 2] Predicted next UID
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0003 OK [READ-WRITE] Select completed.
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP Inbox selected [unauth]
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0004 UID FETCH 1:* (UID)
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0004 OK Fetch completed.
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP got uids [unauth]
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP Inbox has 0 messages
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0005 CLOSE
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0005 OK Close completed.
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP in cbClosed [unauth]
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] Mailbox closed
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0006 LOGOUT
> 2009-12-01 23:35:33-0600 [-] [mailgateway] 2009-12-01 23:35:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * BYE Logging out
> 
> 
> Here is a set of log entries after trying to send an invite:
> 
> 2009-12-01 23:40:01-0600 [-] [caldav-1]  [LimitingHTTPChannel,1,10.0.1.1] [twistedcaldav.extensions#info] POST /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/outbox/ HTTP/1.1
> 2009-12-01 23:40:01-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twisted.web2.dav.resource#info] Authentication failed: Invalid nonce value: 76386775211099560861840090282
> 2009-12-01 23:40:01-0600 [-] [caldav-1]  [LimitingHTTPChannel,2,10.0.1.1] [twistedcaldav.extensions#info] POST /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/outbox/ HTTP/1.1
> 2009-12-01 23:40:01-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.extensions#info] PROPFIND /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/ HTTP/1.1
> 2009-12-01 23:40:01-0600 [-] [caldav-1]  [-] [twistedcaldav.directory.sudo.SudoDirectoryService#info] Directory service has no GUID; generating service GUID from realm name.
> 2009-12-01 23:40:01-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler#info] METHOD: REQUEST, Component: VFREEBUSY
> 2009-12-01 23:40:01-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.imip#error] Could not do server-to-imip request : iMIP VFREEBUSY REQUESTs not supported.
> 2009-12-01 23:40:01-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler.ScheduleResponseQueue#error] Error during POST for mailto:conrad.wasmer at gmail.com: None
> 2009-12-01 23:40:01-0600 [-] [caldav-0]  [-] [twistedcaldav.directory.sudo.SudoDirectoryService#info] Directory service has no GUID; generating service GUID from realm name.
> 2009-12-01 23:40:01-0600 [-] [caldav-0]  [-] [twistedcaldav.directory.principal#error] No principal found for UID: admin
> 2009-12-01 23:40:02-0600 [-] [caldav-1]  [LimitingHTTPChannel,3,10.0.1.1] [twistedcaldav.extensions#info] REPORT /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/calendar/ HTTP/1.1
> 2009-12-01 23:40:04-0600 [-] [caldav-0]  [LimitingHTTPChannel,1,10.0.1.1] [twistedcaldav.extensions#info] POST /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/outbox/ HTTP/1.1
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler#info] METHOD: REQUEST, Component: VEVENT
> 2009-12-01 23:40:05-0600 [-] [caldav-1]  [PooledMemCacheProtocol,client] [twistedcaldav.memcacheprops#info] Instantiating memcache connection for MemcachePropertyCollection
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [LimitingHTTPChannel,2,10.0.1.1] [twistedcaldav.extensions#info] PUT /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/calendar/67FD43A4-3F07-4DE8-B2E0-BAF57AC67126.ics HTTP/1.1
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [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: '67FD43A4-3F07-4DE8-B2E0-BAF57AC67126'
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.scheduling.scheduler#info] METHOD: REQUEST, Component: VEVENT
> 2009-12-01 23:40:05-0600 [-] [mailgateway] 2009-12-01 23:40:05-0600 [HTTPChannel,0,127.0.0.1] [twistedcaldav.extensions#info] POST /inbox HTTP/1.0
> 2009-12-01 23:40:05-0600 [-] [mailgateway] 2009-12-01 23:40:05-0600 [HTTPChannel,0,127.0.0.1] [twistedcaldav.mail#error] iMIP injection principal not found: com.apple.calendarserver
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [AuthorizedHTTPGetter,client] [twistedcaldav.scheduling.imip#error] Could not do server-to-imip request : 403 Forbidden
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [AuthorizedHTTPGetter,client] [twistedcaldav.scheduling.scheduler.ScheduleResponseQueue#error] Error during PUT for mailto:conrad.wasmer at gmail.com: None
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [AuthorizedHTTPGetter,client] [twisted.web2.dav.fileop#info] Writing to file /Library/CalServ/twistedcaldav/test/data/calendars/__uids__/E2/F8/E2F8C7D5-DB6B-4809-80D5-53363D54267C/calendar/67FD43A4-3F07-4DE8-B2E0-BAF57AC67126.ics
> 2009-12-01 23:40:05-0600 [-] [caldav-0]  [-] [twistedcaldav.memcacheprops#info] Instantiating memcache connection for MemcachePropertyCollection
> 2009-12-01 23:40:08-0600 [-] [caldav-0]  [LimitingHTTPChannel,3,10.0.1.1] [twistedcaldav.extensions#info] GET /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/calendar/67FD43A4-3F07-4DE8-B2E0-BAF57AC67126.ics HTTP/1.1
> 2009-12-01 23:40:11-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.extensions#info] PROPFIND /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/ HTTP/1.1
> 2009-12-01 23:40:11-0600 [-] [caldav-0]  [PooledMemCacheProtocol,client] [twistedcaldav.extensions#info] PROPFIND /calendars/__uids__/E2F8C7D5-DB6B-4809-80D5-53363D54267C/calendar/ HTTP/1.1
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * OK Dovecot ready.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP servergreeting
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0001 CAPABILITY
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * CAPABILITY IMAP4rev1 SASL-IR SORT THREAD=REFERENCES MULTIAPPEND UNSELECT LITERAL+ IDLE CHILDREN NAMESPACE LOGIN-REFERRALS STARTTLS AUTH=PLAIN AUTH=LOGIN
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0001 OK Capability completed.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0002 AUTHENTICATE PLAIN
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: + 
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: AGNhbGVuZGFyc2VydmVyAGNhbHNlcnY=
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0002 OK Logged in.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP logged in [unauth]
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0003 SELECT Inbox
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft)
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft \*)] Flags permitted.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * 0 EXISTS
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * 0 RECENT
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * OK [UIDVALIDITY 1259640498] UIDs valid
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * OK [UIDNEXT 2] Predicted next UID
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0003 OK [READ-WRITE] Select completed.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP Inbox selected [unauth]
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0004 UID FETCH 1:* (UID)
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0004 OK Fetch completed.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP got uids [unauth]
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP Inbox has 0 messages
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0005 CLOSE
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0005 OK Close completed.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] IMAP in cbClosed [unauth]
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] Mailbox closed
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] SENDING: 0006 LOGOUT
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: * BYE Logging out
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadProtocol#debug] RECEIVED: 0006 OK Logout completed.
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadFactory#debug] IMAP factory connection lost
> 2009-12-01 23:40:33-0600 [-] [mailgateway] 2009-12-01 23:40:33-0600 [IMAP4DownloadProtocol,client] [twistedcaldav.mail.IMAP4DownloadFactory#debug] Scheduling next IMAP4 poll
>  
> ---
> Conrad Wasmer
> _______________________________________________
> calendarserver-users mailing list
> calendarserver-users at lists.macosforge.org
> http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users