We have moved to a new Sailfish OS Forum. Please start new discussions there.
4

email login failed authentication [not relevant]

Tracked by Jolla (Rejected)

asked 2015-02-27 00:29:55 +0300

pfbach gravatar image

updated 2015-02-27 11:47:03 +0300

After update 1.1.2.15 and 1.1.2.16 I am unable to sync emails.

From Jolla debugging of messageserver5;

[577] Feb 26 23:26:18 [Debug] Messaging :  Running action RetrieveMessageListRequest 2207613190146 
[577] Feb 26 23:26:18 [Debug] Messaging :  Setting imap strategy 31ImapRetrieveMessageListStrategy 
[577] Feb 26 23:26:18 [Debug] IMAP :  "3" About to open connection "email@mailserver.no" "mail.mailserver.no" 
[577] Feb 26 23:26:18 [Debug] Messaging :  QMailTransport::Socket::Socket SET PROXY host= "" port= 0 
[577] Feb 26 23:26:18 [Debug] Messaging :  Opening connection -  "mail.mailserver.no" : 993  SSL 
[577] Feb 26 23:26:19 [Debug] Messaging :  IMAP : connection established 
[577] Feb 26 23:26:19 [Warning] Encrypted connect warnings: "'The certificate is self-signed, and untrusted'" 
[577] Feb 26 23:26:19 [Warning] Accepting untrusted certificates 
[577] Feb 26 23:26:19 [Debug] Messaging :  IMAP : Secure connection established 
[577] Feb 26 23:26:19 [Debug] IMAP :  "3" RECV: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot ready. 
[577] Feb 26 23:26:19 [Debug] IMAP :  "3" SEND a001 CAPABILITY 
[577] Feb 26 23:26:19 [Debug] IMAP :  "3" RECV: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN 
[577] Feb 26 23:26:19 [Debug] IMAP :  "3" RECV: a001 OK Pre-login capabilities listed, post-login capabilities have more. 
[577] Feb 26 23:26:19 [Debug] Messaging :  void SSOSessionManager::recreateSsoIdentity() Recreating SSO identity using auth method  "password" 
[577] Feb 26 23:26:19 [Debug] authsessionimpl.cpp 174 initInterface 
[577] Feb 26 23:26:19 [Debug] authsessionimpl.cpp 270 process sending to queue 
[577] Feb 26 23:26:21 [Debug] dbusoperationqueuehandler.cpp 137 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)" 
[577] Feb 26 23:26:21 [Debug] dbusoperationqueuehandler.cpp 149 execQueuedOperations 
[577] Feb 26 23:26:21 [Debug] authsessionimpl.cpp 265 process sending to daemon 
[577] Feb 26 23:26:21 [Debug] IMAP :  Got SSO response 
[577] Feb 26 23:26:21 [Debug] IMAP :  "3" SEND a002 CAPABILITY 
[577] Feb 26 23:26:21 [Debug] IMAP :  "3" RECV: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN 
[577] Feb 26 23:26:21 [Debug] IMAP :  "3" RECV: a002 OK Pre-login capabilities listed, post-login capabilities have more. 
[577] Feb 26 23:26:21 [Debug] IMAP :  "3" SEND a003 AUTHENTICATE CRAM-MD5 
[577] Feb 26 23:26:21 [Debug] IMAP :  "3" RECV: a003 NO [ALERT] Unsupported authentication mechanism. 
[577] Feb 26 23:26:21 [Debug] Messaging :  void SSOSessionManager::credentialsNeedUpdate() Setting credentials need update for the service  "imap4"  from account  2  using authentication method  "password" 
[577] Feb 26 23:26:21 [Debug] Messaging :  Action completed 2207613190146 result failure

On my mailserver I get the following:

Feb 26 23:26:22 auth: Info: login(?,168.235.xx.xx): Empty username
Feb 26 23:26:23 imap-login: Info: Authenticate CRAM-MD5 failed: Unsupported authentication mechanism.: user=<>, method=CRAM-MD5, rip=80.203.xx.xx, lip=192.168.xx.xx, TLS, session=<2EbvPQUQFABQy2fM>
Feb 26 23:26:23 imap-login: Info: Disconnected (tried to use unsupported auth mechanism): user=<>, method=CRAM-MD5, rip=80.203.xx.xx, lip=192.168.xx.xx, TLS: Disconnected, session=<2EbvPQUQFABQy2fM>

