JollaC Exchange sync issues

asked 2017-04-05 16:45:04 +0200

twi42 gravatar image

updated 2017-04-07 13:27:37 +0200

jovirkku gravatar image

I moved from Jolla 1 environment to Jolla C realm, and while my Office365 Business Exchange worked flawlessly with Jolla 1, now with Jolla C's new EAS there are multiple issues:

  1. Creating the Exchange account has to be done with 'Provisioning' unchecked, only after which it can be enabled
  2. Only standard Exchange folders are visible in the folder view (Inbox, Sent Items, Outbox, Deleted items etc) but my own folders do not appear at all, regardless whether they are under or adjacent to Inbox.
  3. Syncing contacts, calendar and email works, but only when it is manually triggered (from email client or account view) even though settings are 'Always up to date', with or without schedule. It seems that the EAS daemon just freezes after completing a single sync.

EAS log with debug options shows nothing too critical, apart from those curious Network Errors:

Apr 05 16:17:32 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:32 +0300 , 0: Sync folders: account:  17 , folderIds:  ("77") , requestId:  2
Apr 05 16:17:32 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:32 +0300 , 0: Start request: 2 for account 17
Apr 05 16:17:32 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:32 +0300 , 0: AS OPTIONS URL:  "https://outlook.office365.com:443/Microsoft-Server-ActiveSync"
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300 , 0: progress 0 / 0
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300 , 0: Supported versions:  ("2.0", "2.1", "2.5", "12.0", "12.1", "14.0", "14.1", "16.0", "16.1")
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300 , 0: Versions: (4608, 4609, 5120, 5121)
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300 , 0: Supported commands: ("Sync", "SendMail", "SmartForward", "SmartReply", "GetAttachment", "GetHierarchy", "CreateCollection", "DeleteCollection", "MoveCollect
ion", "FolderSync", "FolderCreate", "FolderDelete", "FolderUpdate", "MoveItems", "GetItemEstimate", "MeetingResponse", "Search", "Settings", "Ping", "ItemOperations", "Provision", "ResolveRecipients", "ValidateCert", "Find")
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300 , 0: : 0  =>  5121
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300 , 0: AS Command URL:  "https://outlook.office365.com:443/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=<removed>&DeviceId=l500d911514800184143&DeviceType=S
ailfishOS"
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300  FolderSyncCommand::start, 82: WBXML tree:
Apr 05 16:17:33 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:33 +0300 , 0: reporting sync started 2 for account: 17
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: progress 25 / -1
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: progress 25 / 25
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Network error:  QNetworkReply::NetworkError(NoError)
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Length:  25
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300  FolderSyncCommand::handleFinished, 143: WBXML tree:
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Status: 1
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: SyncKey: "1"
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: added: ()
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: updated: ()
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: deleted: ()
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: FolderSync command finished:  true
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Server ID:  "9" , syncKey:  "1340259319"
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: initialSync size 0 , sync size 1
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: AS Command URL:  "https://outlook.office365.com:443/Microsoft-Server-ActiveSync?Cmd=Sync&User=<removed>&DeviceId=l500d911514800184143&DeviceType=Sailfis
hOS"
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300  SyncCommand::start, 106: WBXML tree:
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: progress 11002 / -1
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: progress 11002 / 11002
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Headers:  (QPair("Cache-Control","private"), QPair("Content-Type","application/vnd.ms-sync.wbxml"), QPair("Content-Encoding","gzip"), QPair("Vary","Accept-E
ncoding"), QPair("Server","Microsoft-IIS/8.5"), QPair("request-id","55d8f05a-0841-4179-b55f-e726576843dc"), QPair("X-CalculatedBETarget","AM3PR03MB1091.eurprd03.prod.outlook.com"), QPair("X-BackEndHttpStatus","200"), QPair("MS-Server-ActiveSync","
15.1"), QPair("X-MS-BackOffDuration","L/-469"), QPair("X-AspNet-Version","4.0.30319"), QPair("X-DiagInfo","AM3PR03MB1091"), QPair("X-BEServer","AM3PR03MB1091"), QPair("X-Powered-By","ASP.NET"), QPair("X-FEServer","AM4PR0701CA0020"), QPair("Date","
Wed, 05 Apr 2017 13:17:34 GMT"))
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Length:  11002
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: SYNC_KEY =  "639280405"
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: COLLECTION_ID =  "9"
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: status: Eas::SyncStatusUndefined
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: limit: 0
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Network error:  QNetworkReply::NetworkError(NoError)
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Sync command finished:  true
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: handling non-MIME email body
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: handling non-MIME email body
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: handling non-MIME email body
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: Update last synchronized QDateTime(2017-04-05 16:17:34.821 EEST Qt::TimeSpec(LocalTime)) for service "Exchange Email"
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: reporting sync completed 2 for account: 17 with status 0
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: onRequestCompleted: 2 for account 17
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: reporting request  2 finished with status 0
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: account 17 updated
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: updating connection account config
Apr 05 16:17:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:17:34 +0300 , 0: updating email account config
Apr 05 16:18:34 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:18:34 +0300 , 0: database is released
Apr 05 16:27:35 Sailfish sailfish-eas[1134]: [EAS] [1134]: 05 Apr 2017 16:27:35 +0300 , 0: Messaging :  closing database
edit retag flag offensive close delete

Comments

In the initialization of EAS there were actually some more errors:

    Apr 05 16:57:30 Sailfish sailfish-eas[1079]: [EAS] [1079]: 05 Apr 2017 16:57:30 +0300 , 0: Already created item: "2"
Apr 05 16:57:30 Sailfish sailfish-eas[1079]: [EAS] [1079]: 05 Apr 2017 16:57:30 +0300 , 0: connection-manager.cpp 106 setupSocketConnection p2p error: QDBusError("org.freedesktop.DBus.Error.FileNotFound", "Failed to connect to socket /run/user/100
000/signond/socket: No such file or directory") 1
Apr 05 16:57:30 Sailfish sailfish-eas[1079]: [EAS] [1079]: 05 Apr 2017 16:57:30 +0300 , 0: connection-manager.cpp 132 init Peer connection unavailable, activating service
Apr 05 16:57:30 Sailfish sailfish-eas[1079]: [EAS] [1079]: 05 Apr 2017 16:57:30 +0300 , 0: identityimpl.cpp 96 updateState Updating state:  "PendingRegistration" SignOn::IdentityImpl(0x1cc2b60)
Apr 05 16:57:30 Sailfish sailfish-eas[1079]: [EAS] [1079]: 05 Apr 2017 16:57:30 +0300 , 0: authsessionimpl.cpp 94 initInterface
Apr 05 16:57:30 Sailfish sailfish-eas[1079]: [EAS] [1079]: 05 Apr 2017 16:57:30 +0300 , 0: authsessionimpl.cpp 94 initInterface
Apr 05 16:57:30 Sailfish sailfish-eas[1079]: [EAS] [1079]: 05 Apr 2017 16:57:30 +0300 , 0: Messaging :  opening database
twi42 ( 2017-04-05 17:02:12 +0200 )edit

One more observation - when creating the account, the wizard finishes oddly to folder list with only 'Drafts' visible.

twi42 ( 2017-04-07 22:08:29 +0200 )edit

This is most likely related to overall connectivity issue for EAS, as calendar sync also needs to be manually triggered. It is very annoying though in business use.

twi42 ( 2017-04-20 11:48:55 +0200 )edit

Noticed that Alien Dalvik has similar connectivity issues e.g. with WhatsApp, so the root cause might be connman?

twi42 ( 2017-04-23 23:07:09 +0200 )edit

Cslendar and contacts sync are similarly unreliable, requiring sometimes manual sync/reboot to complete. However, by setting e.g. a 15min update instead of 'Always up to date' EAS works slightly better, but can still delay incoming emails with an hour or so.

twi42 ( 2017-04-29 10:56:53 +0200 )edit