version 6.5.3: ['[ALREADYEXISTS] Duplicate folder name (Failure)

ael law_ence.dev at ntlworld.com
Sun Apr 29 20:37:27 BST 2012 

I have been using the old debian offlineimap package (6.3.4-1) which
worked fairly well apart from what seemed to be occasional deadlocks.

On 3 differnt machines I have just done a git clone on the master branch 
and installed the current release. The 3 different machines have various
CPUs and architectures: I know that shouldn't make any difference,
except perhaps to race conditions.

On 2 of the three machines, I am seeing this sort of error:

--------------------------------------------------------------------------------
$ offlineimap -a ntl 
OfflineIMAP 6.5.3
  Licensed under the GNU GPL v2+ (v2 or any later version)
Account sync ntl:
 *** Processing account ntl
 Establishing connection to imap.ntlworld.com:993
Enter password for account 'ntlserv': 
 Creating folder Follow up[ntlserv]
 ERROR: Creating folder Follow up on repository ntlserv
  Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])
 ERROR: Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])
 *** Finished account 'ntl' in 0:06
ERROR: Exceptions occurred during the run!
ERROR: Creating folder Follow up on repository ntlserv
  Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])
ERROR: Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])

--------------------------------------------------------------------------------------
I had purged the ~/offlineimap/ caches before the test.

Furthermore, I see the problem on 3 different imap servers:
1) a badge engineered gmail system;
2) a microsoft exchange server and
3) another microsoft exchange server (2010).

Here is the ~/.offlineimaprc configuration for the ntl account above:

[general]
accounts = ...,ntl,...

[Account ntl]
localrepository = ntlimap
remoterepository = ntlserv

[Repository ntlimap]
type = Maildir
localfolders = ~/Mail/ntl_imap
sep = /

[Repository ntlserv]
type = Gmail
ssl = yes
remoteprt = 993
remotehost = imap.ntlworld.com
remoteuser = user at ntlworld.com
trashfolder = [Gmail]/Bin
folderfilter = lambda foldername: foldername not in ['[Gmail]/All Mail','[Gmail]/Bin']

[Account ntlspam]
localrepository = ntlspamimap
remoterepository = ntlspamserv

----------------------------------------------------------------------------------------

