Every other message gets stuck?

Mutual Help
1

Delta Chat version

1.2.1

Expected behavior

Each message that is sent off should be sent off (not endless pending)

Actual behavior

Many messages get stuck while they are being sent off

Steps to reproduce the problem

Just send several messages quickly after each other

Screen snapshots

Debug logs

Logs 
<!--
If applicable, debug logs can be copied from within the Delta Chat app: 

03-07 22:41:19.282 10675 10707 I DeltaChat: ThreadId(5) src/imap/idle.rs:101: Idle entering wait-on-remote state
03-07 22:41:19.362 10675 10708 I DeltaChat: ThreadId(7) src/imap/mod.rs:629: fetch_new_messages: ignoring uid 258, last seen was 258
03-07 22:41:19.362 10675 10708 I DeltaChat: ThreadId(7) src/imap/mod.rs:694: 0 mails read from "Gesendet".
03-07 22:41:19.362 10675 10708 I DeltaChat: ThreadId(7) src/job_thread.rs:113: SENTBOX-fetch done in 125 ms.
03-07 22:41:19.367 10675 10708 I DeltaChat: ThreadId(7) src/job_thread.rs:181: SENTBOX-IDLE started...
03-07 22:41:19.500 10675 10708 I DeltaChat: ThreadId(7) src/imap/idle.rs:101: Idle entering wait-on-remote state
03-07 22:41:19.791 10675 10745 D OpenGLRenderer: endAllActiveAnimators on 0x7e0dd98300 (RippleDrawable) with handle 0x7e0fc998a0
03-07 22:41:23.145 10675 10745 W Adreno-EGL: <qeglDrvAPI_eglGetConfigAttrib:607>: EGL_BAD_ATTRIBUTE
03-07 22:41:23.146 10675 10745 D vndksupport: Loading /vendor/lib64/hw/gralloc.msm8916.so from current namespace instead of sphal namespace.
03-07 22:41:24.808 10675 10745 D OpenGLRenderer: endAllActiveAnimators on 0x7e0e3c4400 (RippleDrawable) with handle 0x7e0fc995c0
03-07 22:41:27.205 10675 10745 W Adreno-EGL: <qeglDrvAPI_eglGetConfigAttrib:607>: EGL_BAD_ATTRIBUTE
03-07 22:41:27.206 10675 10745 D vndksupport: Loading /vendor/lib64/hw/gralloc.msm8916.so from current namespace instead of sphal namespace.
03-07 22:41:28.742 10675 10745 D OpenGLRenderer: endAllActiveAnimators on 0x7e0cef0e00 (RippleDrawable) with handle 0x7e0f5d5a60
03-07 22:42:07.788 10675 13587 I DeltaChat: ThreadId(33) src/chat.rs:803: [autocrypt] no peerstate for xxxx@yyyy
03-07 22:42:07.857 10675 13587 I DeltaChat: ThreadId(33) src/e2ee.rs:80: peerstate for "Xxx" missing, cannot encrypt
03-07 22:42:07.887 10675 13587 I DeltaChat: ThreadId(33) src/job.rs:1169: Interrupting SMTP-idle...
03-07 22:42:07.887 10675 13587 I DeltaChat: ThreadId(33) src/job.rs:1177: Interrupting SMTP-idle... ended
03-07 22:42:07.888 10675 10709 I DeltaChat: ThreadId(8) src/job.rs:739: SMTP-idle ended.
03-07 22:42:07.892 10675 10709 I DeltaChat: ThreadId(8) src/job.rs:697: SMTP-jobs started...
03-07 22:42:07.894 10675 10709 I DeltaChat: ThreadId(8) src/job.rs:921: Smtp-job #2412, action SendMsgToSmtp started...
03-07 22:42:07.894 10675 10709 I DeltaChat: ThreadId(8) src/job.rs:1039: Smtp begin immediate try 0 of job #2412, action SendMsgToSmtp
03-07 22:42:07.990 10675 10709 W DeltaChat: ThreadId(8) src/job.rs:195: SMTP failed to send: 5.5.0 Service unavailable
03-07 22:42:07.991 10675 10709 I DeltaChat: ThreadId(8) src/job.rs:1069: Smtp finished immediate try 0 of job #2412, action SendMsgToSmtp
03-07 22:42:07.991 10675 10709 W DeltaChat: ThreadId(8) src/job.rs:1024: Smtp removes job #2412, action SendMsgToSmtp as it failed with error Message("Permanent SMTP error: 5.5.0 Service unavailable")
03-07 22:42:08.020 10675 10709 I DeltaChat: ThreadId(8) src/job.rs:699: SMTP-jobs ended.
03-07 22:42:08.022 10675 10709 I DeltaChat: ThreadId(8) src/job.rs:710: SMTP-idle started...
03-07 22:42:28.597 10675 10675 W StaticLayout: maxLineHeight should not be -1.  maxLines:1 lineCount:1
03-07 22:42:28.620 10675 10675 W StaticLayout: maxLineHeight should not be -1.  maxLines:1 lineCount:1
03-07 22:42:28.629 10675 10675 W CameraView: skipping layout due to zero-width/height preview size
03-07 22:42:28.664 10675 10675 W KeyboardAwareLinearLayout: onKeyboardClose()
03-07 22:42:50.294 10675 10686 I chat.delta: Background concurrent copying GC freed 193663(4MB) AllocSpace objects, 0(0B) LOS objects, 50% free, 4MB/9MB, paused 167us total 123.731ms
03-07 22:42:51.445 10675 10675 W PassphraseRequiredActionBarActivity: onPause()
03-07 22:42:57.968 10675 10675 W PassphraseRequiredActionBarActivity: onResume()
03-07 22:42:57.998 10675 10675 W StaticLayout: maxLineHeight should not be -1.  maxLines:1 lineCount:1
03-07 22:42:57.999 10675 10675 W StaticLayout: maxLineHeight should not be -1.  maxLines:1 lineCount:1
03-07 22:42:58.018 10675 10745 W Adreno-EGL: <qeglDrvAPI_eglGetConfigAttrib:607>: EGL_BAD_ATTRIBUTE
03-07 22:42:58.019 10675 10675 W StaticLayout: maxLineHeight should not be -1.  maxLines:1 lineCount:1
03-07 22:42:58.019 10675 10675 W StaticLayout: maxLineHeight should not be -1.  maxLines:1 lineCount:1
03-07 22:42:58.019 10675 10745 D vndksupport: Loading /vendor/lib64/hw/gralloc.msm8916.so from current namespace instead of sphal namespace.
03-07 22:43:01.170 10675 10675 W PassphraseRequiredActionBarActivity: onPause()
03-07 22:43:04.637 10675 10675 W PassphraseRequiredActionBarActivity: onResume()
03-07 22:43:04.667 10675 10675 W StaticLayout: maxLineHeight should not be -1.  maxLines:1 lineCount:1
03-07 22:43:04.667 10675 10675 W StaticLayout: maxLineHeight




