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.was...@gmail.com [1]: 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.was...@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.was...@gmail.com [2]: 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 Links: ------ [1] mailto:conrad.was...@gmail.com [2] mailto:conrad.was...@gmail.com
_______________________________________________ calendarserver-users mailing list calendarserver-users@lists.macosforge.org http://lists.macosforge.org/mailman/listinfo.cgi/calendarserver-users
