offlineimap3 icon indicating copy to clipboard operation
offlineimap3 copied to clipboard

Published 20 hours ago • verified publisher icon OfflineIMAP

Offlineimap "stops" after a while

Open WhyNotHugo opened this issue 3 years ago • 6 comments

General informations

  • system/distribution (with version): ArchLinux (amd64)
  • offlineimap version (offlineimap -V): offlineimap v8.0.0, imaplib2 v3.06, Python v3.10.4, OpenSSL 1.1.1n 15 Mar 2022
  • Python version: Python 3.10.4
  • server name or domain: Fastmail / Cyrus
  • CLI options: -u basic

Configuration file offlineimaprc

[general]
pythonfile = ~/.local/lib/offlineimap-helper
metadata = ~/.local/share/offlineimap
accounts = Personal
maxsyncaccounts = 12
ui = basic
socktimeout = 30

### Personal Account ###

[Account Personal]
localrepository = LocalPersonal
remoterepository = RemotePersonal
postsynchook = /usr/bin/notmuch new
autorefresh = 0.5
quick = 10

[Repository LocalPersonal]
type = Maildir
localfolders = ~/.local/share/maildir/
restoreatime = no
createfolders = True

[Repository RemotePersonal]
type = IMAP
remotehost = imap.fastmail.com
remoteuser = [email protected]
remotepasseval = get_password("[email protected]")
ssl = yes
cert_fingerprint = dabc543700b3a4424017ffac7ee366a7825062e835158d25c0edf02f96c833d379d4bfd08317a95f871780cee8fba21a65c1bb4a3f6f9b2d1b763ab8a9a4171b
maxconnections = 2
createfolders = False
idlefolders = ['INBOX']

# vim: ft=dosini

pythonfile (if any)

#!/usr/bin/env python

import keyring


def get_password(account: str) -> str:
    return keyring.get_password("offlineimap", account)

Logs, error

Apr 25 17:41:27 victory offlineimap[56991]: Syncing Trash: IMAP -> Maildir
Apr 25 17:41:28 victory offlineimap[56991]: Calling hook: /usr/bin/notmuch new
Apr 25 17:41:30 victory offlineimap[56991]: Hook stdout: Processed 26 total files in almost no time.
Apr 25 17:41:30 victory offlineimap[56991]: Added 23 new messages to the database.
Apr 25 17:41:30 victory offlineimap[56991]: Hook stderr:
Apr 25 17:41:30 victory offlineimap[56991]: Hook return code: 0
Apr 25 17:41:30 victory offlineimap[56991]: *** Finished account 'Personal' in 0:13
Apr 25 17:42:00 victory offlineimap[56991]: *** Processing account Personal

Description

Offlineimap synchronises for a while, but eventually stops. It's currently 18:44, and this above is the last bit of logs; it hasn't done anything in an hour.

When I restart it, it works for a while and eventually does the same and simply stops.

Steps to reproduce the error

  • Run offlineimap
  • Wait. In this case, it only ran for a few minutes and then stopped.

WhyNotHugo avatar Apr 25 '22 16:04 WhyNotHugo

Dear Hugo,

thanks for your report. Could you run offlineimap with debug (-dALL) and when it finished get the return code (echo $?). Something like:

$ offlineimap -DAll .... $ echo $?

Please, send the last lines of the "stuff of sync" to try to debug the problem (please, be carefull with password and other sensible info) and the "return code".

Thanks. Best regards. kix

Rodolfo García Peñas (kix) http://www.kix.es/

"I asked him once how to change the key bindings and Dave said 'You use the Change Configuration command. On Unix it is abbreviated as cc.' Dave Conroy and Lawrence Stewart.

------- Original Message ------- On Monday, April 25th, 2022 at 18:45, Hugo @.***> wrote:

General informations

  • system/distribution (with version): ArchLinux (amd64)
  • offlineimap version (offlineimap -V): offlineimap v8.0.0, imaplib2 v3.06, Python v3.10.4, OpenSSL 1.1.1n 15 Mar 2022
  • Python version: Python 3.10.4
  • server name or domain: Fastmail / Cyrus
  • CLI options: -u basic

Configuration file offlineimaprc

[general]

pythonfile

= ~/.local/lib/offlineimap-helper

metadata

= ~/.local/share/offlineimap

accounts

= Personal

maxsyncaccounts

= 12

ui

= basic

socktimeout

= 30

