Aaarrrg! randomly decides it will not send an email

smjohnston · February 18, 2023, 10:54pm

Sometimes it sends, sometimes it does not… and then will not.
I have K-9 installed on a Samsung A5.
I have used K-9 for years but sometimes it just gets a bug up its… and will not send a message.
I will recreate the message and then sometimes it will send it but not the original one.
Sometimes It will work fine for months and then poof temper tantrum.
here is the an excerpt from my exported configuration:

  <outgoing-server type="SMTP">
        <host>mail.shaw.ca</host>
        <port>587</port>
        <connection-security>STARTTLS_REQUIRED</connection-security>
        <authentication-type>PLAIN</authentication-type>
        <username>myusername</username>
      </outgoing-server>

Any other parts of the config someone would need to see in order to troubleshoot?

cketti · February 18, 2023, 11:10pm

Can you share the error message the app is displaying?

smjohnston · February 19, 2023, 12:20am

Where do I find those? android app. It does not display any to me…
I have exported a log but it is not an accepted upload apparently… only wants pictures.
Here is what seems relevant:

 02-18 16:02:09.434 15479 15499 I MessagingController: Running command 'sendPendingMessages', seq = 11279 (background priority)
02-18 16:02:09.439 15479 15499 D Notification: allPendingIntents
02-18 16:02:09.439 15479 15499 D Notification: allPendingIntents
02-18 16:02:09.453 15479 15499 I MessagingController: Scanning Outbox folder for messages to send
02-18 16:02:09.455 15479 15479 D ViewRootImpl@ea5a689[PopupWindow:6fa7842]: Relayout returned: old=[480,84][1068,516] new=[480,84][1068,516] result=0x1 surface={valid=true 544828452864} changed=false
02-18 16:02:09.457 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: MSG_WINDOW_FOCUS_CHANGED 1
02-18 16:02:09.459 15479 15499 V MessagingController: Skipping sending message K9LOCAL:ca2e248f-5c25-4cec-823c-ab8d99ed3d19 (reason: error)
02-18 16:02:09.461 15479 15479 D ViewRootImpl@ea5a689[PopupWindow:6fa7842]: MSG_WINDOW_FOCUS_CHANGED 0
02-18 16:02:09.465 15479 15499 D Notification: allPendingIntents
02-18 16:02:09.465 15479 15499 D Notification: allPendingIntents
02-18 16:02:09.468 15479 15499 I MessagingController:  Command 'sendPendingMessages' completed

and from another section just in case it is:

