Can't add Microsoft Exchange account since 1.1.9.28 update
Hi fellow sailors,
today i noticed that my company email account did not sync for 5 days. 5 days ago i was updating to 1.1.9.28... It worked flawlessly before.
So i tried to re-enter the password -> did not help Tried to disable and re-enable the account -> did not help
i ended up deleting the account and i am now trying to add the account but it doesn't work. It says checking of account data failed (translated from german).
I am online, the account data is 100% right and the mail server is online and reachable. And it worked before the update with the exact same data.
What does
"Credentials check FAILED! error == 5
mean?
We do not have activated any restrictions for mobile devices. I already read some threads where someone has activated PIN or lock device after 8 false attempts nad then it worked. Not applicable here
I am not a pro in analyzing logs but it seems something is not right with "signond" service...
Sep 14 10:03:36 Jolla dbus-daemon[919]: Activating service name='org.nemomobile.calendardataservice'
Sep 14 10:03:36 Jolla dbus-daemon[919]: Successfully activated service 'org.nemomobile.calendardataservice'
Sep 14 10:03:36 Jolla kernel: dbus-daemon(919) send signal 9 to dbus-daemon(8262)
Sep 14 10:03:36 Jolla calendardataservice[8263]: [W] mKCal::SqliteStorage::open:179 - sqlitestorage.cpp: 179 - database "/home/nemo/.local/share/system/privileged/Calendar/mkcal/db" opened
Sep 14 10:03:37 Jolla estart[1290]: [D] Accounts::Account::Private::account_store_cb:626 - account.cpp: 626 account_store_cb Saved accunt ID: 29
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::ConnectionManager::setupSocketConnection:106 - connection-manager.cpp 106 setupSocketConnection p2p error: QDBusError("org.freedesktop.DBus.Error.FileNotFound", "Failed to connect to socket /run/user/100000/signond/socket: Datei oder Verzeichnis nicht gefunden") 1
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::ConnectionManager::init:132 - connection-manager.cpp 132 init Peer connection unavailable, activating service
Sep 14 10:03:37 Jolla dbus-daemon[919]: Activating service name='com.google.code.AccountsSSO.SingleSignOn'
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "PendingRegistration" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::storeCredentials:172 - identityimpl.cpp 172 storeCredentials Storing credentials
Sep 14 10:03:37 Jolla [6153]: signondaemon.cpp 388 init Failed to SUID root. Secure storage will not be available.
Sep 14 10:03:37 Jolla dbus-daemon[919]: Successfully activated service 'com.google.code.AccountsSSO.SingleSignOn'
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::ConnectionManager::init:150 - connection-manager.cpp 150 init Connected to "libsignon-qt12"
Sep 14 10:03:37 Jolla kernel: dbus-daemon(919) send signal 9 to dbus-daemon(8272)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "Ready" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "NeedsUpdate" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "PendingUpdate" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::infoUpdated:442 - identityimpl.cpp 442 infoUpdated SERVER INFO UPDATED. NeedsUpdate " 0 "
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::storeCredentialsReply:345 - identityimpl.cpp 345 storeCredentialsReply stored id: 32 old id: 0
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::AuthSessionImpl::initInterface:94 - authsessionimpl.cpp 94 initInterface
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::AuthSessionImpl::initInterface:94 - authsessionimpl.cpp 94 initInterface
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::getInfoReply:381 - identityimpl.cpp 381 getInfoReply QMap(("ACL", QVariant(QStringList, () ) ) ( "AuthMethods" , QVariant(QDBusArgument, ) ) ( "Caption" , QVariant(QString, "Jolla") ) ( "Id" , QVariant(uint, 32) ) ( "Owner" , QVariant(QStringList, () ) ) ( "Realms" , QVariant(QStringList, () ) ) ( "RefCount" , QVariant(int, 0) ) ( "StoreSecret" , QVariant(bool, true) ) ( "Type" , QVariant(int, 0) ) ( "UserName" , QVariant(QString, "*CLEARED FOR SECURITY*") ) ( "UserNameSecret" , QVariant(bool, false) ) ( "Validated" , QVariant(bool, false) ) )
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "Ready" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "NeedsUpdate" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "PendingUpdate" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::infoUpdated:442 - identityimpl.cpp 442 infoUpdated SERVER INFO UPDATED. NeedsUpdate " 32 "
Sep 14 10:03:37 Jolla estart[1290]: [D] Accounts::Account::Private::account_store_cb:626 - account.cpp: 626 account_store_cb Saved accunt ID: 29
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::getInfoReply:381 - identityimpl.cpp 381 getInfoReply QMap(("ACL", QVariant(QStringList, () ) ) ( "AuthMethods" , QVariant(QDBusArgument, ) ) ( "Caption" , QVariant(QString, "Jolla") ) ( "Id" , QVariant(uint, 32) ) ( "Owner" , QVariant(QStringList, () ) ) ( "Realms" , QVariant(QStringList, () ) ) ( "RefCount" , QVariant(int, 0) ) ( "StoreSecret" , QVariant(bool, true) ) ( "Type" , QVariant(int, 0) ) ( "UserName" , QVariant(QString, "*CLEARED FOR SECURITY*") ) ( "UserNameSecret" , QVariant(bool, false) ) ( "Validated" , QVariant(bool, true) ) )
Sep 14 10:03:37 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "Ready" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:44 Jolla estart[1290]: [D] onCheckCredentialsFailed:234 - [jsa-eas] Credentials check FAILED!
error == 5
Sep 14 10:03:45 Jolla [1205]: [W] {anonymous}::SyncAdapter::syncPrivilegedToNonprivileged:684 - CDExport: exporting changes: 0 17 0
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "PendingRegistration" SignOn::IdentityImpl(0x4d1237d8)
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::signOut:285 - identityimpl.cpp 285 signOut Signing out.
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::remove:199 - identityimpl.cpp 199 remove Removing credentials.
Sep 14 10:03:46 Jolla estart[1290]: [D] Accounts::Account::Private::on_deleted:184 - account.cpp: 184 on_deleted
Sep 14 10:03:46 Jolla estart[1290]: [D] Accounts::Account::Private::on_deleted:184 - account.cpp: 184 on_deleted
Sep 14 10:03:46 Jolla estart[1290]: [D] Accounts::Account::Private::on_deleted:184 - account.cpp: 184 on_deleted
Sep 14 10:03:46 Jolla [968]: [D] Accounts::Account::Private::on_deleted:184 - account.cpp: 184 on_deleted
Sep 14 10:03:46 Jolla [968]: [W] Buteo::ProfileManagerPrivate::parseFile:1117 - Profile file not found: "/home/nemo/.cache/msyncd/sync/activesync-email.xml"
Sep 14 10:03:46 Jolla [968]: [W] Buteo::ProfileManagerPrivate::load:152 - Failed to load profile: "activesync-email"
Sep 14 10:03:46 Jolla [968]: [W] Buteo::ProfileManagerPrivate::parseFile:1117 - Profile file not found: "/home/nemo/.cache/msyncd/sync/.xml"
Sep 14 10:03:46 Jolla [968]: [W] Buteo::ProfileManagerPrivate::load:152 - Failed to load profile: ""
Sep 14 10:03:46 Jolla estart[1290]: [D] Accounts::Account::Private::account_store_cb:626 - account.cpp: 626 account_store_cb Saved accunt ID: 29
Sep 14 10:03:46 Jolla estart[1290]: [D] Accounts::Account::Private::account_store_cb:626 - account.cpp: 626 account_store_cb Saved accunt ID: 30
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "Ready" SignOn::IdentityImpl(0x4d1237d8)
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::infoUpdated:442 - identityimpl.cpp 442 infoUpdated SERVER INFO UPDATED. SignedOut " 32 "
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::infoUpdated:442 - identityimpl.cpp 442 infoUpdated SERVER INFO UPDATED. SignedOut " 32 "
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "Removed" SignOn::IdentityImpl(0x4d1237d8)
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::infoUpdated:442 - identityimpl.cpp 442 infoUpdated SERVER INFO UPDATED. Removed " 32 "
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "Removed" SignOn::IdentityImpl(0x4f059a70)
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::infoUpdated:442 - identityimpl.cpp 442 infoUpdated SERVER INFO UPDATED. Removed " 32 "
Sep 14 10:03:46 Jolla estart[1290]: [D] SignOn::IdentityImpl::updateState:96 - identityimpl.cpp 96 updateState Updating state: "Removed" SignOn::IdentityImpl(0x4d1237d8)
Sep 14 10:03:47 Jolla dbus-daemon[919]: invoker: Invoking execution: '/usr/bin/signond'
Hi I have the same issue and I have reported this to Jolla., also commented here under another related thread. Hopefully we have a fix soon.
shertell ( 2015-09-14 12:28:59 +0200 )editMine stopped syncing the calendar and email syncs only over 3G/4G so I guess it is a related problem :/ Don't know how contacts are syncing yet.
Wizah ( 2015-09-14 23:48:20 +0200 )editThanks for the report.
Is this the error log reported when you attempt to create the new Exchange account, via Settings/Accounts?
Can you give any information about the version of the remote exchange server?
Thanks, Chris.
chris.adams ( 2015-09-18 04:25:25 +0200 )editI have the same issue. In my case the exchange server is 2003.
shertell ( 2015-09-18 07:19:09 +0200 )editYes @chris.adams this is the error log created while trying to add an account, which always fails with an alleged credentials problem... we run M$ Exchange Server 2007 on Windows Server 2003 SE (x64) I tried to analyze the logs on the machine but don't know where to start :( i could not find any entrys in the standard logs as if jolla never tried to connect...maybe i have to enable some extended logs but im not that into it..
daywalker ( 2015-09-18 10:02:43 +0200 )editWe are continuing to investigate this issue, but unfortunately are not making much headway currently. We cannot reproduce with our server, which makes it difficult. Hopefully we can glean which codepath is being hit from the information contained in the logs (reported both here and also to Jolla Care), and from that inspect the code to determine what might be happening.
chris.adams ( 2015-09-22 04:55:15 +0200 )editThat error code = 5 corresponds to one of two cases:
1) if the response from the server to a credentials check request, does not contain a specific header: "MS-ASProtocolVersions"
2) if the response from the server to a credentials check request, is an error code other than SslHandshakeFailedError, AuthenticationRequiredError, TemporaryNetworkFailureError, TimeoutError, HostNotFoundError, NetworkSessionFailedError, UnknownNetworkError, or a NetworkProxyError.
Unfortunately, the code in question doesn't have enough debug logging to help us ascertain which of these two codepaths might be being activated in your case.
chris.adams ( 2015-09-22 05:09:57 +0200 )edit