It was working before the update. Mailserver says PLAIN and LOGIN, so why is mailprogram trying CRAM-MD5?

Update

Recreating account 150227

[9631] Feb 27 10:35:09 [Debug] identityimpl.cpp 103 updateState Updating state:  NeedsUpdate 
[9631] Feb 27 10:35:09 [Debug] identityimpl.cpp 711 infoUpdated SERVER INFO UPDATED. NeedsUpdate " 19 " 
[9631] Feb 27 10:35:09 [Debug] identityimpl.cpp 103 updateState Updating state:  NeedsUpdate 
[9631] Feb 27 10:35:09 [Debug] identityimpl.cpp 711 infoUpdated SERVER INFO UPDATED. NeedsUpdate " 17 " 
[9631] Feb 27 10:35:10 [Warning] QSharedPointer<Accounts::Account> QMailStorePrivate::getEmailAccount(Accounts::AccountId) E-mail Services not found, make sure that *.service and *.provider files are properly installed and e-mail services are enabled. 
[9631] Feb 27 10:35:10 [Warning] 9631 Unable to account 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 103 updateState Updating state:  NeedsUpdate 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 711 infoUpdated SERVER INFO UPDATED. NeedsUpdate " 18 " 
[9631] Feb 27 10:35:10 [Warning] QSharedPointer<Accounts::Account> QMailStorePrivate::getEmailAccount(Accounts::AccountId) E-mail Services not found, make sure that *.service and *.provider files are properly installed and e-mail services are enabled. 
[9631] Feb 27 10:35:10 [Warning] 9631 Unable to account 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 103 updateState Updating state:  PendingRegistration 
[9631] Feb 27 10:35:10 [Debug] Messaging :  bool SSOSessionManager::createSsoIdentity(const QMailAccountId&, const QString&) Creating SSO identity for the service  "imap4"  from account  17  using authentication method  "password" 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 174 initInterface 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 270 process sending to queue 
[9631] Feb 27 10:35:10 [Debug] IMAP :  void ImapClient::setAccount(const QMailAccountId&) SSO identity is found for account id:  17 
[9631] Feb 27 10:35:10 [Debug] Messaging :  Registering service: "imap4" for account: 17 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 103 updateState Updating state:  PendingRegistration 
[9631] Feb 27 10:35:10 [Debug] Messaging :  bool SSOSessionManager::createSsoIdentity(const QMailAccountId&, const QString&) Creating SSO identity for the service  "smtp"  from account  17  using authentication method  "password" 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 174 initInterface 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 270 process sending to queue 
[9631] Feb 27 10:35:10 [Debug] SMTP :  void SmtpClient::setAccount(const QMailAccountId&) SSO identity is found for account id:  17 
[9631] Feb 27 10:35:10 [Debug] Messaging :  Registering service: "smtp" for account: 17 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 103 updateState Updating state:  PendingRegistration 
[9631] Feb 27 10:35:10 [Debug] Messaging :  bool SSOSessionManager::createSsoIdentity(const QMailAccountId&, const QString&) Creating SSO identity for the service  "imap4"  from account  17  using authentication method  "password" 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 174 initInterface 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 270 process sending to queue 
[9631] Feb 27 10:35:10 [Debug] IMAP :  void ImapClient::setAccount(const QMailAccountId&) SSO identity is found for account id:  17 
[9631] Feb 27 10:35:10 [Debug] Messaging :  Registering service: "imap4" for account: 17 
[9631] Feb 27 10:35:10 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:10 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 103 updateState Updating state:  Ready 
[9631] Feb 27 10:35:10 [Debug] dbusoperationqueuehandler.cpp 137 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)" 
[9631] Feb 27 10:35:10 [Debug] dbusoperationqueuehandler.cpp 149 execQueuedOperations 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 265 process sending to daemon 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 103 updateState Updating state:  Ready 
[9631] Feb 27 10:35:10 [Debug] dbusoperationqueuehandler.cpp 137 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)" 
[9631] Feb 27 10:35:10 [Debug] dbusoperationqueuehandler.cpp 149 execQueuedOperations 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 265 process sending to daemon 
[9631] Feb 27 10:35:10 [Debug] identityimpl.cpp 103 updateState Updating state:  Ready 
[9631] Feb 27 10:35:10 [Debug] dbusoperationqueuehandler.cpp 137 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)" 
[9631] Feb 27 10:35:10 [Debug] dbusoperationqueuehandler.cpp 149 execQueuedOperations 
[9631] Feb 27 10:35:10 [Debug] authsessionimpl.cpp 265 process sending to daemon 
[9631] Feb 27 10:35:10 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:10 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:10 [Debug] IMAP :  Got SSO response 
[9631] Feb 27 10:35:10 [Debug] Messaging :  Running action RetrieveFolderListRequest 32401233281026 
[9631] Feb 27 10:35:10 [Debug] Messaging :  Setting imap strategy 30ImapRetrieveFolderListStrategy 
[9631] Feb 27 10:35:10 [Debug] IMAP :  "7" About to open connection "email@mailserver.no" "mail.mailserver.no" 
[9631] Feb 27 10:35:10 [Debug] Messaging :  QMailTransport::Socket::Socket SET PROXY host= "" port= 0 
[9631] Feb 27 10:35:10 [Debug] Messaging :  Opening connection -  "mail.mailserver.no" : 993  SSL 
[9631] Feb 27 10:35:13 [Debug] IMAP :  Got SSO response 
[9631] Feb 27 10:35:13 [Debug] SMTP :  Got SSO response 
[9631] Feb 27 10:35:13 [Debug] Messaging :  IMAP : connection established 
[9631] Feb 27 10:35:13 [Warning] Encrypted connect warnings: "'The certificate is self-signed, and untrusted'" 
[9631] Feb 27 10:35:13 [Warning] Accepting untrusted certificates 
[9631] Feb 27 10:35:13 [Debug] Messaging :  IMAP : Secure connection established 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN] Dovecot ready. 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" SEND a001 CAPABILITY 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE AUTH=PLAIN AUTH=LOGIN 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: a001 OK Pre-login capabilities listed, post-login capabilities have more. 
[9631] Feb 27 10:35:13 [Debug] IMAP :  Discovering authentication from capabilities for account id: 17 
[9631] Feb 27 10:35:13 [Debug] IMAP :  Auths found:  ("PLAIN", "LOGIN") 
[9631] Feb 27 10:35:13 [Debug] IMAP :  Returning auth PLAIN 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] identityimpl.cpp 103 updateState Updating state:  PendingRegistration 
[9631] Feb 27 10:35:13 [Debug] Messaging :  bool SSOSessionManager::createSsoIdentity(const QMailAccountId&, const QString&) Creating SSO identity for the service  "imap4"  from account  17  using authentication method  "password" 
[9631] Feb 27 10:35:13 [Debug] authsessionimpl.cpp 174 initInterface 
[9631] Feb 27 10:35:13 [Debug] authsessionimpl.cpp 270 process sending to queue 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::setAccount(const QMailAccountId&) SSO identity is found for account id:  17 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" SEND a002 AUTHENTICATE PLAIN 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] identityimpl.cpp 103 updateState Updating state:  Ready 
[9631] Feb 27 10:35:13 [Debug] dbusoperationqueuehandler.cpp 137 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)" 
[9631] Feb 27 10:35:13 [Debug] dbusoperationqueuehandler.cpp 149 execQueuedOperations 
[9631] Feb 27 10:35:13 [Debug] authsessionimpl.cpp 265 process sending to daemon 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: +  
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" SEND SEND: <login hidden> 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND UNSELECT CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS SPECIAL-USE 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: a002 OK Logged in 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] identityimpl.cpp 103 updateState Updating state:  PendingRegistration 
[9631] Feb 27 10:35:13 [Debug] Messaging :  bool SSOSessionManager::createSsoIdentity(const QMailAccountId&, const QString&) Creating SSO identity for the service  "imap4"  from account  17  using authentication method  "password" 
[9631] Feb 27 10:35:13 [Debug] authsessionimpl.cpp 174 initInterface 
[9631] Feb 27 10:35:13 [Debug] authsessionimpl.cpp 270 process sending to queue 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::setAccount(const QMailAccountId&) SSO identity is found for account id:  17 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" SEND a003 ENABLE QRESYNC CONDSTORE 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] identityimpl.cpp 103 updateState Updating state:  Ready 
[9631] Feb 27 10:35:13 [Debug] dbusoperationqueuehandler.cpp 137 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)" 
[9631] Feb 27 10:35:13 [Debug] dbusoperationqueuehandler.cpp 149 execQueuedOperations 
[9631] Feb 27 10:35:13 [Debug] authsessionimpl.cpp 265 process sending to daemon 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * ENABLED QRESYNC CONDSTORE 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: a003 OK Enabled. 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" SEND a004 LIST "" "*" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren \Junk) "." "Junk" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren) "." "Templates" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren \Drafts) "." "Drafts" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasChildren) "." "INBOX" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren) "." "INBOX.Tagesbericht" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren) "." "INBOX.DNBNor" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren) "." "Deleted Messages" 
[9631] Feb 27 10:35:13 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren \Sent) "." "Sent" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren \Trash) "." "Trash" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  "7" RECV: * LIST (\HasNoChildren \Sent) "." "Sent Messages" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  "7" RECV: a004 OK List completed. 
[9631] Feb 27 10:35:14 [Debug] Messaging :  Action completed 32401233281026 result success 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  Got SSO response 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:14 [Debug] Messaging :  Running action TransmitMessagesRequest 32401233281028 
[9631] Feb 27 10:35:14 [Debug] SMTP :  newConnection 
[9631] Feb 27 10:35:14 [Debug] SMTP :  Open SMTP connection 
[9631] Feb 27 10:35:14 [Debug] Messaging :  QMailTransport::Socket::Socket SET PROXY host= "" port= 0 
[9631] Feb 27 10:35:14 [Debug] Messaging :  Opening connection -  "mail.mailserver.no" : 587  TLS 
[9631] Feb 27 10:35:14 [Debug] Messaging :  SMTP : connection established 
[9631] Feb 27 10:35:18 [Debug] SMTP :  RECV: "220 hostname.mailserver.no ESMTP Exim 4.80 Fri, 27 Feb 2015 10:35:18 +0100" 
[9631] Feb 27 10:35:18 [Debug] SMTP :  SEND: "EHLO [127.0.0.1]" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-hostname.mailserver.no Hello xx.xx-xx-xx.isp.com [xx.xx.xx.xx]" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-SIZE 52428800" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-8BITMIME" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-PIPELINING" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-AUTH LOGIN PLAIN" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-STARTTLS" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250 HELP" 
[9631] Feb 27 10:35:21 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:21 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  SEND: "STARTTLS" 
[9631] Feb 27 10:35:21 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:21 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "220 TLS go ahead" 
[9631] Feb 27 10:35:21 [Warning] Encrypted connect warnings: "'The certificate is self-signed, and untrusted'" 
[9631] Feb 27 10:35:21 [Warning] Accepting untrusted certificates 
[9631] Feb 27 10:35:21 [Debug] Messaging :  SMTP : Secure connection established 
[9631] Feb 27 10:35:21 [Debug] SMTP :  Connected 
[9631] Feb 27 10:35:21 [Debug] SMTP :  SEND: "EHLO [127.0.0.1]" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-hostname.mailserver.no Hello xx.xx-xx-xx.isp.com [xx.xx.xx.xx]" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-SIZE 52428800" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-8BITMIME" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-PIPELINING" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250-AUTH LOGIN PLAIN" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  RECV: "250 HELP" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  Discovering authentication from capabilities for account id: 17 
[9631] Feb 27 10:35:21 [Debug] SMTP :  Auths found:  ("AUTH", "LOGIN", "PLAIN", "AUTH", "LOGIN", "PLAIN") 
[9631] Feb 27 10:35:21 [Debug] SMTP :  Returning auth PLAIN 
[9631] Feb 27 10:35:21 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:21 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:21 [Debug] SMTP :  SEND: "AUTH PLAIN <login hidden>" 
[9631] Feb 27 10:35:22 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:22 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "email@mailserver.no" 
[9631] Feb 27 10:35:22 [Debug] SMTP :  RECV: "235 Authentication succeeded" 
[9631] Feb 27 10:35:22 [Debug] SMTP :  SEND: "QUIT" 
[9631] Feb 27 10:35:22 [Debug] SMTP :  Closed connection 
[9631] Feb 27 10:35:22 [Debug] Messaging :  Action completed 32401233281028 result success 
[9631] Feb 27 10:35:24 [Debug] IMAP :  "7" SEND a005 LOGOUT 
[9631] Feb 27 10:35:25 [Debug] IMAP :  "7" RECV: * BYE Logging out 
[9631] Feb 27 10:35:25 [Debug] IMAP :  "7" RECV: a005 OK Logout completed.

