Avatar for Username

Search Support

Avoid support scams. We will never ask you to call or text a phone number or share personal information. Please report suspicious activity using the “Report Abuse” option.

Learn More

This thread was archived. Please ask a new question if you need help.

Can't sync: errsync-log reports login error though firefox accepted the sync confirmation email?

  • No replies
  • 1 has this problem
  • 7 views
jklotz
jklotz
more options

Tried to add a new computer (W7) with firefox installed from scratch to sync. After getting the message that the confirmation email is sent, it took several (>10) hours for the email to arrive at my mail server 

   Received: from a27-85.smtp-out.us-west-2.amazonses.com (a27-85.smtp-out.us-west-2.amazonses.com [54.240.27.85])

by v86702.1blu.de (Postfix) with ESMTP id AA90CF2E2A33 for <firefox.sync2@klotz-juergen.de>; Sat, 17 Sep 2016 05:25:00 +0200 (CEST) 

   <snipped/>

   Date: Fri, 16 Sep 2016 17:20:19 +0000

Firefox accepted the the email's confirmation link without error, however when trying to check eg bookmarks in the config dialog, the box's border become grey instead of showing a check mark

Tried a couple of times disconnecting and reconnecting the computer from the sync account but this did not help. Sync emails always took serveral hours to arrive, btw.

Having a look at the logs in weave\logs\ showed the following (removed (xxxxxx) email and uids)

1474115037024 Sync.LogManager DEBUG Flushing file log 1474115037027 Sync.Service DEBUG Exception: Login failed: error.login.reason.network 1474115037027 Sync.Service DEBUG Not syncing: login returned false. 1474115037036 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115037040 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115037055 browserwindow.syncui DEBUG observed: weave:ui:clear-error 1474115037137 Sync.LogManager DEBUG Log cleanup threshold time: 1473251037137 1474115037160 Sync.LogManager DEBUG Done deleting files. 1474115037221 Services.Common.RESTRequest TRACE onStartRequest: POST https://oauth.accounts.firefox.com/v1/authorization 1474115037222 Services.Common.RESTRequest TRACE Channel for POST https://oauth.accounts.firefox.com/v1/authorization returned status code 0 1474115037222 Services.Common.RESTRequest DEBUG POST https://oauth.accounts.firefox.com/v1/authorization 200 1474115037222 Services.Common.RESTRequest TRACE POST body: {"access_token":"ec861404f0601683141bad7115ecdb9345b3cfcb1ca5fe60b98736c24711f289","token_type":"bearer","expires_in":1209599,"scope":"profile","auth_at":1474115036} 1474115037223 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"] 1474115037225 Services.Common.RESTRequest DEBUG GET request to https://profile.accounts.firefox.com/v1/profile 1474115037225 Services.Common.RESTRequest TRACE HTTP Header authorization: ***** (suppressed) 1474115037225 Services.Common.RESTRequest TRACE HTTP Header accept: application/json 1474115037226 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","deviceRegistrationVersion","oauthTokens"] 1474115037234 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"] 1474115037979 Services.Common.RESTRequest TRACE onStartRequest: GET https://profile.accounts.firefox.com/v1/profile 1474115037980 Services.Common.RESTRequest TRACE Channel for GET https://profile.accounts.firefox.com/v1/profile returned status code 0 1474115037980 Services.Common.RESTRequest DEBUG GET https://profile.accounts.firefox.com/v1/profile 200 1474115037980 Services.Common.RESTRequest TRACE GET body: {"email":"xxxxxxxx","uid":"xxxxxxxxxxxxxxxxxxxx"} 1474115037982 FirefoxAccounts DEBUG notifying profile changed for user xxxxxxxxxx 1474115045588 Sync.ErrorHandler DEBUG Beginning user-triggered sync. 1474115045588 Sync.Service DEBUG User-Agent: Firefox/48.0.2 FxSync/1.50.0.20160823121617. 1474115045589 Sync.Service INFO Starting sync at 2016-09-17 14:24:05 1474115045590 Sync.Service DEBUG In sync: should login. 1474115045591 browserwindow.syncui DEBUG observed: weave:service:login:start 1474115045593 Sync.Status DEBUG Status.service: error.login.failed => success.status_ok 1474115045593 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1474115045593 Sync.Service INFO Logging in the user. 1474115045596 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115045602 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115045604 Sync.Service INFO User logged in successfully - verifying login. 1474115045605 Sync.BrowserIDManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys 1474115045606 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok 1474115045608 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1474115045608 Sync.BrowserIDManager DEBUG _findCluster returning https://sync-374-us-west-2.sync.services.mozilla.com/1.5/52685247/ 1474115045609 Sync.Service DEBUG Cluster value = https://sync-374-us-west-2.sync.services.mozilla.com/1.5/52685247/ 1474115045609 Sync.Service DEBUG Setting cluster to https://sync-374-us-west-2.sync.services.mozilla.com/1.5/52685247/ 1474115045612 Sync.Service DEBUG verifyLogin failed: Error: newChannel requires the 'uri' property on the options object. (resource://services-sync/resource.js:405:19) JS Stack trace: _doRequest@resource.js:196:19 < Res__request@resource.js:397:7 < Res_get@resource.js:425:12 < verifyLogin@service.js:694:18 < onNotify@service.js:977:12 < WrappedNotify@util.js:146:21 < WrappedLock@util.js:101:16 < WrappedCatch@util.js:75:16 < login@service.js:988:12 < sync/<@service.js:1236:14 < WrappedCatch@util.js:75:16 < sync@service.js:1232:5 < Res_get@resource.js:425:12 < verifyLogin@service.js:694:18 < onNotify@service.js:977:12 < WrappedNotify@util.js:146:21 < WrappedLock@util.js:101:16 < WrappedCatch@util.js:75:16 < login@service.js:988:12 < sync/<@service.js:1236:14 < WrappedCatch@util.js:75:16 < sync@service.js:1232:5 1474115045612 Sync.Status DEBUG Status.login: error.login.reason.network => error.login.reason.network 1474115045613 Sync.Status DEBUG Status.service: success.status_ok => error.login.failed 1474115045618 browserwindow.syncui DEBUG observed: weave:service:login:error 1474115045618 browserwindow.syncui DEBUG onLoginError: login=error.login.reason.network, sync=success.sync 1474115045619 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1474115045620 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. 1474115045621 Sync.ErrorHandler ERROR Sync encountered a login error

