1433744251228 Sync.Service INFO Loading Weave 1.38.0 1433744251231 Sync.Engine.Clients DEBUG Engine initialized 1433744251232 Sync.Engine.Clients DEBUG Resetting clients last sync time 1433744251238 Sync.Engine.Bookmarks DEBUG Engine initialized 1433744251242 Sync.Engine.Forms DEBUG Engine initialized 1433744251246 Sync.Engine.History DEBUG Engine initialized 1433744251250 Sync.Engine.Passwords DEBUG Engine initialized 1433744251253 Sync.Engine.Prefs DEBUG Engine initialized 1433744251256 Sync.Engine.Tabs DEBUG Engine initialized 1433744251257 Sync.Engine.Tabs DEBUG Resetting tabs last sync time 1433744251263 Sync.Engine.Addons DEBUG Engine initialized 1433744251265 Sync.Service INFO Mozilla/5.0 (Windows NT 6.1; WOW64; rv:36.0) Gecko/20100101 Firefox/36.0 1433744251268 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1433744251269 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744251269 Sync.Service DEBUG Caching URLs under storage user base: https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/ 1433744251269 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744251270 Sync.AddonsReconciler INFO Registering as Add-on Manager listener. 1433744251270 Sync.AddonsReconciler DEBUG Adding change listener. 1433744251271 Sync.Tracker.History INFO Adding Places observer. 1433744251316 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744251317 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744251363 FirefoxAccounts DEBUG got keyPair 1433744251363 FirefoxAccounts DEBUG getCertificateSigned: true true 1433744252492 FirefoxAccounts DEBUG getCertificate got a new one: true 1433744252492 FirefoxAccounts DEBUG getAssertionFromCert 1433744252497 FirefoxAccounts DEBUG getAssertionFromCert returning signed: true 1433744252498 Sync.BrowserIDManager DEBUG Getting a token 1433744253249 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744253670 Sync.BrowserIDManager DEBUG Successfully got a sync token 1433744253672 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined 1433744253673 Sync.BrowserIDManager INFO Background fetch for key bundle done 1433744253673 Sync.Status DEBUG Status.login: success.login => success.login 1433744253673 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744255333 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744255336 Sync.Service DEBUG User-Agent: Firefox/36.0.1 FxSync/1.38.0.20150305021524. 1433744255336 Sync.Service INFO Starting sync at 2015-06-08 08:17:35 1433744255336 Sync.Service DEBUG In sync: should login. 1433744255337 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744255337 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744255338 Sync.Service INFO Logging in the user. 1433744255338 Sync.Service DEBUG Caching URLs under storage user base: https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/ 1433744255339 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok 1433744255341 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744256521 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. 1433744256769 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433744256769 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433744271965 Sync.Service DEBUG Fetching global metadata record 1433744271967 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744272284 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433744272284 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433744272285 Sync.Service DEBUG Clearing cached meta record. metaModified is undefined, setting to 1423962102.75 1433744272287 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744272577 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433744272577 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433744272578 Sync.Service DEBUG Weave Version: 1.38.0 Local Storage: 5 Remote Storage: 5 1433744272579 Sync.Service INFO Sync key is up-to-date: no need to upgrade. 1433744272580 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1433744272581 Sync.Service INFO Testing info/collections: {"passwords":1433577446,"addons":1433714575.3,"tabs":1433714574.83,"clients":1433714573.85,"crypto":1423962069.46,"forms":1433588857.43,"meta":1423962102.75,"bookmarks":1432139899.28,"prefs":1423962072.1,"history":1433714575.84} 1433744272581 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 0 1433744272581 Sync.Service INFO collection keys reports that a key update is needed. 1433744272583 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744272939 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/crypto/keys 1433744272939 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/crypto/keys 1433744272940 Sync.CollectionKeyManager INFO Updating collection keys... 1433744272944 Sync.CollectionKeyManager INFO Setting collection keys contents. Our last modified: 0, input modified: 1423962069.46. 1433744272944 Sync.BulkKeyBundle INFO BulkKeyBundle being created for [default] 1433744272945 Sync.CollectionKeyManager INFO Processing downloaded per-collection keys. 1433744272945 Sync.CollectionKeyManager INFO Clearing collection keys... 1433744272945 Sync.CollectionKeyManager INFO Saving downloaded keys. 1433744272945 Sync.CollectionKeyManager INFO Bumping last modified to 1423962069.46 1433744272945 Sync.CollectionKeyManager INFO Collection keys updated. 1433744272945 Sync.Status DEBUG Status.login: success.login => success.login 1433744272945 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744272958 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744272960 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1433744272964 Sync.Status INFO Resetting Status. 1433744272964 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433744272966 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744273246 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433744273246 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433744273248 Sync.Service DEBUG Fetching global metadata record 1433744273248 Sync.Service DEBUG Weave Version: 1.38.0 Local Storage: 5 Remote Storage: 5 1433744273248 Sync.Service INFO Sync key is up-to-date: no need to upgrade. 1433744273248 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1433744273248 Sync.Service INFO Testing info/collections: {"passwords":1433577446,"addons":1433714575.3,"tabs":1433714574.83,"clients":1433714573.85,"crypto":1423962069.46,"forms":1433588857.43,"meta":1423962102.75,"bookmarks":1432139899.28,"prefs":1423962072.1,"history":1433714575.84} 1433744273248 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1423962069.46 1433744273248 Sync.Synchronizer DEBUG Refreshing client list. 1433744273249 Sync.Engine.Clients DEBUG First sync, uploading all items 1433744273250 Sync.Engine.Clients INFO 1 outgoing items pre-reconciliation 1433744273252 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744273532 Sync.Collection DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/clients?full=1 1433744273532 Sync.Collection DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/clients?full=1 1433744273534 Sync.Engine.Clients INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433744273539 Sync.Engine.Clients INFO Uploading all of 1 records 1433744273540 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744273543 Sync.Collection DEBUG POST Length: 485 1433744273948 Sync.Status DEBUG Status for engine clients: error.engine.reason.unknown_fail 1433744273948 Sync.Status DEBUG Status.service: success.status_ok => error.sync.failed_partial 1433744273948 Sync.ErrorHandler DEBUG clients failed: Error: App. Quitting (resource://services-sync/resource.js:402:18) JS Stack trace: waitForSyncCallback@async.js:101:11 < Res__request@resource.js:398:13 < Res_post@resource.js:436:11 < SyncEngine.prototype._uploadOutgoing/doUpload<@engines.js:1400:19 < innerBind@util.js:525:41 < SyncEngine.prototype._uploadOutgoing@engines.js:1448:8 < SyncEngine.prototype._sync@engines.js:1499:6 < WrappedNotify@util.js:148:20 < Engine.prototype.sync@engines.js:657:4 < _syncEngine@enginesync.js:199:6 < sync@enginesync.js:91:9 < onNotify@service.js:1275:6 < WrappedNotify@util.js:148:20 < WrappedLock@util.js:103:15 < _lockedSync@service.js:1265:11 < sync/<@service.js:1257:13 < WrappedCatch@util.js:77:15 < sync@service.js:1245:4 < Res_post@resource.js:436:12 < SyncEngine.prototype._uploadOutgoing/doUpload<@engines.js:1400:20 < innerBind@util.js:525:42 < SyncEngine.prototype._uploadOutgoing@engines.js:1448:9 < SyncEngine.prototype._sync@engines.js:1499:7 < WrappedNotify@util.js:148:21 < Engine.prototype.sync@engines.js:657:5 < _syncEngine@enginesync.js:199:7 < sync@enginesync.js:91:10 < onNotify@service.js:1275:7 < WrappedNotify@util.js:148:21 < WrappedLock@util.js:103:16 < _lockedSync@service.js:1265:12 < sync/<@service.js:1257:14 < WrappedCatch@util.js:77:16 < sync@service.js:1245:5 1433744273948 Sync.Synchronizer INFO Updating enabled engines: 1 clients. 1433744273952 Sync.Engine.Prefs INFO 0 outgoing items pre-reconciliation 1433744273953 Sync.Engine.Prefs INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433744273954 Sync.Engine.Passwords INFO 0 outgoing items pre-reconciliation 1433744273955 Sync.Engine.Passwords INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433744273957 Sync.Engine.Tabs DEBUG First sync, uploading all items 1433744273957 Sync.Engine.Tabs INFO 1 outgoing items pre-reconciliation 1433744273958 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433744273959 Sync.BrowserIDManager ERROR Failed to fetch a token for authentication: [Exception... "App. Quitting" nsresult: "0x80004004 (NS_ERROR_ABORT)" location: "JS frame :: resource://services-common/async.js :: checkAppReady/onQuitApplication/Async.checkAppReady :: line 126" data: no] 1433744273960 Sync.Status DEBUG Status for engine tabs: error.engine.reason.unknown_fail 1433744273960 Sync.Status DEBUG Status.service: error.sync.failed_partial => error.sync.failed_partial 1433744273960 Sync.ErrorHandler DEBUG tabs failed: Error: App. Quitting (resource://services-sync/resource.js:402:18) JS Stack trace: waitForSyncCallback@async.js:101:11 < Res__request@resource.js:398:13 < Res_get@resource.js:422:11 < SyncEngine.prototype._processIncoming@engines.js:1085:17 < SyncEngine.prototype._sync@engines.js:1497:6 < WrappedNotify@util.js:148:20 < Engine.prototype.sync@engines.js:657:4 < _syncEngine@enginesync.js:199:6 < sync@enginesync.js:149:14 < onNotify@service.js:1275:6 < WrappedNotify@util.js:148:20 < WrappedLock@util.js:103:15 < _lockedSync@service.js:1265:11 < sync/<@service.js:1257:13 < WrappedCatch@util.js:77:15 < sync@service.js:1245:4 < Res_get@resource.js:422:12 < SyncEngine.prototype._processIncoming@engines.js:1085:18 < SyncEngine.prototype._sync@engines.js:1497:7 < WrappedNotify@util.js:148:21 < Engine.prototype.sync@engines.js:657:5 < _syncEngine@enginesync.js:199:7 < sync@enginesync.js:149:15 < onNotify@service.js:1275:7 < WrappedNotify@util.js:148:21 < WrappedLock@util.js:103:16 < _lockedSync@service.js:1265:12 < sync/<@service.js:1257:14 < WrappedCatch@util.js:77:16 < sync@service.js:1245:5 1433744273964 Sync.Engine.Bookmarks INFO 0 outgoing items pre-reconciliation 1433744273965 Sync.Status DEBUG Status for engine bookmarks: error.engine.reason.unknown_fail 1433744273965 Sync.Status DEBUG Status.service: error.sync.failed_partial => error.sync.failed_partial 1433744273966 Sync.ErrorHandler DEBUG bookmarks failed: [Exception... "App. Quitting" nsresult: "0x80004004 (NS_ERROR_ABORT)" location: "JS frame :: resource://services-common/async.js :: checkAppReady/onQuitApplication/Async.checkAppReady :: line 126" data: no] Stack trace: checkAppReady/onQuitApplication/Async.checkAppReady()@resource://services-common/async.js:126 < waitForSyncCallback()@resource://services-common/async.js:101 < makeSpinningCallback/callback.wait()@resource://services-common/async.js:145 < _syncStart()@resource://gre/modules/services-sync/engines/bookmarks.js:370 < SyncEngine.prototype._sync()@resource://services-sync/engines.js:1495 < wrappedSync()@resource://gre/modules/services-sync/engines/bookmarks.js:217 < _sync()@resource://gre/modules/services-sync/engines/bookmarks.js:214 < WrappedNotify()@resource://services-sync/util.js:148 < Engine.prototype.sync()@resource://services-sync/engines.js:657 < _syncEngine()@resource://services-sync/stages/enginesync.js:199 < sync()@resource://services-sync/stages/enginesync.js:149 < onNotify()@resource://gre/modules/services-sync/service.js:1275 < WrappedNotify()@resource://services-sync/util.js:148 < WrappedLock()@resource://services-sync/util.js:103 < _lockedSync()@resource://gre/modules/services-sync/service.js:1265 < sync/<()@resource://gre/modules/services-sync/service.js:1257 < WrappedCatch()@resource://services-sync/util.js:77 < sync()@resource://gre/modules/services-sync/service.js:1245 < 1433744273967 Sync.Engine.Addons DEBUG Refreshing reconciler state 1433744273967 Sync.AddonsReconciler INFO Refreshing global state from AddonManager. 1433744273969 Sync.Status DEBUG Status for engine addons: error.engine.reason.unknown_fail 1433744273969 Sync.Status DEBUG Status.service: error.sync.failed_partial => error.sync.failed_partial 1433744273970 Sync.ErrorHandler DEBUG addons failed: [Exception... "App. Quitting" nsresult: "0x80004004 (NS_ERROR_ABORT)" location: "JS frame :: resource://services-common/async.js :: checkAppReady/onQuitApplication/Async.checkAppReady :: line 126" data: no] Stack trace: checkAppReady/onQuitApplication/Async.checkAppReady()@resource://services-common/async.js:126 < waitForSyncCallback()@resource://services-common/async.js:101 < makeSpinningCallback/callback.wait()@resource://services-common/async.js:145 < _ensureStateLoaded()@resource://gre/modules/services-sync/addonsreconciler.js:579 < pruneChangesBeforeDate()@resource://gre/modules/services-sync/addonsreconciler.js:526 < _syncCleanup()@resource://gre/modules/services-sync/engines/addons.js:218 < SyncEngine.prototype._sync()@resource://services-sync/engines.js:1502 < WrappedNotify()@resource://services-sync/util.js:148 < Engine.prototype.sync()@resource://services-sync/engines.js:657 < _syncEngine()@resource://services-sync/stages/enginesync.js:199 < sync()@resource://services-sync/stages/enginesync.js:149 < onNotify()@resource://gre/modules/services-sync/service.js:1275 < WrappedNotify()@resource://services-sync/util.js:148 < WrappedLock()@resource://services-sync/util.js:103 < _lockedSync()@resource://gre/modules/services-sync/service.js:1265 < sync/<()@resource://gre/modules/services-sync/service.js:1257 < WrappedCatch()@resource://services-sync/util.js:77 < sync()@resource://gre/modules/services-sync/service.js:1245 < 1433744273971 Sync.Engine.Forms INFO 0 outgoing items pre-reconciliation 1433744273972 Sync.Engine.Forms INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433744273973 Sync.Engine.History INFO 1 outgoing items pre-reconciliation 1433744273974 Sync.Engine.History INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433744273977 Sync.Engine.History WARN Error creating record: [Exception... "App. Quitting" nsresult: "0x80004004 (NS_ERROR_ABORT)" location: "JS frame :: resource://services-common/async.js :: checkAppReady/onQuitApplication/Async.checkAppReady :: line 126" data: no] Stack trace: checkAppReady/onQuitApplication/Async.checkAppReady()@resource://services-common/async.js:126 < waitForSyncCallback()@resource://services-common/async.js:101 < querySpinningly()@resource://services-common/async.js:201 < HistStore__findURLByGUID()@resource://gre/modules/services-sync/engines/history.js:165 < createRecord()@resource://gre/modules/services-sync/engines/history.js:333 < SyncEngine.prototype._createRecord()@resource://services-sync/engines.js:831 < SyncEngine.prototype._uploadOutgoing()@resource://services-sync/engines.js:1428 < SyncEngine.prototype._sync()@resource://services-sync/engines.js:1499 < WrappedNotify()@resource://services-sync/util.js:148 < Engine.prototype.sync()@resource://services-sync/engines.js:657 < _syncEngine()@resource://services-sync/stages/enginesync.js:199 < sync()@resource://services-sync/stages/enginesync.js:149 < onNotify()@resource://gre/modules/services-sync/service.js:1275 < WrappedNotify()@resource://services-sync/util.js:148 < WrappedLock()@resource://services-sync/util.js:103 < _lockedSync()@resource://gre/modules/services-sync/service.js:1265 < sync/<()@resource://gre/modules/services-sync/service.js:1257 < WrappedCatch()@resource://services-sync/util.js:77 < sync()@resource://gre/modules/services-sync/service.js:1245 < 1433744273978 Sync.Status DEBUG Status for engine history: error.engine.reason.unknown_fail 1433744273978 Sync.Status DEBUG Status.service: error.sync.failed_partial => error.sync.failed_partial 1433744273979 Sync.ErrorHandler DEBUG history failed: [Exception... "App. Quitting" nsresult: "0x80004004 (NS_ERROR_ABORT)" location: "JS frame :: resource://services-common/async.js :: checkAppReady/onQuitApplication/Async.checkAppReady :: line 126" data: no] Stack trace: checkAppReady/onQuitApplication/Async.checkAppReady()@resource://services-common/async.js:126 < waitForSyncCallback()@resource://services-common/async.js:101 < _sleep()@resource://services-sync/engines.js:275 < SyncEngine.prototype._uploadOutgoing()@resource://services-sync/engines.js:1443 < SyncEngine.prototype._sync()@resource://services-sync/engines.js:1499 < WrappedNotify()@resource://services-sync/util.js:148 < Engine.prototype.sync()@resource://services-sync/engines.js:657 < _syncEngine()@resource://services-sync/stages/enginesync.js:199 < sync()@resource://services-sync/stages/enginesync.js:149 < onNotify()@resource://gre/modules/services-sync/service.js:1275 < WrappedNotify()@resource://services-sync/util.js:148 < WrappedLock()@resource://services-sync/util.js:103 < _lockedSync()@resource://gre/modules/services-sync/service.js:1265 < sync/<()@resource://gre/modules/services-sync/service.js:1257 < WrappedCatch()@resource://services-sync/util.js:77 < sync()@resource://gre/modules/services-sync/service.js:1245 < 1433744273979 Sync.Synchronizer INFO Sync completed at 2015-06-08 08:17:53 after 1.02 secs. 1433744273980 Sync.SyncScheduler DEBUG Next sync in 3600000 ms.