Ask / Submit
1

[BUG] Exchange doesn't synchronize (2.0.5.6)

Tracked by Jolla

asked 2017-03-04 20:43:16 +0200

louisbob gravatar image

Hi,

I'm currently using the netcourrier french provider. They provide an Active Exchange solution.

I'm unable to properly sync my email, my calendar, and my contact with it. The 2 ways contact sync does not work as well. Sometimes I'm able to get the email working, but impossible to sync contact to and from the server.

This my log:

[root@Sailfish nemo]# cat qmf.log 
[1099] Mar 04 19:17:16 [Warning] void QCopClient::connectToServer() QLocalSocket::ServerNotFoundError     "QLocalSocket::connectToServer: Invalid name" 
[1099] Mar 04 19:17:16 [Debug] Messaging :  MessageServer ctor begin 
[1099] Mar 04 19:17:16 [Debug] Messaging :  opening database 
[1099] Mar 04 19:17:17 [Debug] ExchangeActiveSync :      ActiveSyncServicePlugin::ActiveSyncServicePlugin() 
[1099] Mar 04 19:17:17 [Debug] ExchangeActiveSync :  virtual QString ActiveSyncServicePlugin::key()     const 
[1099] Mar 04 19:17:17 [Debug] ExchangeActiveSync :  virtual QMailMessageService*     ActiveSyncServicePlugin::createService(const QMailAccountId&) 
[1099] Mar 04 19:17:17 [Debug] ExchangeActiveSync :  ActiveSyncClient::ActiveSyncClient(QObject*) 
[1099] Mar 04 19:17:17 [Debug] ExchangeActiveSync :  ActiveSyncService::ActiveSyncService(const     QMailAccountId&) 
[1099] Mar 04 19:17:17 [Debug] Messaging :  Registering service: "sailfisheas-email" for account: 46 
[1099] Mar 04 19:17:17 [Debug] Messaging :  Initiating messageserver plugins. 
[1099] Mar 04 19:19:22 [Debug] Messaging :  Running action RetrieveFolderListRequest 27131308408833 
[1099] Mar 04 19:19:22 [Debug] ExchangeActiveSync :  virtual bool     ActiveSyncServiceSource::retrieveFolderList(const QMailAccountId&, const QMailFolderId&, bool) 
[1099] Mar 04 19:19:22 [Debug] ExchangeActiveSync :  bool ActiveSyncClient::syncFolders() 
[1099] Mar 04 19:19:22 [Debug] ExchangeActiveSync :  bool ActiveSyncClient::syncMessages() 
[1099] Mar 04 19:19:23 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onSyncStarted(qulonglong,     qulonglong) 
[1099] Mar 04 19:19:24 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestProgress(    qulonglong, qulonglong, qlonglong, qlonglong) 1 100 20 
[1099] Mar 04 19:19:24 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onSyncCompleted(    qulonglong, qulonglong, int) 46 1 10299 
[1099] Mar 04 19:19:24 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestFinished(    qulonglong, qulonglong, int) requestId 1 status 10299 
[1099] Mar 04 19:19:24 [Debug] ExchangeActiveSync :  void ActiveSyncService::onServiceActionError(    QMailServiceAction::Status::ErrorCode) 
[1099] Mar 04 19:19:24 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestFailed(int) 
[1099] Mar 04 19:19:24 [Debug] Messaging :  Action completed 27131308408833 result failure 
[1099] Mar 04 19:19:46 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onSyncStarted(qulonglong,     qulonglong) 
[1099] Mar 04 19:19:46 [Debug] Messaging :  Running action ProtocolRequestRequestType 4720169058305 
[1099] Mar 04 19:19:46 [Debug] ExchangeActiveSync :  virtual bool     ActiveSyncServiceSource::protocolRequest(const QMailAccountId&, const QString&, const QVariant&) 
[1099] Mar 04 19:19:47 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestProgress(    qulonglong, qulonglong, qlonglong, qlonglong) 2 100 20 
[1099] Mar 04 19:19:47 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onSyncCompleted(    qulonglong, qulonglong, int) 46 2 10299 
[1099] Mar 04 19:19:47 [Debug] ExchangeActiveSync :  void ActiveSyncService::onServiceActionError(    QMailServiceAction::Status::ErrorCode) 
[1099] Mar 04 19:19:47 [Debug] Messaging :  Action completed 4720169058305 result failure 
[1099] Mar 04 19:19:47 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestFinished(    qulonglong, qulonglong, int) requestId 2 status 10299 
[1099] Mar 04 19:19:47 [Debug] ExchangeActiveSync :  void ActiveSyncService::onServiceActionError(    QMailServiceAction::Status::ErrorCode) 
[1099] Mar 04 19:19:47 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestFailed(int) 
[1099] Mar 04 19:19:47 [Debug] ExchangeActiveSync :  request failed event ignored, unknown request id 
[1099] Mar 04 19:19:48 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onSyncStarted(qulonglong,     qulonglong) 
[1099] Mar 04 19:19:48 [Debug] Messaging :  Running action ProtocolRequestRequestType 4720169058306 
[1099] Mar 04 19:19:48 [Debug] ExchangeActiveSync :  virtual bool     ActiveSyncServiceSource::protocolRequest(const QMailAccountId&, const QString&, const QVariant&) 
[1099] Mar 04 19:19:49 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestProgress(    qulonglong, qulonglong, qlonglong, qlonglong) 3 100 20 
[1099] Mar 04 19:19:49 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onSyncCompleted(    qulonglong, qulonglong, int) 46 3 10299 
[1099] Mar 04 19:19:49 [Debug] ExchangeActiveSync :  void ActiveSyncService::onServiceActionError(    QMailServiceAction::Status::ErrorCode) 
[1099] Mar 04 19:19:49 [Debug] Messaging :  Action completed 4720169058306 result failure 
[1099] Mar 04 19:19:49 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestFinished(    qulonglong, qulonglong, int) requestId 3 status 10299 
[1099] Mar 04 19:19:49 [Debug] ExchangeActiveSync :  void ActiveSyncService::onServiceActionError(    QMailServiceAction::Status::ErrorCode) 
[1099] Mar 04 19:19:49 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onRequestFailed(int) 
[1099] Mar 04 19:19:49 [Debug] ExchangeActiveSync :  void ActiveSyncClient::onSyncStarted(qulonglong,     qulonglong) 
[1099] Mar 04 19:19:49 [Debug] Messaging :  Running action ProtocolRequestRequestType 4720169058307