Tried to add a new computer (W7) with firefox installed from scratch to sync. After getting the message that the confirmation email is sent, it took several (>10) hours for the email to arrive at my mail server Received: from a27-85.smtp-out.us-west-2.amazonses.com (a27-85.smtp-out.us-west-2.amazonses.com [54.240.27.85]) by v86702.1blu.de (Postfix) with ESMTP id AA90CF2E2A33 for <firefox.sync2@klotz-juergen.de>; Sat, 17 Sep 2016 05:25:00 +0200 (CEST) <snipped/> Date: Fri, 16 Sep 2016 17:20:19 +0000 Firefox accepted the the email's confirmation link without error, however when trying to check eg bookmarks in the config dialog, the box's border become grey instead of showing a check mark Tried a couple of times disconnecting and reconnecting the computer from the sync account but this did not help. Sync emails always took serveral hours to arrive, btw. Having a look at the logs in weave\logs\ showed the following (removed (xxxxxx) email and uids) 1474115037024 Sync.LogManager DEBUG Flushing file log 1474115037027 Sync.Service DEBUG Exception: Login failed: error.login.reason.network 1474115037027 Sync.Service DEBUG Not syncing: login returned false. 1474115037036 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115037040 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115037055 browserwindow.syncui DEBUG observed: weave:ui:clear-error 1474115037137 Sync.LogManager DEBUG Log cleanup threshold time: 1473251037137 1474115037160 Sync.LogManager DEBUG Done deleting files. 1474115037221 Services.Common.RESTRequest TRACE onStartRequest: POST https://oauth.accounts.firefox.com/v1/authorization 1474115037222 Services.Common.RESTRequest TRACE Channel for POST https://oauth.accounts.firefox.com/v1/authorization returned status code 0 1474115037222 Services.Common.RESTRequest DEBUG POST https://oauth.accounts.firefox.com/v1/authorization 200 1474115037222 Services.Common.RESTRequest TRACE POST body: {"access_token":"ec861404f0601683141bad7115ecdb9345b3cfcb1ca5fe60b98736c24711f289","token_type":"bearer","expires_in":1209599,"scope":"profile","auth_at":1474115036} 1474115037223 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"] 1474115037225 Services.Common.RESTRequest DEBUG GET request to https://profile.accounts.firefox.com/v1/profile 1474115037225 Services.Common.RESTRequest TRACE HTTP Header authorization: ***** (suppressed) 1474115037225 Services.Common.RESTRequest TRACE HTTP Header accept: application/json 1474115037226 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","deviceRegistrationVersion","oauthTokens"] 1474115037234 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"] 1474115037979 Services.Common.RESTRequest TRACE onStartRequest: GET https://profile.accounts.firefox.com/v1/profile 1474115037980 Services.Common.RESTRequest TRACE Channel for GET https://profile.accounts.firefox.com/v1/profile returned status code 0 1474115037980 Services.Common.RESTRequest DEBUG GET https://profile.accounts.firefox.com/v1/profile 200 1474115037980 Services.Common.RESTRequest TRACE GET body: {"email":"xxxxxxxx","uid":"xxxxxxxxxxxxxxxxxxxx"} 1474115037982 FirefoxAccounts DEBUG notifying profile changed for user xxxxxxxxxx 1474115045588 Sync.ErrorHandler DEBUG Beginning user-triggered sync. 1474115045588 Sync.Service DEBUG User-Agent: Firefox/48.0.2 FxSync/1.50.0.20160823121617. 1474115045589 Sync.Service INFO Starting sync at 2016-09-17 14:24:05 1474115045590 Sync.Service DEBUG In sync: should login. 1474115045591 browserwindow.syncui DEBUG observed: weave:service:login:start 1474115045593 Sync.Status DEBUG Status.service: error.login.failed => success.status_ok 1474115045593 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1474115045593 Sync.Service INFO Logging in the user. 1474115045596 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115045602 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network 1474115045604 Sync.Service INFO User logged in successfully - verifying login. 1474115045605 Sync.BrowserIDManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys 1474115045606 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok 1474115045608 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1474115045608 Sync.BrowserIDManager DEBUG _findCluster returning https://sync-374-us-west-2.sync.services.mozilla.com/1.5/52685247/ 1474115045609 Sync.Service DEBUG Cluster value = https://sync-374-us-west-2.sync.services.mozilla.com/1.5/52685247/ 1474115045609 Sync.Service DEBUG Setting cluster to https://sync-374-us-west-2.sync.services.mozilla.com/1.5/52685247/ 1474115045612 Sync.Service DEBUG verifyLogin failed: Error: newChannel requires the 'uri' property on the options object. (resource://services-sync/resource.js:405:19) JS Stack trace: _doRequest@resource.js:196:19 < Res__request@resource.js:397:7 < Res_get@resource.js:425:12 < verifyLogin@service.js:694:18 < onNotify@service.js:977:12 < WrappedNotify@util.js:146:21 < WrappedLock@util.js:101:16 < WrappedCatch@util.js:75:16 < login@service.js:988:12 < sync/<@service.js:1236:14 < WrappedCatch@util.js:75:16 < sync@service.js:1232:5 < Res_get@resource.js:425:12 < verifyLogin@service.js:694:18 < onNotify@service.js:977:12 < WrappedNotify@util.js:146:21 < WrappedLock@util.js:101:16 < WrappedCatch@util.js:75:16 < login@service.js:988:12 < sync/<@service.js:1236:14 < WrappedCatch@util.js:75:16 < sync@service.js:1232:5 1474115045612 Sync.Status DEBUG Status.login: error.login.reason.network => error.login.reason.network 1474115045613 Sync.Status DEBUG Status.service: success.status_ok => error.login.failed 1474115045618 browserwindow.syncui DEBUG observed: weave:service:login:error 1474115045618 browserwindow.syncui DEBUG onLoginError: login=error.login.reason.network, sync=success.sync 1474115045619 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1474115045620 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. 1474115045621 Sync.ErrorHandler ERROR Sync encountered a login error