02-18 14:47:40.353 15479 10029 V AndroidPowerManager$AndroidWakeLock: AndroidWakeLock for tag ImapFolderPusher-shawmail-INBOX / id 7164: releasing after 791 ms, timeout = null ms
02-18 14:49:41.457 15479 10028 V AndroidPowerManager$AndroidWakeLock: AndroidWakeLock for tag ImapFolderPusher-shawmail-Drafts / id 7165: acquired with no timeout.
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: Exception in ImapFolderPusher
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: javax.net.ssl.SSLException: Read error: ssl=0x7ee1a17000: I/O error during system call, Connection reset by peer
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.android.org.conscrypt.NativeCrypto.SSL_read(Native Method)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.android.org.conscrypt.OpenSSLSocketImpl$SSLInputStream.read(OpenSSLSocketImpl.java:766)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at java.io.BufferedInputStream.fill(BufferedInputStream.java:248)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at java.io.BufferedInputStream.read(BufferedInputStream.java:267)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.mail.filter.PeekableInputStream.peek(PeekableInputStream.java:36)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:37)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.mail.store.imap.RealImapConnection.readResponse(RealImapConnection.kt:809)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.mail.store.imap.RealImapConnection.readResponse(RealImapConnection.kt:801)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.mail.store.imap.RealImapFolderIdler.idle(RealImapFolderIdler.kt:124)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.mail.store.imap.RealImapFolderIdler.idle(RealImapFolderIdler.kt:39)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher.runPushLoop(ImapFolderPusher.kt:73)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher.access$runPushLoop(ImapFolderPusher.kt:15)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher$start$1.invoke(ImapFolderPusher.kt:36)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher$start$1.invoke(ImapFolderPusher.kt:33)
02-18 14:49:41.459 15479 10028 V ImapFolderPusher: 	at kotlin.concurrent.ThreadsKt$thread$thread$1.run(Thread.kt:30)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: I/O error while trying to use IDLE
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: javax.net.ssl.SSLException: Read error: ssl=0x7ee1a17000: I/O error during system call, Connection reset by peer
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.android.org.conscrypt.NativeCrypto.SSL_read(Native Method)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.android.org.conscrypt.OpenSSLSocketImpl$SSLInputStream.read(OpenSSLSocketImpl.java:766)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at java.io.BufferedInputStream.fill(BufferedInputStream.java:248)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at java.io.BufferedInputStream.read(BufferedInputStream.java:267)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.mail.filter.PeekableInputStream.peek(PeekableInputStream.java:36)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.mail.store.imap.ImapResponseParser.readResponse(ImapResponseParser.java:37)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.mail.store.imap.RealImapConnection.readResponse(RealImapConnection.kt:809)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.mail.store.imap.RealImapConnection.readResponse(RealImapConnection.kt:801)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.mail.store.imap.RealImapFolderIdler.idle(RealImapFolderIdler.kt:124)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.mail.store.imap.RealImapFolderIdler.idle(RealImapFolderIdler.kt:39)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher.runPushLoop(ImapFolderPusher.kt:73)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher.access$runPushLoop(ImapFolderPusher.kt:15)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher$start$1.invoke(ImapFolderPusher.kt:36)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at com.fsck.k9.backend.imap.ImapFolderPusher$start$1.invoke(ImapFolderPusher.kt:33)
02-18 14:49:41.459 15479 10028 V ImapBackendPusher: 	at kotlin.concurrent.ThreadsKt$thread$thread$1.run(Thread.kt:30)
02-18 14:49:41.460 15479 10028 V ImapBackendPusher: ImapBackendPusher for folder Drafts sleeping for 300000 ms
02-18 14:49:41.464 15479 10028 V AndroidPowerManager$AndroidWakeLock: AndroidWakeLock for tag ImapFolderPusher-shawmail-Drafts / id 7165: releasing after 7 ms, timeout = null ms
02-18 14:49:41.466 15479 10028 V ImapFolderPusher$start: Exiting ImapFolderPusher thread for shawmail / Drafts
02-18 14:52:02.370 15479 24398 D MailSyncWorker: Executing periodic mail sync for account 0449fb9f-3e7a-43ad-9d6d-28e055847982
02-18 14:52:02.373 15479 15499 I MessagingController: Running command 'checkMail', seq = 11215 (background priority)
02-18 14:52:02.373 15479 15499 I MessagingController: Starting mail check
02-18 14:52:02.374 15479 24398 V MessagingController: performPeriodicMailSync(0449fb9f-3e7a-43ad-9d6d-28e055847982) about to await latch release
02-18 14:52:02.378 15479 15499 I MessagingController: Synchronizing account 0449fb9f-3e7a-43ad-9d6d-28e055847982
02-18 14:52:02.379 15479 15499 D MessagingController: Last folder list refresh @ Sat Feb 18 14:22:01 PST 2023. Refreshing now…
02-18 14:52:02.474 15479 15499 D RealImapConnection: Connecting to imap.shaw.ca as imap.shaw.ca/64.59.136.142
02-18 14:52:02.597 15479 15499 V RealImapConnection: conn54207825 <<< #null# [OK, IMAP4rev1 proxy server ready]
02-18 14:52:02.598 15479 15499 I RealImapConnection: Did not get capabilities in banner, requesting CAPABILITY for conn54207825
02-18 14:52:02.599 15479 15499 V RealImapConnection: conn54207825>>> 1 CAPABILITY
02-18 14:52:02.636 15479 15499 V ImapResponseParser: conn54207825<<<#null# [CAPABILITY, ACL, BINARY, CATENATE, CHILDREN, CONDSTORE, ENABLE, ESEARCH, ESORT, I18NLEVEL=1, ID, IDLE, IMAP4rev1, LIST-EXTENDED, LITERAL+, MULTIAPPEND, NAMESPACE, QRESYNC, QUOTA, RIGHTS=ektx, SASL-IR, SEARCHRES, SORT, THREAD=ORDEREDSUBJECT, UIDPLUS, UNSELECT, WITHIN, LIST-STATUS, XLIST, AUTH=PLAIN, STARTTLS]
02-18 14:52:02.637 15479 15499 V ImapResponseParser: conn54207825<<<#1# [OK, completed]
02-18 14:52:02.637 15479 15499 D RealImapConnection: Saving [NAMESPACE, LIST-STATUS, QUOTA, BINARY, SASL-IR, WITHIN, MULTIAPPEND, SEARCHRES, XLIST, ESORT, CONDSTORE, AUTH=PLAIN, I18NLEVEL=1, CATENATE, ID, RIGHTS=EKTX, QRESYNC, UNSELECT, ACL, ENABLE, THREAD=ORDEREDSUBJECT, UIDPLUS, CHILDREN, LITERAL+, ESEARCH, IDLE, IMAP4REV1, SORT, LIST-EXTENDED, STARTTLS] capabilities for conn54207825
02-18 14:52:02.642 15479 15499 V RealImapConnection: conn54207825>>> 2 AUTHENTICATE PLAIN
02-18 14:52:02.695 15479 15499 V RealImapConnection: conn54207825<<<#+# []
02-18 14:52:02.755 15479 15499 V ImapResponseParser: conn54207825<<<#2# [OK, [CAPABILITY, IMAP4rev1, ACL, BINARY, CATENATE, CHILDREN, CONDSTORE, ENABLE, ESEARCH, ESORT, I18NLEVEL=1, ID, IDLE, LIST-EXTENDED, LIST-STATUS, LITERAL+, LOGIN-REFERRALS, MULTIAPPEND, NAMESPACE, QRESYNC, QUOTA, RIGHTS=ektx, SASL-IR, SEARCHRES, SORT, THREAD=ORDEREDSUBJECT, UIDPLUS, UNSELECT, WITHIN, XLIST], AUTHENTICATE completed]
02-18 14:52:02.757 15479 15499 D RealImapConnection: Saving [NAMESPACE, LIST-STATUS, QUOTA, BINARY, SASL-IR, WITHIN, MULTIAPPEND, SEARCHRES, XLIST, ESORT, CONDSTORE, I18NLEVEL=1, CATENATE, ID, RIGHTS=EKTX, QRESYNC, UNSELECT, ACL, ENABLE, THREAD=ORDEREDSUBJECT, UIDPLUS, CHILDREN, LITERAL+, IMAP4REV1, ESEARCH, IDLE, LOGIN-REFERRALS, SORT, LIST-EXTENDED] capabilities for conn54207825
02-18 14:52:02.758 15479 15499 V RealImapConnection: conn54207825>>> 3 LIST "" "*"
02-18 14:52:02.796 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\HasNoChildren, \Drafts], /, Drafts]
02-18 14:52:02.797 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\HasChildren], /, INBOX]
02-18 14:52:02.797 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\HasNoChildren], /, INBOX/bought]
02-18 14:52:02.798 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\HasNoChildren], /, INBOX/Encrypted Sec Keys]
02-18 14:52:02.798 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\NoInferiors, \Junk], /, Junk]
02-18 14:52:02.799 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\HasNoChildren, \Sent], /, Sent]
02-18 14:52:02.799 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\HasNoChildren, \Trash], /, Trash]
02-18 14:52:02.799 15479 15499 V ImapResponseParser: conn54207825<<<#null# [LIST, [\HasNoChildren], /, Unwanted]
02-18 14:52:02.800 15479 15499 V ImapResponseParser: conn54207825<<<#3# [OK, LIST completed]