JOURNALCTL LOG

Mar 04 19:19:45 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:45 +0100      CalendarAccountConfigurationImpl::onCalendarAccountUpdated, 97: updating calendar account config
Mar 04 19:19:45 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:45 +0100  CalendarStorageImpl::enable,     1949: Already enabled
Mar 04 19:19:45 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:45 +0100      ContactsAccountConfigurationImpl::onContactsAccountUpdated, 89: updating contacts account config
Mar 04 19:19:45 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:45 +0100      ContactStorageImpl::onContactsConfigurationChanged, 532: contacts configuration changed for account 46
Mar 04 19:19:45 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:45 +0100      AccountStorageImpl::onEmailAccountUpdated, 153: account 46 updated
Mar 04 19:19:45 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:45 +0100      ConnectionAccountConfigurationImpl::onAccountUpdated, 110: updating connection account config
Mar 04 19:19:45 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:45 +0100      EmailAccountConfigurationImpl::onEmailAccountUpdated, 140: updating email account config
Mar 04 19:19:46 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:46 +0100      RequestManager::onSyncServiceRequest, 624: Sync Service: account:  46 , service:  2 , requestId:  2
Mar 04 19:19:46 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:46 +0100  Request::start, 120: Start     request: 2 for account 46
Mar 04 19:19:46 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:46 +0100      BaseProtocolCommand::configureNetworkRequest, 220: AS Command URL:  "https://sync.net-c.com:443/    Microsoft-Server-ActiveSync?Cmd=FolderSync&User=lpc@netccc.org&DeviceId=l500d356396070030855&DeviceType=SailfishOS"
Mar 04 19:19:46 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:46 +0100  FolderSyncCommand::start, 82:     WBXML tree:
                                             START_DOCUMENT
                                             <FolderSync>
                                               <SyncKey>0</SyncKey>
                                             </FolderSync>
                                             END_DOCUMENT