Okay, so this works.

edit retag flag offensive reopen delete

The question has been closed for the following reason "question is not relevant or outdated" by Spam Hunter
close date 2020-03-07 23:28:25.101597

Comments

Seems like a bug.. @VDVsx?

tigeli ( 2015-02-27 00:43:05 +0300 )edit

You must mean 1.1.2.15 / 1.1.2.16 versions?

foss4ever ( 2015-02-27 01:02:34 +0300 )edit

Yes, you are right. I have corrected the question.

pfbach ( 2015-02-27 01:05:01 +0300 )edit

@pfbach - Please collect a log while adding the account, I don't see the lines here where auth is detected, might be that at some point you configured this manually ? (using ag-tool)

VDVsx ( 2015-02-27 08:08:02 +0300 )edit

It works if I recreate the account.

However, is this expected approach to fix this after an update?

I don't know ag-tool.

pfbach ( 2015-02-27 11:46:33 +0300 )edit

1 Answer

Sort by » oldest newest most voted
1

answered 2015-09-22 11:33:54 +0300

Arturo gravatar image

updated 2015-09-22 12:00:05 +0300

Even today it is not possible to log in! It is absent the possibility to change the authentication method or otherwise automatically after failing MD5 authentication to try again with another method (plain or login).

Easy incurring server who have a separate user base between the MD5 and the plain; with the first only with very few users; the problem does not occur with other devices or operating systems more famous, therefore, it is simple to solve: first I try MD5, if it fails try another method among those available!!!

