Email are only synced initally, aftersward fails silently [released]
asked 2014-12-18 21:31:59 +0300
Hello!
Hello after setting up an "General mail" account with IMAP4, I can sync initally my emails and see all of them. All subsequent syncs, automatic or manually, silently fail. Neither new mails are shown nor removed mails are deleted from the inbox. I discovered a limited workaround, in scrolling the howl way down and executing "Get more mails" as described here. This will show up new emails in the inbox, but doesn't delete already removed ones by an other client (in my case Evolution on a ThinkPad with GNU/Linux).
I'm using a Jolla with release 1.0.8.21 and "General mail" with IMAP4, system language is English (UK) and timezone is automatic (Berlin/Germany in my case). My mailbox is hosted by manitu.de which provides access to my own mails via "mail.manitu.de" (SSL-Cerificate matches) or via my own domain "ttyhoney.com" (SSL-Ceritficate doesn't match, therefore I'm using the option "Accept untrusted certificates").
I followed this instructions and enabled logging. As far as it looks to me, their seems to be at least an issue with a SQL-INSERT against an internal database (value seems to be empty/null)?
[943] Dec 18 19:57:38 [Warning] void QCopClient::connectToServer() QLocalSocket::ServerNotFoundError "QLocalSocket::connectToServer: Invalid name"
[943] Dec 18 19:57:38 [Debug] Messaging : MessageServer ctor begin
[943] Dec 18 19:57:38 [Debug] Messaging : opening database
[943] Dec 18 19:57:39 [Debug] Messaging : Custom Field: "qmf-smtp-capabilities-listed" = "true"
[943] Dec 18 19:57:39 [Debug] ActiveSync : ActiveSyncServicePlugin::ActiveSyncServicePlugin()
[943] Dec 18 19:57:39 [Debug] ActiveSync : virtual QString ActiveSyncServicePlugin::key() const
[943] Dec 18 19:57:39 [Debug] identityimpl.cpp 103 updateState Updating state: PendingRegistration
[943] Dec 18 19:57:39 [Debug] Messaging : bool SSOSessionManager::createSsoIdentity(const QMailAccountId&, const QString&, int) Creating SSO identity for the service "imap4" from account 16 using authentication method "password"
[943] Dec 18 19:57:39 [Debug] authsessionimpl.cpp 174 initInterface
[943] Dec 18 19:57:39 [Debug] authsessionimpl.cpp 270 process sending to queue
[943] Dec 18 19:57:39 [Debug] IMAP : void ImapClient::setAccount(const QMailAccountId&) SSO identity is found for account id: 16
[943] Dec 18 19:57:39 [Debug] Messaging : Registering service: "imap4" for account: 16
[943] Dec 18 19:57:39 [Debug] identityimpl.cpp 103 updateState Updating state: PendingRegistration
[943] Dec 18 19:57:39 [Debug] Messaging : bool SSOSessionManager::createSsoIdentity(const QMailAccountId&, const QString&, int) Creating SSO identity for the service "smtp" from account 16 using authentication method "password"
[943] Dec 18 19:57:39 [Debug] authsessionimpl.cpp 174 initInterface
[943] Dec 18 19:57:39 [Debug] authsessionimpl.cpp 270 process sending to queue
[943] Dec 18 19:57:39 [Debug] SMTP : void SmtpClient::setAccount(const QMailAccountId&) SSO identity is found for account id: 16
[943] Dec 18 19:57:39 [Debug] Messaging : Registering service: "smtp" for account: 16
[943] Dec 18 19:57:39 [Debug] Messaging : Initiating messageserver plugins.
[943] Dec 18 19:57:39 [Debug] Store observer initialized
[943] Dec 18 19:57:39 [Debug] No published notification!
[943] Dec 18 19:57:41 [Debug] identityimpl.cpp 103 updateState Updating state: Ready
[943] Dec 18 19:57:41 [Debug] dbusoperationqueuehandler.cpp 135 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)"
[943] Dec 18 19:57:41 [Debug] dbusoperationqueuehandler.cpp 147 execQueuedOperations
[943] Dec 18 19:57:41 [Debug] authsessionimpl.cpp 265 process sending to daemon
[943] Dec 18 19:57:41 [Debug] identityimpl.cpp 103 updateState Updating state: Ready
[943] Dec 18 19:57:41 [Debug] dbusoperationqueuehandler.cpp 135 execQueuedOperations Executing cached oparation: SIGNATURE: "process(SessionData,QString)"
[943] Dec 18 19:57:41 [Debug] dbusoperationqueuehandler.cpp 147 execQueuedOperations
[943] Dec 18 19:57:41 [Debug] authsessionimpl.cpp 265 process sending to daemon
[943] Dec 18 19:57:42 [Debug] SMTP : Got SSO response
[943] Dec 18 19:57:42 [Debug] IMAP : Got SSO response
[943] Dec 18 19:58:17 [Debug] identityimpl.cpp 103 updateState Updating state: NeedsRegistration
[943] Dec 18 19:58:17 [Debug] identityimpl.cpp 103 updateState Updating state: NeedsRegistration
[943] Dec 18 19:59:23 [Debug] Messaging : Running action RetrieveMessageListRequest 5196910428162
[943] Dec 18 19:59:23 [Debug] Messaging : Setting imap strategy 31ImapRetrieveMessageListStrategy
[943] Dec 18 19:59:24 [Debug] IMAP : "1" About to open connection "foo" "ttyhoney.com"
[943] Dec 18 19:59:24 [Debug] bool ImapConfiguration::acceptUntrustedCertificates() const "1"
[943] Dec 18 19:59:24 [Debug] Messaging : QMailTransport::Socket::Socket SET PROXY host= "" port= 0
[943] Dec 18 19:59:24 [Debug] Messaging : Opening connection - "ttyhoney.com" : 993 SSL
[943] Dec 18 19:59:24 [Debug] Messaging : IMAP : connection established
[943] Dec 18 19:59:24 [Warning] Encrypted connect warnings: "'The host name did not match any of the valid hosts for this certificate', 'The issuer certificate of a locally looked up certificate could not be found', 'The root CA certificate is not trusted for this purpose', 'No certificates could be verified'"
[943] Dec 18 19:59:24 [Warning] Accepting untrusted certificates
[943] Dec 18 19:59:24 [Debug] Messaging : IMAP : Secure connection established
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE AUTH=PLAIN AUTH=LOGIN] Dovecot ready.
[943] Dec 18 19:59:24 [Debug] IMAP : "1" SEND a001 CAPABILITY
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND UNSELECT IDLE CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS QUOTA AUTH=PLAIN AUTH=LOGIN
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: a001 OK Capability completed.
[943] Dec 18 19:59:24 [Debug] IMAP : "1" SEND a002 LOGIN foo <password hidden>
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: a002 OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND UNSELECT IDLE CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS QUOTA] Logged in
[943] Dec 18 19:59:24 [Debug] IMAP : "1" SEND a003 ENABLE QRESYNC CONDSTORE
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * ENABLED QRESYNC CONDSTORE
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: a003 OK Enabled.
[943] Dec 18 19:59:24 [Debug] IMAP : "1" SEND a004 SELECT INBOX (CONDSTORE)
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $Forwarded $has_cal receipt-handled)
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft $Forwarded $has_cal receipt-handled \*)] Flags permitted.
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * 348 EXISTS
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * 0 RECENT
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * OK [UNSEEN 348] First unseen.
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * OK [UIDVALIDITY 1286628079] UIDs valid
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * OK [UIDNEXT 2762] Predicted next UID
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: * OK [NOMODSEQ] No permanent modsequences
[943] Dec 18 19:59:24 [Debug] IMAP : "1" RECV: a004 OK [READ-WRITE] Select completed.
[943] Dec 18 19:59:24 [Warning] ( 943 ) Failed to execute query; error:"mailfoldercustom.value may not be NULL Unable to fetch row"; statement:"INSERT INTO mailfoldercustom (id,name,value) VALUES (26,'qmf-highestmodseq','')"
[943] Dec 18 19:59:24 [Warning] Failed to execute query; error:"mailfoldercustom.value may not be NULL Unable to fetch row"; statement:"INSERT INTO mailfoldercustom (id,name,value) VALUES (26,'qmf-highestmodseq','')"
[943] Dec 18 19:59:24 [Warning] Could not execute query "mailfoldercustom update custom insert query"
[943] Dec 18 19:59:24 [Warning] 943 Unable to updateFolder - constraint failure
[943] Dec 18 19:59:24 [Warning] Unable to update folder HIGHESTMODSEQ for account: 16
[943] Dec 18 19:59:24 [Debug] Messaging : Action completed 5196910428162 result success
[943] Dec 18 19:59:32 [Debug] Messaging : Running action RetrieveMessageListRequest 5196910428164
[943] Dec 18 19:59:32 [Debug] Messaging : Setting imap strategy 31ImapRetrieveMessageListStrategy
[943] Dec 18 19:59:32 [Debug] IMAP : "1" SEND a005 SELECT INBOX (CONDSTORE)
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * OK [CLOSED] Previous mailbox closed.
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * FLAGS (\Answered \Flagged \Deleted \Seen \Draft $Forwarded $has_cal receipt-handled)
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * OK [PERMANENTFLAGS (\Answered \Flagged \Deleted \Seen \Draft $Forwarded $has_cal receipt-handled \*)] Flags permitted.
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * 348 EXISTS
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * 0 RECENT
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * OK [UNSEEN 348] First unseen.
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * OK [UIDVALIDITY 1286628079] UIDs valid
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * OK [UIDNEXT 2762] Predicted next UID
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: * OK [NOMODSEQ] No permanent modsequences
[943] Dec 18 19:59:32 [Debug] IMAP : "1" RECV: a005 OK [READ-WRITE] Select completed.
[943] Dec 18 19:59:32 [Warning] ( 943 ) Failed to execute query; error:"mailfoldercustom.value may not be NULL Unable to fetch row"; statement:"INSERT INTO mailfoldercustom (id,name,value) VALUES (26,'qmf-highestmodseq','')"
[943] Dec 18 19:59:32 [Warning] Failed to execute query; error:"mailfoldercustom.value may not be NULL Unable to fetch row"; statement:"INSERT INTO mailfoldercustom (id,name,value) VALUES (26,'qmf-highestmodseq','')"
[943] Dec 18 19:59:32 [Warning] Could not execute query "mailfoldercustom update custom insert query"
[943] Dec 18 19:59:32 [Warning] 943 Unable to updateFolder - constraint failure
[943] Dec 18 19:59:32 [Warning] Unable to update folder HIGHESTMODSEQ for account: 16
[943] Dec 18 19:59:32 [Debug] Messaging : Action completed 5196910428164 result success
[943] Dec 18 19:59:41 [Debug] IMAP : "1" SEND a006 LOGOUT
Thank you
Hope you can help me
Update 1
I also deleted and recreated the account several times, furthermore I reseted the device completely.
Update 2
I've contacted my hoster. At first glance they thought QRESYNC is OFF, but it seems to be ON:
$ telnet ttyhoney.com 143
Trying 217.11.48.115...
Connected to ttyhoney.com.
Escape character is '^]'.
* OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE STARTTLS AUTH=PLAIN AUTH=LOGIN] Dovecot ready.
a login "foo" "bar"
a NO [AUTHENTICATIONFAILED] Authentication failed.
a login "correctuser" "correctpasswd"
a OK [CAPABILITY IMAP4rev1 LITERAL+ SASL-IR LOGIN-REFERRALS ID ENABLE SORT SORT=DISPLAY THREAD=REFERENCES THREAD=REFS MULTIAPPEND UNSELECT IDLE CHILDREN NAMESPACE UIDPLUS LIST-EXTENDED I18NLEVEL=1 CONDSTORE QRESYNC ESEARCH ESORT SEARCHRES WITHIN CONTEXT=SEARCH LIST-STATUS QUOTA] Logged in
Configuration of a mailserver requires witchcraft.
PS: I've changed my username in the log file to "foo" ;-)
Update 3
I've upgraded to 1.1.1.27. No change.
Looks like a issue with QRESYNC, your server is probably sending something the email MW is not expecting, need to investigate to see which one is not complaining to the standard.
VDVsx ( 2014-12-19 10:00:12 +0300 )editThanks! I'm looking forward to hear from you. In meanwhile I've written to my hoster and asked them to double check the server configuration.
hoschi ( 2014-12-19 12:53:52 +0300 )editProbably same as here then: https://together.jolla.com/question/56907/imap-qresync-used-without-server-confirmation/
VDVsx ( 2014-12-20 12:38:40 +0300 )editMaybe, but the log looks different. Can we check if a server really makes use of QRESYNC?
hoschi ( 2014-12-20 13:38:40 +0300 )editAny progress or ideas? May I can provide you with a test account on the mail server, if this can help you.
hoschi ( 2015-01-15 15:09:31 +0300 )edit