Deltachat-desktop: IMAP IDLE protocol failed to init/complete

[Gozala]

• Operating System (Linux/Mac/Windows/iOS/Android): mac
• Delta Chat Version: Version 1.4.1 (1.4.1)
• Expected behavior: Not to have that error
• Actual behavior: Often times after returning to delta chat after it was running in the background, I do see IMAP IDLE protocol failed to init/complete error.
  
  
  
  • Clicking the error makes it go away
  
  
  • But new messages do not get picked up (I see response in a regular email client).
  
  
  • After restarting delta chat it picks up new message.
  
  
• Steps to reproduce the problem:
  
  
  
  • Have delta chat in the background for several hours
  
  
  • Switch to the delta chat window
  
  
• Screenshots:

• Logs:

2020-07-15T03:55:51.229Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T03:55:51.899Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 31, last seen was 31"
2020-07-15T03:55:51.901Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"DeltaChat\"."
2020-07-15T03:55:52.222Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T03:57:15.040Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T03:57:15.514Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/job.rs:1068: loading job for Imap-thread"
2020-07-15T03:57:15.878Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 14324, last seen was 14330"
2020-07-15T03:57:15.879Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"INBOX\"."
2020-07-15T03:57:16.448Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T04:59:53.261Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T04:59:53.430Z    core/event              ERROR       "DC_EVENT_ERROR"    0   "IMAP IDLE protocol failed to init/complete"
2020-07-15T04:59:55.528Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 397, last seen was 397"
2020-07-15T04:59:55.530Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"Sent\"."
2020-07-15T04:59:55.854Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T05:02:33.753Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T05:02:33.918Z    core/event              ERROR       "DC_EVENT_ERROR"    0   "IMAP IDLE protocol failed to init/complete"
2020-07-15T05:02:36.033Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 31, last seen was 31"
2020-07-15T05:02:36.033Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"DeltaChat\"."
2020-07-15T05:02:36.355Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T05:03:51.324Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T05:03:51.497Z    core/event              ERROR       "DC_EVENT_ERROR"    0   "IMAP IDLE protocol failed to init/complete"
2020-07-15T05:03:51.498Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/job.rs:1068: loading job for Imap-thread"
2020-07-15T05:03:53.612Z    core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 14324, last seen was 14330"

[hpk42]

Could you update to 1.4.3 from https://get.delta.chat
and see if the problem persists?
We also have some core issues around this topic and @dignifiedquire
recently did a bit of cleanup in the IDLE area. It's quite possible
there still are problems though and we'd really like to iron them out ...
(a messenger has one job: receiving and sending messages and should
be super-robust doing that)

On Tue, Jul 14, 2020 at 23:09 -0700, Irakli Gozalishvili wrote:

• Operating System (Linux/Mac/Windows/iOS/Android): mac
• Delta Chat Version: Version 1.4.1 (1.4.1)
• Expected behavior: Not to have that error
• Actual behavior: Often times after returning to delta chat after it was running in the background, I do see IMAP IDLE protocol failed to init/complete error.
  
  
  
  • Clicking the error makes it go away
  
  
  • But new messages do not get picked up (I see response in a regular email client).
  
  
  • After restarting delta chat it picks up new message.
  
  
• Steps to reproduce the problem:
  
  
  
  • Have delta chat in the background for several hours
  
  
  • Switch to the delta chat window
  
  
• Screenshots:

• Logs:

2020-07-15T03:55:51.229Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T03:55:51.899Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 31, last seen was 31"
2020-07-15T03:55:51.901Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"DeltaChat\"."
2020-07-15T03:55:52.222Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T03:57:15.040Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T03:57:15.514Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/job.rs:1068: loading job for Imap-thread"
2020-07-15T03:57:15.878Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 14324, last seen was 14330"
2020-07-15T03:57:15.879Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"INBOX\"."
2020-07-15T03:57:16.448Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T04:59:53.261Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T04:59:53.430Z  core/event              ERROR       "DC_EVENT_ERROR"    0   "IMAP IDLE protocol failed to init/complete"
2020-07-15T04:59:55.528Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 397, last seen was 397"
2020-07-15T04:59:55.530Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"Sent\"."
2020-07-15T04:59:55.854Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T05:02:33.753Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T05:02:33.918Z  core/event              ERROR       "DC_EVENT_ERROR"    0   "IMAP IDLE protocol failed to init/complete"
2020-07-15T05:02:36.033Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 31, last seen was 31"
2020-07-15T05:02:36.033Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:643: 0 mails read from \"DeltaChat\"."
2020-07-15T05:02:36.355Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:77: Idle entering wait-on-remote state"
2020-07-15T05:03:51.324Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/idle.rs:92: Idle-wait timeout or interruption"
2020-07-15T05:03:51.497Z  core/event              ERROR       "DC_EVENT_ERROR"    0   "IMAP IDLE protocol failed to init/complete"
2020-07-15T05:03:51.498Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/job.rs:1068: loading job for Imap-thread"
2020-07-15T05:03:53.612Z  core/event              INFO        "DC_EVENT_INFO" 0   "src/imap/mod.rs:693: fetch_new_messages: ignoring uid 14324, last seen was 14330"

--
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/deltachat/deltachat-desktop/issues/1797

[Simon-Laux]

you can also test our upcoming release https://support.delta.chat/t/test-upcomming-desktop-1-10-x-release/1080

[Gozala]

Trying upcoming release. Have not seen this issue since.

[gerryfrancis]

@Gozala Thanks for reporting back, I am closing here. Should you experience the issue again, please let us know, and it will be reopened.