Personal Account

[Account Personal]

localrepository

= LocalPersonal

remoterepository

= RemotePersonal

postsynchook

= /usr/bin/notmuch new

autorefresh

= 0.5

quick

= 10

[Repository LocalPersonal]

type

= Maildir

localfolders

= ~/.local/share/maildir/

restoreatime

= no

createfolders

= True

[Repository RemotePersonal]

type

= IMAP

remotehost

= imap.fastmail.com

remoteuser

= @.***

remotepasseval

= get_password(

"

@.***

"

)

ssl

= yes

cert_fingerprint

= dabc543700b3a4424017ffac7ee366a7825062e835158d25c0edf02f96c833d379d4bfd08317a95f871780cee8fba21a65c1bb4a3f6f9b2d1b763ab8a9a4171b

maxconnections

= 2

createfolders

= False

idlefolders

= [

'

INBOX

'

]

vim: ft=dosini

pythonfile (if any)

#!/usr/bin/env python

import keyring

def get_password(account: str) -> str: return keyring.get_password("offlineimap", account)

Logs, error

Apr 25 17:41:27 victory offlineimap[56991]: Syncing Trash: IMAP -> Maildir Apr 25 17:41:28 victory offlineimap[56991]: Calling hook: /usr/bin/notmuch new Apr 25 17:41:30 victory offlineimap[56991]: Hook stdout: Processed 26 total files in almost no time. Apr 25 17:41:30 victory offlineimap[56991]: Added 23 new messages to the database. Apr 25 17:41:30 victory offlineimap[56991]: Hook stderr: Apr 25 17:41:30 victory offlineimap[56991]: Hook return code: 0 Apr 25 17:41:30 victory offlineimap[56991]: *** Finished account 'Personal' in 0:13 Apr 25 17:42:00 victory offlineimap[56991]: *** Processing account Personal

Description

Offlineimap synchronises for a while, but eventually stops. It's currently 18:44, and this above is the last bit of logs; it hasn't done anything in an hour.

When I restart it, it works for a while and eventually does the same and simply stops.

Steps to reproduce the error

  • Run offlineimap
  • Wait. In this case, it only ran for a few minutes and then stopped.

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you are subscribed to this thread.Message ID: @.***>

thekix avatar Oct 11 '22 08:10 thekix

I had removed the idlefolders directive for some time and the issue never reemerged. This is strictly tied to IDLE being enabled.

Logs:

[imap]:   54:23.25 imap.fastmail.com reader poll => []
[imap]:   54:53.28 imap.fastmail.com reader poll => []
[imap]:   55:23.31 imap.fastmail.com reader poll => []
[imap]:   55:53.35 imap.fastmail.com reader poll => []
[imap]:   56:23.38 imap.fastmail.com reader poll => []
[imap]:   56:53.41 imap.fastmail.com reader poll => []
[imap]:   57:23.44 imap.fastmail.com reader poll => []
[imap]:   57:53.47 imap.fastmail.com reader poll => []
[imap]:   58:23.50 imap.fastmail.com reader poll => []
[imap]:   58:53.53 imap.fastmail.com reader poll => []
[imap]:   59:23.54 imap.fastmail.com reader poll => []
[imap]:   59:53.57 imap.fastmail.com reader poll => []
[imap]:   00:23.60 imap.fastmail.com reader poll => []
[imap]:   00:53.63 imap.fastmail.com reader poll => []
[imap]:   01:23.66 imap.fastmail.com reader poll => []
[imap]:   01:53.70 imap.fastmail.com reader poll => []
[imap]:   02:23.73 imap.fastmail.com reader poll => []
[imap]:   02:53.76 imap.fastmail.com reader poll => []
[imap]:   03:23.80 imap.fastmail.com reader poll => []
[imap]:   03:53.83 imap.fastmail.com reader poll => []
[imap]:   04:23.86 imap.fastmail.com reader poll => []
[imap]:   04:53.89 imap.fastmail.com reader poll => []
[imap]:   05:20.22 imap.fastmail.com reader poll => [(5, 1)]
[imap]:   05:20.23 imap.fastmail.com reader rcvd 0
[imap]:   05:20.33 imap.fastmail.com reader poll => [(5, 1)]
[imap]:   05:20.33 imap.fastmail.com reader rcvd 0
[imap]:   05:20.43 imap.fastmail.com reader poll => [(5, 1)]
[imap]:   05:20.43 imap.fastmail.com reader rcvd 0
[imap]:   05:20.53 imap.fastmail.com reader poll => [(5, 1)]
[imap]:   05:20.53 imap.fastmail.com reader rcvd 0
[imap]:   05:20.63 imap.fastmail.com reader poll => [(5, 1)]
[imap]:   05:20.63 imap.fastmail.com reader rcvd 0
[imap]:   05:20.73 imap.fastmail.com reader poll => [(5, 1)]
[imap]:   05:20.73 imap.fastmail.com reader rcvd 0
[imap]:   05:20.73 imap.fastmail.com reader last 20 log messages:
[imap]:   04:46.05 Folder Notes [acc: Personal] [async] FETCH ('1:*', '(FLAGS UID INTERNALDATE)')
[imap]:   04:46.26 Folder promos [acc: Personal] [sync] SELECT ('promos',)
[imap]:   04:46.41 Folder promos [acc: Personal] state => SELECTED
[imap]:   04:46.41 Folder promos [acc: Personal] [sync] EXAMINE ('promos',)
[imap]:   04:46.54 Folder promos [acc: Personal] state => SELECTED
[imap]:   04:46.54 Folder promos [acc: Personal] [async] FETCH ('1:*', '(FLAGS UID INTERNALDATE)')
[imap]:   04:46.78 Folder purchases [acc: Personal] [sync] SELECT ('purchases',)
[imap]:   04:46.93 Folder purchases [acc: Personal] state => SELECTED
[imap]:   04:46.94 Folder purchases [acc: Personal] [sync] EXAMINE ('purchases',)
[imap]:   04:47.07 Folder purchases [acc: Personal] state => SELECTED
[imap]:   04:47.07 Folder purchases [acc: Personal] [async] FETCH ('1:*', '(FLAGS UID INTERNALDATE)')
[imap]:   04:49.16 Folder spotify [acc: Personal] [sync] SELECT ('spotify',)
[imap]:   04:49.28 Folder spotify [acc: Personal] state => SELECTED
[imap]:   04:49.28 Folder spotify [acc: Personal] [sync] EXAMINE ('spotify',)
[imap]:   04:49.37 Folder spotify [acc: Personal] state => SELECTED
[imap]:   04:49.37 Folder spotify [acc: Personal] [async] FETCH ('1:*', '(FLAGS UID INTERNALDATE)')
[imap]:   04:50.11 Thread-7 (__idle) [sync] SELECT ('INBOX',)
[imap]:   04:50.20 Thread-7 (__idle) state => SELECTED
[imap]:   04:50.20 Thread-7 (__idle) [sync] IDLE ()
[imap]:   05:20.14 Thread-7 (__idle) [async] NOOP ()
[imap]:   05:20.73 imap.fastmail.com reader socket error: <class 'OSError'> - Too many read 0
[imap]:   05:20.73 imap.fastmail.com reader finished
[imap]:   05:20.73 imap.fastmail.com handler terminating: "socket error: <class 'OSError'> - Too many read 0"
[imap]:   05:20.73 imap.fastmail.com handler state_change_free.set
[imap]:   05:20.73 imap.fastmail.com handler finished
[imap]:   05:20.73 imap.fastmail.com writer finished

There's no exit code: it's just frozen there.

WhyNotHugo avatar Nov 17 '22 18:11 WhyNotHugo

Also ctrl-c once won't exit, but there's logs now:

^CPreparing to shutdown after sync (this may take some time), press CTRL-C three times to shutdown immediately
[thread]: ** Thread List:

[thread]: 1 Thread is at:
  File: "/usr/lib/python3.10/threading.py", line 1016, in _bootstrap_inner
    self.run()
  File: "/usr/lib/python3.10/threading.py", line 953, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.10/site-packages/offlineimap/imapserver.py", line 828, in noop
    self.stop_sig.wait()  # wait until we are supposed to quit
  File: "/usr/lib/python3.10/threading.py", line 607, in wait
    signaled = self._cond.wait(timeout)
  File: "/usr/lib/python3.10/threading.py", line 320, in wait
    waiter.acquire()

