Closed Bug 1714878 Opened 3 years ago Closed 3 years ago

Messages are not being sent. Receiving "Send Message Error Sending of the message failed." error mesage

Categories

(MailNews Core :: Networking: SMTP, defect)

Thunderbird 90
defect

Tracking

(thunderbird_esr78 unaffected, thunderbird90 fixed)

RESOLVED FIXED
91 Branch
Tracking Status
thunderbird_esr78 --- unaffected
thunderbird90 --- fixed

People

(Reporter: timsmith108, Assigned: rnons)

References

(Regression)

Details

(Keywords: regression)

Attachments

(5 files)

User Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_12_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.93 Safari/537.36 Edg/90.0.818.56

Steps to reproduce:

In MacOS 10.12.6 using Thunderbird 90.0b1,

Compose an email message. Hit Send.

Actual results:

Instead of message going out as it should, this error message pops up:

"Send Message Error
Sending of the message failed."

Expected results:

Message should have been sent normally.

Thanks for reporting, can you provide some debug logs with the following steps:

  1. go to the Config Editor, set mailnews.smtp.loglevel to All
  2. open DevTools and select the Console tab, clear any existing logs
  3. send a mail, debug logs should show up in the Console tab
  4. copy/paste the logs here

I'm new at reading logs, so I'm guessing that the below is the right part of the logs. It is from the bottom of the log scroll. I hope that's the latest part? (Or is it the latest at the top of the scroll?)