I ran with -d imap:
$ offlineimap -a ntl -d imap -l /tmp/olibug.txt
and I have attached the log file (21K so I didn't bother to compress).


In passing the deadlocks are still there and are not cleared by ^C, but
that is another matter for another time.

ael

-------------- next part --------------
2012-04-29 19:47:39 INFO: OfflineImap 6.5.3 starting...
  Python: 2.7.2 Platform: linux2
  Args: /usr/local/bin/offlineimap -a ntl -d imap -l /tmp/olibug.txt
2012-04-29 19:47:39 INFO: Debug mode: Forcing to singlethreaded.
2012-04-29 19:47:39 DEBUG: Now debugging for imap: IMAP protocol debugging
2012-04-29 19:47:39 DEBUG: Now debugging for : Other offlineimap related sync messages
2012-04-29 19:47:39 INFO: *** Processing account ntl
2012-04-29 19:47:39 INFO: Establishing connection to imap.ntlworld.com:993
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.37 Account sync ntl imaplib2 version 2.29
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.37 Account sync ntl imaplib2 debug level 5, buffer level 3
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.54 imap.ntlworld.com writer starting
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.54 imap.ntlworld.com reader starting using poll
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.54 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.55 imap.ntlworld.com reader rcvd 56
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.55 imap.ntlworld.com reader < * OK Virgin Media IMAP4 server ready [ e4c558782NTL ].\r\n
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.55 Account sync ntl _request_push(continuation, None, {}) = GAOB0
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.55 Account sync ntl None:GAOB0.ready.wait
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 imap.ntlworld.com handler starting
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 imap.ntlworld.com handler untagged_responses[OK] 0 += ["Virgin Media IMAP4 server ready [ e4c558782NTL ]."]
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 imap.ntlworld.com handler _request_pop(continuation, (False, '* OK Virgin Media IMAP4 server ready [ e4c558782NTL ].')) = GAOB0
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 Account sync ntl _get_untagged_response(OK) => ['Virgin Media IMAP4 server ready [ e4c558782NTL ].']
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 Account sync ntl state => NONAUTH
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 Account sync ntl [async] CAPABILITY ()
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 Account sync ntl state_change_pending.acquire
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 Account sync ntl state_change_pending.release
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 Account sync ntl _request_push(GAOB1, CAPABILITY, {}) = GAOB1
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.65 Account sync ntl data=GAOB1 CAPABILITY
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.66 imap.ntlworld.com writer > GAOB1 CAPABILITY\r\n
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.66 Account sync ntl CAPABILITY:GAOB1.ready.wait
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.66 imap.ntlworld.com handler None:GAOB0.ready.set
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.68 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.68 imap.ntlworld.com reader rcvd 34
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.68 imap.ntlworld.com reader < * CAPABILITY IMAP4rev1 NAMESPACE\r\n
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.68 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.68 imap.ntlworld.com reader rcvd 32
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.68 imap.ntlworld.com reader < GAOB1 OK CAPABILITY completed.\r\n
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.68 imap.ntlworld.com handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 NAMESPACE"]
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.69 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.69 imap.ntlworld.com handler _request_pop(GAOB1, ('OK', ['CAPABILITY completed.'])) = GAOB1
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.69 Account sync ntl _get_untagged_response(CAPABILITY) => ['IMAP4rev1 NAMESPACE']
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.69 Account sync ntl _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 NAMESPACE']
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.69 Account sync ntl CAPABILITY: ('IMAP4REV1', 'NAMESPACE')
2012-04-29 19:47:39 DEBUG: [imap]: Attempting plain authentication
2012-04-29 19:47:39 DEBUG: [imap]:   47:39.69 imap.ntlworld.com handler CAPABILITY:GAOB1.ready.set
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.31 Account sync ntl [sync] LOGIN ('user at ntlworld.com', '"password"')
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.31 Account sync ntl state_change_pending.acquire
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.31 Account sync ntl _request_push(GAOB2, LOGIN, {}) = GAOB2
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.31 Account sync ntl data=GAOB2 LOGIN user at ntlworld.com "password"
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.31 Account sync ntl LOGIN:GAOB2.ready.wait
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.31 imap.ntlworld.com writer > GAOB2 LOGIN user at ntlworld.com "password"\r\n
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 imap.ntlworld.com reader rcvd 76
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 imap.ntlworld.com reader < GAOB2 OK user at ntlworld.com NAME1 NAME2 authenticated (Success)\r\n
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 imap.ntlworld.com handler _request_pop(GAOB2, ('OK', ['user at ntlworld.com NAME1 NAME2 authenticated (Success)'])) = GAOB2
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 imap.ntlworld.com handler LOGIN:GAOB2.ready.set
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl state => AUTH
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl state_change_pending.release
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl [async] CAPABILITY ()
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl state_change_pending.acquire
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl state_change_pending.release
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl _request_push(GAOB3, CAPABILITY, {}) = GAOB3
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl data=GAOB3 CAPABILITY
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 imap.ntlworld.com writer > GAOB3 CAPABILITY\r\n
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.80 Account sync ntl CAPABILITY:GAOB3.ready.wait
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com reader rcvd 126
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com reader < * CAPABILITY IMAP4rev1 UNSELECT IDLE NAMESPACE QUOTA ID XLIST CHILDREN X-GM-EXT-1 UIDPLUS COMPRESS=DEFLATE\r\n
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com reader < GAOB3 OK Success\r\n
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 UNSELECT IDLE NAMESPACE QUOTA ID XLIST CHILDREN X-GM-EXT-1 UIDPLUS COMPRESS=DEFLATE"]
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com handler _request_pop(GAOB3, ('OK', ['Success'])) = GAOB3
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com handler CAPABILITY:GAOB3.ready.set
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl _get_untagged_response(CAPABILITY) => ['IMAP4rev1 UNSELECT IDLE NAMESPACE QUOTA ID XLIST CHILDREN X-GM-EXT-1 UIDPLUS COMPRESS=DEFLATE']
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 UNSELECT IDLE NAMESPACE QUOTA ID XLIST CHILDREN X-GM-EXT-1 UIDPLUS COMPRESS=DEFLATE']
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl [async] LIST ('', '""')
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl state_change_pending.acquire
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl state_change_pending.release
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl _request_push(GAOB4, LIST, {}) = GAOB4
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl data=GAOB4 LIST "" ""
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 imap.ntlworld.com writer > GAOB4 LIST "" ""\r\n
2012-04-29 19:47:45 DEBUG: [imap]:   47:45.95 Account sync ntl LIST:GAOB4.ready.wait
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com reader rcvd 46
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com reader < * LIST (\Noselect) "/" "/"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com reader < GAOB4 OK Success\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com handler untagged_responses[LIST] 0 += ["(\Noselect) "/" "/""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com handler _request_pop(GAOB4, ('OK', ['Success'])) = GAOB4
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com handler LIST:GAOB4.ready.set
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl _get_untagged_response(LIST) => ['(\\Noselect) "/" "/"']
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl _untagged_response(OK, ?, LIST) => ['(\\Noselect) "/" "/"']
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl [async] LIST ('', '*')
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl state_change_pending.acquire
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl state_change_pending.release
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl _request_push(GAOB5, LIST, {}) = GAOB5
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl data=GAOB5 LIST "" "*"
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 imap.ntlworld.com writer > GAOB5 LIST "" "*"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.14 Account sync ntl LIST:GAOB5.ready.wait
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.31 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.31 imap.ntlworld.com reader rcvd 422
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.31 imap.ntlworld.com reader < * LIST (\HasNoChildren) "/" "INBOX"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.31 imap.ntlworld.com reader < * LIST (\HasNoChildren) "/" "Misc"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.31 imap.ntlworld.com reader < * LIST (\HasNoChildren) "/" "SentMail"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.31 imap.ntlworld.com reader < * LIST (\HasNoChildren) "/" "Spam"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com reader < * LIST (\Noselect \HasChildren) "/" "[Gmail]"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com reader < * LIST (\HasChildren \HasNoChildren) "/" "[Gmail]/Bin"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com reader < * LIST (\HasNoChildren) "/" "[Gmail]/Drafts"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com reader < * LIST (\HasChildren \HasNoChildren) "/" "[Gmail]/Sent Mail"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com reader < * LIST (\HasNoChildren) "/" "[Gmail]/Spam"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com reader < GAOB5 OK Success\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 0 += ["(\HasNoChildren) "/" "INBOX""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 1 += ["(\HasNoChildren) "/" "Misc""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 2 += ["(\HasNoChildren) "/" "SentMail""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 3 += ["(\HasNoChildren) "/" "Spam""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 4 += ["(\Noselect \HasChildren) "/" "[Gmail]""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 5 += ["(\HasChildren \HasNoChildren) "/" "[Gmail]/Bin""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 6 += ["(\HasNoChildren) "/" "[Gmail]/Drafts""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 7 += ["(\HasChildren \HasNoChildren) "/" "[Gmail]/Sent Mail""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler untagged_responses[LIST] 8 += ["(\HasNoChildren) "/" "[Gmail]/Spam""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler _request_pop(GAOB5, ('OK', ['Success'])) = GAOB5
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 Account sync ntl _get_untagged_response(LIST) => ['(\\HasNoChildren) "/" "INBOX"', '(\\HasNoChildren) "/" "Misc"', '(\\HasNoChildren) "/" "SentMail"', '(\\HasNoChildren) "/" "Spam"', '(\\Noselect \\HasChildren) "/" "[Gmail]"', '(\\HasChildren \\HasNoChildren) "/" "[Gmail]/Bin"', '(\\HasNoChildren) "/" "[Gmail]/Drafts"', '(\\HasChildren \\HasNoChildren) "/" "[Gmail]/Sent Mail"', '(\\HasNoChildren) "/" "[Gmail]/Spam"']
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 Account sync ntl _untagged_response(OK, ?, LIST) => ['(\\HasNoChildren) "/" "INBOX"', '(\\HasNoChildren) "/" "Misc"', '(\\HasNoChildren) "/" "SentMail"', '(\\HasNoChildren) "/" "Spam"', '(\\Noselect \\HasChildren) "/" "[Gmail]"', '(\\HasChildren \\HasNoChildren) "/" "[Gmail]/Bin"', '(\\HasNoChildren) "/" "[Gmail]/Drafts"', '(\\HasChildren \\HasNoChildren) "/" "[Gmail]/Sent Mail"', '(\\HasNoChildren) "/" "[Gmail]/Spam"']
2012-04-29 19:47:46 DEBUG: [imap]: Filtering out '[Gmail]/Bin'[ntlserv] due to folderfilter
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.32 imap.ntlworld.com handler LIST:GAOB5.ready.set
2012-04-29 19:47:46 DEBUG: []: Not creating folder '[Gmail]/All Mail' (repository 'ntlserv') as it would be filtered out on that repository.
2012-04-29 19:47:46 INFO: Creating folder Follow up[ntlserv]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.33 Account sync ntl [async] CREATE ('Follow up',)
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.33 Account sync ntl state_change_pending.acquire
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.33 Account sync ntl state_change_pending.release
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.33 Account sync ntl _request_push(GAOB6, CREATE, {}) = GAOB6
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.34 Account sync ntl data=GAOB6 CREATE "Follow up"
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.34 imap.ntlworld.com writer > GAOB6 CREATE "Follow up"\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.34 Account sync ntl CREATE:GAOB6.ready.wait
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com reader rcvd 58
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com reader < GAOB6 NO [ALREADYEXISTS] Duplicate folder name (Failure)\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com handler _request_pop(GAOB6, ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])) = GAOB6
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com handler CREATE:GAOB6.ready.set
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com handler untagged_responses[ALREADYEXISTS] 0 += [""]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.48 imap.ntlworld.com handler NO response: [ALREADYEXISTS] Duplicate folder name (Failure)
2012-04-29 19:47:46 INFO: ERROR: Creating folder Follow up on repository ntlserv
  Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])
2012-04-29 19:47:46 INFO: ['  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/Base.py", line 214, in sync_folder_structure\n    src_repo.makefolder(dst_name_t)\n', '  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/IMAP.py", line 357, in makefolder\n    OfflineImapError.ERROR.FOLDER)\n']
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.49 Account sync ntl state => LOGOUT
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.49 Account sync ntl [sync] LOGOUT ()
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.49 Account sync ntl state_change_pending.acquire
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.49 Account sync ntl _request_push(GAOB7, LOGOUT, {}) = GAOB7
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.49 Account sync ntl data=GAOB7 LOGOUT
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.49 imap.ntlworld.com writer > GAOB7 LOGOUT\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.49 Account sync ntl LOGOUT:GAOB7.ready.wait
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com reader rcvd 56
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com reader < * BYE LOGOUT Requested\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com reader < GAOB7 OK 73 good day (Success)\r\n
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com handler untagged_responses[BYE] 0 += ["LOGOUT Requested"]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com handler BYE response: LOGOUT Requested
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com handler terminating: 'connection terminated'
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com handler LOGOUT:GAOB7.ready.set
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com handler state_change_free.set
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com handler finished
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com writer finished
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.64 imap.ntlworld.com reader poll => [(5, 1)]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.65 imap.ntlworld.com reader rcvd 0
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.65 Account sync ntl ["<class 'offlineimap.imaplib2.abort'>: command: LOGOUT => connection terminated"]
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.65 Account sync ntl _close_threads
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.65 Account sync ntl call shutdown
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.75 imap.ntlworld.com reader finished
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.75 Account sync ntl state_change_pending.release
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.75 Account sync ntl connection closed
2012-04-29 19:47:46 DEBUG: [imap]:   47:46.75 Account sync ntl _get_untagged_response(BYE) => ['LOGOUT Requested']
2012-04-29 19:47:46 INFO: ERROR: Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])
2012-04-29 19:47:46 INFO: ['  File "/usr/local/lib/python2.7/dist-packages/offlineimap/accounts.py", line 234, in syncrunner\n    self.sync()\n', '  File "/usr/local/lib/python2.7/dist-packages/offlineimap/accounts.py", line 294, in sync\n    remoterepos.sync_folder_structure(localrepos, statusrepos)\n', '  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/Base.py", line 214, in sync_folder_structure\n    src_repo.makefolder(dst_name_t)\n', '  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/IMAP.py", line 357, in makefolder\n    OfflineImapError.ERROR.FOLDER)\n']
2012-04-29 19:47:46 INFO: *** Finished account 'ntl' in 0:07
2012-04-29 19:47:46 WARNING: ERROR: Exceptions occurred during the run!
2012-04-29 19:47:46 WARNING: ERROR: Creating folder Follow up on repository ntlserv
  Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])
2012-04-29 19:47:46 WARNING: 
Traceback:
  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/Base.py", line 214, in sync_folder_structure
    src_repo.makefolder(dst_name_t)
  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/IMAP.py", line 357, in makefolder
    OfflineImapError.ERROR.FOLDER)

2012-04-29 19:47:46 WARNING: ERROR: Folder 'Follow up'[ntlserv] could not be created. Server responded: ('NO', ['[ALREADYEXISTS] Duplicate folder name (Failure)'])
2012-04-29 19:47:46 WARNING: 
Traceback:
  File "/usr/local/lib/python2.7/dist-packages/offlineimap/accounts.py", line 234, in syncrunner
    self.sync()
  File "/usr/local/lib/python2.7/dist-packages/offlineimap/accounts.py", line 294, in sync
    remoterepos.sync_folder_structure(localrepos, statusrepos)
  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/Base.py", line 214, in sync_folder_structure
    src_repo.makefolder(dst_name_t)
  File "/usr/local/lib/python2.7/dist-packages/offlineimap/repository/IMAP.py", line 357, in makefolder
    OfflineImapError.ERROR.FOLDER)

More information about the OfflineIMAP-project mailing list