[thread]: 1 Thread is at:
  File: "/usr/lib/python3.10/threading.py", line 1016, in _bootstrap_inner
    self.run()
  File: "/usr/lib/python3.10/threading.py", line 953, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.10/site-packages/offlineimap/imapserver.py", line 903, in __idle
    noop(imapobj)
  File: "/usr/lib/python3.10/site-packages/offlineimap/imapserver.py", line 873, in noop
    self.parent.releaseconnection(imapobj)
  File: "/usr/lib/python3.10/site-packages/offlineimap/imapserver.py", line 774, in releaseconnection
    self.connectionlock.acquire()

[thread]: 1 Thread is at:
  File: "/usr/lib/python3.10/threading.py", line 953, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.10/site-packages/offlineimap/imapserver.py", line 759, in keepalive
    idler.join()
  File: "/usr/lib/python3.10/site-packages/offlineimap/imapserver.py", line 808, in join
    self.thread.join()
  File: "/usr/lib/python3.10/threading.py", line 1096, in join
    self._wait_for_tstate_lock()
  File: "/usr/lib/python3.10/threading.py", line 1116, in _wait_for_tstate_lock
    if lock.acquire(block, timeout):

[thread]: 1 Thread is at:
  File: "/usr/lib/python3.10/site-packages/offlineimap/repository/IMAP.py", line 83, in dropconnections
    self.imapserver.close()
  File: "/usr/lib/python3.10/site-packages/offlineimap/imapserver.py", line 714, in close
    threadutil.semaphorereset(self.semaphore, self.maxconnections)
  File: "/usr/lib/python3.10/site-packages/offlineimap/threadutil.py", line 34, in semaphorereset
    semaphore.acquire()
  File: "/usr/lib/python3.10/threading.py", line 467, in acquire
    self._cond.wait(timeout)
  File: "/usr/lib/python3.10/threading.py", line 320, in wait
    waiter.acquire()

[thread]: 1 Thread is at:
  File: "/usr/lib/python3.10/threading.py", line 953, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.10/site-packages/offlineimap/init.py", line 63, in syncitall
    threads.wait()  # Blocks until all accounts are processed.
  File: "/usr/lib/python3.10/site-packages/offlineimap/threadutil.py", line 67, in wait
    thread.join()
  File: "/usr/lib/python3.10/threading.py", line 1096, in join
    self._wait_for_tstate_lock()
  File: "/usr/lib/python3.10/threading.py", line 1116, in _wait_for_tstate_lock
    if lock.acquire(block, timeout):

[thread]: 1 Thread is at:
    return self.__sync(options)
  File: "/usr/lib/python3.10/site-packages/offlineimap/init.py", line 489, in __sync
    threadutil.monitor()
  File: "/usr/lib/python3.10/site-packages/offlineimap/threadutil.py", line 95, in monitor
    thread = exitedThreads.get(True, 60)
  File: "/usr/lib/python3.10/queue.py", line 180, in get
    self.not_empty.wait(remaining)
  File: "/usr/lib/python3.10/threading.py", line 324, in wait
    gotit = waiter.acquire(True, timeout)
  => Stopped to handle current signal. 

[thread]: Dumped a total of 6 Threads.

I think this will never complete due to some socket having died.

WhyNotHugo avatar Nov 17 '22 19:11 WhyNotHugo

I have a similiar issue. It always stops after a while (roughly 30 to 90 seconds), even in single thread mode!

As shown above I tried the debug option

offlineimap -1 -dAll

but nothing special besides polling after wait?

