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

Email: IMAP sending from Drafts results in Problem with sync

asked 2014-10-05 04:04:37 +0300

axaq gravatar image

I had not used saving to Drafts and sending very often. The mail server runs "Courier-IMAP".

Sequence to reproduce the problem:

  • create a new mail
  • save as draft
  • reopen the saved draft mail
  • send the mail
  • update draft folder

After saving to drafts it is visible at the servers draft folder. I tested it using the web access of the IMAP server. Updating drafts folder on the phone results in no error.

After reopening the draft mail and sending it, it is still visible at the servers draft folder and not in the send folder. Updating drafts folder on the phone results now in "Problem with sync".

When updating results in "Problem with sync" I get with debug enabled the following log:

[22050] Oct 05 02:15:18 [Debug] Messaging :  Running action RetrieveMessageListRequest 88776974008411
[22050] Oct 05 02:15:18 [Debug] Messaging :  Setting imap strategy 31ImapRetrieveMessageListStrategy
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" SEND a005 SELECT INBOX.Drafts
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * FLAGS (\Draft \Answered \Flagged \Deleted \Seen \Recent)
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * OK [PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * 1 EXISTS
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * 0 RECENT
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * OK [UIDVALIDITY 1256374978] Ok
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * OK [MYRIGHTS "cdilrsw"] ACL
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: a005 OK [READ-WRITE] Ok
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" SEND a006 FETCH 1:* (FLAGS UID)
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * 1 FETCH (FLAGS (\Draft \Seen) UID 14)
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: a006 OK FETCH completed.
[22050] Oct 05 02:15:18 [Warning] WARNING server contains uids in contiguous region not on client!!! "14"
[22050] Oct 05 02:15:18 [Warning] WARNING clientMin 1 clientMax 14
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" SEND a007 UID FETCH 14 (FLAGS UID INTERNALDATE RFC822.SIZE BODYSTRUCTURE RFC822.HEADER)
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: * 1 FETCH (FLAGS (\Draft \Seen) UID 14 INTERNALDATE "05-Oct-2014 01:57:27 +0200" RFC822.SIZE 280 BODYSTRUCTURE ("text" "plain" ("charset" "utf-8") NIL NIL "base64" 54 1 NIL NIL NIL) RFC822.HEADER {226}
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: <226 literal bytes received>
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: )
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: a007 OK FETCH completed.
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" SEND a008 CLOSE
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: a008 OK mailbox closed.
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" SEND a009 UID FETCH 14 (FLAGS UID RFC822.SIZE BODY.PEEK[])
[22050] Oct 05 02:15:18 [Debug] IMAP :  "1" RECV: a009 NO Error in IMAP command received by server.
[22050] Oct 05 02:15:18 [Debug] Messaging :  Action completed 88776974008411 result failure

So I don't know if the mail has been send successfully and I can't solve the drafts "Problem with sync" situation on the phone itself.

If I remove the draft mail at the server than updating drafts folder on the phone results in no error.

Maybe this is the same bug as reported here.

edit retag flag offensive close delete

Comments

This looks like a issue we fixed in update9, if problem persists after that I'll take a look.

VDVsx ( 2014-10-07 08:35:12 +0300 )edit

1 Answer

Sort by » oldest newest most voted
1

answered 2014-12-18 17:40:58 +0300

VDVsx gravatar image

Fixed in 1.1.1.26, Vaarainjärvi, if you still have issues please comment here.

edit flag offensive delete publish link more

Comments

Updated to 1.1.1.26 but still getting "Problem with sync" messages after selecting "Update" from pulley menu. Sending and receiving seems to work luckily. How can I help you to debug the problem? The "Problem with sync" text is shown instead of the folder name and is annoying.

axaq ( 2014-12-19 04:31:29 +0300 )edit

@axaq - Please collect the logs as you did before, make sure to do some operation after this one, like sync inbox for example in order to make the log flush to the file.

VDVsx ( 2014-12-19 09:31:03 +0300 )edit

Started the Message app fresh. It showed "Inbox" in to line.

First log when starting "Update" from Inbox pulley menu:

(Replaced my acccount with ACCOUNT)

[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: a001 OK CAPABILITY completed 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" SEND a002 STARTTLS 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: a002 OK Begin SSL/TLS negotiation now. 
[6749] Jan. 09 02:46:53 [Debug] Messaging :  IMAP : Secure connection established 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" SEND a003 CAPABILITY 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: a003 OK CAPABILITY completed 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" SEND a004 LOGIN ACCOUNT <password hidden> 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: a004 OK connected to proxy server. 
[6749] Jan. 09 02:46:53 [Debug] Messaging :  Setting imap strategy 31ImapRetrieveMessageListStrategy 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" SEND a005 SELECT INBOX 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * FLAGS ($FORWARDED \Draft \Answered \Flagged \Deleted \Seen \Recent) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * OK [PERMANENTFLAGS ($FORWARDED \* \Draft \Answered \Flagged \Deleted \Seen)] Limited 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1442 EXISTS 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 0 RECENT 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * OK [UIDVALIDITY 420189887] Ok 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * OK [MYRIGHTS "cdilrsw"] ACL 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: a005 OK [READ-WRITE] Ok 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" SEND a006 FETCH 1385:* (FLAGS UID) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1385 FETCH (FLAGS (\Seen) UID 1385) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1386 FETCH (FLAGS (\Seen) UID 1386) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1387 FETCH (FLAGS (\Seen) UID 1387) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1388 FETCH (FLAGS (\Seen) UID 1388) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1389 FETCH (FLAGS (\Seen) UID 1389) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1390 FETCH (FLAGS (\Seen) UID 1390) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1391 FETCH (FLAGS (\Seen) UID 1391) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1392 FETCH (FLAGS (\Seen) UID 1392) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1393 FETCH (FLAGS (\Seen $FORWARDED) UID 1393) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1394 FETCH (FLAGS (\Seen) UID 1394) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1395 FETCH (FLAGS (\Seen) UID 1395) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1396 FETCH (FLAGS (\Seen) UID 1396) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1397 FETCH (FLAGS (\Seen) UID 1397) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1398 FETCH (FLAGS (\Seen) UID 1398) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1399 FETCH (FLAGS (\Seen) UID 1399) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1400 FETCH (FLAGS (\Seen) UID 1400) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1401 FETCH (FLAGS (\Seen) UID 1401) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1402 FETCH (FLAGS (\Seen) UID 1402) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1403 FETCH (FLAGS (\Answered \Seen) UID 1403) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1404 FETCH (FLAGS (\Seen) UID 1404) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1405 FETCH (FLAGS (\Seen) UID 1405) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1406 FETCH (FLAGS (\Seen) UID 1406) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1407 FETCH (FLAGS (\Seen) UID 1407) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1408 FETCH (FLAGS (\Seen) UID 1408) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1409 FETCH (FLAGS (\Seen) UID 1409) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1410 FETCH (FLAGS (\Seen) UID 1410) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1411 FETCH (FLAGS (\Seen) UID 1411) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1412 FETCH (FLAGS (\Seen) UID 1412) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1413 FETCH (FLAGS (\Seen) UID 1413) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1414 FETCH (FLAGS (\Seen) UID 1414) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1415 FETCH (FLAGS (\Seen) UID 1415) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1416 FETCH (FLAGS (\Seen) UID 1416) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1417 FETCH (FLAGS (\Seen) UID 1417) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1418 FETCH (FLAGS (\Seen) UID 1418) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1419 FETCH (FLAGS (\Seen) UID 1419) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1420 FETCH (FLAGS (\Seen) UID 1420) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1421 FETCH (FLAGS (\Seen) UID 1421) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1422 FETCH (FLAGS () UID 1422) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1423 FETCH (FLAGS (\Seen) UID 1423) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1424 FETCH (FLAGS (\Seen) UID 1425) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1425 FETCH (FLAGS (\Answered \Seen) UID 1427) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1426 FETCH (FLAGS (\Seen) UID 1429) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1427 FETCH (FLAGS (\Seen) UID 1436) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1428 FETCH (FLAGS (\Seen) UID 1437) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1429 FETCH (FLAGS (\Seen) UID 1438) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1430 FETCH (FLAGS (\Seen) UID 1439) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1431 FETCH (FLAGS (\Seen) UID 1440) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1432 FETCH (FLAGS (\Seen) UID 1441) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1433 FETCH (FLAGS (\Seen) UID 1442) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1434 FETCH (FLAGS (\Seen) UID 1443) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1435 FETCH (FLAGS () UID 1445) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1436 FETCH (FLAGS () UID 1446) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1437 FETCH (FLAGS () UID 1447) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1438 FETCH (FLAGS (\Seen) UID 1448) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1439 FETCH (FLAGS (\Seen) UID 1449) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1440 FETCH (FLAGS () UID 1450) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1441 FETCH (FLAGS (\Answered \Seen) UID 1451) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: * 1442 FETCH (FLAGS (\Seen) UID 1452) 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  "1" RECV: a006 OK FETCH completed. 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "ACCOUNT" 
[6749] Jan. 09 02:46:53 [Debug] Messaging :  Action completed 27539330301954 result success 
[6749] Jan. 09 02:46:53 [Debug] IMAP :  void ImapClient::onAccountsUpdated(const QMailAccountIdList&) "ACCOUNT" 
[6749] Jan. 09 02:47:03 [Debug] IMAP :  "1" SEND a007 LOGOUT 
[6749] Jan. 09 02:47:03 [Debug] IMAP :  "1" RECV: * BYE Courier-IMAP server shutting down 
[6749] Jan. 09 02:47:03 [Debug] IMAP :  "1" RECV: a007 OK LOGOUT completed

After "Update" the first line showed "Problem with sync" in top line.

axaq ( 2015-01-09 04:05:48 +0300 )edit

Deletet an entry from draft folder:

(Replaced my acccount with ACCOUNT and provider address with PROVIDER)

[6749] Jan. 09 02:50:34 [Debug] Messaging :  Running action ExportUpdatesRequest 27539330301959 
[6749] Jan. 09 02:50:34 [Debug] Messaging :  Setting imap strategy 24ImapMoveMessagesStrategy 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" About to open connection "ACCOUNT" "PROVIDER" 
[6749] Jan. 09 02:50:34 [Debug] Messaging :  QMailTransport::Socket::Socket SET PROXY host= "" port= 0 
[6749] Jan. 09 02:50:34 [Debug] Messaging :  Opening connection -  "PROVIDER" : 143  TLS 
[6749] Jan. 09 02:50:34 [Debug] Messaging :  IMAP : connection established 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" RECV: * OK [CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE ACL ACL2=UNION STARTTLS] Courier-IMAP ready. Copyright 1998-2010 Double Precision, Inc.  See COPYING for distribution information. 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" SEND a001 CAPABILITY 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" RECV: * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE ACL ACL2=UNION STARTTLS 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" RECV: a001 OK CAPABILITY completed 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" SEND a002 STARTTLS 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" RECV: a002 OK Begin SSL/TLS negotiation now. 
[6749] Jan. 09 02:50:34 [Debug] Messaging :  IMAP : Secure connection established 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" SEND a003 CAPABILITY 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" RECV: * CAPABILITY IMAP4rev1 UIDPLUS CHILDREN NAMESPACE THREAD=ORDEREDSUBJECT THREAD=REFERENCES SORT QUOTA IDLE AUTH=PLAIN ACL ACL2=UNION 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" RECV: a003 OK CAPABILITY completed 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" SEND a004 LOGIN ACCOUNT <password hidden> 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" RECV: a004 OK connected to proxy server. 
[6749] Jan. 09 02:50:34 [Debug] IMAP :  "1" SEND a005 SELECT INBOX.Trash 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: * FLAGS (\Draft \Answered \Flagged \Deleted \Seen \Recent) 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: * OK [PERMANENTFLAGS (\* \Draft \Answered \Flagged \Deleted \Seen)] Limited 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: * 38 EXISTS 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: * 0 RECENT 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: * OK [UIDVALIDITY 1251758014] Ok 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: * OK [MYRIGHTS "cdilrsw"] ACL 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: a005 OK [READ-WRITE] Ok 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" SEND a006 CLOSE 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: a006 OK mailbox closed. 
[6749] Jan. 09 02:50:35 [Warning] 6749 Unable to messageMetaData(uid/accountId) 
[6749] Jan. 09 02:50:35 [Warning] 6749 Unable to message(id) 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" SEND a007 APPEND INBOX.Trash () "" {59} 
[6749] Jan. 09 02:50:35 [Debug] IMAP :  "1" RECV: a007 NO Error in IMAP command received by server. 
[6749] Jan. 09 02:50:35 [Debug] Messaging :  Action completed 27539330301959 result failure

First line still showed "Problem with sync" in top line.

axaq ( 2015-01-09 04:08:21 +0300 )edit

Deleting the draft entry seemed not really working and I cleared it with my desktop kmail program. I also cleared the Trash folder with kmail. But after update of the Trash folder on the phone it still contained several entries but not all old Trash entries any more.

Now I selected on the phone the remaining Trash entries and deleted all.

After that the top line showed "Deleted items" and not "Problem with sync". I also updated other folders with success. So no "Problem with sync" at the moment.

Reading the logs I'm wondering that when updating a folder it seems that first the Draft folder will be checked and updated and after that the required folder will be checked and updated?

I do see first a line like

SEND a005 SELECT INBOX.Drafts

and some lines later

SEND a006 SELECT INBOX.Trash

when updating for example only the Trash folder.

At the end maybe some problematic/old data in the phone has lead to the "Problem with sync" lines. Hope the problem is gone now and for ever :-)

@VDVsx: Thanks for your help.

axaq ( 2015-01-09 04:24:50 +0300 )edit
Login/Signup to Answer

Question tools

Follow
2 followers

Stats

Asked: 2014-10-05 04:04:37 +0300

Seen: 267 times

Last updated: Dec 18 '14