[CalendarServer-users] iMIP settings
Conrad Wasmer
conrad.wasmer at comcast.net
Wed Dec 2 08:01:33 PST 2009
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 [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.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 [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.wasmer at gmail.com
[2] mailto:conrad.wasmer at gmail.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.macosforge.org/pipermail/calendarserver-users/attachments/20091202/68c52dd4/attachment-0001.html>
More information about the calendarserver-users
mailing list