Received-SPF: none (domain of hhhv...==
------=_Part_3194_1398902460.1523521862229--
'
 [maildir]: Write mail 'x_Insurance.HHH:12' with flags {'S'}
 [maildir]: savemessage: returning uid 12
 Copy message UID 13 (10/29) usernameRemote:x_Insurance/HHH -> usernameLocal:x_Insurance.HHH
 [imap]:   00:13.12 Account sync user-name-de [async] UID ('FETCH', '13', '(BODY.PEEK[])')
 [imap]:   00:13.12 Account sync user-name-de state_change_pending.acquire
 [imap]:   00:13.12 Account sync user-name-de state_change_pending.release
 [imap]:   00:13.12 Account sync user-name-de _request_push(b'BHJD118', UID, {}) = b'BHJD118'
 [imap]:   00:13.12 Account sync user-name-de data=b'BHJD118 UID FETCH 13 (BODY.PEEK[])'
 [imap]:   00:13.12 Account sync user-name-de UID:b'BHJD118'.ready.wait
mail.gandi.net writer:
 [imap]:   00:13.12 mail.gandi.net writer > b'BHJD118 UID FETCH 13 (BODY.PEEK[])\r\n'
mail.gandi.net reader:
 [imap]:   00:43.10 mail.gandi.net reader poll => []
 [imap]:   01:13.13 mail.gandi.net reader poll => []
 [imap]:   01:43.16 mail.gandi.net reader poll => []
 [imap]:   02:13.19 mail.gandi.net reader poll => []
 [imap]:   02:43.20 mail.gandi.net reader poll => []
 [imap]:   03:13.23 mail.gandi.net reader poll => []
 [imap]:   03:43.24 mail.gandi.net reader poll => []
 [imap]:   04:13.27 mail.gandi.net reader poll => []
 [imap]:   04:43.30 mail.gandi.net reader poll => []
 [imap]:   05:13.33 mail.gandi.net reader poll => []

Cancel the run with Ctrl+C

^CAccount sync user-name-de:
 Preparing to shutdown after sync (this may take some time), press CTRL-C three times to shutdown immediately
 [thread]: ** Thread List:

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1770, in _handler
    line = self.inq.get(True, timeout)
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 995, in _bootstrap
    self._bootstrap_inner()
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1853, in _reader
    r = poll.poll(timeout)

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1974, in _writer
    rqb = self.ouq.get()
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
    return self._command_complete(self._command(name, *args), kw)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1449, in _command_complete
    typ, dat = rqb.get_response('command: %s => %%s' % rqb.name)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 206, in get_response
    self.ready.wait()
  File: "/usr/lib64/python3.11/threading.py", line 622, in wait
    signaled = self._cond.wait(timeout)
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()
  => Stopped to handle current signal. 

 [thread]: Dumped a total of 4 Threads.
mail.gandi.net reader:
 [imap]:   10:13.63 mail.gandi.net reader poll => []
^CAccount sync user-name-de:
 Preparing to shutdown after sync (this may take some time), press CTRL-C three times to shutdown immediately
 [thread]: ** Thread List:

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1770, in _handler
    line = self.inq.get(True, timeout)
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 995, in _bootstrap
    self._bootstrap_inner()
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1853, in _reader
    r = poll.poll(timeout)

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1974, in _writer
    rqb = self.ouq.get()
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
    return self._command_complete(self._command(name, *args), kw)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1449, in _command_complete
    typ, dat = rqb.get_response('command: %s => %%s' % rqb.name)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 206, in get_response
    self.ready.wait()
  File: "/usr/lib64/python3.11/threading.py", line 622, in wait
    signaled = self._cond.wait(timeout)
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()
  => Stopped to handle current signal. 

 [thread]: Dumped a total of 4 Threads.
^C Preparing to shutdown after sync (this may take some time), press CTRL-C three times to shutdown immediately
 [thread]: ** Thread List:

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1770, in _handler
    line = self.inq.get(True, timeout)
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 995, in _bootstrap
    self._bootstrap_inner()
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1853, in _reader
    r = poll.poll(timeout)

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1974, in _writer
    rqb = self.ouq.get()
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
    return self._command_complete(self._command(name, *args), kw)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1449, in _command_complete
    typ, dat = rqb.get_response('command: %s => %%s' % rqb.name)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 206, in get_response
    self.ready.wait()
  File: "/usr/lib64/python3.11/threading.py", line 622, in wait
    signaled = self._cond.wait(timeout)
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()
  => Stopped to handle current signal. 

 [thread]: Dumped a total of 4 Threads.
 Signaled thrice. Aborting!
 [imap]:   10:20.20 Account sync user-name-de state => LOGOUT
 [imap]:   10:20.20 Account sync user-name-de [sync] LOGOUT ()
 [imap]:   10:20.20 Account sync user-name-de state_change_pending.acquire
 [imap]:   10:20.20 Account sync user-name-de sync command LOGOUT waiting for empty commands Q
^C Preparing to shutdown after sync (this may take some time), press CTRL-C three times to shutdown immediately
 [thread]: ** Thread List:

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1770, in _handler
    line = self.inq.get(True, timeout)
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 995, in _bootstrap
    self._bootstrap_inner()
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1853, in _reader
    r = poll.poll(timeout)

 [thread]: 1 Thread is at:
  File: "/usr/lib64/python3.11/threading.py", line 1038, in _bootstrap_inner
    self.run()
  File: "/usr/lib64/python3.11/threading.py", line 975, in run
    self._target(*self._args, **self._kwargs)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1974, in _writer
    rqb = self.ouq.get()
  File: "/usr/lib64/python3.11/queue.py", line 171, in get
    self.not_empty.wait()
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()

 [thread]: 1 Thread is at:
    typ, dat = self._simple_command('LOGOUT')
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1705, in _simple_command
    return self._command_complete(self._command(name, *args), kw)
  File: "/usr/lib/python3.11/site-packages/offlineimap/imaplib2.py", line 1350, in _command
    self.state_change_free.wait()
  File: "/usr/lib64/python3.11/threading.py", line 622, in wait
    signaled = self._cond.wait(timeout)
  File: "/usr/lib64/python3.11/threading.py", line 320, in wait
    waiter.acquire()
  => Stopped to handle current signal. 

 [thread]: Dumped a total of 4 Threads.
 Signaled thrice. Aborting!
 [imap]:   10:42.12 Account sync user-name-de ["<class 'SystemExit'>: 1"]
 [imap]:   10:42.12 Account sync user-name-de _close_threads
mail.gandi.net writer:
 [imap]:   10:42.12 mail.gandi.net writer finished
mail.gandi.net handler:
 [imap]:   10:42.12 mail.gandi.net handler terminating: 'Terminated'
 [imap]:   10:42.12 mail.gandi.net handler UID:b'BHJD118'.ready.set
 [imap]:   10:42.12 mail.gandi.net handler state_change_free.set
 [imap]:   10:42.12 mail.gandi.net handler finished
Account sync user-name-de:
 [imap]:   10:42.12 Account sync user-name-de call shutdown
mail.gandi.net reader:
 [imap]:   10:42.12 mail.gandi.net reader poll => [(4, 17)]
 [imap]:   10:42.12 mail.gandi.net reader finished
Account sync user-name-de:
 [imap]:   10:42.12 Account sync user-name-de state_change_pending.release
 [imap]:   10:42.12 Account sync user-name-de connection closed
 *** Finished account 'user-name-de' in 10:43

After several runs, all is synced and it no longer stucks on updating.

Tested with a second account, which has most mails in the INBOX, less folders, it stopped first time at

 Copy message UID 2486 (1499/4695) ...Remote:INBOX -> ...Local:INBOX

Second run took about 1:42 minutes to almost the same amount of mails

 Copy message UID 3843 (1342/3197) ...Remote:INBOX -> ...:INBOX

Third run took about 1:30 minutes for 856 mails

 Copy message UID 4698 (856/1856) ...Remote:INBOX -> ...:INBOX

The cursor is blinking few times after the last line appears Fourth run is about 0:45 minutes for 426 mails

 Copy message UID 5123 (426/1001) ...Remote:INBOX -> ...:INBOX

Fifth run did the rest of INBOX and several other folders.

 Copy message UID 63 (62/62) ...Remote:Sent Messages -> ...Local:Sent Messages
 Syncing Trash: IMAP -> Maildir
 Copy message UID 1 (1/1) ...Remote:Trash -> ...Local:Trash
 *** Finished account 'user2-name-de' in 2:37

This was the first time a run was more than 2 minutes

gitfineon avatar Nov 30 '23 22:11 gitfineon

I am also having this issue:

 [imap]:   19:20.80 imap.fastmail.com reader < b'////////////////////////////////////////////////////////////////////////////\r\n'
 [imap]:   19:20.80 imap.fastmail.com reader < b'////////////////////////////////////////////////////////////////////////////\r\n'
 [imap]:   19:20.80 imap.fastmail.com reader < b'////////////////////////////////////////////////////////////////////////////\r\n'
 [imap]:   19:20.80 imap.fastmail.com reader < b'////////////////////////////////////////////////////////////////////////////\r\n'
 [imap]:   19:20.80 imap.fastmail.com reader < b'////////////////////////////////////////////////////////////////////////////\r\n'
 [imap]:   19:20.80 imap.fastmail.com reader < b'////////////////////////////////////////////////////////////////////////////\r\n'
 [imap]:   19:20.80 imap.fastmail.com reader < b'////////////////////////////////////////////////////////////////////////////\r\n'
imap.fastmail.com handler:
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 40129, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 40051, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39973, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39895, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39817, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39739, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39661, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39583, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39505, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39427, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39349, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39271, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39193, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39115, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 39037, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38959, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38881, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38803, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38725, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38647, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38569, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38491, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38413, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38335, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38257, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38179, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38101, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 38023, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37945, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37867, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37789, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37711, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37633, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37555, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37477, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37399, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37321, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37243, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37165, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37087, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 37009, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36931, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36853, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36775, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36697, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36619, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36541, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36463, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36385, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36307, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36229, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36151, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 36073, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35995, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35917, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35839, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35761, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35683, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35605, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35527, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35449, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35371, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35293, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35215, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35137, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 35059, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34981, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34903, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34825, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34747, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34669, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34591, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34513, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34435, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34357, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34279, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34201, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34123, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 34045, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33967, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33889, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33811, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33733, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33655, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33577, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33499, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33421, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33343, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33265, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33187, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33109, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 33031, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32953, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32875, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32797, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32719, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32641, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32563, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32485, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32407, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32329, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32251, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32173, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32095, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 32017, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31939, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31861, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31783, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31705, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31627, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31549, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31471, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31393, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31315, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31237, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31159, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31081, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 31003, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30925, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30847, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30769, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30691, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30613, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30535, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30457, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30379, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30301, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30223, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30145, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 30067, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29989, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29911, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29833, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29755, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29677, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29599, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29521, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29443, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29365, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29287, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29209, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29131, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 29053, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28975, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28897, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28819, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28741, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28663, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28585, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28507, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28429, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28351, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28273, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28195, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28117, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 28039, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27961, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27883, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27805, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27727, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27649, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27571, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27493, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27415, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27337, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27259, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27181, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27103, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 27025, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26947, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26869, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26791, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26713, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26635, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26557, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26479, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26401, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26323, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26245, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26167, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26089, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 26011, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25933, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25855, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25777, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25699, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25621, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25543, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25465, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25387, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25309, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25231, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25153, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 25075, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24997, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24919, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24841, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24763, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24685, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24607, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24529, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24451, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24373, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24295, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24217, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24139, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 24061, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 23983, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 23905, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 23827, got 78
 [imap]:   19:20.80 imap.fastmail.com handler _put_response expecting data len 23749, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23671, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23593, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23515, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23437, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23359, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23281, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23203, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23125, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 23047, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22969, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22891, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22813, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22735, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22657, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22579, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22501, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22423, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22345, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22267, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22189, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22111, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 22033, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21955, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21877, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21799, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21721, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21643, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21565, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21487, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21409, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21331, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21253, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21175, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21097, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 21019, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20941, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20863, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20785, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20707, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20629, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20551, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20473, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20395, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20317, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20239, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20161, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20083, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 20005, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19927, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19849, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19771, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19693, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19615, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19537, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19459, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19381, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19303, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19225, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19147, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 19069, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18991, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18913, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18835, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18757, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18679, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18601, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18523, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18445, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18367, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18289, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18211, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18133, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 18055, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17977, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17899, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17821, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17743, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17665, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17587, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17509, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17431, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17353, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17275, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17197, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17119, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 17041, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16963, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16885, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16807, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16729, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16651, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16573, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16495, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16417, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16339, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16261, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16183, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16105, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 16027, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15949, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15871, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15793, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15715, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15637, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15559, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15481, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15403, got 78
 [imap]:   19:20.81 imap.fastmail.com handler _put_response expecting data len 15325, got 78
imap.fastmail.com reader:
 [imap]:   19:50.80 imap.fastmail.com reader poll => []
 [imap]:   20:20.80 imap.fastmail.com reader poll => []
 [imap]:   20:50.81 imap.fastmail.com reader poll => []
 [imap]:   21:20.81 imap.fastmail.com reader poll => []
 [imap]:   21:50.81 imap.fastmail.com reader poll => []
 [imap]:   22:20.81 imap.fastmail.com reader poll => []
 [imap]:   22:50.81 imap.fastmail.com reader poll => []
 [imap]:   23:20.82 imap.fastmail.com reader poll => []
 [imap]:   23:50.82 imap.fastmail.com reader poll => []
 [imap]:   24:20.82 imap.fastmail.com reader poll => []
 [imap]:   24:50.82 imap.fastmail.com reader poll => []
 [imap]:   25:20.82 imap.fastmail.com reader poll => []
 [imap]:   25:50.82 imap.fastmail.com reader poll => []
 [imap]:   26:20.82 imap.fastmail.com reader poll => []
 [imap]:   26:50.83 imap.fastmail.com reader poll => []
 [imap]:   27:20.83 imap.fastmail.com reader poll => []
 [imap]:   27:50.83 imap.fastmail.com reader poll => []


 [imap]:   28:20.83 imap.fastmail.com reader poll => []
 [imap]:   28:50.83 imap.fastmail.com reader poll => []
 [imap]:   29:20.83 imap.fastmail.com reader poll => []
 [imap]:   29:50.83 imap.fastmail.com reader poll => []
 [imap]:   30:20.84 imap.fastmail.com reader poll => []
 [imap]:   30:50.84 imap.fastmail.com reader poll => []
 [imap]:   31:20.84 imap.fastmail.com reader poll => []
 [imap]:   31:50.84 imap.fastmail.com reader poll => []
 [imap]:   32:20.84 imap.fastmail.com reader poll => []
 [imap]:   32:50.84 imap.fastmail.com reader poll => []
 [imap]:   33:20.85 imap.fastmail.com reader poll => []
 [imap]:   33:50.85 imap.fastmail.com reader poll => []
 [imap]:   34:20.85 imap.fastmail.com reader poll => []
 [imap]:   34:50.85 imap.fastmail.com reader poll => []
 [imap]:   35:20.85 imap.fastmail.com reader poll => []
 [imap]:   35:50.85 imap.fastmail.com reader poll => []
 [imap]:   36:20.85 imap.fastmail.com reader poll => []
 [imap]:   36:50.85 imap.fastmail.com reader poll => []
 [imap]:   37:20.86 imap.fastmail.com reader poll => []
 [imap]:   37:50.86 imap.fastmail.com reader poll => []
 [imap]:   38:20.86 imap.fastmail.com reader poll => []
 [imap]:   38:50.86 imap.fastmail.com reader poll => []
 [imap]:   39:20.86 imap.fastmail.com reader poll => []
 [imap]:   39:50.86 imap.fastmail.com reader poll => []
 [imap]:   40:20.87 imap.fastmail.com reader poll => []
 [imap]:   40:50.87 imap.fastmail.com reader poll => []
 [imap]:   41:20.87 imap.fastmail.com reader poll => []
 [imap]:   41:50.87 imap.fastmail.com reader poll => []
 [imap]:   42:20.87 imap.fastmail.com reader poll => []
 [imap]:   42:50.87 imap.fastmail.com reader poll => []
 [imap]:   43:20.87 imap.fastmail.com reader poll => []
 [imap]:   43:50.88 imap.fastmail.com reader poll => []


 [imap]:   44:20.88 imap.fastmail.com reader poll => []
 [imap]:   44:50.88 imap.fastmail.com reader poll => []
 [imap]:   45:20.88 imap.fastmail.com reader poll => []
 [imap]:   45:50.88 imap.fastmail.com reader poll => []
 [imap]:   46:20.88 imap.fastmail.com reader poll => []
 [imap]:   46:50.89 imap.fastmail.com reader poll => []
 [imap]:   47:20.89 imap.fastmail.com reader poll => []
 [imap]:   47:50.89 imap.fastmail.com reader poll => []
 [imap]:   48:20.89 imap.fastmail.com reader poll => []
 [imap]:   48:50.89 imap.fastmail.com reader poll => []
 [imap]:   49:20.89 imap.fastmail.com reader poll => []
 [imap]:   49:50.89 imap.fastmail.com reader poll => []
 [imap]:   50:20.89 imap.fastmail.com reader poll => []
 [imap]:   50:50.90 imap.fastmail.com reader poll => []
 [imap]:   51:20.90 imap.fastmail.com reader poll => []
 [imap]:   51:50.90 imap.fastmail.com reader poll => []
 [imap]:   52:20.90 imap.fastmail.com reader poll => []
 [imap]:   52:50.90 imap.fastmail.com reader poll => []
 [imap]:   53:20.90 imap.fastmail.com reader poll => []
 [imap]:   53:50.90 imap.fastmail.com reader poll => []
 [imap]:   54:20.91 imap.fastmail.com reader poll => []
 [imap]:   54:50.91 imap.fastmail.com reader poll => []
 [imap]:   55:20.91 imap.fastmail.com reader poll => []
 [imap]:   55:50.91 imap.fastmail.com reader poll => []
 [imap]:   56:20.91 imap.fastmail.com reader poll => []

turian avatar Jan 14 '24 03:01 turian

And it just hangs there

turian avatar Jan 14 '24 03:01 turian