SMTP auth error when sending the first message of the session
I have 2 clients a configuration with 4 IMAP accouns, 2 of which are email addresses of the domain @virgilio.it.
The 2 virgilio account work regoularly, except when sending the first message after starting a thunderbird session. Every time one OR the other account asks for the smtp password. Then I click on retry one or maybe two times and it starts to work normally.
I attach a log of the smtp session:
2017-05-30 12:59:11.502000 UTC - 0[170f140]: SMTP Connecting to: out.virgilio.it 2017-05-30 12:59:11.673000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.673000 UTC - 0[170f140]: SMTP Response: 220 smtp-42.iol.local smtp-42.iol.local ESMTP server ready 2017-05-30 12:59:11.673000 UTC - 0[170f140]: SMTP entering state: 14 2017-05-30 12:59:11.673000 UTC - 0[170f140]: SMTP Send: EHLO [10.0.0.112] 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP Response: 250-smtp-42.iol.local hello [79.0.226.235], pleased to meet you 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP Response: 250-HELP 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP Response: 250-AUTH LOGIN PLAIN CRAM-MD5 DIGEST-MD5 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP Response: 250-SIZE 50000000 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP Response: 250-8BITMIME 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP Response: 250 OK 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 4 2017-05-30 12:59:11.722000 UTC - 0[170f140]: SMTP entering state: 21 2017-05-30 12:59:11.723000 UTC - 0[170f140]: SMTP auth: server caps 0x12310, pref 0x300, failed 0x0, avail caps 0x300 2017-05-30 12:59:11.723000 UTC - 0[170f140]: (GSSAPI = 0x800, CRAM = 0x2000, NTLM = 0x4000, MSN = 0x8000, PLAIN = 0x200, LOGIN = 0x100, EXTERNAL = 0x400) 2017-05-30 12:59:11.723000 UTC - 0[170f140]: trying auth method 0x200 2017-05-30 12:59:11.723000 UTC - 0[170f140]: SMTP entering state: 16 2017-05-30 12:59:11.723000 UTC - 0[170f140]: SMTP AuthLoginStep1() for <XXXXXXXXX@virgilio.it>@P]®R@]®R 2017-05-30 12:59:11.728000 UTC - 0[170f140]: PLAIN auth 2017-05-30 12:59:11.728000 UTC - 0[170f140]: Logging suppressed for this command (it probably contained authentication information) 2017-05-30 12:59:11.783000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:11.783000 UTC - 0[170f140]: SMTP Response: 535 Invalid User or Password [smtp-42.iol.local; VIR_300] 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP entering state: 18 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP Login response, code 535 2017-05-30 12:59:11.784000 UTC - 0[170f140]: marking auth method 0x200 failed 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP auth: server caps 0x12310, pref 0x300, failed 0x200, avail caps 0x100 2017-05-30 12:59:11.784000 UTC - 0[170f140]: (GSSAPI = 0x800, CRAM = 0x2000, NTLM = 0x4000, MSN = 0x8000, PLAIN = 0x200, LOGIN = 0x100, EXTERNAL = 0x400) 2017-05-30 12:59:11.784000 UTC - 0[170f140]: trying auth method 0x100 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP: login failed: failed 200, current 100 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP entering state: 21 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP auth: server caps 0x12310, pref 0x300, failed 0x200, avail caps 0x100 2017-05-30 12:59:11.784000 UTC - 0[170f140]: (GSSAPI = 0x800, CRAM = 0x2000, NTLM = 0x4000, MSN = 0x8000, PLAIN = 0x200, LOGIN = 0x100, EXTERNAL = 0x400) 2017-05-30 12:59:11.784000 UTC - 0[170f140]: trying auth method 0x100 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP entering state: 15 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP: MSN or LOGIN auth, step 0 2017-05-30 12:59:11.784000 UTC - 0[170f140]: SMTP Send: AUTH LOGIN 2017-05-30 12:59:11.786000 UTC - 0[170f140]: SMTP connection dropped after 261 total bytes read 2017-05-30 12:59:11.787000 UTC - 0[170f140]: SMTP Login response, code 535 2017-05-30 12:59:11.787000 UTC - 0[170f140]: marking auth method 0x100 failed 2017-05-30 12:59:11.787000 UTC - 0[170f140]: SMTP auth: server caps 0x12311, pref 0x300, failed 0x300, avail caps 0x0 2017-05-30 12:59:11.787000 UTC - 0[170f140]: (GSSAPI = 0x800, CRAM = 0x2000, NTLM = 0x4000, MSN = 0x8000, PLAIN = 0x200, LOGIN = 0x100, EXTERNAL = 0x400) 2017-05-30 12:59:11.787000 UTC - 0[170f140]: no auth method remaining 2017-05-30 12:59:11.787000 UTC - 0[170f140]: SMTP: ask user what to do (after login failed): new password, retry or cancel 2017-05-30 12:59:13.660000 UTC - 0[170f140]: retry button pressed 2017-05-30 12:59:13.660000 UTC - 0[170f140]: SMTP: login failed: failed 0, current 0 2017-05-30 12:59:13.661000 UTC - 0[170f140]: SMTP Connecting to: out.virgilio.it 2017-05-30 12:59:13.832000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.832000 UTC - 0[170f140]: SMTP Response: 220 smtp-42.iol.local smtp-42.iol.local ESMTP server ready 2017-05-30 12:59:13.832000 UTC - 0[170f140]: SMTP entering state: 14 2017-05-30 12:59:13.832000 UTC - 0[170f140]: SMTP Send: EHLO [10.0.0.112]
2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP Response: 250-smtp-42.iol.local hello [79.0.226.235], pleased to meet you 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP Response: 250-HELP 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP Response: 250-AUTH LOGIN PLAIN CRAM-MD5 DIGEST-MD5 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP Response: 250-SIZE 50000000 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP Response: 250-8BITMIME 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP Response: 250 OK 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 4 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 21 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP auth: server caps 0x12310, pref 0x300, failed 0x0, avail caps 0x300 2017-05-30 12:59:13.883000 UTC - 0[170f140]: (GSSAPI = 0x800, CRAM = 0x2000, NTLM = 0x4000, MSN = 0x8000, PLAIN = 0x200, LOGIN = 0x100, EXTERNAL = 0x400) 2017-05-30 12:59:13.883000 UTC - 0[170f140]: trying auth method 0x200 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP entering state: 16 2017-05-30 12:59:13.883000 UTC - 0[170f140]: SMTP AuthLoginStep1() for <XXXXXX@virgilio.it>@P]®R@]®R 2017-05-30 12:59:13.883000 UTC - 0[170f140]: PLAIN auth 2017-05-30 12:59:13.883000 UTC - 0[170f140]: Logging suppressed for this command (it probably contained authentication information) 2017-05-30 12:59:13.947000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:13.947000 UTC - 0[170f140]: SMTP Response: 235 ... authentication succeeded 2017-05-30 12:59:13.947000 UTC - 0[170f140]: SMTP entering state: 18 2017-05-30 12:59:13.947000 UTC - 0[170f140]: SMTP Login response, code 235 2017-05-30 12:59:13.947000 UTC - 0[170f140]: SMTP entering state: 3 2017-05-30 12:59:13.947000 UTC - 0[170f140]: SMTP Send: MAIL FROM:<XXXXXXX@virgilio.it> SIZE=431 2017-05-30 12:59:14.048000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:14.049000 UTC - 0[170f140]: SMTP Response: 250 <XXXXXXX@virgilio.it> sender ok 2017-05-30 12:59:14.049000 UTC - 0[170f140]: SMTP entering state: 5 2017-05-30 12:59:14.049000 UTC - 0[170f140]: SMTP Send: RCPT TO:<XXXXXXX@gmail.com> 2017-05-30 12:59:14.102000 UTC - 0[170f140]: SMTP entering state: 0 2017-05-30 12:59:14.103000 UTC - 0[170f140]: SMTP Response: 250 <XXXXXXX@gmail.com> recipient ok 2017-05-30 12:59:14.103000 UTC - 0[170f140]: SMTP entering state: 6
All Replies (4)
Does your anti virus have a password vault type function?
Have you tried disabling the anti virus outgoing mail scanner?
They have norton internet security. I did not try to disable it, but I don't have an antivirus and I experience the same problem when I configure a new thunderbird on my workstation.
Looks like a server problem. It behaves the same way using other clients too, so nevermind.
You speak like this is a workplace. in that situation there may well be an edge appliance or corporate spam filter/firewall that is causing you issues. This may also be an issue if they have an internal mail server. sending mail ports might be automatically routed to the mail server and it takes time to establish a route to the server you are attempting to connect to.