Is there something else I should be looking for or have a go at pasting the whole thing??

cketti · February 19, 2023, 2:33pm

The app should display an error notification when there’s an error sending an email. You should be able to re-create such a notification by going to the Outbox, then selecting “Send messages” from the menu.

smjohnston · February 19, 2023, 6:32pm

I personally think that would be great but…
I have uninstalled and reinstalled and never seen an error.
Here is what does happen:

At 10:03 this morning I went to the Outbox folder.
I selected the three dots in the top right of the screen.
I waited.
As soon as the time changed to 10:04 I selected “Send messages”
Nothing happened.
No Error Message
I then went to Debugging and exported the logs.
Here is what was in the logs from 10:04 on…


02-19 10:04:02.476 15479 15479 D ViewRootImpl@24fec16[PopupWindow:e26bcbb]: ViewPostIme pointer 0
02-19 10:04:02.533 15479 15479 D ViewRootImpl@24fec16[PopupWindow:e26bcbb]: ViewPostIme pointer 1
02-19 10:04:02.534 15479 15479 D AbsListView: onTouchUp() mTouchMode : 0
02-19 10:04:02.603 15479 15499 I MessagingController: Running command 'sendPendingMessages', seq = 11751 (background priority)
02-19 10:04:02.619 15479 15479 D ViewRootImpl@24fec16[PopupWindow:e26bcbb]: Relayout returned: old=[480,84][1068,516] new=[480,84][1068,516] result=0x1 surface={valid=true 544823492608} changed=false
02-19 10:04:02.622 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: MSG_WINDOW_FOCUS_CHANGED 1
02-19 10:04:02.627 15479 15499 D Notification: allPendingIntents
02-19 10:04:02.627 15479 15499 D Notification: allPendingIntents
02-19 10:04:02.629 15479 15479 D ViewRootImpl@24fec16[PopupWindow:e26bcbb]: MSG_WINDOW_FOCUS_CHANGED 0
02-19 10:04:02.647 15479 15499 I MessagingController: Scanning Outbox folder for messages to send
02-19 10:04:02.653 15479 15499 V MessagingController: Skipping sending message K9LOCAL:ca2e248f-5c25-4cec-823c-ab8d99ed3d19 (reason: error)
02-19 10:04:02.658 15479 15499 D Notification: allPendingIntents
02-19 10:04:02.658 15479 15499 D Notification: allPendingIntents
02-19 10:04:02.662 15479 15499 I MessagingController:  Command 'sendPendingMessages' completed
02-19 10:04:02.939 15479  9593 D OpenGLRenderer: eglDestroySurface = 0x7ee31ac8c0
02-19 10:04:02.939 15479  9593 D OpenGLRenderer: endAllActiveAnimators on 0x7eda46f800 (MenuPopupWindow$MenuDropDownListView) with handle 0x7ed82f6b80
02-19 10:04:02.939 15479 15479 D ViewRootImpl@24fec16[PopupWindow:e26bcbb]: dispatchDetachedFromWindow
02-19 10:04:02.940 15479 15479 D InputEventReceiver: channel '6d8af1 PopupWindow:e26bcbb (client)' ~ Disposing input event receiver.
02-19 10:04:02.940 15479 15479 D InputEventReceiver: channel '6d8af1 PopupWindow:e26bcbb (client)' ~NativeInputEventReceiver.
02-19 10:04:10.102 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: ViewPostIme pointer 0
02-19 10:04:10.182 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: ViewPostIme pointer 1
02-19 10:04:10.638 15479 15479 D InputMethodManager: HSIFW - flag : 0 Pid : 15479
02-19 10:04:11.820 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: ViewPostIme pointer 0
02-19 10:04:11.896 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: ViewPostIme pointer 1
02-19 10:04:12.045 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: setView = DecorView@be0240c[SettingsActivity] TM=true MM=false
02-19 10:04:12.046 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: MSG_WINDOW_FOCUS_CHANGED 0
02-19 10:04:12.050 15479 15479 V InputMethodManager: Not IME target window, ignoring
02-19 10:04:12.050 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: dispatchAttachedToWindow
02-19 10:04:12.064 15479 15479 V Surface : sf_framedrop debug : 0x4f4c, game : false, logging : 0
02-19 10:04:12.064 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: Relayout returned: old=[0,0][0,0] new=[0,0][1080,1920] result=0x7 surface={valid=true 544954507264} changed=true
02-19 10:04:12.070 15479  9593 D mali_winsys: EGLint new_window_surface(egl_winsys_display *, void *, EGLSurface, EGLConfig, egl_winsys_surface **, egl_color_buffer_format *, EGLBoolean) returns 0x3000,  [1080x1920]-format:1
02-19 10:04:12.070 15479  9593 D OpenGLRenderer: eglCreateWindowSurface = 0x7ee1ad3540
02-19 10:04:12.143 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: MSG_RESIZED_REPORT: frame=Rect(0, 0 - 1080, 1920) ci=Rect(0, 72 - 0, 0) vi=Rect(0, 72 - 0, 0) or=1
02-19 10:04:12.144 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: MSG_WINDOW_FOCUS_CHANGED 1
02-19 10:04:12.152 15479 15479 V InputMethodManager: Starting input: tba=android.view.inputmethod.EditorInfo@33e12f6 nm : com.fsck.k9 ic=null
02-19 10:04:12.152 15479 15479 I InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus
02-19 10:04:12.171 15479  9593 D OpenGLRenderer: eglDestroySurface = 0x7ee41b31c0
02-19 10:04:12.172 15479  9593 D OpenGLRenderer: endAllActiveAnimators on 0x7eda0afc00 (ConstraintLayout) with handle 0x7ed82f69c0
02-19 10:04:12.177 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x5 surface={valid=false 0} changed=true
02-19 10:04:12.568 15479 15479 D ConnectivityManager: unregisterNetworkCallback; CallingUid : 10212, CallingPid : 15479
02-19 10:04:12.590 15479 15479 D ViewRootImpl@15c9fb8[MessageList]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x1 surface={valid=false 0} changed=false
02-19 10:04:24.371 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: ViewPostIme pointer 0
02-19 10:04:24.435 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: ViewPostIme pointer 1
02-19 10:04:24.533 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: setView = DecorView@55f3716[GeneralSettingsActivity] TM=true MM=false
02-19 10:04:24.534 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: MSG_WINDOW_FOCUS_CHANGED 0
02-19 10:04:24.536 15479 15479 V InputMethodManager: Not IME target window, ignoring
02-19 10:04:24.536 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: dispatchAttachedToWindow
02-19 10:04:24.548 15479 15479 V Surface : sf_framedrop debug : 0x4f4c, game : false, logging : 0
02-19 10:04:24.548 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: Relayout returned: old=[0,0][0,0] new=[0,0][1080,1920] result=0x7 surface={valid=true 544909271040} changed=true
02-19 10:04:24.553 15479  9593 D mali_winsys: EGLint new_window_surface(egl_winsys_display *, void *, EGLSurface, EGLConfig, egl_winsys_surface **, egl_color_buffer_format *, EGLBoolean) returns 0x3000,  [1080x1920]-format:1
02-19 10:04:24.553 15479  9593 D OpenGLRenderer: eglCreateWindowSurface = 0x7ee30dcfc0
02-19 10:04:24.666 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_RESIZED_REPORT: frame=Rect(0, 0 - 1080, 1920) ci=Rect(0, 72 - 0, 0) vi=Rect(0, 72 - 0, 0) or=1
02-19 10:04:24.666 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_WINDOW_FOCUS_CHANGED 1
02-19 10:04:24.682 15479 15479 V InputMethodManager: Starting input: tba=android.view.inputmethod.EditorInfo@709c705 nm : com.fsck.k9 ic=null
02-19 10:04:24.683 15479 15479 I InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus
02-19 10:04:24.708 15479  9593 D OpenGLRenderer: eglDestroySurface = 0x7ee1ad3540
02-19 10:04:24.708 15479  9593 D OpenGLRenderer: endAllActiveAnimators on 0x7edb42d800 (FrameLayout) with handle 0x7ed83518a0
02-19 10:04:24.714 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x5 surface={valid=false 0} changed=true
02-19 10:04:25.099 15479 15479 D ViewRootImpl@fb61196[SettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x1 surface={valid=false 0} changed=false
02-19 10:04:25.931 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: ViewPostIme pointer 0
02-19 10:04:25.995 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: ViewPostIme pointer 1
02-19 10:04:26.051 15479 15479 V InputMethodManager: Starting input: tba=android.view.inputmethod.EditorInfo@e080c5 nm : com.fsck.k9 ic=null
02-19 10:04:26.051 15479 15479 I InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus
02-19 10:04:26.073 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x1 surface={valid=true 544909271040} changed=false
02-19 10:04:28.937 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: ViewPostIme pointer 0
02-19 10:04:29.025 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: ViewPostIme pointer 1
02-19 10:04:29.050 15479 10330 I K9StorageEditor: Committing preference changes
02-19 10:04:29.072 15479 10330 I K9StorageEditor: Preferences commit took 23 ms
02-19 10:04:31.488 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: ViewPostIme pointer 0
02-19 10:04:31.522 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: ViewPostIme pointer 1
02-19 10:04:31.571 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: setView = android.widget.PopupWindow$PopupDecorView{e713a9c V.E..V... ......I. 0,0-0,0} TM=true MM=false
02-19 10:04:31.585 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: dispatchAttachedToWindow
02-19 10:04:31.606 15479 15479 V Surface : sf_framedrop debug : 0x4f4c, game : false, logging : 0
02-19 10:04:31.606 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: Relayout returned: old=[0,0][0,0] new=[480,84][1068,228] result=0x7 surface={valid=true 544954507264} changed=true
02-19 10:04:31.609 15479  9593 D mali_winsys: EGLint new_window_surface(egl_winsys_display *, void *, EGLSurface, EGLConfig, egl_winsys_surface **, egl_color_buffer_format *, EGLBoolean) returns 0x3000,  [780x336]-format:1
02-19 10:04:31.609 15479  9593 D OpenGLRenderer: eglCreateWindowSurface = 0x7edf118b80
02-19 10:04:31.620 15479 15479 D AbsListView:  in onLayout changed 
02-19 10:04:31.628 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: MSG_WINDOW_FOCUS_CHANGED 1
02-19 10:04:31.629 15479 15479 V InputMethodManager: Not IME target window, ignoring
02-19 10:04:31.642 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: MSG_RESIZED_REPORT: frame=Rect(480, 84 - 1068, 228) ci=Rect(0, 0 - 0, 0) vi=Rect(0, 0 - 0, 0) or=1
02-19 10:04:31.663 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_WINDOW_FOCUS_CHANGED 0
02-19 10:04:36.292 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: ViewPostIme pointer 0
02-19 10:04:36.352 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: ViewPostIme pointer 1
02-19 10:04:36.352 15479 15479 D AbsListView: onTouchUp() mTouchMode : 0
02-19 10:04:36.457 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: Relayout returned: old=[480,84][1068,228] new=[480,84][1068,228] result=0x1 surface={valid=true 544954507264} changed=false
02-19 10:04:36.496 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_WINDOW_FOCUS_CHANGED 1
02-19 10:04:36.499 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: MSG_WINDOW_FOCUS_CHANGED 0
02-19 10:04:36.510 15479  9593 I OpenGLRenderer: prepareTree mLayerUpdateQueue is not empty, size = 1
02-19 10:04:36.510 15479  9593 I OpenGLRenderer: name = PopupWindow$PopupBackgroundView, size (588, 144)
02-19 10:04:36.529 15479  9593 D OpenGLRenderer: eglDestroySurface = 0x7edf118b80
02-19 10:04:36.529 15479  9593 D OpenGLRenderer: endAllActiveAnimators on 0x7ee18b7000 (MenuPopupWindow$MenuDropDownListView) with handle 0x7edac94c00
02-19 10:04:36.542 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: Relayout returned: old=[480,84][1068,228] new=[480,84][1068,228] result=0x5 surface={valid=false 0} changed=true
02-19 10:04:36.546 15479  9593 D OpenGLRenderer: eglDestroySurface = 0x7ee30dcfc0
02-19 10:04:36.554 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x5 surface={valid=false 0} changed=true
02-19 10:04:36.554 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_WINDOW_FOCUS_CHANGED 0
02-19 10:04:36.796 15479 15479 D ViewRootImpl@2acfa0f[PopupWindow:72a1770]: dispatchDetachedFromWindow
02-19 10:04:36.796 15479 15479 D InputEventReceiver: channel '703c1b8 PopupWindow:72a1770 (client)' ~ Disposing input event receiver.
02-19 10:04:36.796 15479 15479 D InputEventReceiver: channel '703c1b8 PopupWindow:72a1770 (client)' ~NativeInputEventReceiver.
02-19 10:04:36.984 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x1 surface={valid=false 0} changed=false
02-19 10:04:55.760 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x1 surface={valid=false 0} changed=false
02-19 10:04:55.773 15479 10330 V LogcatLogFileWriter: Writing logcat output to content URI: content://com.android.providers.downloads.documents/document/2018
02-19 10:04:55.822 15479 15479 V Surface : sf_framedrop debug : 0x4f4c, game : false, logging : 0
02-19 10:04:55.823 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x7 surface={valid=true 544909271040} changed=true
02-19 10:04:55.830 15479  9593 D mali_winsys: EGLint new_window_surface(egl_winsys_display *, void *, EGLSurface, EGLConfig, egl_winsys_surface **, egl_color_buffer_format *, EGLBoolean) returns 0x3000,  [1080x1920]-format:1
02-19 10:04:55.830 15479  9593 D OpenGLRenderer: eglCreateWindowSurface = 0x7ee402a5c0
02-19 10:04:55.849 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_RESIZED_REPORT: frame=Rect(0, 0 - 1080, 1920) ci=Rect(0, 72 - 0, 873) vi=Rect(0, 72 - 0, 873) or=1
02-19 10:04:55.849 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_WINDOW_FOCUS_CHANGED 1
02-19 10:04:55.866 15479 15479 V InputMethodManager: Starting input: tba=android.view.inputmethod.EditorInfo@163197e nm : com.fsck.k9 ic=null
02-19 10:04:55.866 15479 15479 I InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus
02-19 10:04:55.955 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: MSG_RESIZED: frame=Rect(0, 0 - 1080, 1920) ci=Rect(0, 72 - 0, 0) vi=Rect(0, 72 - 0, 0) or=1
02-19 10:04:55.973 15479 15479 D ViewRootImpl@57f9431[GeneralSettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x1 surface={valid=true 544909271040} changed=false

I have used K-9 for years and it mostly works fine but sometimes this happens. I can make other emails at some point that will send but I have to delete the orriginals then try again and sometimes I have to do that several times and think “well that’s it for me” and then suddenly one of them sends but the original still wont go…

This line seems important to me:

02-19 10:04:02.653 15479 15499 V MessagingController: Skipping sending message K9LOCAL:ca2e248f-5c25-4cec-823c-ab8d99ed3d19 (reason: error)

And so you see why I have no eyebrows left. (Aaaahrrrg!!)

cketti · February 19, 2023, 7:10pm

Which app version are you using? There was a bug in older versions that lead to the error notification only to be displayed the first time sending the message failed. But it was fixed in K-9 Mail 6.400.

smjohnston · February 19, 2023, 7:57pm

I am using version 6.400
It is the latest version from the play store.

cketti · February 20, 2023, 12:46pm

Did you disable “miscellaneous” notifications for K-9 Mail? You can get to the system settings screen via Settings → [Account] → Notifications → Miscellaneous.

smjohnston · February 20, 2023, 8:04pm

Here’s a pic

Also:
Under Notifications
New mail notifications is checked.
Include outgoing mail is checked.
Sync notifications is checked.

ALSO: I exported my account settings so I could go over them on my computer and sent them to myself using K-9… It went fine… Here is what that looks like in the logs:

02-20 12:08:57.005 30771 30788 I MessagingController: Running command 'sendPendingMessages', seq = 709 (background priority)
02-20 12:08:57.013 30771 30771 D ViewRootImpl@f8c2d0a[MessageCompose]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x5 surface={valid=false 0} changed=true
02-20 12:08:57.031 30771 30788 D Notification: allPendingIntents
02-20 12:08:57.031 30771 30788 D Notification: allPendingIntents
02-20 12:08:57.041 30771 30788 I MessagingController: Scanning Outbox folder for messages to send
02-20 12:08:57.042 30771 30788 I MessagingController: Send count for message K9LOCAL:4c3c23a1-e3e0-44df-862e-123613523ae5 is 0
02-20 12:08:57.065 30771 30771 D ViewRootImpl@50ae4e8[SettingsActivity]: MSG_RESIZED: frame=Rect(0, 0 - 1080, 1920) ci=Rect(0, 72 - 0, 0) vi=Rect(0, 72 - 0, 0) or=1
02-20 12:08:57.074 30771 30788 V LockableDatabase: LockableDatabase: Transaction ended, took 6 ms / com.fsck.k9.mailstore.LocalMessage.setFlag(LocalMessage.java:256)
02-20 12:08:57.074 30771 30788 I MessagingController: Sending message with UID K9LOCAL:4c3c23a1-e3e0-44df-862e-123613523ae5
02-20 12:08:57.081 30771 30771 D ViewRootImpl@50ae4e8[SettingsActivity]: Relayout returned: old=[0,0][1080,1920] new=[0,0][1080,1920] result=0x1 surface={valid=true 544998850560} changed=false
02-20 12:08:57.104 30771 30788 D SmtpTransport: Connecting to mail.shaw.ca as mail.shaw.ca/64.59.128.135
02-20 12:08:57.519 30771 30788 V SmtpTransport: SMTP <<< 220 shw-obgw-4004a.ext.cloudfilter.net cmsmtp ESMTP server ready
02-20 12:08:57.519 30771 30788 D SmtpTransport: SMTP >>> EHLO [127.0.0.1]
02-20 12:08:57.542 30771 30771 D ConnectivityManager: unregisterNetworkCallback; CallingUid : 10212, CallingPid : 30771
02-20 12:08:57.589 30771 30771 D ViewRootImpl@f8c2d0a[MessageCompose]: dispatchDetachedFromWindow
02-20 12:08:57.589 30771 30771 D InputEventReceiver: channel 'f3b6d7f com.fsck.k9/com.fsck.k9.activity.MessageCompose (client)' ~ Disposing input event receiver.
02-20 12:08:57.589 30771 30771 D InputEventReceiver: channel 'f3b6d7f com.fsck.k9/com.fsck.k9.activity.MessageCompose (client)' ~NativeInputEventReceiver.
[redacted]
02-20 12:08:58.745 30771 30788 V LockableDatabase: LockableDatabase: Transaction ended, took 15 ms / com.fsck.k9.mailstore.LocalMessage.setFlag(LocalMessage.java:256)
02-20 12:08:58.760 30771 30788 V LockableDatabase: LockableDatabase: Transaction ended, took 9 ms / com.fsck.k9.mailstore.LocalMessage.setFlag(LocalMessage.java:256)
02-20 12:08:58.765 30771 30788 I MessagingController: Moving sent message to folder 'Sent' (7)
02-20 12:08:58.767 30771 30788 D MoveMessageOperations: Moving message [ID: 4985] to folder [ID: 7]
02-20 12:08:58.805 30771 30788 V LockableDatabase: LockableDatabase: Transaction ended, took 15 ms / com.fsck.k9.storage.messages.MoveMessageOperations.moveMessage(MoveMessageOperations.kt:20)
02-20 12:08:58.806 30771 30788 I MessagingController: Moved sent message to folder 'Sent' (7)
02-20 12:08:58.827 30771 30788 V MessagingController: Skipping sending message K9LOCAL:ca2e248f-5c25-4cec-823c-ab8d99ed3d19 (reason: error)
02-20 12:08:58.832 30771 30788 D Notification: allPendingIntents
02-20 12:08:58.832 30771 30788 D Notification: allPendingIntents
02-20 12:08:58.836 30771 30788 I MessagingController:  Command 'sendPendingMessages' completed

The email it does not like is still sitting in the outbox.

smjohnston · February 20, 2023, 10:36pm

Another question that is perhaps related…

When I updating the outgoing server settings with a NEW password and select next:

If things do not go well I get a notification that “Setup could not finish”.
if things do go well all is I see a spinning circle for a moment and then it disappears.

If the change is successful shouldn’t I get a message to the effect of Setup successful"?

Is this another place where I should be getting a message but I am not?

smjohnston · February 20, 2023, 10:52pm

Ok… this was my problem I did not notice the email pulled from my contacts ended in “.coo” and not “.com”
I found a few more of the same in other contacts. Which explains why it appeared random to me.

Thanks everyone who tried to help but it was my error.