Here, the log of an attempt:

Sep 22 10:46:52 dark dovecot: imap-login: Disconnected (auth failed, 1 attempts in 5 secs): user=<snailbox>, method=CRAM-MD5, rip=1.1.2.133, lip=1.1.1.9, secured, session=<*****>
Sep 22 10:46:52 dark dovecot: auth: Debug: auth client connected (pid=11718)
Sep 22 10:46:52 dark dovecot: auth: Debug: client in: AUTH^I1^ICRAM-MD5^Iservice=imap^Isecured^Ino-penalty^Isession=*****^Ilip=1.1.1.9^Irip=1.1.2.133^Ilport=143^Irport=42913
Sep 22 10:46:52 dark dovecot: auth-worker(11709): Debug: shadow(snailbox,1.1.2.133): lookup
Sep 22 10:46:52 dark dovecot: auth-worker(11709): password(snailbox,1.1.2.133): Requested CRAM-MD5 scheme, but we have only CRYPT (input: ***************)
Sep 22 10:46:52 dark dovecot: auth: Debug: passwd-file(snailbox,1.1.2.133,<*****>): lookup: user=snailbox file=/etc/dovecot/dovecot.cram-md5
Sep 22 10:46:52 dark dovecot: auth: passwd-file(snailbox,1.1.2.133,<*****>): unknown user

But the server reports as many as 3 methods to authenticate; and I repeat, easy to find a server with the store MD5 empty or with few users, separate from the archive used to authenticate with passwords in clear text (it seems almost natural):

* OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE IDLE STARTTLS AUTH=PLAIN AUTH=LOGIN AUTH=CRAM-MD5] Service ready.

Please, fix it now! I do not read the mail by seven months, I have a few thousand messages waiting :-D

edit flag offensive delete publish link more

Question tools

Follow
3 followers

Stats

Asked: 2015-02-27 00:29:55 +0300

Seen: 1,126 times

Last updated: Sep 22 '15