Mar 04 19:19:46 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:46 +0100      DBusInterfaceThread::reportSyncStarted, 170: reporting sync started 2 for account: 46
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      BaseProtocolCommand::onReplyError, 465: networkError 299
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      BaseProtocolCommand::onReplyError, 467: Headers:  (QPair("Date","Sat, 04 Mar 2017 18:19:47 GMT") ,      QPair("Server","Apache") ,  QPair("Vary","Accept-Encoding") ,  QPair("Content-Encoding","gzip") ,      QPair("Keep-Alive","timeout=5, max=5") ,  QPair("Connection","Keep-Alive") ,  QPair("Content-Type","text/html") )
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      BaseProtocolCommand::onReplyError, 468: Body:  ""
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      BaseProtocolCommand::onReplyError, 472: We should redirect our requests to a different URL
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100  BaseProtocolCommand::handleError,     356: Network error: 299
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      SyncRequest::processFolderSyncFinished, 1161: FolderSync command finished:  false
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      SyncRequest::processFolderSyncFinished, 1171: FolderSync command failed
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      DBusInterfaceThread::reportSyncCompleted, 184: reporting sync completed 2 for account: 46 with status 10299
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      ContactStorageImpl::onSyncCompleted, 456: syncCompleted 2 status 10299 for account 46
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      QtContactsSqliteExtensions::TwoWayContactSyncAdapter::storeSyncStateData, 1434: virtual bool     QtContactsSqliteExtensions::TwoWayContactSyncAdapter::storeSyncStateData(const QString&) invalid state 0
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      RequestManager::onRequestCompleted, 709: onRequestCompleted: 2 for account 46
Mar 04 19:19:47 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:47 +0100      DBusInterfaceThread::reportRequestFinished, 96: reporting request  2 finished with status 10299
Mar 04 19:19:48 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:48 +0100      RequestManager::onSyncServiceRequest, 624: Sync Service: account:  46 , service:  4 , requestId:  3
Mar 04 19:19:48 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:48 +0100  Request::start, 120: Start     request: 3 for account 46
Mar 04 19:19:48 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:48 +0100      BaseProtocolCommand::configureNetworkRequest, 220: AS Command URL:  "https://sync.net-c.com:443/    Microsoft-Server-ActiveSync?Cmd=FolderSync&User=lpc@netccc.org&DeviceId=l500d356396070030855&DeviceType=SailfishOS"
Mar 04 19:19:48 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:48 +0100  FolderSyncCommand::start, 82:     WBXML tree:
                                             START_DOCUMENT
                                             <FolderSync>
                                               <SyncKey>0</SyncKey>
                                             </FolderSync>
                                             END_DOCUMENT
