[offlineimap] Unable to sync IMAP - looping with "reader poll => []" (#207)

Piotr Król notifications at github.com
Sat Jun 13 17:11:28 BST 2015 

I'm trying to configure IMAP with Polish hosting provider home.pl and have problems with syncing any message. They use custom IMAP server. I tried telnet and mutt to verify if IMAP works and have no problem with login and listing directories. Despite that offlineimap hangs on `reader poll => []` and can't proceed further.

Log with `-d all`:

```
[17:57:31] pietrushnic:python-venv $ bin/offlineimap -d all -a foo at bar.com
OfflineIMAP 6.5.7
  Licensed under the GNU GPL v2 or any later version (with an OpenSSL exception)
Now debugging for imap: IMAP protocol debugging
Now debugging for maildir: Maildir repository debugging
Now debugging for thread: Threading debugging
Now debugging for : Other offlineimap related sync messages
Account sync foo at bar.com:
 [thread]: Register new thread 'Account sync foo at bar.com' (account 'foo at bar.com')
 [imap]: Using authentication mechanisms ['GSSAPI', 'CRAM-MD5', 'PLAIN', 'LOGIN']
 [maildir]: MaildirRepository initialized, sep is '.'
 *** Processing account foo at bar.com
 Establishing connection to foobar.home.pl:143
 [imap]:   58:34.61 Account sync foo at bar.com imaplib2 version 2.43
 [imap]:   58:34.61 Account sync foo at bar.com imaplib2 debug level 5, buffer level 3
foobar.home.pl writer:
 [imap]:   58:34.67 foobar.home.pl writer starting
Account sync foo at bar.com:
 [imap]:   58:34.67 Account sync foo at bar.com _request_push(continuation, None, {}) = GAJF0
foobar.home.pl reader:
 [imap]:   58:34.67 foobar.home.pl reader starting using poll
Account sync foo at bar.com:
 [imap]:   58:34.67 Account sync foo at bar.com None:GAJF0.ready.wait
foobar.home.pl reader:
 [imap]:   58:34.69 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.69 foobar.home.pl reader rcvd 55
 [imap]:   58:34.69 foobar.home.pl reader < * OK foobar.home.pl IdeaImapServer v0.80 ready\r\n
foobar.home.pl handler:
 [imap]:   58:34.77 foobar.home.pl handler starting
 [imap]:   58:34.77 foobar.home.pl handler _put_response(* OK foobar.home.pl IdeaImapServer v0.80 ready)
 [imap]:   58:34.77 foobar.home.pl handler untagged_responses[OK] 0 += ["foobar.home.pl IdeaImapServer v0.80 ready"]
 [imap]:   58:34.77 foobar.home.pl handler state_change_free.set
 [imap]:   58:34.77 foobar.home.pl handler _request_pop(continuation, (False, '* OK foobar.home.pl IdeaImapServer v0.80 ready')) = GAJF0
 [imap]:   58:34.77 foobar.home.pl handler None:GAJF0.ready.set
Account sync foo at bar.com:
 [imap]:   58:34.77 Account sync foo at bar.com _get_untagged_response(OK) => ['foobar.home.pl IdeaImapServer v0.80 ready']
 [imap]:   58:34.77 Account sync foo at bar.com state => NONAUTH
 [imap]:   58:34.77 Account sync foo at bar.com [async] CAPABILITY ()
 [imap]:   58:34.77 Account sync foo at bar.com state_change_pending.acquire
 [imap]:   58:34.77 Account sync foo at bar.com state_change_pending.release
 [imap]:   58:34.77 Account sync foo at bar.com _request_push(GAJF1, CAPABILITY, {}) = GAJF1
 [imap]:   58:34.77 Account sync foo at bar.com data=GAJF1 CAPABILITY
 [imap]:   58:34.77 Account sync foo at bar.com CAPABILITY:GAJF1.ready.wait
foobar.home.pl writer:
 [imap]:   58:34.77 foobar.home.pl writer > GAJF1 CAPABILITY\r\n
foobar.home.pl reader:
 [imap]:   58:34.80 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.80 foobar.home.pl reader rcvd 138
 [imap]:   58:34.80 foobar.home.pl reader < * CAPABILITY IMAP4rev1 LITERAL+ CHILDREN I18NLEVEL=1 IDLE SORT UIDPLUS UNSELECT XLIST STARTTLS AUTH=PLAIN AUTH=LOGIN\r\n
 [imap]:   58:34.80 foobar.home.pl reader < GAJF1 OK Completed\r\n
foobar.home.pl handler:
 [imap]:   58:34.80 foobar.home.pl handler _put_response(* CAPABILITY IMAP4rev1 LITERAL+ CHILDREN I18NLEVEL=1 IDLE SORT UIDPLUS UNSELECT XLIST STARTTLS AUTH=PLAIN AUTH=LOGIN)
 [imap]:   58:34.80 foobar.home.pl handler untagged_responses[CAPABILITY] 0 += ["IMAP4rev1 LITERAL+ CHILDREN I18NLEVEL=1 IDLE SORT UIDPLUS UNSELECT XLIST STARTTLS AUTH=PLAIN AUTH=LOGIN"]
 [imap]:   58:34.80 foobar.home.pl handler _put_response(GAJF1 OK Completed)
 [imap]:   58:34.80 foobar.home.pl handler state_change_free.set
 [imap]:   58:34.80 foobar.home.pl handler _request_pop(GAJF1, ('OK', ['Completed'])) = GAJF1
 [imap]:   58:34.80 foobar.home.pl handler CAPABILITY:GAJF1.ready.set
Account sync foo at bar.com:
 [imap]:   58:34.80 Account sync foo at bar.com _get_untagged_response(CAPABILITY) => ['IMAP4rev1 LITERAL+ CHILDREN I18NLEVEL=1 IDLE SORT UIDPLUS UNSELECT XLIST STARTTLS AUTH=PLAIN AUTH=LOGIN']
 [imap]:   58:34.80 Account sync foo at bar.com _untagged_response(OK, ?, CAPABILITY) => ['IMAP4rev1 LITERAL+ CHILDREN I18NLEVEL=1 IDLE SORT UIDPLUS UNSELECT XLIST STARTTLS AUTH=PLAIN AUTH=LOGIN']
 [imap]:   58:34.80 Account sync foo at bar.com CAPABILITY: ('IMAP4REV1', 'LITERAL+', 'CHILDREN', 'I18NLEVEL=1', 'IDLE', 'SORT', 'UIDPLUS', 'UNSELECT', 'XLIST', 'STARTTLS', 'AUTH=PLAIN', 'AUTH=LOGIN')
 [imap]: Using STARTTLS connection
 [imap]:   58:34.80 Account sync foo at bar.com [sync] STARTTLS ()
 [imap]:   58:34.80 Account sync foo at bar.com state_change_pending.acquire
 [imap]:   58:34.80 Account sync foo at bar.com _request_push(GAJF2, STARTTLS, {}) = GAJF2
 [imap]:   58:34.80 Account sync foo at bar.com data=GAJF2 STARTTLS
 [imap]:   58:34.80 Account sync foo at bar.com STARTTLS:GAJF2.ready.wait
foobar.home.pl writer:
 [imap]:   58:34.80 foobar.home.pl writer > GAJF2 STARTTLS\r\n
foobar.home.pl reader:
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.83 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.83 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader poll => [(4, 1)]
 [imap]:   58:34.84 foobar.home.pl reader rcvd 1
 [imap]:   58:34.84 foobar.home.pl reader < GAJF2 OK Begin TLS negotiation\r\n
foobar.home.pl handler:
 [imap]:   58:34.84 foobar.home.pl handler _put_response(GAJF2 OK Begin TLS negotiation)
 [imap]:   58:34.84 foobar.home.pl handler state_change_free.set
 [imap]:   58:34.84 foobar.home.pl handler _request_pop(GAJF2, ('OK', ['Begin TLS negotiation'])) = GAJF2
 [imap]:   58:34.84 foobar.home.pl handler STARTTLS:GAJF2.ready.set
foobar.home.pl reader:
 [imap]:   58:34.84 foobar.home.pl reader finished
Account sync foo at bar.com:
 [imap]:   58:34.84 Account sync foo at bar.com state_change_pending.release
foobar.home.pl reader:
 [imap]:   58:35.07 foobar.home.pl reader starting using poll
Account sync foo at bar.com:
 [imap]:   58:35.07 Account sync foo at bar.com [async] CAPABILITY ()
 [imap]:   58:35.07 Account sync foo at bar.com state_change_pending.acquire
 [imap]:   58:35.07 Account sync foo at bar.com state_change_pending.release
 [imap]:   58:35.07 Account sync foo at bar.com _request_push(GAJF3, CAPABILITY, {}) = GAJF3
 [imap]:   58:35.07 Account sync foo at bar.com data=GAJF3 CAPABILITY
 [imap]:   58:35.07 Account sync foo at bar.com CAPABILITY:GAJF3.ready.wait
foobar.home.pl writer:
 [imap]:   58:35.07 foobar.home.pl writer > GAJF3 CAPABILITY\r\n
```
offlineimaprc:
```
[mbnames]
enabled = yes
filename = ~/.mutt/muttrc.mailboxes
header = "mailboxes "
peritem = "+%(accountname)s/%(foldername)s"
sep = " "
footer = "\n"

[general]
metadata = ~/.offlineimap
maxsyncaccounts = 6
maxconnections = 2
accounts = foo at bar.com
status_backend = sqlite
pythonfile = ~/.mutt/offlineimap.py

[Account foo at bar.com]
autorefresh = 3
localrepository = foo_bar
remoterepository = foo_bar_remote
postsynchook = notmuch new

[Repository foo_bar]
type = Maildir
#localfolders = ~/.mail/foo at bar.com
localfolders = ~/.mail/tmp

[Repository foo_bar_remote]
type = IMAP
remotehost = foobar.home.pl
remoteusereval = get_username("bar/username")
remotepasseval = get_password("email/foo at bar")
ssl = no
remoteport = 143
```

Any idea how to fix this problem ?

---
Reply to this email directly or view it on GitHub:
https://github.com/OfflineIMAP/offlineimap/issues/207
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.alioth.debian.org/pipermail/offlineimap-project/attachments/20150613/9e7abbac/attachment.html>

More information about the OfflineIMAP-project mailing list