![Screenshot_20200307-224311_Delta_Chat|281x500](upload://atsDUix1Z0vo6PuIeMvs6BQRayD.png)![IMG_20200307_224354|313x500](upload://4by2QPVmKBGX4cTbpBQ4sxvd6rl.jpeg)
1 Like
2

Could you disclose which email provider this is (both recipient and sender are interesting)? According to the log, the server returned Permanent SMTP error: 5.5.0 Service unavailable error.

We have indeed changed how we process these errors recently:

Instead of retrying the message later, we don’t retry it at all if returned error code is in 5xx range, which means that error is permanent. Maybe the error is not permanent, but the server returns incorrect error code. Quick search shows that it happens with outlook recipient emails a lot.

I think we should change error processing to keep retrying message in any case then.

3

I have filed an issue https://github.com/deltachat/deltachat-core-rust/issues/1330 but even when it is fixed by retrying the message later, you might want to change email provider, ask you provider to whitelist you or try to resolve the server error in other way. Otherwise you will keep experiencing delays.

4

Hey Alexander,

sender: Posteo.de
Receiver: web.de

Please keep in mind that this is not a permanent error!

This also happens with gmx.de (receiver) and our company e-mail…

Greetings,
E.

IMG_20200307_224354.jpg
IMG_20200307_224354.jpg720×1150 87.9 KB

5

posteo.de runs postfix according to their SMTP server banner. Seems like mail server misconfiguration: they decided to reject your mail instead of tempfail:
http://postfix.1071664.n5.nabble.com/Email-delivery-fails-on-postfix-on-heavy-load-td45413.html

Official documentation says it should tempfail by default: http://www.postfix.org/MILTER_README.html#errors

The difference can also be seen in the source code (some random mirror on github): https://github.com/vdukhovni/postfix/blob/master/postfix/src/milter/milter8.c#L552

It is probably worth telling posteo.de about this problem, they should be able to fix their configuration to return tempfail in this case.

6

I have sent a support request with a link to this page, waiting for reply.

7

Thanks a lot!!!
Let’s see what they will reply!
Usually it takes a few days till they answer - but they do!

Greetings,
E.