Another comment is that the problem doesn't happen 100% of the time. About 1-2 times a day I can send send an email normally, but 95% of the time, I can't as above. :(

mailnews.smtp: Possible auth methods: SmtpClient.jsm:898:17
mailnews.smtp: Maximum allowd message size: 52428800 SmtpClient.jsm:904:19
mailnews.smtp: Error: No available auth method.
_authenticateUser resource:///modules/SmtpClient.jsm:638
_actionEHLO resource:///modules/SmtpClient.jsm:913
_onCommand resource:///modules/SmtpClient.jsm:507
_parse resource:///modules/SmtpClient.jsm:368
_onData resource:///modules/SmtpClient.jsm:416
SmtpClient.jsm:436:17
mailnews.send: Sending failed; Sending of the message failed., exitCode=2147500037, originalMsgURI= MessageSend.jsm:321:27
fail resource:///modules/MessageSend.jsm:321
_deliveryExitProcessing resource:///modules/MessageSend.jsm:611
sendDeliveryCallback resource:///modules/MessageSend.jsm:676
OnStopRunningUrl resource:///modules/MessageSend.jsm:1293
onerror resource:///modules/SmtpService.jsm:160
_onError resource:///modules/SmtpClient.jsm:445
_authenticateUser resource:///modules/SmtpClient.jsm:638
_actionEHLO resource:///modules/SmtpClient.jsm:913
_onCommand resource:///modules/SmtpClient.jsm:507
_parse resource:///modules/SmtpClient.jsm:368
_onData resource:///modules/SmtpClient.jsm:416
Prompter: internal dialogs not available in this context. Falling back to window prompt. Prompter.jsm:1084
set modalType resource://gre/modules/Prompter.jsm:1084
ModalPrompter resource://gre/modules/Prompter.jsm:1040
getPrompt resource://gre/modules/Prompter.jsm:65
getDefaultPrompt resource:///modules/MessageSend.jsm:300
fail resource:///modules/MessageSend.jsm:319
_deliveryExitProcessing resource:///modules/MessageSend.jsm:611
sendDeliveryCallback resource:///modules/MessageSend.jsm:676
OnStopRunningUrl resource:///modules/MessageSend.jsm:1293
onerror resource:///modules/SmtpService.jsm:160
_onError resource:///modules/SmtpClient.jsm:445
_authenticateUser resource:///modules/SmtpClient.jsm:638
_actionEHLO resource:///modules/SmtpClient.jsm:913
_onCommand resource:///modules/SmtpClient.jsm:507
_parse resource:///modules/SmtpClient.jsm:368
_onData resource:///modules/SmtpClient.jsm:416
_onData self-hosted:1175
mailnews.smtp: Closing connection... SmtpClient.jsm:169:17
mailnews.smtp: Socket closed. SmtpClient.jsm:485:17
NS_ERROR_FILE_NOT_FOUND: SessionStoreFunctions.jsm:106
SSF_updateSessionStoreForWindow resource://gre/modules/SessionStoreFunctions.jsm:106
UpdateSessionStoreForStorage resource://gre/modules/SessionStoreFunctions.jsm:49
Prompter: internal dialogs not available in this context. Falling back to window prompt. Prompter.jsm:1084
set modalType resource://gre/modules/Prompter.jsm:1084
ModalPrompter resource://gre/modules/Prompter.jsm:1040
pickPrompter resource://gre/modules/Prompter.jsm:44
confirmEx resource://gre/modules/Prompter.jsm:304
ComposeCanClose chrome://messenger/content/messengercompose/MsgComposeCommands.js:6070
DoCommandClose chrome://messenger/content/messengercompose/MsgComposeCommands.js:2616
doCommand chrome://messenger/content/messengercompose/MsgComposeCommands.js:877
doCommand chrome://messenger/content/messengercompose/MsgComposeCommands.js:1103
goDoCommand chrome://global/content/globalOverlay.js:123
oncommand chrome://messenger/content/messengercompose/messengercompose.xhtml:1
Already started legacy listener for document-event on server1.conn0.parentProcessTarget2 resource-command.js:833:15

Also, I tried downgrading to TB 78.11 and the problem was still there!

Seems you forgot to go to the Config Editor, set mailnews.smtp.loglevel to All

mailnews.smtp: Possible auth methods: SmtpClient.jsm:898:17
mailnews.smtp: Maximum allowd message size: 52428800 SmtpClient.jsm:904:19
mailnews.smtp: Error: No available auth method.

From this log and the fact that TB 78 also doesn't work, I think your config maybe incorrect. Can you share your SMTP config?

Attached image smtpconfig.jpeg
Attached image stmp settings.jpeg

The above image (I believe) is my config information.

Let me know please if that's not the right stuff, thanks.

Thanks, that looks correct. Can you try comment 1 again to get the full debug logs? I have an idea about what might be wrong, but need to be confirmed by your logs.

Ok, this is everything from the Console
Thanks

Uncaught (in promise) undefined
[Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getCharPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource:///modules/cloudFileAccounts.jsm :: getAccountsForType :: line 186" data: no] 2 cloudFileAccounts.jsm:186:33
getAccountsForType resource:///modules/cloudFileAccounts.jsm:186
getAllAccounts chrome://messenger/content/parent/ext-cloudFile.js:350
getAllAccounts self-hosted:1175
result resource://gre/modules/ExtensionParent.jsm:935
withPendingBrowser resource://gre/modules/ExtensionParent.jsm:491
result resource://gre/modules/ExtensionParent.jsm:935
callAndLog resource://gre/modules/ExtensionParent.jsm:897
recvAPICall resource://gre/modules/ExtensionParent.jsm:934
InterpretGeneratorResume self-hosted:1482
AsyncFunctionNext self-hosted:692
Successfully loaded OpenPGP library librnp.dylib version 0.14+git20210121.7c8492b4.MZLA from /Applications/Thunderbird.app/Contents/MacOS/librnp.dylib RNPLib.jsm:92:15
XML Parsing Error: undefined entity
Location: moz-nullprincipal:{3db7585e-ed2f-4956-9fdd-40ffe4aea0b3}
Line Number 10, Column 27: {3db7585e-ed2f-4956-9fdd-40ffe4aea0b3}:10:27
not well-formed XML customElements.js:566
parseXULToFragment chrome://global/content/customElements.js:566
onLoadWindow jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/{a300a000-5e21-4ee0-a115-9ec8f4eaa92b}.xpi!/api/WindowListener/implementation.js:256
checkAndRunExtensionCode resource:///modules/ExtensionSupport.jsm:220
_checkAndRunMatchingExtensions resource:///modules/ExtensionSupport.jsm:192
registerWindowListener resource:///modules/ExtensionSupport.jsm:71
forEach self-hosted:4353
registerWindowListener resource:///modules/ExtensionSupport.jsm:70
startListening jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/{a300a000-5e21-4ee0-a115-9ec8f4eaa92b}.xpi!/api/WindowListener/implementation.js:239
startListening self-hosted:1175
result resource://gre/modules/ExtensionParent.jsm:935
withPendingBrowser resource://gre/modules/ExtensionParent.jsm:491
result resource://gre/modules/ExtensionParent.jsm:935
callAndLog resource://gre/modules/ExtensionParent.jsm:897
recvAPICall resource://gre/modules/ExtensionParent.jsm:934
AsyncFunctionNext self-hosted:692
Array.from(...).find(...).xulBrowser.contentWindow is null implementation.js:494
_loadIntoWindow jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/{a300a000-5e21-4ee0-a115-9ec8f4eaa92b}.xpi!/api/WindowListener/implementation.js:494
onLoadWindow jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/{a300a000-5e21-4ee0-a115-9ec8f4eaa92b}.xpi!/api/WindowListener/implementation.js:334
checkAndRunExtensionCode resource:///modules/ExtensionSupport.jsm:220
_checkAndRunMatchingExtensions resource:///modules/ExtensionSupport.jsm:192
registerWindowListener resource:///modules/ExtensionSupport.jsm:71
forEach self-hosted:4353
registerWindowListener resource:///modules/ExtensionSupport.jsm:70
startListening jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/{a300a000-5e21-4ee0-a115-9ec8f4eaa92b}.xpi!/api/WindowListener/implementation.js:239
startListening self-hosted:1175
result resource://gre/modules/ExtensionParent.jsm:935
withPendingBrowser resource://gre/modules/ExtensionParent.jsm:491
result resource://gre/modules/ExtensionParent.jsm:935
callAndLog resource://gre/modules/ExtensionParent.jsm:897
recvAPICall resource://gre/modules/ExtensionParent.jsm:934
AsyncFunctionNext self-hosted:692
Error: An unexpected error occurred 2 undefined
Array.from(...).find(...).xulBrowser.contentWindow is null implementation.js:494
_loadIntoWindow jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/emoji@ganss.org.xpi!/api/WindowListener/implementation.js:494
onLoadWindow jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/emoji@ganss.org.xpi!/api/WindowListener/implementation.js:334
checkAndRunExtensionCode resource:///modules/ExtensionSupport.jsm:220
_checkAndRunMatchingExtensions resource:///modules/ExtensionSupport.jsm:192
registerWindowListener resource:///modules/ExtensionSupport.jsm:71
forEach self-hosted:4353
registerWindowListener resource:///modules/ExtensionSupport.jsm:70
startListening jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/emoji@ganss.org.xpi!/api/WindowListener/implementation.js:239
startListening self-hosted:1175
result resource://gre/modules/ExtensionParent.jsm:935
withPendingBrowser resource://gre/modules/ExtensionParent.jsm:491
result resource://gre/modules/ExtensionParent.jsm:935
callAndLog resource://gre/modules/ExtensionParent.jsm:897
recvAPICall resource://gre/modules/ExtensionParent.jsm:934
AsyncFunctionNext self-hosted:692
Found 0 public keys and 0 secret keys (0 protected, 0 unprotected) RNPLib.jsm:288:15
Trying to load /Applications/Thunderbird.app/Contents/MacOS/libotr.dylib OTRLib.jsm:64:11
Successfully loaded OTR library /Applications/Thunderbird.app/Contents/MacOS/libotr.dylib OTRLib.jsm:72:13
Unknown Collection "thunderbird/hijack-blocklists" RemoteSettingsClient.jsm:160
UnknownCollectionError resource://services-settings/RemoteSettingsClient.jsm:160
sync resource://services-settings/RemoteSettingsClient.jsm:470
InterpretGeneratorResume self-hosted:1482
AsyncFunctionNext self-hosted:692
Unknown Collection "thunderbird/url-classifier-skip-urls" RemoteSettingsClient.jsm:160
UnknownCollectionError resource://services-settings/RemoteSettingsClient.jsm:160
sync resource://services-settings/RemoteSettingsClient.jsm:470
InterpretGeneratorResume self-hosted:1482
AsyncFunctionNext self-hosted:692
NS_ERROR_FILE_NOT_FOUND: 11 SessionStoreFunctions.jsm:106
Element.releaseCapture() is deprecated. Use Element.releasePointerCapture() instead. For more help https://developer.mozilla.org/docs/Web/API/Element/releasePointerCapture menupopup.js:178:13
NS_ERROR_FILE_NOT_FOUND: SessionStoreFunctions.jsm:106
SSF_updateSessionStoreForWindow resource://gre/modules/SessionStoreFunctions.jsm:106
UpdateSessionStoreForStorage resource://gre/modules/SessionStoreFunctions.jsm:49
Element.releaseCapture() is deprecated. Use Element.releasePointerCapture() instead. For more help https://developer.mozilla.org/docs/Web/API/Element/releasePointerCapture menupopup.js:178:13
1623208076391 addons.xpi-utils WARN disabling legacy extension followup@amulya.com
1623208076392 addons.xpi-utils WARN disabling legacy extension ThunderBrowse@thunderbrowse.com
1623208076392 addons.xpi-utils WARN disabling legacy extension {78136133-1994-415a-8d30-69d505d924fc}
1623208076392 addons.xpi-utils WARN disabling legacy extension {12345678-1234-1234-1234-123456789abc}
1623208076392 addons.xpi-utils WARN disabling legacy extension {0e2d954d-c1a6-4d83-8136-4fe7c5011d0e}
1623208076392 addons.xpi-utils WARN disabling legacy extension {1510928E-A4FA-43C2-A9AF-99E521B90BC5}
1623208076392 addons.xpi-utils WARN disabling legacy extension {3ed8cc52-86fc-4613-9026-c1ef969da4c3}
1623208076393 addons.xpi-utils WARN Add-on sendlater3@kamens.us is not compatible with application version.
NS_ERROR_FAILURE: No directory for uri=jsaddrbook://impab.sqlite AddrBookManager.jsm:253
1623208316086 addons.xpi-utils WARN disabling legacy extension followup@amulya.com
1623208316090 addons.xpi-utils WARN disabling legacy extension ThunderBrowse@thunderbrowse.com
1623208316092 addons.xpi-utils WARN disabling legacy extension {78136133-1994-415a-8d30-69d505d924fc}
1623208316094 addons.xpi-utils WARN disabling legacy extension {12345678-1234-1234-1234-123456789abc}
1623208316096 addons.xpi-utils WARN disabling legacy extension {0e2d954d-c1a6-4d83-8136-4fe7c5011d0e}
1623208316098 addons.xpi-utils WARN disabling legacy extension {1510928E-A4FA-43C2-A9AF-99E521B90BC5}
1623208316100 addons.xpi-utils WARN disabling legacy extension {3ed8cc52-86fc-4613-9026-c1ef969da4c3}
1623208316110 addons.xpi-utils WARN Add-on sendlater3@kamens.us is not compatible with application version.
Array.from(...).find(...).xulBrowser.contentWindow is null implementation.js:494
_loadIntoWindow jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/emoji@ganss.org.xpi!/api/WindowListener/implementation.js:494
onLoadWindow jar:file:///Users/ddk/Library/Thunderbird/Profiles/riqz0ivl.default/extensions/emoji@ganss.org.xpi!/api/WindowListener/implementation.js:334
checkAndRunExtensionCode resource:///modules/ExtensionSupport.jsm:220
_checkAndRunMatchingExtensions resource:///modules/ExtensionSupport.jsm:195
_addToListAndNotify resource:///modules/ExtensionSupport.jsm:179
_waitForLoad resource:///modules/ExtensionSupport.jsm:156
Prompter: internal dialogs not available in this context. Falling back to window prompt. Prompter.jsm:1084
set modalType resource://gre/modules/Prompter.jsm:1084
ModalPrompter resource://gre/modules/Prompter.jsm:1040
getPrompt resource://gre/modules/Prompter.jsm:65
CompleteGenericSendMessage chrome://messenger/content/messengercompose/MsgComposeCommands.js:4856
GenericSendMessage chrome://messenger/content/messengercompose/MsgComposeCommands.js:4792
SendMessage chrome://messenger/content/messengercompose/MsgComposeCommands.js:5252
doCommand chrome://messenger/content/messengercompose/MsgComposeCommands.js:925
doCommand chrome://messenger/content/messengercompose/MsgComposeCommands.js:1103
goDoCommand chrome://global/content/globalOverlay.js:123
oncommand chrome://messenger/content/messengercompose/messengercompose.xhtml:1
mailnews.smtp: Sending message <5c26d6fe-ac98-43fb-3041-6ed955b94d9d@earthlink.net> SmtpService.jsm:84:18
mailnews.smtp: S: 220 Welcome to smtp.oxsus-vadesecure.net ESMTP oxsus1nmtao02p smtp

SmtpClient.jsm:415:17
mailnews.smtp: C: EHLO [192.168.0.199] SmtpClient.jsm:582:19
mailnews.smtp: S: 250-smtp.oxsus-vadesecure.net ESMTP oxsus1nmtao02p smtp

250-AUTH LOGIN PLAIN

250-STARTTLS

250-8BITMIME

250-PIPELINING

250-HELP

250-CHUNKING

250-BINARYMIME

250 SIZE 52428800

SmtpClient.jsm:415:17
mailnews.smtp: C: STARTTLS SmtpClient.jsm:582:19
mailnews.smtp: S: 220 Ready to start TLS

SmtpClient.jsm:415:17
mailnews.smtp: C: EHLO [192.168.0.199] SmtpClient.jsm:582:19
mailnews.smtp: S: 250-hello TLS

250-AUTH LOGIN PLAIN

250-XCLIENT 587

250-8BITMIME

250-PIPELINING

250-HELP

250-CHUNKING

250-BINARYMIME

250 SIZE 52428800

SmtpClient.jsm:415:17
mailnews.smtp: Possible auth methods: SmtpClient.jsm:898:17
mailnews.smtp: Maximum allowd message size: 52428800 SmtpClient.jsm:904:19
mailnews.smtp: Error: No available auth method.
_authenticateUser resource:///modules/SmtpClient.jsm:638
_actionEHLO resource:///modules/SmtpClient.jsm:913
_onCommand resource:///modules/SmtpClient.jsm:507
_parse resource:///modules/SmtpClient.jsm:368
_onData resource:///modules/SmtpClient.jsm:416
SmtpClient.jsm:436:17
_onError resource:///modules/SmtpClient.jsm:436
_authenticateUser resource:///modules/SmtpClient.jsm:638
_actionEHLO resource:///modules/SmtpClient.jsm:913
_onCommand resource:///modules/SmtpClient.jsm:507
_parse resource:///modules/SmtpClient.jsm:368
_onData resource:///modules/SmtpClient.jsm:416
mailnews.send: Sending failed; Sending of the message failed., exitCode=2147500037, originalMsgURI= MessageSend.jsm:321:27
fail resource:///modules/MessageSend.jsm:321
_deliveryExitProcessing resource:///modules/MessageSend.jsm:611
sendDeliveryCallback resource:///modules/MessageSend.jsm:676
OnStopRunningUrl resource:///modules/MessageSend.jsm:1293
onerror resource:///modules/SmtpService.jsm:160
_onError resource:///modules/SmtpClient.jsm:445
_authenticateUser resource:///modules/SmtpClient.jsm:638
_actionEHLO resource:///modules/SmtpClient.jsm:913
_onCommand resource:///modules/SmtpClient.jsm:507
_parse resource:///modules/SmtpClient.jsm:368
_onData resource:///modules/SmtpClient.jsm:416
Prompter: internal dialogs not available in this context. Falling back to window prompt. Prompter.jsm:1084
set modalType resource://gre/modules/Prompter.jsm:1084
ModalPrompter resource://gre/modules/Prompter.jsm:1040
getPrompt resource://gre/modules/Prompter.jsm:65
getDefaultPrompt resource:///modules/MessageSend.jsm:300
fail resource:///modules/MessageSend.jsm:319
_deliveryExitProcessing resource:///modules/MessageSend.jsm:611
sendDeliveryCallback resource:///modules/MessageSend.jsm:676
OnStopRunningUrl resource:///modules/MessageSend.jsm:1293
onerror resource:///modules/SmtpService.jsm:160
_onError resource:///modules/SmtpClient.jsm:445
_authenticateUser resource:///modules/SmtpClient.jsm:638
_actionEHLO resource:///modules/SmtpClient.jsm:913
_onCommand resource:///modules/SmtpClient.jsm:507
_parse resource:///modules/SmtpClient.jsm:368
_onData resource:///modules/SmtpClient.jsm:416
_onData self-hosted:1175
mailnews.smtp: Closing connection... SmtpClient.jsm:169:17
mailnews.smtp: Socket closed. SmtpClient.jsm:485:17
NS_ERROR_FILE_NOT_FOUND: SessionStoreFunctions.jsm:106
SSF_updateSessionStoreForWindow resource://gre/modules/SessionStoreFunctions.jsm:106
UpdateSessionStoreForStorage resource://gre/modules/SessionStoreFunctions.jsm:49

From comment 5, you're using Encrypted password, from the logs, your SMTP server supports 250-AUTH LOGIN PLAIN. You need to change the authentication method to Normal password, then it will work.

Assignee: nobody → remotenonsense
Status: UNCONFIRMED → ASSIGNED
Ever confirmed: true
Regressed by: smtp-js
Target Milestone: --- → 91 Branch

Pushed by mkmelin@iki.fi:
https://hg.mozilla.org/comm-central/rev/6a3fcf78a619
Show specific auth error message in SmtpClient.jsm. r=mkmelin

Status: ASSIGNED → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Component: Untriaged → Networking: SMTP
Product: Thunderbird → MailNews Core

Comment on attachment 9226064 [details]
Bug 1714878 - Show specific auth error message in SmtpClient.jsm. r=mkmelin

[Approval Request Comment]
Regression caused by (bug #): bug 1661694
User impact if declined: User doesn't get helpful error message when the configured auth method is not supported by the server
Testing completed (on c-c, etc.): c-c
Risk to taking this patch (and alternatives if risky): low

Attachment #9226064 - Flags: approval-comm-beta?

Comment on attachment 9226064 [details]
Bug 1714878 - Show specific auth error message in SmtpClient.jsm. r=mkmelin

[Triage Comment]
Approved for beta

Attachment #9226064 - Flags: approval-comm-beta? → approval-comm-beta+
Attached image Send message error.jpeg

Thanks so much for your responses and the work you do on TB.

The recommendation Ping Chen gave about changing my password to Normal has worked, thanks!

BUT, there was one small wrinkle.

Right AFTER I changed the password status to normal, a few times I again got the same error message (see message directly above this one) as before.

Interestingly, when I clicked on OK, and then attempted to send the same email again, it went out just fine.

This was replicated, as it happened around 3 times, all in the space of a day or two.

The phenomena then stopped happening, and hasn't recurred since.

FYI,

Tim

Can you please try again and report back when TB 90.0b2 is released, thanks.

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: