1433782152631 Sync.Service INFO Loading Weave 1.38.0 1433782152663 Sync.Engine.Clients DEBUG Engine initialized 1433782152666 Sync.Engine.Clients DEBUG Resetting clients last sync time 1433782152693 Sync.Engine.Bookmarks DEBUG Engine initialized 1433782152703 Sync.Engine.Forms DEBUG Engine initialized 1433782152715 Sync.Engine.History DEBUG Engine initialized 1433782152725 Sync.Engine.Passwords DEBUG Engine initialized 1433782152732 Sync.Engine.Prefs DEBUG Engine initialized 1433782152740 Sync.Engine.Tabs DEBUG Engine initialized 1433782152742 Sync.Engine.Tabs DEBUG Resetting tabs last sync time 1433782152757 Sync.Engine.Addons DEBUG Engine initialized 1433782152761 Sync.Service INFO Mozilla/5.0 (Windows NT 6.1; WOW64; rv:36.0) Gecko/20100101 Firefox/36.0 1433782152820 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1433782152822 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782152823 Sync.Service DEBUG Caching URLs under storage user base: https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/ 1433782152823 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782152824 Sync.AddonsReconciler INFO Registering as Add-on Manager listener. 1433782152824 Sync.AddonsReconciler DEBUG Adding change listener. 1433782152826 Sync.Tracker.History INFO Adding Places observer. 1433782152983 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782153009 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782153010 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782153043 FirefoxAccounts DEBUG got keyPair 1433782153058 FirefoxAccounts DEBUG getCertificateSigned: true true 1433782154512 FirefoxAccounts DEBUG getCertificate got a new one: true 1433782154513 FirefoxAccounts DEBUG getAssertionFromCert 1433782154517 FirefoxAccounts DEBUG getAssertionFromCert returning signed: true 1433782154518 Sync.BrowserIDManager DEBUG Getting a token 1433782155826 Sync.BrowserIDManager DEBUG Successfully got a sync token 1433782155828 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined 1433782155829 Sync.BrowserIDManager INFO Background fetch for key bundle done 1433782155829 Sync.Status DEBUG Status.login: success.login => success.login 1433782155829 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782158016 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782158017 Sync.Service DEBUG User-Agent: Firefox/36.0.1 FxSync/1.38.0.20150305021524. 1433782158017 Sync.Service INFO Starting sync at 2015-06-08 18:49:18 1433782158017 Sync.Service DEBUG In sync: should login. 1433782158018 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782158018 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782158018 Sync.Service INFO Logging in the user. 1433782158018 Sync.Service DEBUG Caching URLs under storage user base: https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/ 1433782158019 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok 1433782158020 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782159543 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433782159543 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433782159544 Sync.Service DEBUG Fetching global metadata record 1433782159546 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782159944 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433782159944 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433782159945 Sync.Service DEBUG Clearing cached meta record. metaModified is undefined, setting to 1423962102.75 1433782159947 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782160370 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433782160370 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/meta/global 1433782160370 Sync.Service DEBUG Weave Version: 1.38.0 Local Storage: 5 Remote Storage: 5 1433782160371 Sync.Service INFO Sync key is up-to-date: no need to upgrade. 1433782160371 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1433782160372 Sync.Service INFO Testing info/collections: {"passwords":1433577446,"tabs":1433714574.83,"clients":1433744273.83,"crypto":1423962069.46,"forms":1433588857.43,"meta":1423962102.75,"prefs":1423962072.1,"bookmarks":1432139899.28,"addons":1433714575.3,"history":1433714575.84} 1433782160372 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 0 1433782160372 Sync.Service INFO collection keys reports that a key update is needed. 1433782160373 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782160896 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/crypto/keys 1433782160897 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/crypto/keys 1433782160897 Sync.CollectionKeyManager INFO Updating collection keys... 1433782160909 Sync.CollectionKeyManager INFO Setting collection keys contents. Our last modified: 0, input modified: 1423962069.46. 1433782160909 Sync.BulkKeyBundle INFO BulkKeyBundle being created for [default] 1433782160910 Sync.CollectionKeyManager INFO Processing downloaded per-collection keys. 1433782160910 Sync.CollectionKeyManager INFO Clearing collection keys... 1433782160910 Sync.CollectionKeyManager INFO Saving downloaded keys. 1433782160910 Sync.CollectionKeyManager INFO Bumping last modified to 1423962069.46 1433782160910 Sync.CollectionKeyManager INFO Collection keys updated. 1433782160910 Sync.Status DEBUG Status.login: success.login => success.login 1433782160910 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782160939 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782160940 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782160941 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1433782160945 Sync.Status INFO Resetting Status. 1433782160945 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1433782160946 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782161405 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. 1433782161431 Sync.Resource DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433782161431 Sync.Resource DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/info/collections 1433782161435 Sync.Service DEBUG Fetching global metadata record 1433782161435 Sync.Service DEBUG Weave Version: 1.38.0 Local Storage: 5 Remote Storage: 5 1433782161435 Sync.Service INFO Sync key is up-to-date: no need to upgrade. 1433782161436 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1433782161436 Sync.Service INFO Testing info/collections: {"passwords":1433577446,"tabs":1433714574.83,"clients":1433744273.83,"crypto":1423962069.46,"forms":1433588857.43,"meta":1423962102.75,"prefs":1423962072.1,"bookmarks":1432139899.28,"addons":1433714575.3,"history":1433714575.84} 1433782161436 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1423962069.46 1433782161436 Sync.Synchronizer DEBUG Refreshing client list. 1433782161437 Sync.Engine.Clients DEBUG First sync, uploading all items 1433782161438 Sync.Engine.Clients INFO 1 outgoing items pre-reconciliation 1433782161483 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782161881 Sync.Collection DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/clients?full=1 1433782161881 Sync.Collection DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/clients?full=1 1433782161883 Sync.Engine.Clients INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433782161889 Sync.Engine.Clients INFO Uploading all of 1 records 1433782161890 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782161893 Sync.Collection DEBUG POST Length: 485 1433782162032 Sync.Tracker.History DEBUG Saving changed IDs to history 1433782162278 Sync.Collection DEBUG mesg: POST success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/clients 1433782162278 Sync.Collection DEBUG POST success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/clients 1433782162279 Sync.Synchronizer INFO Updating enabled engines: 1 clients. 1433782162283 Sync.Engine.Prefs INFO 0 outgoing items pre-reconciliation 1433782162284 Sync.Engine.Prefs INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433782162285 Sync.Engine.Passwords INFO 0 outgoing items pre-reconciliation 1433782162287 Sync.Engine.Passwords INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433782162289 Sync.Engine.Tabs DEBUG First sync, uploading all items 1433782162289 Sync.Engine.Tabs INFO 1 outgoing items pre-reconciliation 1433782162291 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782162493 Sync.Tracker.Clients DEBUG Saving changed IDs to clients 1433782162787 Sync.Engine.Tabs WARN Failed to reconcile incoming record adbdzOpkkgpf 1433782162789 Sync.Engine.Tabs WARN Encountered exception: [Exception... "Default view is not tracked" nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)" location: "JS frame :: resource:///modules/sessionstore/SessionStore.jsm :: ssi_getTabState :: line 1581" data: no] Stack trace: ssi_getTabState()@resource:///modules/sessionstore/SessionStore.jsm:1581 < ss_getTabState()@resource:///modules/sessionstore/SessionStore.jsm:196 < TabStore.prototype.getTabState()@resource://gre/modules/services-sync/engines/tabs.js:111 < TabStore.prototype.getAllTabs()@resource://gre/modules/services-sync/engines/tabs.js:127 < createRecord()@resource://gre/modules/services-sync/engines/tabs.js:163 < SyncEngine.prototype._createRecord()@resource://services-sync/engines.js:831 < SyncEngine.prototype._reconcile()@resource://services-sync/engines.js:1351 < SyncEngine.prototype._processIncoming/newitems.recordHandler()@resource://services-sync/engines.js:1057 < Collection.prototype.recordHandler/this._onProgress()@resource://services-sync/record.js:625 < Channel_onDataAvail()@resource://services-sync/resource.js:551 < waitForSyncCallback()@resource://services-common/async.js:102 < Res__request()@resource://services-sync/resource.js:398 < Res_get()@resource://services-sync/resource.js:422 < SyncEngine.prototype._processIncoming()@resource://services-sync/engines.js:1085 < SyncEngine.prototype._sync()@resource://services-sync/engines.js:1497 < 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 < 1433782162790 Sync.Collection DEBUG mesg: GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/tabs?full=1 1433782162790 Sync.Collection DEBUG GET success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/tabs?full=1 1433782162790 Sync.Engine.Tabs DEBUG Records that failed to apply: adbdzOpkkgpf 1433782162791 Sync.Engine.Tabs INFO Records: 0 applied, 0 successfully, 1 failed to apply, 1 newly failed to apply, 0 reconciled. 1433782162792 Sync.Status DEBUG Status for engine tabs: error.engine.reason.apply_fail 1433782162792 Sync.Status DEBUG Status.service: success.status_ok => error.sync.failed_partial 1433782162792 Sync.ErrorHandler DEBUG tabs failed to apply some records. 1433782162795 Sync.Engine.Tabs WARN Error creating record: [Exception... "Default view is not tracked" nsresult: "0x80070057 (NS_ERROR_ILLEGAL_VALUE)" location: "JS frame :: resource:///modules/sessionstore/SessionStore.jsm :: ssi_getTabState :: line 1581" data: no] Stack trace: ssi_getTabState()@resource:///modules/sessionstore/SessionStore.jsm:1581 < ss_getTabState()@resource:///modules/sessionstore/SessionStore.jsm:196 < TabStore.prototype.getTabState()@resource://gre/modules/services-sync/engines/tabs.js:111 < TabStore.prototype.getAllTabs()@resource://gre/modules/services-sync/engines/tabs.js:127 < createRecord()@resource://gre/modules/services-sync/engines/tabs.js:163 < 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 < 1433782162811 Sync.Engine.Tabs INFO Uploading all of 1 records 1433782162812 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782162814 Sync.Collection DEBUG POST Length: 2 1433782162838 Sync.Engine.Tabs ERROR null 1433782163238 Sync.Collection DEBUG mesg: POST success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/tabs 1433782163238 Sync.Collection DEBUG POST success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/tabs 1433782163245 Sync.Engine.Bookmarks INFO 0 outgoing items pre-reconciliation 1433782163249 Sync.Engine.Bookmarks INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433782163269 Sync.Engine.Addons DEBUG Refreshing reconciler state 1433782163269 Sync.AddonsReconciler INFO Refreshing global state from AddonManager. 1433782163289 Sync.Tracker.Passwords DEBUG Saving changed IDs to passwords 1433782163785 Sync.AddonsReconciler DEBUG Rectifying state for addon: gmp-gmpopenh264 1433782163785 Sync.AddonsReconciler DEBUG Rectifying state for addon: {c00bf7ce-2b6e-bc3f-7058-ae4b9a37d279} 1433782163785 Sync.AddonsReconciler DEBUG Rectifying state for addon: {10c68f07-63a6-4653-8f43-9fc763f22443} 1433782163785 Sync.AddonsReconciler DEBUG Rectifying state for addon: {b0abad6a-59ad-ab23-b4cf-b9093349a0ed} 1433782163793 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1433782163919 Sync.Engine.Addons INFO 0 outgoing items pre-reconciliation 1433782163921 Sync.Engine.Addons INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433782163923 Sync.Engine.Forms INFO 0 outgoing items pre-reconciliation 1433782163924 Sync.Engine.Forms INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433782163925 Sync.Engine.History INFO 12 outgoing items pre-reconciliation 1433782163926 Sync.Engine.History INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1433782164087 Sync.Engine.History INFO Uploading all of 12 records 1433782164088 Sync.BrowserIDManager DEBUG _ensureValidToken already has one 1433782164094 Sync.Collection DEBUG POST Length: 7667 1433782164247 Sync.Tracker.Tabs DEBUG Saving changed IDs to tabs 1433782164253 Sync.Tracker.Bookmarks DEBUG Saving changed IDs to bookmarks 1433782164923 Sync.Tracker.Addons DEBUG Saving changed IDs to addons 1433782164928 Sync.Tracker.Forms DEBUG Saving changed IDs to forms 1433782164939 Sync.Tracker.History DEBUG Saving changed IDs to history 1433782165025 Sync.Collection DEBUG mesg: POST success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/history 1433782165026 Sync.Collection DEBUG POST success 200 https://sync-203-us-west-2.sync.services.mozilla.com/1.5/20473201/storage/history 1433782165081 Sync.Synchronizer INFO Sync completed at 2015-06-08 18:49:25 after 4.08 secs. 1433782165086 Sync.Declined DEBUG Handling remote declined: [] 1433782165086 Sync.Declined DEBUG Handling local declined: [] 1433782165086 Sync.Declined DEBUG Declined changed? false 1433782165087 Sync.Service INFO No change to declined engines. Not reuploading meta/global. 1433782165089 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. 1433782165090 Sync.ErrorHandler DEBUG Some engines did not sync correctly.