Mar 04 19:19:48 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:48 +0100      DBusInterfaceThread::reportSyncStarted, 170: reporting sync started 3 for account: 46
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      RequestManager::onSyncServiceRequest, 624: Sync Service: account:  46 , service:  1 , requestId:  4
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 465: networkError 299
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 467: Headers:  (QPair("Date","Sat, 04 Mar 2017 18:19:49 GMT") ,      QPair("Server","Apache") ,  QPair("Vary","Accept-Encoding") ,  QPair("Content-Encoding","gzip") ,      QPair("Keep-Alive","timeout=5, max=4") ,  QPair("Connection","Keep-Alive") ,  QPair("Content-Type","text/html") )
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 468: Body:  ""
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 472: We should redirect our requests to a different URL
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100  BaseProtocolCommand::handleError,     356: Network error: 299
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      SyncRequest::processFolderSyncFinished, 1161: FolderSync command finished:  false
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      SyncRequest::processFolderSyncFinished, 1171: FolderSync command failed
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      DBusInterfaceThread::reportSyncCompleted, 184: reporting sync completed 3 for account: 46 with status 10299
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      ContactStorageImpl::onSyncCompleted, 456: syncCompleted 3 status 10299 for account 46
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      QtContactsSqliteExtensions::TwoWayContactSyncAdapter::storeSyncStateData, 1434: virtual bool     QtContactsSqliteExtensions::TwoWayContactSyncAdapter::storeSyncStateData(const QString&) invalid state 0
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      RequestManager::onRequestCompleted, 709: onRequestCompleted: 3 for account 46
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      DBusInterfaceThread::reportRequestFinished, 96: reporting request  3 finished with status 10299
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100  Request::start, 120: Start     request: 4 for account 46
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::configureNetworkRequest, 220: AS Command URL:  "https://sync.net-c.com:443/    Microsoft-Server-ActiveSync?Cmd=FolderSync&User=lpc@netccc.org&DeviceId=l500d356396070030855&DeviceType=SailfishOS"
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100  FolderSyncCommand::start, 82:     WBXML tree:
                                             START_DOCUMENT
                                             <FolderSync>
                                               <SyncKey>0</SyncKey>
                                             </FolderSync>
                                             END_DOCUMENT
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      DBusInterfaceThread::reportSyncStarted, 170: reporting sync started 4 for account: 46
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 465: networkError 299
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 467: Headers:  (QPair("Date","Sat, 04 Mar 2017 18:19:49 GMT") ,      QPair("Server","Apache") ,  QPair("Vary","Accept-Encoding") ,  QPair("Content-Encoding","gzip") ,      QPair("Keep-Alive","timeout=5, max=3") ,  QPair("Connection","Keep-Alive") ,  QPair("Content-Type","text/html") )
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 468: Body:  ""
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      BaseProtocolCommand::onReplyError, 472: We should redirect our requests to a different URL
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100  BaseProtocolCommand::handleError,     356: Network error: 299
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      SyncRequest::processFolderSyncFinished, 1161: FolderSync command finished:  false
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      SyncRequest::processFolderSyncFinished, 1171: FolderSync command failed
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      ContactStorageImpl::onSyncCompleted, 456: syncCompleted 4 status 10299 for account 46
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      DBusInterfaceThread::reportSyncCompleted, 184: reporting sync completed 4 for account: 46 with status 10299
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      QtContactsSqliteExtensions::TwoWayContactSyncAdapter::storeSyncStateData, 1434: virtual bool     QtContactsSqliteExtensions::TwoWayContactSyncAdapter::storeSyncStateData(const QString&) invalid state 0
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      RequestManager::onRequestCompleted, 709: onRequestCompleted: 4 for account 46
Mar 04 19:19:49 Sailfish sailfish-eas[1140]: [EAS] [1140]: 04 Mar 2017 19:19:49 +0100      DBusInterfaceThread::reportRequestFinished, 96: reporting request  4 finished with status 10299
edit retag flag offensive close delete

1 Answer

Sort by » oldest newest most voted
0

answered 2017-03-20 17:59:56 +0200

louisbob gravatar image

Is there any bug tracker system where I can forward my request?

BR

edit flag offensive delete publish link more

Comments

I have created JB#38125 to track this issue internally. Thanks for the report and logs - are you able to provide any more information about the server (e.g., is it definitely a Microsoft ActiveSync server as it seems from the logs, or is it a Zimbra etc server, or? Do you know what version and what protocol version?)

chris.adams ( 2017-03-21 03:53:19 +0200 )edit

Hi,

I asked my provider and they told me that they implemented the Activesync for their backend. What I can do is to create a test account, provide you the login in private message...

louisbob ( 2017-03-28 21:47:32 +0200 )edit

I'm also trying to install exchange in my SDK emulator to track the bug. Unfortunately, I can't find the package for installing aes on my emulator. Any clue?

louisbob ( 2017-03-29 01:06:53 +0200 )edit

Any news on this issue? I would like to help & debug but I need to make a clean test environment with exchange packages

louisbob ( 2017-04-06 22:46:20 +0200 )edit

Hi louisbob - thanks for the offer to help. Unfortunately I don't know anything about the emulator or application development sdk. The repository which the sailfish-eas packages come from is in an internal, closed repository, so it may be that it's not available for installation in the emulator.

Current status unfortunately is that we haven't been able to investigate this issue further, internally, as this particular bug wasn't prioritised for the 2.1.1 branching. Hopefully we will get some time to investigate it during the next few weeks.

If you do have a test login I can use, please email it to chris dot adams at jolla dot com - thanks!

chris.adams ( 2017-04-11 09:12:42 +0200 )edit
Login/Signup to Answer

Question tools

Follow
2 followers

Stats

Asked: 2017-03-04 20:43:16 +0200

Seen: 173 times

Last updated: Mar 20