Attempts to resync all emails if I define I reverse nametrans
Antonis Christofides
anthony at itia.ntua.gr
Sun Aug 17 21:55:21 BST 2014
Hello,
let me explain the problem in more detail. I'm using the master branch
(the behaviour is the same in offlineimap 6.5.4 & 6.5.6), syncing a
remote imap account with a local maildir account. I created a test user
for that purpose, who only has an inbox, which has three emails in it.
Here is the configuration file:
[general]
accounts = test2314
ui = ttyui
[Account test2314]
localrepository = Local
remoterepository = Remote
[Repository Local]
type = Maildir
localfolders = /var/tmp/oimap/Maildir
[Repository Remote]
type = IMAP
ssl = yes
remotehost = mail.itia.ntua.gr
cert_fingerprint = 3f20f76fc71eb4317649414f19ff5dafbd236e86
remoteuser = test2314
remotepass = topsecret
createfolders = False
nametrans = lambda folder: re.sub('^INBOX\.*', '.', folder)
I have already synced, so if I try again it does nothing:
anthony at laura:oimap$ offlineimap -c offlineimaprc
OfflineIMAP 6.5.6.1-devel
Licensed under the GNU GPL v2+ (v2 or any later version)
Account sync test2314:
*** Processing account test2314
Establishing connection to mail.itia.ntua.gr:993
Folder [remote name INBOX] [acc: test2314]:
Syncing INBOX: IMAP -> Maildir
Account sync test2314:
*** Finished account 'test2314' in 0:06
So far so good. The problem now is that if I add a reverse nametrans,
then it downloads the emails from scratch:
anthony at laura:oimap$ offlineimap -c offlineimaprc -k "Repository Local:nametrans=lambda f: 'INBOX' + f"
OfflineIMAP 6.5.6.1-devel
Licensed under the GNU GPL v2+ (v2 or any later version)
Account sync test2314:
*** Processing account test2314
Establishing connection to mail.itia.ntua.gr:993
Folder [remote name INBOX] [acc: test2314]:
Syncing INBOX: IMAP -> Maildir
Copy message -2 (1 of 3) Local: -> Remote
Copy message -1 (2 of 3) Local: -> Remote
Copy message -3 (3 of 3) Local: -> Remote
Deleting 3 messages (1:3) in IMAP[INBOX]
Account sync test2314:
*** Finished account 'test2314' in 0:10
Of course in that case this is not a serious issue, but my real account
has several GB of emails, so I don't want that to happen.
More information:
anthony at laura:oimap$ offlineimap -c offlineimaprc --info
OfflineIMAP 6.5.6.1-devel
Licensed under the GNU GPL v2+ (v2 or any later version)
Remote repository 'Remote': type 'IMAP'
Host: mail.itia.ntua.gr Port: None SSL: True
Establishing connection to mail.itia.ntua.gr:993
Server welcome string: * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.
Server capabilities: ('IMAP4REV1', 'UIDPLUS', 'CHILDREN', 'NAMESPACE', 'THREAD=ORDEREDSUBJECT', 'THREAD=REFERENCES', 'SORT', 'QUOTA', 'IDLE', 'AUTH=PLAIN', 'ACL', 'ACL2=UNION')
nametrans= lambda folder: re.sub('^INBOX\.*', '.', folder)
Folderlist:
INBOX ->
Local repository 'Local': type 'Maildir'
Folderlist:
and:
anthony at laura:oimap$ offlineimap -c offlineimaprc -k "Repository Local:nametrans=lambda f: 'INBOX' + f" --info
OfflineIMAP 6.5.6.1-devel
Licensed under the GNU GPL v2+ (v2 or any later version)
Remote repository 'Remote': type 'IMAP'
Host: mail.itia.ntua.gr Port: None SSL: True
Establishing connection to mail.itia.ntua.gr:993
Server welcome string: * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.
Server capabilities: ('IMAP4REV1', 'UIDPLUS', 'CHILDREN', 'NAMESPACE', 'THREAD=ORDEREDSUBJECT', 'THREAD=REFERENCES', 'SORT', 'QUOTA', 'IDLE', 'AUTH=PLAIN', 'ACL', 'ACL2=UNION')
nametrans= lambda folder: re.sub('^INBOX\.*', '.', folder)
Folderlist:
INBOX ->
Local repository 'Local': type 'Maildir'
nametrans= lambda f: 'INBOX' + f
Folderlist:
-> INBOX
I also attach a full debug log of a run with the reverse nametrans.
-------------- next part --------------
2014-08-17 23:40:58 INFO: OfflineImap 6.5.6.1-devel starting...
Python: 2.7.3 Platform: linux2
Args: /home/anthony/bin/offlineimap -c offlineimaprc -k Repository Local:nametrans=lambda f: 'INBOX' + f --info -d imap,maildir -l debug.log
2014-08-17 23:40:58 INFO: Debug mode: Forcing to singlethreaded.
2014-08-17 23:40:58 DEBUG: Now debugging for imap: IMAP protocol debugging
2014-08-17 23:40:58 DEBUG: Now debugging for maildir: Maildir repository debugging
2014-08-17 23:40:58 DEBUG: Now debugging for : Other offlineimap related sync messages
2014-08-17 23:40:58 DEBUG: [imap]: Using authentication mechanisms ['GSSAPI', 'CRAM-MD5', 'PLAIN', 'LOGIN']
2014-08-17 23:40:58 DEBUG: [maildir]: MaildirRepository initialized, sep is '.'
2014-08-17 23:40:58 INFO: Remote repository 'Remote': type 'IMAP'
2014-08-17 23:40:58 INFO: Host: mail.itia.ntua.gr Port: None SSL: True
2014-08-17 23:40:58 INFO: Establishing connection to mail.itia.ntua.gr:993
2014-08-17 23:40:58 DEBUG: [imap]: 40:58.80 MainThread imaplib2 version 2.36
2014-08-17 23:40:58 DEBUG: [imap]: 40:58.80 MainThread imaplib2 debug level 5, buffer level 3
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.10 mail.itia.ntua.gr writer starting
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.10 mail.itia.ntua.gr reader starting using poll
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.10 MainThread _request_push(continuation, None, {}) = NMLC0
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.10 MainThread None:NMLC0.ready.wait
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.20 mail.itia.ntua.gr handler starting
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr reader rcvd 244
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr reader < * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.\r\n
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information."]
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr handler _request_pop(continuation, (False, '* OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.')) = NMLC0
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 mail.itia.ntua.gr handler None:NMLC0.ready.set
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 MainThread _get_untagged_response(OK) => ['[CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.']
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 MainThread state => NONAUTH
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.35 MainThread [async] CAPABILITY ()
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.36 MainThread state_change_pending.acquire
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.36 MainThread state_change_pending.release
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.36 MainThread _request_push(NMLC1, CAPABILITY, {}) = NMLC1
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.36 MainThread data=NMLC1 CAPABILITY
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.36 MainThread CAPABILITY:NMLC1.ready.wait
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.36 mail.itia.ntua.gr writer > NMLC1 CAPABILITY\r\n
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr reader rcvd 164
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr reader < * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION\r\n
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr reader < NMLC1 OK CAPABILITY completed\r\n
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 1 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr handler _request_pop(NMLC1, ('OK', ['CAPABILITY completed'])) = NMLC1
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 mail.itia.ntua.gr handler CAPABILITY:NMLC1.ready.set
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 MainThread _get_untagged_response(CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION', 'IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.61 MainThread _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION', 'IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 MainThread CAPABILITY: ('IMAP4REV1', 'UIDPLUS', 'CHILDREN', 'NAMESPACE', 'THREAD=ORDEREDSUBJECT', 'THREAD=REFERENCES', 'SORT', 'QUOTA', 'IDLE', 'AUTH=PLAIN', 'ACL', 'ACL2=UNION')
2014-08-17 23:41:05 DEBUG: [imap]: Attempting PLAIN authentication
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 MainThread [sync] AUTHENTICATE ('PLAIN',)
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 MainThread state_change_pending.acquire
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 MainThread _request_push(NMLC2, AUTHENTICATE, {}) = NMLC2
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 MainThread data=NMLC2 AUTHENTICATE PLAIN
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 MainThread _request_push(continuation, None, {}) = NMLC3
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 MainThread None:NMLC3.ready.wait
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.62 mail.itia.ntua.gr writer > NMLC2 AUTHENTICATE PLAIN\r\n
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 mail.itia.ntua.gr reader rcvd 4
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 mail.itia.ntua.gr reader < + \r\n
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 mail.itia.ntua.gr handler _request_pop(continuation, (True, '')) = NMLC3
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 mail.itia.ntua.gr handler None:NMLC3.ready.set
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 MainThread continuation => True,
2014-08-17 23:41:05 DEBUG: [imap]: __plainhandler: returning test2314 topsecret
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 MainThread _request_push(continuation, None, {}) = NMLC4
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 MainThread write literal size 28
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.89 MainThread None:NMLC4.ready.wait
2014-08-17 23:41:05 DEBUG: [imap]: 41:05.90 mail.itia.ntua.gr writer > AHRlc3QyMzE0AHRvcHNlY3JldA==\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr reader rcvd 20
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr reader < NMLC2 OK LOGIN Ok.\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr handler _request_pop(NMLC2, ('OK', ['LOGIN Ok.'])) = NMLC2
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr handler AUTHENTICATE:NMLC2.ready.set
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr handler _request_pop(continuation, (False, 'NMLC2 OK LOGIN Ok.')) = NMLC4
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 mail.itia.ntua.gr handler None:NMLC4.ready.set
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 MainThread continuation => False, NMLC2 OK LOGIN Ok.
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.17 MainThread AUTHENTICATE:NMLC2.ready.wait
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread state => AUTH
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread state_change_pending.release
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread [async] CAPABILITY ()
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread state_change_pending.acquire
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread state_change_pending.release
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread _request_push(NMLC5, CAPABILITY, {}) = NMLC5
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread data=NMLC5 CAPABILITY
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 MainThread CAPABILITY:NMLC5.ready.wait
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.18 mail.itia.ntua.gr writer > NMLC5 CAPABILITY\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.47 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.47 mail.itia.ntua.gr reader rcvd 164
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.47 mail.itia.ntua.gr reader < * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.47 mail.itia.ntua.gr reader < NMLC5 OK CAPABILITY completed\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.47 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.47 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.47 mail.itia.ntua.gr handler _request_pop(NMLC5, ('OK', ['CAPABILITY completed'])) = NMLC5
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 mail.itia.ntua.gr handler CAPABILITY:NMLC5.ready.set
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread _get_untagged_response(CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread [async] LIST ('', '""')
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread state_change_pending.acquire
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread state_change_pending.release
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread _request_push(NMLC6, LIST, {}) = NMLC6
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread data=NMLC6 LIST "" ""
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 MainThread LIST:NMLC6.ready.wait
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.48 mail.itia.ntua.gr writer > NMLC6 LIST "" ""\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr reader rcvd 52
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr reader < * LIST (\Noselect) "." ""\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr reader < NMLC6 OK LIST completed\r\n
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr handler untagged_responses[LIST] 0 += ["(\Noselect) "." """]
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr handler _request_pop(NMLC6, ('OK', ['LIST completed'])) = NMLC6
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 mail.itia.ntua.gr handler LIST:NMLC6.ready.set
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 MainThread _get_untagged_response(LIST) => ['(\\Noselect) "." ""']
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.75 MainThread _untagged_response(OK, ?, LIST) => ['(\\Noselect) "." ""']
2014-08-17 23:41:06 INFO: Server welcome string: * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.
2014-08-17 23:41:06 INFO: Server capabilities: ('IMAP4REV1', 'UIDPLUS', 'CHILDREN', 'NAMESPACE', 'THREAD=ORDEREDSUBJECT', 'THREAD=REFERENCES', 'SORT', 'QUOTA', 'IDLE', 'AUTH=PLAIN', 'ACL', 'ACL2=UNION')
2014-08-17 23:41:06 INFO: nametrans= lambda folder: re.sub('^INBOX\.*', '.', folder)
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.76 MainThread [async] LIST ('', '*')
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.76 MainThread state_change_pending.acquire
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.76 MainThread state_change_pending.release
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.76 MainThread _request_push(NMLC7, LIST, {}) = NMLC7
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.76 MainThread data=NMLC7 LIST "" "*"
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.76 MainThread LIST:NMLC7.ready.wait
2014-08-17 23:41:06 DEBUG: [imap]: 41:06.76 mail.itia.ntua.gr writer > NMLC7 LIST "" "*"\r\n
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr reader rcvd 70
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr reader < * LIST (\Marked \HasNoChildren) "." "INBOX"\r\n
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr reader < NMLC7 OK LIST completed\r\n
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr handler untagged_responses[LIST] 0 += ["(\Marked \HasNoChildren) "." "INBOX""]
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr handler _request_pop(NMLC7, ('OK', ['LIST completed'])) = NMLC7
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 mail.itia.ntua.gr handler LIST:NMLC7.ready.set
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 MainThread _get_untagged_response(LIST) => ['(\\Marked \\HasNoChildren) "." "INBOX"']
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.01 MainThread _untagged_response(OK, ?, LIST) => ['(\\Marked \\HasNoChildren) "." "INBOX"']
2014-08-17 23:41:07 INFO: Folderlist:
INBOX ->
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.02 MainThread state => LOGOUT
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.02 MainThread [sync] LOGOUT ()
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.02 MainThread state_change_pending.acquire
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.02 MainThread _request_push(NMLC8, LOGOUT, {}) = NMLC8
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.02 MainThread data=NMLC8 LOGOUT
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.02 MainThread LOGOUT:NMLC8.ready.wait
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.02 mail.itia.ntua.gr writer > NMLC8 LOGOUT\r\n
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr reader rcvd 68
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr reader < * BYE Courier-IMAP server shutting down\r\n
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr reader < NMLC8 OK LOGOUT completed\r\n
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr handler untagged_responses[BYE] 0 += ["Courier-IMAP server shutting down"]
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr handler BYE response: Courier-IMAP server shutting down
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr handler terminating: 'connection terminated'
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr handler LOGOUT:NMLC8.ready.set
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 MainThread ["<class 'offlineimap.imaplib2.abort'>: command: LOGOUT => connection terminated"]
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr reader poll => []
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.31 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 mail.itia.ntua.gr handler finished
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 mail.itia.ntua.gr reader finished
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 mail.itia.ntua.gr writer finished
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 MainThread _close_threads
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 MainThread call shutdown
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 MainThread state_change_pending.release
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 MainThread connection closed
2014-08-17 23:41:07 DEBUG: [imap]: 41:07.32 MainThread _get_untagged_response(BYE) => ['Courier-IMAP server shutting down']
2014-08-17 23:41:07 INFO: Local repository 'Local': type 'Maildir'
2014-08-17 23:41:07 INFO: nametrans= lambda f: 'INBOX' + f
2014-08-17 23:41:07 DEBUG: [maildir]: _GETFOLDERS_SCANDIR STARTING. root = /var/tmp/oimap/Maildir, extension = None
2014-08-17 23:41:07 DEBUG: [maildir]: toppath = /var/tmp/oimap/Maildir
2014-08-17 23:41:07 DEBUG: [maildir]: dirname = new
2014-08-17 23:41:07 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:41:07 DEBUG: [maildir]: dirname = cur
2014-08-17 23:41:07 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:41:07 DEBUG: [maildir]: dirname = tmp
2014-08-17 23:41:07 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:41:07 DEBUG: [maildir]: dirname =
2014-08-17 23:41:07 DEBUG: [maildir]: This is maildir folder ''.
2014-08-17 23:41:07 DEBUG: [maildir]: _GETFOLDERS_SCANDIR RETURNING ['']
2014-08-17 23:41:07 INFO: Folderlist:
-> INBOX
2014-08-17 23:46:19 INFO: OfflineImap 6.5.6.1-devel starting...
Python: 2.7.3 Platform: linux2
Args: /home/anthony/bin/offlineimap -c offlineimaprc -k Repository Local:nametrans=lambda f: 'INBOX' + f --info -d imap,maildir -l debug.log
2014-08-17 23:46:19 INFO: Debug mode: Forcing to singlethreaded.
2014-08-17 23:46:19 DEBUG: Now debugging for imap: IMAP protocol debugging
2014-08-17 23:46:19 DEBUG: Now debugging for maildir: Maildir repository debugging
2014-08-17 23:46:19 DEBUG: Now debugging for : Other offlineimap related sync messages
2014-08-17 23:46:19 DEBUG: [imap]: Using authentication mechanisms ['GSSAPI', 'CRAM-MD5', 'PLAIN', 'LOGIN']
2014-08-17 23:46:19 DEBUG: [maildir]: MaildirRepository initialized, sep is '.'
2014-08-17 23:46:19 INFO: Remote repository 'Remote': type 'IMAP'
2014-08-17 23:46:19 INFO: Host: mail.itia.ntua.gr Port: None SSL: True
2014-08-17 23:46:19 INFO: Establishing connection to mail.itia.ntua.gr:993
2014-08-17 23:46:19 DEBUG: [imap]: 46:19.15 MainThread imaplib2 version 2.36
2014-08-17 23:46:19 DEBUG: [imap]: 46:19.15 MainThread imaplib2 debug level 5, buffer level 3
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.26 mail.itia.ntua.gr writer starting
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.26 mail.itia.ntua.gr reader starting using poll
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.26 MainThread _request_push(continuation, None, {}) = IPGI0
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.26 MainThread None:IPGI0.ready.wait
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.36 mail.itia.ntua.gr handler starting
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr reader rcvd 244
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr reader < * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information."]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr handler _request_pop(continuation, (False, '* OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.')) = IPGI0
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr handler None:IPGI0.ready.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread _get_untagged_response(OK) => ['[CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.']
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread state => NONAUTH
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread [async] CAPABILITY ()
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread state_change_pending.acquire
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread state_change_pending.release
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread _request_push(IPGI1, CAPABILITY, {}) = IPGI1
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread data=IPGI1 CAPABILITY
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 MainThread CAPABILITY:IPGI1.ready.wait
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.43 mail.itia.ntua.gr writer > IPGI1 CAPABILITY\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr reader rcvd 164
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr reader < * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr reader < IPGI1 OK CAPABILITY completed\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 1 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr handler _request_pop(IPGI1, ('OK', ['CAPABILITY completed'])) = IPGI1
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 mail.itia.ntua.gr handler CAPABILITY:IPGI1.ready.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread _get_untagged_response(CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION', 'IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION', 'IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread CAPABILITY: ('IMAP4REV1', 'UIDPLUS', 'CHILDREN', 'NAMESPACE', 'THREAD=ORDEREDSUBJECT', 'THREAD=REFERENCES', 'SORT', 'QUOTA', 'IDLE', 'AUTH=PLAIN', 'ACL', 'ACL2=UNION')
2014-08-17 23:46:22 DEBUG: [imap]: Attempting PLAIN authentication
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread [sync] AUTHENTICATE ('PLAIN',)
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread state_change_pending.acquire
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread _request_push(IPGI2, AUTHENTICATE, {}) = IPGI2
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread data=IPGI2 AUTHENTICATE PLAIN
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.58 MainThread _request_push(continuation, None, {}) = IPGI3
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.59 MainThread None:IPGI3.ready.wait
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.59 mail.itia.ntua.gr writer > IPGI2 AUTHENTICATE PLAIN\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 mail.itia.ntua.gr reader rcvd 4
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 mail.itia.ntua.gr reader < + \r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 mail.itia.ntua.gr handler _request_pop(continuation, (True, '')) = IPGI3
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 mail.itia.ntua.gr handler None:IPGI3.ready.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 MainThread continuation => True,
2014-08-17 23:46:22 DEBUG: [imap]: __plainhandler: returning test2314 topsecret
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 MainThread _request_push(continuation, None, {}) = IPGI4
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 MainThread write literal size 28
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 MainThread None:IPGI4.ready.wait
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.71 mail.itia.ntua.gr writer > AHRlc3QyMzE0AHRvcHNlY3JldA==\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr reader rcvd 20
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr reader < IPGI2 OK LOGIN Ok.\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr handler _request_pop(IPGI2, ('OK', ['LOGIN Ok.'])) = IPGI2
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr handler AUTHENTICATE:IPGI2.ready.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr handler _request_pop(continuation, (False, 'IPGI2 OK LOGIN Ok.')) = IPGI4
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 mail.itia.ntua.gr handler None:IPGI4.ready.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread continuation => False, IPGI2 OK LOGIN Ok.
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread AUTHENTICATE:IPGI2.ready.wait
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread state => AUTH
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread state_change_pending.release
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread [async] CAPABILITY ()
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread state_change_pending.acquire
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread state_change_pending.release
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread _request_push(IPGI5, CAPABILITY, {}) = IPGI5
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread data=IPGI5 CAPABILITY
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.86 MainThread CAPABILITY:IPGI5.ready.wait
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.87 mail.itia.ntua.gr writer > IPGI5 CAPABILITY\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr reader rcvd 164
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr reader < * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr reader < IPGI5 OK CAPABILITY completed\r\n
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr handler _request_pop(IPGI5, ('OK', ['CAPABILITY completed'])) = IPGI5
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 mail.itia.ntua.gr handler CAPABILITY:IPGI5.ready.set
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 MainThread _get_untagged_response(CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 MainThread _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 MainThread [async] LIST ('', '""')
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 MainThread state_change_pending.acquire
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 MainThread state_change_pending.release
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 MainThread _request_push(IPGI6, LIST, {}) = IPGI6
2014-08-17 23:46:22 DEBUG: [imap]: 46:22.99 MainThread data=IPGI6 LIST "" ""
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.00 MainThread LIST:IPGI6.ready.wait
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.00 mail.itia.ntua.gr writer > IPGI6 LIST "" ""\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr reader rcvd 52
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr reader < * LIST (\Noselect) "." ""\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr reader < IPGI6 OK LIST completed\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr handler untagged_responses[LIST] 0 += ["(\Noselect) "." """]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr handler _request_pop(IPGI6, ('OK', ['LIST completed'])) = IPGI6
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 mail.itia.ntua.gr handler LIST:IPGI6.ready.set
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 MainThread _get_untagged_response(LIST) => ['(\\Noselect) "." ""']
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.12 MainThread _untagged_response(OK, ?, LIST) => ['(\\Noselect) "." ""']
2014-08-17 23:46:23 INFO: Server welcome string: * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.
2014-08-17 23:46:23 INFO: Server capabilities: ('IMAP4REV1', 'UIDPLUS', 'CHILDREN', 'NAMESPACE', 'THREAD=ORDEREDSUBJECT', 'THREAD=REFERENCES', 'SORT', 'QUOTA', 'IDLE', 'AUTH=PLAIN', 'ACL', 'ACL2=UNION')
2014-08-17 23:46:23 INFO: nametrans= lambda folder: re.sub('^INBOX\.*', '.', folder)
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.13 MainThread [async] LIST ('', '*')
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.13 MainThread state_change_pending.acquire
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.13 MainThread state_change_pending.release
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.13 MainThread _request_push(IPGI7, LIST, {}) = IPGI7
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.13 MainThread data=IPGI7 LIST "" "*"
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.13 MainThread LIST:IPGI7.ready.wait
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.13 mail.itia.ntua.gr writer > IPGI7 LIST "" "*"\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr reader rcvd 70
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr reader < * LIST (\Marked \HasNoChildren) "." "INBOX"\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr reader < IPGI7 OK LIST completed\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr handler untagged_responses[LIST] 0 += ["(\Marked \HasNoChildren) "." "INBOX""]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr handler _request_pop(IPGI7, ('OK', ['LIST completed'])) = IPGI7
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 mail.itia.ntua.gr handler LIST:IPGI7.ready.set
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 MainThread _get_untagged_response(LIST) => ['(\\Marked \\HasNoChildren) "." "INBOX"']
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.25 MainThread _untagged_response(OK, ?, LIST) => ['(\\Marked \\HasNoChildren) "." "INBOX"']
2014-08-17 23:46:23 INFO: Folderlist:
INBOX ->
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.26 MainThread state => LOGOUT
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.26 MainThread [sync] LOGOUT ()
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.26 MainThread state_change_pending.acquire
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.26 MainThread _request_push(IPGI8, LOGOUT, {}) = IPGI8
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.26 MainThread data=IPGI8 LOGOUT
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.26 MainThread LOGOUT:IPGI8.ready.wait
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.26 mail.itia.ntua.gr writer > IPGI8 LOGOUT\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr reader rcvd 68
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr reader < * BYE Courier-IMAP server shutting down\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr reader < IPGI8 OK LOGOUT completed\r\n
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr handler untagged_responses[BYE] 0 += ["Courier-IMAP server shutting down"]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr handler BYE response: Courier-IMAP server shutting down
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr reader poll => [(4, 1)]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr handler terminating: 'connection terminated'
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr reader rcvd 0
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr handler LOGOUT:IPGI8.ready.set
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.39 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.40 mail.itia.ntua.gr handler finished
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.40 MainThread ["<class 'offlineimap.imaplib2.abort'>: command: LOGOUT => connection terminated"]
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.40 mail.itia.ntua.gr writer finished
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.40 MainThread _close_threads
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.40 MainThread call shutdown
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.49 mail.itia.ntua.gr reader finished
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.49 MainThread state_change_pending.release
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.50 MainThread connection closed
2014-08-17 23:46:23 DEBUG: [imap]: 46:23.50 MainThread _get_untagged_response(BYE) => ['Courier-IMAP server shutting down']
2014-08-17 23:46:23 INFO: Local repository 'Local': type 'Maildir'
2014-08-17 23:46:23 INFO: nametrans= lambda f: 'INBOX' + f
2014-08-17 23:46:23 DEBUG: [maildir]: _GETFOLDERS_SCANDIR STARTING. root = /var/tmp/oimap/Maildir, extension = None
2014-08-17 23:46:23 DEBUG: [maildir]: toppath = /var/tmp/oimap/Maildir
2014-08-17 23:46:23 DEBUG: [maildir]: dirname = new
2014-08-17 23:46:23 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:46:23 DEBUG: [maildir]: dirname = cur
2014-08-17 23:46:23 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:46:23 DEBUG: [maildir]: dirname = tmp
2014-08-17 23:46:23 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:46:23 DEBUG: [maildir]: dirname =
2014-08-17 23:46:23 DEBUG: [maildir]: This is maildir folder ''.
2014-08-17 23:46:23 DEBUG: [maildir]: _GETFOLDERS_SCANDIR RETURNING ['']
2014-08-17 23:46:23 INFO: Folderlist:
-> INBOX
2014-08-17 23:50:42 INFO: OfflineImap 6.5.6.1-devel starting...
Python: 2.7.3 Platform: linux2
Args: /home/anthony/bin/offlineimap -c offlineimaprc -k Repository Local:nametrans=lambda f: 'INBOX' + f -d imap,maildir -l debug.log
2014-08-17 23:50:42 INFO: Debug mode: Forcing to singlethreaded.
2014-08-17 23:50:42 DEBUG: Now debugging for imap: IMAP protocol debugging
2014-08-17 23:50:42 DEBUG: Now debugging for maildir: Maildir repository debugging
2014-08-17 23:50:42 DEBUG: Now debugging for : Other offlineimap related sync messages
2014-08-17 23:50:42 DEBUG: [imap]: Using authentication mechanisms ['GSSAPI', 'CRAM-MD5', 'PLAIN', 'LOGIN']
2014-08-17 23:50:42 DEBUG: [maildir]: MaildirRepository initialized, sep is '.'
2014-08-17 23:50:42 INFO: *** Processing account test2314
2014-08-17 23:50:42 INFO: Establishing connection to mail.itia.ntua.gr:993
2014-08-17 23:50:42 DEBUG: [imap]: 50:42.82 Account sync test2314 imaplib2 version 2.36
2014-08-17 23:50:42 DEBUG: [imap]: 50:42.82 Account sync test2314 imaplib2 debug level 5, buffer level 3
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.22 mail.itia.ntua.gr writer starting
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.22 mail.itia.ntua.gr reader starting using poll
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.23 Account sync test2314 _request_push(continuation, None, {}) = NPFD0
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.23 Account sync test2314 None:NPFD0.ready.wait
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.33 mail.itia.ntua.gr handler starting
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr reader rcvd 244
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr reader < * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.\r\n
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information."]
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr handler _request_pop(continuation, (False, '* OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.')) = NPFD0
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.46 mail.itia.ntua.gr handler None:NPFD0.ready.set
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 _get_untagged_response(OK) => ['[CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc. See COPYING for distribution information.']
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 state => NONAUTH
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 [async] CAPABILITY ()
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 state_change_pending.release
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 _request_push(NPFD1, CAPABILITY, {}) = NPFD1
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 data=NPFD1 CAPABILITY
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 Account sync test2314 CAPABILITY:NPFD1.ready.wait
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.47 mail.itia.ntua.gr writer > NPFD1 CAPABILITY\r\n
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr reader rcvd 164
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr reader < * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION\r\n
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr reader < NPFD1 OK CAPABILITY completed\r\n
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 1 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr handler _request_pop(NPFD1, ('OK', ['CAPABILITY completed'])) = NPFD1
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.66 mail.itia.ntua.gr handler CAPABILITY:NPFD1.ready.set
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 _get_untagged_response(CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION', 'IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION', 'IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 CAPABILITY: ('IMAP4REV1', 'UIDPLUS', 'CHILDREN', 'NAMESPACE', 'THREAD=ORDEREDSUBJECT', 'THREAD=REFERENCES', 'SORT', 'QUOTA', 'IDLE', 'AUTH=PLAIN', 'ACL', 'ACL2=UNION')
2014-08-17 23:50:46 DEBUG: [imap]: Attempting PLAIN authentication
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 [sync] AUTHENTICATE ('PLAIN',)
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 _request_push(NPFD2, AUTHENTICATE, {}) = NPFD2
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 data=NPFD2 AUTHENTICATE PLAIN
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 _request_push(continuation, None, {}) = NPFD3
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 Account sync test2314 None:NPFD3.ready.wait
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.67 mail.itia.ntua.gr writer > NPFD2 AUTHENTICATE PLAIN\r\n
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 mail.itia.ntua.gr reader rcvd 4
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 mail.itia.ntua.gr reader < + \r\n
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 mail.itia.ntua.gr handler _request_pop(continuation, (True, '')) = NPFD3
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 mail.itia.ntua.gr handler None:NPFD3.ready.set
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 Account sync test2314 continuation => True,
2014-08-17 23:50:46 DEBUG: [imap]: __plainhandler: returning test2314 topsecret
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 Account sync test2314 _request_push(continuation, None, {}) = NPFD4
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.84 Account sync test2314 write literal size 28
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.85 Account sync test2314 None:NPFD4.ready.wait
2014-08-17 23:50:46 DEBUG: [imap]: 50:46.85 mail.itia.ntua.gr writer > AHRlc3QyMzE0AHRvcHNlY3JldA==\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.17 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.17 mail.itia.ntua.gr reader rcvd 20
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.17 mail.itia.ntua.gr reader < NPFD2 OK LOGIN Ok.\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.17 mail.itia.ntua.gr handler _request_pop(NPFD2, ('OK', ['LOGIN Ok.'])) = NPFD2
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.17 mail.itia.ntua.gr handler AUTHENTICATE:NPFD2.ready.set
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.17 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.17 mail.itia.ntua.gr handler _request_pop(continuation, (False, 'NPFD2 OK LOGIN Ok.')) = NPFD4
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 mail.itia.ntua.gr handler None:NPFD4.ready.set
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 continuation => False, NPFD2 OK LOGIN Ok.
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 AUTHENTICATE:NPFD2.ready.wait
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 state => AUTH
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 state_change_pending.release
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 [async] CAPABILITY ()
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 state_change_pending.release
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 _request_push(NPFD5, CAPABILITY, {}) = NPFD5
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 data=NPFD5 CAPABILITY
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 Account sync test2314 CAPABILITY:NPFD5.ready.wait
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.18 mail.itia.ntua.gr writer > NPFD5 CAPABILITY\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.45 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.45 mail.itia.ntua.gr reader rcvd 164
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.45 mail.itia.ntua.gr reader < * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.45 mail.itia.ntua.gr reader < NPFD5 OK CAPABILITY completed\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.45 mail.itia.ntua.gr handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION"]
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 mail.itia.ntua.gr handler _request_pop(NPFD5, ('OK', ['CAPABILITY completed'])) = NPFD5
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 mail.itia.ntua.gr handler CAPABILITY:NPFD5.ready.set
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 _get_untagged_response(CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION']
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 [async] LIST ('', '""')
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 state_change_pending.release
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 _request_push(NPFD6, LIST, {}) = NPFD6
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 data=NPFD6 LIST "" ""
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 Account sync test2314 LIST:NPFD6.ready.wait
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.46 mail.itia.ntua.gr writer > NPFD6 LIST "" ""\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr reader rcvd 52
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr reader < * LIST (\Noselect) "." ""\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr reader < NPFD6 OK LIST completed\r\n
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr handler untagged_responses[LIST] 0 += ["(\Noselect) "." """]
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr handler _request_pop(NPFD6, ('OK', ['LIST completed'])) = NPFD6
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 mail.itia.ntua.gr handler LIST:NPFD6.ready.set
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 Account sync test2314 _get_untagged_response(LIST) => ['(\\Noselect) "." ""']
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.72 Account sync test2314 _untagged_response(OK, ?, LIST) => ['(\\Noselect) "." ""']
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.73 Account sync test2314 [async] LIST ('', '*')
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.73 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.73 Account sync test2314 state_change_pending.release
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.73 Account sync test2314 _request_push(NPFD7, LIST, {}) = NPFD7
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.73 Account sync test2314 data=NPFD7 LIST "" "*"
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.73 Account sync test2314 LIST:NPFD7.ready.wait
2014-08-17 23:50:47 DEBUG: [imap]: 50:47.73 mail.itia.ntua.gr writer > NPFD7 LIST "" "*"\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.00 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.00 mail.itia.ntua.gr reader rcvd 70
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.00 mail.itia.ntua.gr reader < * LIST (\Marked \HasNoChildren) "." "INBOX"\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.00 mail.itia.ntua.gr reader < NPFD7 OK LIST completed\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.01 mail.itia.ntua.gr handler untagged_responses[LIST] 0 += ["(\Marked \HasNoChildren) "." "INBOX""]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.01 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.01 mail.itia.ntua.gr handler _request_pop(NPFD7, ('OK', ['LIST completed'])) = NPFD7
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.01 mail.itia.ntua.gr handler LIST:NPFD7.ready.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.01 Account sync test2314 _get_untagged_response(LIST) => ['(\\Marked \\HasNoChildren) "." "INBOX"']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.01 Account sync test2314 _untagged_response(OK, ?, LIST) => ['(\\Marked \\HasNoChildren) "." "INBOX"']
2014-08-17 23:50:48 DEBUG: [maildir]: _GETFOLDERS_SCANDIR STARTING. root = /var/tmp/oimap/Maildir, extension = None
2014-08-17 23:50:48 DEBUG: [maildir]: toppath = /var/tmp/oimap/Maildir
2014-08-17 23:50:48 DEBUG: [maildir]: dirname = new
2014-08-17 23:50:48 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:50:48 DEBUG: [maildir]: dirname = cur
2014-08-17 23:50:48 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:50:48 DEBUG: [maildir]: dirname = tmp
2014-08-17 23:50:48 DEBUG: [maildir]: skip this entry (Maildir special)
2014-08-17 23:50:48 DEBUG: [maildir]: dirname =
2014-08-17 23:50:48 DEBUG: [maildir]: This is maildir folder ''.
2014-08-17 23:50:48 DEBUG: [maildir]: _GETFOLDERS_SCANDIR RETURNING ['']
2014-08-17 23:50:48 DEBUG: []: Copying folder structure from Remote to Local
2014-08-17 23:50:48 INFO: Syncing INBOX: IMAP -> Maildir
2014-08-17 23:50:48 DEBUG: Loading message list for Maildir[]
2014-08-17 23:50:48 DEBUG: Message list for Maildir[] loaded: 3 messages
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.02 Account sync test2314 [sync] SELECT ('INBOX',)
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.02 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.02 Account sync test2314 _request_push(NPFD8, SELECT, {}) = NPFD8
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.02 Account sync test2314 data=NPFD8 SELECT INBOX
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.02 Account sync test2314 SELECT:NPFD8.ready.wait
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.02 mail.itia.ntua.gr writer > NPFD8 SELECT INBOX\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader rcvd 253
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader < * FLAGS (\Draft \Answered \Flagged \Deleted \Seen \Recent)\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader < * OK [PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader < * 3 EXISTS\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader < * 0 RECENT\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader < * OK [UIDVALIDITY 1408307074] Ok\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader < * OK [MYRIGHTS "acdilrsw"] ACL\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr reader < NPFD8 OK [READ-WRITE] Ok\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr handler untagged_responses[FLAGS] 0 += ["(\Draft \Answered \Flagged \Deleted \Seen \Recent)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.30 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[PERMANENTFLAGS] 0 += ["(\* \Draft \Answered \Flagged \Deleted \Seen)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[EXISTS] 0 += ["3"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[RECENT] 0 += ["0"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[UIDVALIDITY 1408307074] Ok"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[UIDVALIDITY] 0 += ["1408307074"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[MYRIGHTS "acdilrsw"] ACL"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[MYRIGHTS] 0 += [""acdilrsw""]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler _request_pop(NPFD8, ('OK', ['[READ-WRITE] Ok'])) = NPFD8
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler SELECT:NPFD8.ready.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 state => SELECTED
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 mail.itia.ntua.gr handler untagged_responses[READ-WRITE] 0 += [""]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 state_change_pending.release
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 _get_untagged_response(EXISTS) => ['3']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 _untagged_response(OK, ?, EXISTS) => ['3']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 _get_untagged_response(UIDVALIDITY) => ['1408307074']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 _untagged_response(UIDVALIDITY, ?, UIDVALIDITY) => ['1408307074']
2014-08-17 23:50:48 DEBUG: Loading message list for IMAP[INBOX]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 [sync] EXAMINE ('INBOX',)
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 _request_push(NPFD9, EXAMINE, {}) = NPFD9
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 data=NPFD9 EXAMINE INBOX
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.31 Account sync test2314 EXAMINE:NPFD9.ready.wait
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.32 mail.itia.ntua.gr writer > NPFD9 EXAMINE INBOX\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr reader rcvd 230
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr reader < * FLAGS (\Draft \Answered \Flagged \Deleted \Seen \Recent)\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr reader < * OK [PERMANENTFLAGS ()] No permanent flags permitted\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr handler untagged_responses[FLAGS] 0 += ["(\Draft \Answered \Flagged \Deleted \Seen \Recent)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr reader < * 3 EXISTS\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[PERMANENTFLAGS ()] No permanent flags permitted"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr reader < * 0 RECENT\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.53 mail.itia.ntua.gr reader < * OK [UIDVALIDITY 1408307074] Ok\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr reader < * OK [MYRIGHTS "acdilrsw"] ACL\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[PERMANENTFLAGS] 0 += ["()"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr reader < NPFD9 OK [READ-ONLY] Ok\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[EXISTS] 0 += ["3"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[RECENT] 0 += ["0"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[UIDVALIDITY 1408307074] Ok"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[UIDVALIDITY] 0 += ["1408307074"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[MYRIGHTS "acdilrsw"] ACL"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[MYRIGHTS] 0 += [""acdilrsw""]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler _request_pop(NPFD9, ('OK', ['[READ-ONLY] Ok'])) = NPFD9
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler EXAMINE:NPFD9.ready.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 state => SELECTED
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 mail.itia.ntua.gr handler untagged_responses[READ-ONLY] 0 += [""]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 state_change_pending.release
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 _get_untagged_response(READ-ONLY) => ['']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 _get_untagged_response(EXISTS) => ['3']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 _untagged_response(OK, ?, EXISTS) => ['3']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 [async] FETCH ("'1:*'", '(FLAGS UID)')
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 state_change_pending.release
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 _get_untagged_response(OK) => ['[PERMANENTFLAGS ()] No permanent flags permitted']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.54 Account sync test2314 _get_untagged_response(READ-ONLY) => ['']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.55 Account sync test2314 _request_push(NPFD10, FETCH, {}) = NPFD10
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.55 Account sync test2314 data=NPFD10 FETCH 1:* (FLAGS UID)
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.55 Account sync test2314 FETCH:NPFD10.ready.wait
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.55 mail.itia.ntua.gr writer > NPFD10 FETCH 1:* (FLAGS UID)\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr reader rcvd 115
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr reader < * 1 FETCH (FLAGS () UID 13)\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr reader < * 2 FETCH (FLAGS () UID 14)\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr reader < * 3 FETCH (FLAGS () UID 15)\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr reader < NPFD10 OK FETCH completed.\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr handler untagged_responses[FETCH] 0 += ["1 (FLAGS () UID 13)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr handler untagged_responses[FETCH] 1 += ["2 (FLAGS () UID 14)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.76 mail.itia.ntua.gr handler untagged_responses[FETCH] 2 += ["3 (FLAGS () UID 15)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.77 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.77 mail.itia.ntua.gr handler _request_pop(NPFD10, ('OK', ['FETCH completed.'])) = NPFD10
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.77 mail.itia.ntua.gr handler FETCH:NPFD10.ready.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.77 Account sync test2314 _get_untagged_response(FETCH) => ['1 (FLAGS () UID 13)', '2 (FLAGS () UID 14)', '3 (FLAGS () UID 15)']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.77 Account sync test2314 _untagged_response(OK, ?, FETCH) => ['1 (FLAGS () UID 13)', '2 (FLAGS () UID 14)', '3 (FLAGS () UID 15)']
2014-08-17 23:50:48 DEBUG: [imap]: __options2hash returning: {'FLAGS': '()', 'UID': '13'}
2014-08-17 23:50:48 DEBUG: [imap]: __options2hash returning: {'FLAGS': '()', 'UID': '14'}
2014-08-17 23:50:48 DEBUG: [imap]: __options2hash returning: {'FLAGS': '()', 'UID': '15'}
2014-08-17 23:50:48 DEBUG: Message list for IMAP[INBOX] loaded: 3 messages
2014-08-17 23:50:48 DEBUG: Syncing messages IMAP[INBOX] -> Maildir[]
2014-08-17 23:50:48 DEBUG: Syncing messages Maildir[] -> IMAP[INBOX]
2014-08-17 23:50:48 INFO: Copy message -2 (1 of 3) Local: -> Remote
2014-08-17 23:50:48 DEBUG: [imap]: Write mail 'INBOX:-2' with flags set([])
2014-08-17 23:50:48 DEBUG: []: deletemessageheaders: called to delete []
2014-08-17 23:50:48 DEBUG: []: getmessageheader: called to get message-id
2014-08-17 23:50:48 DEBUG: []: getmessageheader: eoh = 759
2014-08-17 23:50:48 DEBUG: []: getmessageheader: headers = 'Return-path: <anthony at itia.ntua.gr>\r\nEnvelope-to: test2314 at itia.ntua.gr\r\nDelivery-date: Sun, 17 Aug 2014 23:15:44 +0300\r\nReceived: from [188.73.253.36] (helo=localhost)\r\n\tby acheloos.itia.civil.ntua.gr with esmtpsa (TLS1.0:DHE_RSA_AES_128_CBC_SHA1:16)\r\n\t(Exim 4.72)\r\n\t(envelope-from <anthony at itia.ntua.gr>)\r\n\tid 1XJ6rf-0007Kd-0v\r\n\tfor test2314 at itia.ntua.gr; Sun, 17 Aug 2014 23:15:44 +0300\r\nDate: Sun, 17 Aug 2014 23:15:36 +0300\r\nFrom: Antonis Christofides <anthony at itia.ntua.gr>\r\nTo: test2314 at itia.ntua.gr\r\nSubject: This is the second email\r\nMessage-ID: <20140817201536.GB6043 at itia.ntua.gr>\r\nMIME-Version: 1.0\r\nContent-Type: text/plain; charset=us-ascii\r\nContent-Disposition: inline\r\nUser-Agent: Mutt/1.5.21 (2010-09-15)\r\n\r\nHello. This is the second email.\r\n'
2014-08-17 23:50:48 DEBUG: [imap]: savemessage: date: "17-Aug-2014 23:24:35 +0200", content: 'Return-path: <anthony at itia.ntua.gr>
Envelope-to: test2314 at itia.ntua.gr
Delivery-date: Sun, 17 Aug 2014 23:15:44 +0300
Received: from [188.73.253.36...(2010-09-15)
Hello. This is the second email.
'
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.77 Account sync test2314 [sync] SELECT ('INBOX',)
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.77 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.78 Account sync test2314 _request_push(NPFD11, SELECT, {}) = NPFD11
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.78 Account sync test2314 data=NPFD11 SELECT INBOX
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.78 Account sync test2314 SELECT:NPFD11.ready.wait
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.78 mail.itia.ntua.gr writer > NPFD11 SELECT INBOX\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr reader rcvd 254
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr reader < * FLAGS (\Draft \Answered \Flagged \Deleted \Seen \Recent)\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr reader < * OK [PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr handler untagged_responses[FLAGS] 0 += ["(\Draft \Answered \Flagged \Deleted \Seen \Recent)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr reader < * 3 EXISTS\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr reader < * 0 RECENT\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.97 mail.itia.ntua.gr reader < * OK [UIDVALIDITY 1408307074] Ok\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[PERMANENTFLAGS] 0 += ["(\* \Draft \Answered \Flagged \Deleted \Seen)"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr reader < * OK [MYRIGHTS "acdilrsw"] ACL\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[EXISTS] 0 += ["3"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr reader < NPFD11 OK [READ-WRITE] Ok\r\n
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[RECENT] 0 += ["0"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[UIDVALIDITY 1408307074] Ok"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[UIDVALIDITY] 0 += ["1408307074"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[OK] 0 += ["[MYRIGHTS "acdilrsw"] ACL"]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[MYRIGHTS] 0 += [""acdilrsw""]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler _request_pop(NPFD11, ('OK', ['[READ-WRITE] Ok'])) = NPFD11
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler SELECT:NPFD11.ready.set
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 state => SELECTED
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 mail.itia.ntua.gr handler untagged_responses[READ-WRITE] 0 += [""]
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 state_change_pending.release
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 _get_untagged_response(EXISTS) => ['3']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 _untagged_response(OK, ?, EXISTS) => ['3']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 [sync] APPEND ('INBOX', '()', '"17-Aug-2014 23:24:35 +0200"')
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 _get_untagged_response(OK) => ['[PERMANENTFLAGS (\\* \\Draft \\Answered \\Flagged \\Deleted \\Seen)] Limited']
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 _request_push(NPFD12, APPEND, {}) = NPFD12
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 data=NPFD12 APPEND INBOX () "17-Aug-2014 23:24:35 +0200" {759}
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.98 Account sync test2314 _request_push(continuation, None, {}) = NPFD13
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.99 Account sync test2314 None:NPFD13.ready.wait
2014-08-17 23:50:48 DEBUG: [imap]: 50:48.99 mail.itia.ntua.gr writer > NPFD12 APPEND INBOX () "17-Aug-2014 23:24:35 +0200" {759}\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 mail.itia.ntua.gr reader rcvd 6
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 mail.itia.ntua.gr reader < + OK\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 mail.itia.ntua.gr handler _request_pop(continuation, (True, 'OK')) = NPFD13
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 mail.itia.ntua.gr handler None:NPFD13.ready.set
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 Account sync test2314 continuation => True, OK
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 Account sync test2314 write literal size 759
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.22 Account sync test2314 APPEND:NPFD12.ready.wait
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.23 mail.itia.ntua.gr writer > Return-path: <anthony at itia.ntua.gr>
Envelope-to: test2314 at itia.ntua.gr
Delivery-date: Sun, 17 Aug 2014 23:15:44 +0300
Received: from [188.73.253.36] (helo=localhost)
by acheloos.itia.civil.ntua.gr with esmtpsa (TLS1.0:DHE_RSA_AES_128_CBC_SHA1:16)
(Exim 4.72)
(envelope-from <anthony at itia.ntua.gr>)
id 1XJ6rf-0007Kd-0v
for test2314 at itia.ntua.gr; Sun, 17 Aug 2014 23:15:44 +0300
Date: Sun, 17 Aug 2014 23:15:36 +0300
From: Antonis Christofides <anthony at itia.ntua.gr>
To: test2314 at itia.ntua.gr
Subject: This is the second email
Message-ID: <20140817201536.GB6043 at itia.ntua.gr>
MIME-Version: 1.0
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline
User-Agent: Mutt/1.5.21 (2010-09-15)
Hello. This is the second email.
\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.54 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.54 mail.itia.ntua.gr reader rcvd 48
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.54 mail.itia.ntua.gr reader < NPFD12 OK [APPENDUID 1408307074 16] APPEND Ok.\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.54 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.54 mail.itia.ntua.gr handler _request_pop(NPFD12, ('OK', ['[APPENDUID 1408307074 16] APPEND Ok.'])) = NPFD12
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.54 mail.itia.ntua.gr handler APPEND:NPFD12.ready.set
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.54 Account sync test2314 state_change_pending.release
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 mail.itia.ntua.gr handler untagged_responses[APPENDUID] 0 += ["1408307074 16"]
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 Account sync test2314 [async] CHECK ()
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 Account sync test2314 state_change_pending.release
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 Account sync test2314 _get_untagged_response(OK) => ['[UIDVALIDITY 1408307074] Ok']
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 Account sync test2314 _request_push(NPFD14, CHECK, {}) = NPFD14
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 Account sync test2314 data=NPFD14 CHECK
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 Account sync test2314 CHECK:NPFD14.ready.wait
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.55 mail.itia.ntua.gr writer > NPFD14 CHECK\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr reader rcvd 51
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr reader < * 4 EXISTS\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr reader < * 0 RECENT\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr reader < NPFD14 OK CHECK completed\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr handler untagged_responses[EXISTS] 0 += ["4"]
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr handler untagged_responses[RECENT] 0 += ["0"]
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.76 mail.itia.ntua.gr handler _request_pop(NPFD14, ('OK', ['CHECK completed'])) = NPFD14
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 _get_untagged_response(APPENDUID) => ['1408307074 16']
2014-08-17 23:50:49 DEBUG: [imap]: savemessage: returning new UID 16
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 mail.itia.ntua.gr handler CHECK:NPFD14.ready.set
2014-08-17 23:50:49 INFO: Copy message -1 (2 of 3) Local: -> Remote
2014-08-17 23:50:49 DEBUG: [imap]: Write mail 'INBOX:-1' with flags set([])
2014-08-17 23:50:49 DEBUG: []: deletemessageheaders: called to delete []
2014-08-17 23:50:49 DEBUG: []: getmessageheader: called to get message-id
2014-08-17 23:50:49 DEBUG: []: getmessageheader: eoh = 757
2014-08-17 23:50:49 DEBUG: []: getmessageheader: headers = 'Return-path: <anthony at itia.ntua.gr>\r\nEnvelope-to: test2314 at itia.ntua.gr\r\nDelivery-date: Sun, 17 Aug 2014 23:16:53 +0300\r\nReceived: from [188.73.253.36] (helo=localhost)\r\n\tby acheloos.itia.civil.ntua.gr with esmtpsa (TLS1.0:DHE_RSA_AES_128_CBC_SHA1:16)\r\n\t(Exim 4.72)\r\n\t(envelope-from <anthony at itia.ntua.gr>)\r\n\tid 1XJ6sm-0007M6-Ml\r\n\tfor test2314 at itia.ntua.gr; Sun, 17 Aug 2014 23:16:53 +0300\r\nDate: Sun, 17 Aug 2014 23:16:45 +0300\r\nFrom: Antonis Christofides <anthony at itia.ntua.gr>\r\nTo: test2314 at itia.ntua.gr\r\nSubject: This is the third email\r\nMessage-ID: <20140817201645.GC6043 at itia.ntua.gr>\r\nMIME-Version: 1.0\r\nContent-Type: text/plain; charset=us-ascii\r\nContent-Disposition: inline\r\nUser-Agent: Mutt/1.5.21 (2010-09-15)\r\n\r\nHello. This is the third email.\r\n'
2014-08-17 23:50:49 DEBUG: [imap]: savemessage: date: "17-Aug-2014 23:24:36 +0200", content: 'Return-path: <anthony at itia.ntua.gr>
Envelope-to: test2314 at itia.ntua.gr
Delivery-date: Sun, 17 Aug 2014 23:16:53 +0300
Received: from [188.73.253.36... (2010-09-15)
Hello. This is the third email.
'
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 [sync] APPEND ('INBOX', '()', '"17-Aug-2014 23:24:36 +0200"')
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 _get_untagged_response(OK) => ['[MYRIGHTS "acdilrsw"] ACL']
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 _request_push(NPFD15, APPEND, {}) = NPFD15
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 data=NPFD15 APPEND INBOX () "17-Aug-2014 23:24:36 +0200" {757}
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 _request_push(continuation, None, {}) = NPFD16
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.77 Account sync test2314 None:NPFD16.ready.wait
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.78 mail.itia.ntua.gr writer > NPFD15 APPEND INBOX () "17-Aug-2014 23:24:36 +0200" {757}\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 mail.itia.ntua.gr reader rcvd 6
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 mail.itia.ntua.gr reader < + OK\r\n
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 mail.itia.ntua.gr handler _request_pop(continuation, (True, 'OK')) = NPFD16
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 mail.itia.ntua.gr handler None:NPFD16.ready.set
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 Account sync test2314 continuation => True, OK
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 Account sync test2314 write literal size 757
2014-08-17 23:50:49 DEBUG: [imap]: 50:49.99 Account sync test2314 APPEND:NPFD15.ready.wait
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.00 mail.itia.ntua.gr writer > Return-path: <anthony at itia.ntua.gr>
Envelope-to: test2314 at itia.ntua.gr
Delivery-date: Sun, 17 Aug 2014 23:16:53 +0300
Received: from [188.73.253.36] (helo=localhost)
by acheloos.itia.civil.ntua.gr with esmtpsa (TLS1.0:DHE_RSA_AES_128_CBC_SHA1:16)
(Exim 4.72)
(envelope-from <anthony at itia.ntua.gr>)
id 1XJ6sm-0007M6-Ml
for test2314 at itia.ntua.gr; Sun, 17 Aug 2014 23:16:53 +0300
Date: Sun, 17 Aug 2014 23:16:45 +0300
From: Antonis Christofides <anthony at itia.ntua.gr>
To: test2314 at itia.ntua.gr
Subject: This is the third email
Message-ID: <20140817201645.GC6043 at itia.ntua.gr>
MIME-Version: 1.0
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline
User-Agent: Mutt/1.5.21 (2010-09-15)
Hello. This is the third email.
\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 mail.itia.ntua.gr reader rcvd 48
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 mail.itia.ntua.gr reader < NPFD15 OK [APPENDUID 1408307074 17] APPEND Ok.\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 mail.itia.ntua.gr handler _request_pop(NPFD15, ('OK', ['[APPENDUID 1408307074 17] APPEND Ok.'])) = NPFD15
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 mail.itia.ntua.gr handler APPEND:NPFD15.ready.set
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 Account sync test2314 state_change_pending.release
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.22 mail.itia.ntua.gr handler untagged_responses[APPENDUID] 0 += ["1408307074 17"]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.23 Account sync test2314 [async] CHECK ()
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.23 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.23 Account sync test2314 state_change_pending.release
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.23 Account sync test2314 _request_push(NPFD17, CHECK, {}) = NPFD17
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.23 Account sync test2314 data=NPFD17 CHECK
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.23 Account sync test2314 CHECK:NPFD17.ready.wait
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.23 mail.itia.ntua.gr writer > NPFD17 CHECK\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr reader rcvd 51
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr reader < * 5 EXISTS\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr reader < * 0 RECENT\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr reader < NPFD17 OK CHECK completed\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr handler untagged_responses[EXISTS] 0 += ["5"]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr handler untagged_responses[RECENT] 0 += ["0"]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.44 mail.itia.ntua.gr handler _request_pop(NPFD17, ('OK', ['CHECK completed'])) = NPFD17
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 mail.itia.ntua.gr handler CHECK:NPFD17.ready.set
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 Account sync test2314 _get_untagged_response(APPENDUID) => ['1408307074 17']
2014-08-17 23:50:50 DEBUG: [imap]: savemessage: returning new UID 17
2014-08-17 23:50:50 INFO: Copy message -3 (3 of 3) Local: -> Remote
2014-08-17 23:50:50 DEBUG: [imap]: Write mail 'INBOX:-3' with flags set([])
2014-08-17 23:50:50 DEBUG: []: deletemessageheaders: called to delete []
2014-08-17 23:50:50 DEBUG: []: getmessageheader: called to get message-id
2014-08-17 23:50:50 DEBUG: []: getmessageheader: eoh = 756
2014-08-17 23:50:50 DEBUG: []: getmessageheader: headers = "Return-path: <anthony at itia.ntua.gr>\r\nEnvelope-to: test2314 at itia.ntua.gr\r\nDelivery-date: Sun, 17 Aug 2014 23:15:27 +0300\r\nReceived: from [188.73.253.36] (helo=localhost)\r\n\tby acheloos.itia.civil.ntua.gr with esmtpsa (TLS1.0:DHE_RSA_AES_128_CBC_SHA1:16)\r\n\t(Exim 4.72)\r\n\t(envelope-from <anthony at itia.ntua.gr>)\r\n\tid 1XJ6rN-0007KO-VJ\r\n\tfor test2314 at itia.ntua.gr; Sun, 17 Aug 2014 23:15:27 +0300\r\nDate: Sun, 17 Aug 2014 23:15:15 +0300\r\nFrom: Antonis Christofides <anthony at itia.ntua.gr>\r\nTo: test2314 at itia.ntua.gr\r\nSubject: This is the first email\r\nMessage-ID: <20140817201515.GA6043 at itia.ntua.gr>\r\nMIME-Version: 1.0\r\nContent-Type: text/plain; charset=us-ascii\r\nContent-Disposition: inline\r\nUser-Agent: Mutt/1.5.21 (2010-09-15)\r\n\r\nHello. That's the first email.\r\n"
2014-08-17 23:50:50 DEBUG: [imap]: savemessage: date: "17-Aug-2014 23:24:35 +0200", content: 'Return-path: <anthony at itia.ntua.gr>
Envelope-to: test2314 at itia.ntua.gr
Delivery-date: Sun, 17 Aug 2014 23:15:27 +0300
Received: from [188.73.253.36...1 (2010-09-15)
Hello. That's the first email.
'
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 Account sync test2314 [sync] APPEND ('INBOX', '()', '"17-Aug-2014 23:24:35 +0200"')
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 Account sync test2314 _request_push(NPFD18, APPEND, {}) = NPFD18
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 Account sync test2314 data=NPFD18 APPEND INBOX () "17-Aug-2014 23:24:35 +0200" {756}
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 Account sync test2314 _request_push(continuation, None, {}) = NPFD19
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 Account sync test2314 None:NPFD19.ready.wait
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.45 mail.itia.ntua.gr writer > NPFD18 APPEND INBOX () "17-Aug-2014 23:24:35 +0200" {756}\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 mail.itia.ntua.gr reader rcvd 6
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 mail.itia.ntua.gr reader < + OK\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 mail.itia.ntua.gr handler _request_pop(continuation, (True, 'OK')) = NPFD19
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 mail.itia.ntua.gr handler None:NPFD19.ready.set
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 Account sync test2314 continuation => True, OK
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 Account sync test2314 write literal size 756
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.64 Account sync test2314 APPEND:NPFD18.ready.wait
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.65 mail.itia.ntua.gr writer > Return-path: <anthony at itia.ntua.gr>
Envelope-to: test2314 at itia.ntua.gr
Delivery-date: Sun, 17 Aug 2014 23:15:27 +0300
Received: from [188.73.253.36] (helo=localhost)
by acheloos.itia.civil.ntua.gr with esmtpsa (TLS1.0:DHE_RSA_AES_128_CBC_SHA1:16)
(Exim 4.72)
(envelope-from <anthony at itia.ntua.gr>)
id 1XJ6rN-0007KO-VJ
for test2314 at itia.ntua.gr; Sun, 17 Aug 2014 23:15:27 +0300
Date: Sun, 17 Aug 2014 23:15:15 +0300
From: Antonis Christofides <anthony at itia.ntua.gr>
To: test2314 at itia.ntua.gr
Subject: This is the first email
Message-ID: <20140817201515.GA6043 at itia.ntua.gr>
MIME-Version: 1.0
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline
User-Agent: Mutt/1.5.21 (2010-09-15)
Hello. That's the first email.
\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 mail.itia.ntua.gr reader rcvd 48
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 mail.itia.ntua.gr reader < NPFD18 OK [APPENDUID 1408307074 18] APPEND Ok.\r\n
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 mail.itia.ntua.gr handler _request_pop(NPFD18, ('OK', ['[APPENDUID 1408307074 18] APPEND Ok.'])) = NPFD18
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 mail.itia.ntua.gr handler APPEND:NPFD18.ready.set
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 Account sync test2314 state_change_pending.release
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.83 mail.itia.ntua.gr handler untagged_responses[APPENDUID] 0 += ["1408307074 18"]
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.84 Account sync test2314 [async] CHECK ()
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.84 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.84 Account sync test2314 state_change_pending.release
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.84 Account sync test2314 _request_push(NPFD20, CHECK, {}) = NPFD20
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.84 Account sync test2314 data=NPFD20 CHECK
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.84 Account sync test2314 CHECK:NPFD20.ready.wait
2014-08-17 23:50:50 DEBUG: [imap]: 50:50.84 mail.itia.ntua.gr writer > NPFD20 CHECK\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr reader rcvd 51
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr reader < * 6 EXISTS\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr reader < * 0 RECENT\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr reader < NPFD20 OK CHECK completed\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr handler untagged_responses[EXISTS] 0 += ["6"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr handler untagged_responses[RECENT] 0 += ["0"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.04 mail.itia.ntua.gr handler _request_pop(NPFD20, ('OK', ['CHECK completed'])) = NPFD20
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 mail.itia.ntua.gr handler CHECK:NPFD20.ready.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 Account sync test2314 _get_untagged_response(APPENDUID) => ['1408307074 18']
2014-08-17 23:50:51 DEBUG: [imap]: savemessage: returning new UID 18
2014-08-17 23:50:51 INFO: Deleting 3 messages (13:15) in IMAP[INBOX]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 Account sync test2314 [async] UID ('STORE', '13:15', '+FLAGS', '(\\Deleted)')
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 Account sync test2314 state_change_pending.release
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 Account sync test2314 _request_push(NPFD21, UID, {}) = NPFD21
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 Account sync test2314 data=NPFD21 UID STORE 13:15 +FLAGS (\Deleted)
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 Account sync test2314 UID:NPFD21.ready.wait
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.05 mail.itia.ntua.gr writer > NPFD21 UID STORE 13:15 +FLAGS (\Deleted)\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.28 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.28 mail.itia.ntua.gr reader rcvd 139
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.28 mail.itia.ntua.gr reader < * 1 FETCH (UID 13 FLAGS (\Deleted))\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.28 mail.itia.ntua.gr reader < * 2 FETCH (UID 14 FLAGS (\Deleted))\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.28 mail.itia.ntua.gr reader < * 3 FETCH (UID 15 FLAGS (\Deleted))\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr handler untagged_responses[FETCH] 0 += ["1 (UID 13 FLAGS (\Deleted))"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr reader < NPFD21 OK STORE completed.\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr handler untagged_responses[FETCH] 1 += ["2 (UID 14 FLAGS (\Deleted))"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr handler untagged_responses[FETCH] 2 += ["3 (UID 15 FLAGS (\Deleted))"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr handler _request_pop(NPFD21, ('OK', ['STORE completed.'])) = NPFD21
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr handler UID:NPFD21.ready.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 _get_untagged_response(FETCH) => ['1 (UID 13 FLAGS (\\Deleted))', '2 (UID 14 FLAGS (\\Deleted))', '3 (UID 15 FLAGS (\\Deleted))']
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 _untagged_response(OK, ?, FETCH) => ['1 (UID 13 FLAGS (\\Deleted))', '2 (UID 14 FLAGS (\\Deleted))', '3 (UID 15 FLAGS (\\Deleted))']
2014-08-17 23:50:51 DEBUG: [imap]: __options2hash returning: {'FLAGS': '(\\Deleted)', 'UID': '13'}
2014-08-17 23:50:51 DEBUG: [imap]: __options2hash returning: {'FLAGS': '(\\Deleted)', 'UID': '14'}
2014-08-17 23:50:51 DEBUG: [imap]: __options2hash returning: {'FLAGS': '(\\Deleted)', 'UID': '15'}
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 [async] EXPUNGE ()
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 state_change_pending.release
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 _request_push(NPFD22, EXPUNGE, {}) = NPFD22
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 data=NPFD22 EXPUNGE
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 Account sync test2314 EXPUNGE:NPFD22.ready.wait
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.29 mail.itia.ntua.gr writer > NPFD22 EXPUNGE\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader rcvd 92
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader < * 1 EXPUNGE\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader < * 1 EXPUNGE\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader < * 1 EXPUNGE\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler untagged_responses[EXPUNGE] 0 += ["1"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader < * 3 EXISTS\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler untagged_responses[EXPUNGE] 1 += ["1"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader < * 0 RECENT\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler untagged_responses[EXPUNGE] 2 += ["1"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr reader < NPFD22 OK EXPUNGE completed\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler untagged_responses[EXISTS] 0 += ["3"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler untagged_responses[RECENT] 0 += ["0"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler _request_pop(NPFD22, ('OK', ['EXPUNGE completed'])) = NPFD22
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 mail.itia.ntua.gr handler EXPUNGE:NPFD22.ready.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 Account sync test2314 _get_untagged_response(EXPUNGE) => ['1', '1', '1']
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.46 Account sync test2314 _untagged_response(OK, ?, EXPUNGE) => ['1', '1', '1']
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.47 Account sync test2314 state => LOGOUT
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.47 Account sync test2314 [sync] LOGOUT ()
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.47 Account sync test2314 state_change_pending.acquire
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.47 Account sync test2314 _request_push(NPFD23, LOGOUT, {}) = NPFD23
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.47 Account sync test2314 data=NPFD23 LOGOUT
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.47 Account sync test2314 LOGOUT:NPFD23.ready.wait
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.47 mail.itia.ntua.gr writer > NPFD23 LOGOUT\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr reader poll => [(5, 1)]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr reader rcvd 69
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr reader < * BYE Courier-IMAP server shutting down\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr reader < NPFD23 OK LOGOUT completed\r\n
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr handler untagged_responses[BYE] 0 += ["Courier-IMAP server shutting down"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr handler BYE response: Courier-IMAP server shutting down
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr handler terminating: 'connection terminated'
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.67 mail.itia.ntua.gr handler LOGOUT:NPFD23.ready.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 mail.itia.ntua.gr handler state_change_free.set
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 mail.itia.ntua.gr handler finished
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 mail.itia.ntua.gr reader poll => []
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 mail.itia.ntua.gr reader finished
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 Account sync test2314 ["<class 'offlineimap.imaplib2.abort'>: command: LOGOUT => connection terminated"]
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 mail.itia.ntua.gr writer finished
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 Account sync test2314 _close_threads
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 Account sync test2314 call shutdown
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 Account sync test2314 state_change_pending.release
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 Account sync test2314 connection closed
2014-08-17 23:50:51 DEBUG: [imap]: 50:51.68 Account sync test2314 _get_untagged_response(BYE) => ['Courier-IMAP server shutting down']
2014-08-17 23:50:51 INFO: *** Finished account 'test2314' in 0:08
More information about the OfflineIMAP-project
mailing list