Skip to content

Instantly share code, notes, and snippets.

@scottstensland

scottstensland/FF Nightly Sync log

Created March 5, 2016 02:02
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save scottstensland/dcaf5e98cf59a628a05e to your computer and use it in GitHub Desktop.
Save scottstensland/dcaf5e98cf59a628a05e to your computer and use it in GitHub Desktop.
Download ZIP
Raw
FF Nightly Sync log
1457141318223 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141318223 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141318226 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141318228 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141318230 browserwindow.syncui DEBUG observed: weave:ui:sync:finish
1457141318230 browserwindow.syncui DEBUG observed: weave:ui:sync:finish
1457141318508 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141318508 Sync.RESTResponse TRACE Processing response headers.
1457141318508 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -508
1457141318509 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141318510 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141318510 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141318518 FirefoxAccounts TRACE finished write of json user data - took: 7
1457141318518 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141318518 FirefoxAccounts TRACE starting write of user data to the login manager
1457141318527 FirefoxAccounts TRACE finished write of user data to the login manager
1457141319190 Sync.Tracker.Clients DEBUG Saving changed IDs to clients
1457141319198 Sync.Tracker.AdblockPlus DEBUG Saving changed IDs to adblockplus
1457141319204 Sync.Tracker.Passwords DEBUG Saving changed IDs to passwords
1457141319206 Sync.Tracker.Bookmarks DEBUG Saving changed IDs to bookmarks
1457141319215 Sync.Tracker.Forms DEBUG Saving changed IDs to forms
1457141319215 Sync.Tracker.History DEBUG Saving changed IDs to history
1457141462523 Sync.Tracker.History DEBUG Saving changed IDs to history
1457141471242 Sync.Tracker.History DEBUG Saving changed IDs to history
1457141566455 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1457141566455 FirefoxAccounts DEBUG getOAuthToken enter
1457141566457 FirefoxAccounts TRACE getCachedToken returning cached token
1457141566457 FirefoxAccounts DEBUG getOAuthToken returning a cached token
1457141566851 FirefoxAccounts DEBUG fetched profile matches cached copy
1457141582175 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1457141582175 FirefoxAccounts DEBUG getOAuthToken enter
1457141582177 FirefoxAccounts TRACE getCachedToken returning cached token
1457141582177 FirefoxAccounts DEBUG getOAuthToken returning a cached token
1457141582416 FirefoxAccounts DEBUG fetched profile matches cached copy
1457141590978 FirefoxAccounts DEBUG updating existing device details
1457141590984 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1457141590985 FirefoxAccounts DEBUG getOAuthToken enter
1457141590986 FirefoxAccounts TRACE getCachedToken returning cached token
1457141590986 FirefoxAccounts DEBUG getOAuthToken returning a cached token
1457141591170 FirefoxAccounts DEBUG fetched profile matches cached copy
1457141591302 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141591302 Sync.RESTResponse TRACE Processing response headers.
1457141591303 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -303
1457141591304 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141591306 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141591306 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141591322 FirefoxAccounts TRACE finished write of json user data - took: 16
1457141591322 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141591323 FirefoxAccounts TRACE starting write of user data to the login manager
1457141591336 FirefoxAccounts TRACE finished write of user data to the login manager
1457141596256 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1457141596257 FirefoxAccounts DEBUG getOAuthToken enter
1457141596259 FirefoxAccounts TRACE getCachedToken returning cached token
1457141596259 FirefoxAccounts DEBUG getOAuthToken returning a cached token
1457141596442 FirefoxAccounts DEBUG fetched profile matches cached copy
1457141601762 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1457141601763 FirefoxAccounts DEBUG getOAuthToken enter
1457141601764 FirefoxAccounts TRACE getCachedToken returning cached token
1457141601764 FirefoxAccounts DEBUG getOAuthToken returning a cached token
1457141601976 FirefoxAccounts DEBUG fetched profile matches cached copy
1457141612841 Sync.Tracker.History DEBUG Saving changed IDs to history
1457141622993 Sync.Tracker.History DEBUG Saving changed IDs to history
1457141645454 FirefoxAccounts DEBUG updating existing device details
1457141645461 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1457141645462 FirefoxAccounts DEBUG getOAuthToken enter
1457141645463 FirefoxAccounts TRACE getCachedToken returning cached token
1457141645464 FirefoxAccounts DEBUG getOAuthToken returning a cached token
1457141645558 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141645558 Sync.RESTResponse TRACE Processing response headers.
1457141645559 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -559
1457141645560 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141645561 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141645561 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141645571 FirefoxAccounts TRACE finished write of json user data - took: 9
1457141645572 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141645572 FirefoxAccounts TRACE starting write of user data to the login manager
1457141645579 FirefoxAccounts TRACE finished write of user data to the login manager
1457141645657 FirefoxAccounts DEBUG fetched profile matches cached copy
1457141649359 FirefoxAccounts DEBUG removing account data
1457141649359 FirefoxAccounts TRACE starting write of json user data: null
1457141649378 FirefoxAccounts TRACE finished write of json user data - took: 19
1457141649387 FirefoxAccounts TRACE storage set finished clearing account data
1457141649387 FirefoxAccounts DEBUG account data reset
1457141649387 FirefoxAccounts TRACE StorageManager finalizing
1457141649388 FirefoxAccounts TRACE StorageManager finalized
1457141649389 FirefoxAccounts TRACE initializing new storage manager
1457141649390 FirefoxAccounts TRACE starting fetch of json user data
1457141649392 FirefoxAccounts TRACE finished fetch of json user data - took: 2
1457141649392 FirefoxAccounts TRACE initializing of new storage manager done
1457141649393 FirefoxAccounts DEBUG destroying device and session
1457141649399 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onlogout
1457141649399 Sync.BrowserIDManager DEBUG observed fxaccounts:onlogout
1457141649401 Sync.Tracker.History INFO Removing Places observer.
1457141649403 Sync.Status INFO Resetting Status.
1457141649403 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141649405 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141649511 Hawk DEBUG (Response) /account/device/destroy?service=sync: code: 200 - Status text: OK
1457141649511 Sync.RESTResponse TRACE Processing response headers.
1457141649511 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -511
1457141649513 FirefoxAccounts ERROR Error during remote sign out of Firefox Accounts: Error: No user is logged in (resource://gre/modules/FxAccountsStorage.jsm:201:13) JS Stack trace: this.FxAccountsStorageManager.prototype.updateAccountData<@FxAccountsStorage.jsm:201:13 < TaskImpl_run@Task.jsm:319:40 < Handler.prototype.process@Promise-backend.js:937:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:816:7 < Promise*this.PromiseWalker.scheduleWalkerLoop@Promise-backend.js:747:11 < this.PromiseWalker.schedulePromise@Promise-backend.js:779:7 < this.PromiseWalker.completePromise@Promise-backend.js:714:7 < _onComplete@hawkclient.js:272:7 < onComplete@hawkclient.js:279:9 < onStopRequest@rest.js:498:5 < waitForSyncCallback@async.js:98:7 < Res__request@resource.js:398:14 < Res_delete@resource.js:446:12 < removeClientData@clients.js:190:5 < startOver@service.js:864:11 < this.BrowserIDManager.prototype.observe@browserid_identity.js:308:7 < FxAccountsInternal.prototype.notifyObservers@FxAccounts.jsm:1000:5 < signOut/<@FxAccounts.jsm:668:7 < Handler.prototype.process@Promise-backend.js:937:23 < this.PromiseWalker.walkerLoop@Promise-backend.js:816:7 < Promise*this.PromiseWalker.scheduleWalkerLoop@Promise-backend.js:747:11 < this.PromiseWalker.schedulePromise@Promise-backend.js:779:7 < this.PromiseWalker.completePromise@Promise-backend.js:714:7 < promise callback*this.PromiseWalker.completePromise@Promise-backend.js:705:7 < Handler.prototype.process@Promise-backend.js:973:5 < Promise*this.PromiseWalker.scheduleWalkerLoop@Promise-backend.js:747:11 < this.PromiseWalker.schedulePromise@Promise-backend.js:779:7 < Promise.prototype.then@Promise-backend.js:454:5 < TaskImpl_handleResultValue@Task.jsm:395:7 < TaskImpl_run@Task.jsm:327:13 < TaskImpl@Task.jsm:280:3 < createAsyncFunction/asyncFunction@Task.jsm:254:14 < getUserAccountData@FxAccounts.jsm:145:12 < signOut@FxAccounts.jsm:636:12 < gSyncPane.unlinkFirefoxAccount@sync.js:661:5 < gSyncPane._setupEventListeners/<@sync.js:275:7 < EventListener.handleEvent*setEventListener@sync.js:196:7 < gSyncPane._setupEventListeners@sync.js:274:5 < EventListener.handleEvent*init_all@preferences.js:59:3 < onLoad@preferences.js:42:3 < EventListener.handleEvent*@preferences.js:40:1
1457141649519 FirefoxAccounts DEBUG FxAccountsOAuthGrantClient Initialized
1457141649815 Sync.Resource DEBUG mesg: DELETE success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/clients/HaqA1itiVB8O
1457141649815 Sync.Resource DEBUG DELETE success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/clients/HaqA1itiVB8O
1457141649818 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141649907 FirefoxAccounts DEBUG Notifying observers of testhelper-fxa-signout-complete
1457141649925 Sync.Resource DEBUG mesg: DELETE success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/tabs/HaqA1itiVB8O
1457141649925 Sync.Resource DEBUG DELETE success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/tabs/HaqA1itiVB8O
1457141649927 Sync.Service DEBUG Finished deleting client data.
1457141649927 Sync.Service INFO Service.startOver dropping sync key and logging out.
1457141649927 Sync.Status DEBUG Status.login: success.login => error.login.reason.no_recoverykey
1457141649927 Sync.Status DEBUG Status.service: success.status_ok => service.client_not_configured
1457141649927 Sync.Service INFO Logging out
1457141649929 browserwindow.syncui DEBUG observed: weave:service:logout:finish
1457141649930 browserwindow.syncui DEBUG observed: weave:service:logout:finish
1457141649931 browserwindow.syncui DEBUG observed: weave:service:start-over
1457141649932 browserwindow.syncui DEBUG observed: weave:service:start-over
1457141649933 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1457141649934 Sync.Service INFO Service reset.
1457141649935 Sync.Engine.Clients DEBUG Resetting clients last sync time
1457141649935 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time
1457141649936 Sync.Engine.Forms DEBUG Resetting forms last sync time
1457141649937 Sync.Engine.History DEBUG Resetting history last sync time
1457141649938 Sync.Engine.Passwords DEBUG Resetting passwords last sync time
1457141649939 Sync.Engine.Prefs DEBUG Resetting prefs last sync time
1457141649940 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1457141649940 Sync.Engine.Addons DEBUG Resetting addons last sync time
1457141649941 Sync.Engine.AdblockPlus DEBUG Resetting adblockplus last sync time
1457141649942 Sync.CollectionKeyManager INFO Clearing collection keys...
1457141649946 Sync.Tracker.Clients DEBUG client.name preference changed
1457141649956 Sync.AddonsReconciler INFO Registering as Add-on Manager listener.
1457141649956 Sync.AddonsReconciler DEBUG Adding change listener.
1457141649960 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1457141649967 browserwindow.syncui DEBUG observed: weave:service:start-over:finish
1457141649968 browserwindow.syncui DEBUG observed: weave:service:start-over:finish
1457141649973 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649974 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649975 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649976 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649977 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649978 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649984 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649984 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649984 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649985 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649985 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141649985 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1457141650946 Sync.Tracker.Clients DEBUG Saving changed IDs to clients
1457141654535 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:loaded
1457141654535 FirefoxAccounts WARN Unrecognized FxAccountsWebChannel command: fxaccounts:loaded
1457141677736 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:can_link_account
1457141677736 FirefoxAccounts DEBUG FxAccountsWebChannel response: {"command":"fxaccounts:can_link_account","messageId":1457141677701,"data":{"ok":true}}
1457141704246 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:can_link_account
1457141704247 FirefoxAccounts DEBUG FxAccountsWebChannel response: {"command":"fxaccounts:can_link_account","messageId":1457141704232,"data":{"ok":true}}
1457141704762 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:login
1457141704764 FirefoxAccounts DEBUG setSignedInUser - aborting any existing flows
1457141704764 FirefoxAccounts TRACE StorageManager finalizing
1457141704765 FirefoxAccounts TRACE StorageManager finalized
1457141704767 FirefoxAccounts TRACE initializing new storage manager
1457141704767 FirefoxAccounts WARN Unknown FxA field name in user data, treating as in-memory: customizeSync
1457141704768 FirefoxAccounts WARN Unknown FxA field name in user data, treating as in-memory: declinedSyncEngines
1457141704771 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified"]
1457141704771 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified"]
1457141704783 FirefoxAccounts TRACE finished write of json user data - took: 12
1457141704783 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"]
1457141704784 FirefoxAccounts TRACE starting write of user data to the login manager
1457141704789 FirefoxAccounts TRACE finished write of user data to the login manager
1457141704789 FirefoxAccounts TRACE initializing of new storage manager done
1457141704794 FirefoxAccounts DEBUG registering new device details
1457141704906 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141704907 Sync.RESTResponse TRACE Processing response headers.
1457141704907 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -907
1457141704908 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141704908 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onlogin
1457141704909 Sync.BrowserIDManager DEBUG observed fxaccounts:onlogin
1457141704912 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1457141704912 Sync.BrowserIDManager INFO Waiting for user to be verified.
1457141704912 FirefoxAccounts DEBUG already verified
1457141704913 FirefoxAccounts DEBUG FxAccountsProfileClient: Initialized
1457141704913 Sync.BrowserIDManager INFO Starting fetch for key bundle.
1457141704913 FirefoxAccounts DEBUG already verified
1457141704914 Sync.BrowserIDManager INFO Fetching new keys
1457141704915 FirefoxAccounts DEBUG fetchKeys: true
1457141704920 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId"]
1457141704920 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId"]
1457141704920 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1457141704921 FirefoxAccounts DEBUG getOAuthToken enter
1457141704924 FirefoxAccounts DEBUG FxAccountsOAuthGrantClient Initialized
1457141704924 FirefoxAccounts DEBUG getOAuthToken fetching new token from: https://oauth.accounts.firefox.com/v1
1457141704924 FirefoxAccounts DEBUG enter getAssertion()
1457141704931 FirefoxAccounts DEBUG got keyPair
1457141704931 FirefoxAccounts DEBUG getCertificateSigned: true true
1457141704938 FirefoxAccounts TRACE finished write of json user data - took: 18
1457141704938 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"]
1457141704938 FirefoxAccounts TRACE starting write of user data to the login manager
1457141704945 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705030 Hawk DEBUG (Response) /account/keys: code: 200 - Status text: OK
1457141705030 Sync.RESTResponse TRACE Processing response headers.
1457141705030 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -1030
1457141705033 FirefoxAccounts DEBUG Keys Obtained: kA=true, kB=true
1457141705034 FirefoxAccounts DEBUG _updateAccountData with items: ["kA","kB","keyFetchToken","unwrapBKey"]
1457141705035 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onverified
1457141705043 Sync.BrowserIDManager INFO Getting an assertion from: https://token.services.mozilla.com/1.0/sync/1.5
1457141705044 FirefoxAccounts DEBUG enter getAssertion()
1457141705046 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId"]
1457141705047 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId"]
1457141705083 FirefoxAccounts DEBUG got keyPair
1457141705083 FirefoxAccounts DEBUG getCertificateSigned: true true
1457141705120 FirefoxAccounts TRACE finished write of json user data - took: 73
1457141705121 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141705121 FirefoxAccounts TRACE starting write of user data to the login manager
1457141705126 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705246 Sync.Tracker.Clients DEBUG client.name preference changed
1457141705247 Sync.Tracker.Clients WARN Attempted to add undefined ID to tracker
1457141705249 FirefoxAccounts DEBUG updating existing device details
1457141705265 Hawk DEBUG (Response) /certificate/sign: code: 200 - Status text: OK
1457141705265 Sync.RESTResponse TRACE Processing response headers.
1457141705265 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -265
1457141705266 FirefoxAccounts DEBUG getCertificate got a new one: true
1457141705266 FirefoxAccounts DEBUG _updateAccountData with items: ["keyPair","cert"]
1457141705266 FirefoxAccounts DEBUG getAssertionFromCert
1457141705267 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId"]
1457141705267 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId"]
1457141705269 Hawk DEBUG (Response) /certificate/sign: code: 200 - Status text: OK
1457141705269 Sync.RESTResponse TRACE Processing response headers.
1457141705269 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -269
1457141705269 FirefoxAccounts DEBUG getCertificate got a new one: true
1457141705269 FirefoxAccounts DEBUG _updateAccountData with items: ["keyPair","cert"]
1457141705270 FirefoxAccounts DEBUG getAssertionFromCert
1457141705280 FirefoxAccounts DEBUG getAssertionFromCert returning signed: true
1457141705280 Sync.BrowserIDManager DEBUG Getting a token
1457141705281 Common.TokenServerClient DEBUG Beginning BID assertion exchange: https://token.services.mozilla.com/1.0/sync/1.5
1457141705284 FirefoxAccounts DEBUG getAssertionFromCert returning signed: true
1457141705317 FirefoxAccounts TRACE finished write of json user data - took: 50
1457141705317 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141705317 FirefoxAccounts TRACE starting write of user data to the login manager
1457141705322 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705322 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId"]
1457141705322 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId"]
1457141705336 FirefoxAccounts TRACE finished write of json user data - took: 14
1457141705336 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141705337 FirefoxAccounts TRACE starting write of user data to the login manager
1457141705341 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705351 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141705351 Sync.RESTResponse TRACE Processing response headers.
1457141705351 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -351
1457141705351 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141705352 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId"]
1457141705352 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId"]
1457141705357 FirefoxAccounts TRACE finished write of json user data - took: 5
1457141705357 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141705357 FirefoxAccounts TRACE starting write of user data to the login manager
1457141705361 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705400 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"]
1457141705402 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141705402 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141705405 FirefoxAccounts TRACE finished write of json user data - took: 3
1457141705406 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141705406 FirefoxAccounts TRACE starting write of user data to the login manager
1457141705410 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705574 FirefoxAccounts DEBUG notifying profile changed for user 8f7fb66a0de942b1b35c66eb011d5f4f
1457141705583 FirefoxAccounts DEBUG updating existing device details
1457141705588 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1457141705589 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1457141705601 Common.TokenServerClient DEBUG Got token response: 200
1457141705601 Sync.RESTResponse TRACE Processing response headers.
1457141705601 Common.TokenServerClient DEBUG Successful token response
1457141705601 Sync.BrowserIDManager DEBUG Successfully got a sync token
1457141705603 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined
1457141705603 Sync.BrowserIDManager INFO Background fetch for key bundle done
1457141705603 Sync.Status DEBUG Status.login: error.login.reason.no_recoverykey => success.login
1457141705603 Sync.Status DEBUG Status.service: service.client_not_configured => success.status_ok
1457141705603 Sync.BrowserIDManager INFO Doing initial sync actions
1457141705605 browserwindow.syncui DEBUG observed: weave:service:setup-complete
1457141705605 browserwindow.syncui DEBUG observed: weave:service:setup-complete
1457141705659 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141705662 Sync.Tracker.History INFO Adding Places observer.
1457141705669 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705670 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705672 FirefoxAccounts DEBUG updating existing device details
1457141705678 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705678 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705679 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1457141705679 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1457141705687 Sync.Service DEBUG User-Agent: Firefox/47.0a1 FxSync/1.49.0.20160304030206.
1457141705687 Sync.Service INFO Starting sync at 2016-03-04 20:35:05
1457141705688 Sync.Service DEBUG In sync: should login.
1457141705689 browserwindow.syncui DEBUG observed: weave:service:login:start
1457141705689 browserwindow.syncui DEBUG observed: weave:service:login:start
1457141705690 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141705690 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141705690 Sync.Service INFO Logging in the user.
1457141705691 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705692 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705695 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705695 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141705696 Sync.Service INFO User logged in successfully - verifying login.
1457141705696 Sync.BrowserIDManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys
1457141705696 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok
1457141705697 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141705697 Sync.BrowserIDManager DEBUG _findCluster returning https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/
1457141705697 Sync.Service DEBUG Cluster value = https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/
1457141705697 Sync.Service DEBUG Setting cluster to https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/
1457141705698 Sync.Service DEBUG Caching URLs under storage user base: https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/
1457141705700 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141705703 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141705704 Sync.RESTResponse TRACE Processing response headers.
1457141705704 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -704
1457141705705 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141705706 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141705706 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141705711 FirefoxAccounts TRACE finished write of json user data - took: 5
1457141705711 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141705712 FirefoxAccounts TRACE starting write of user data to the login manager
1457141705718 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705774 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141705774 Sync.RESTResponse TRACE Processing response headers.
1457141705774 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -774
1457141705775 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141705776 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141705776 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141705784 FirefoxAccounts TRACE finished write of json user data - took: 8
1457141705785 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141705785 FirefoxAccounts TRACE starting write of user data to the login manager
1457141705793 FirefoxAccounts TRACE finished write of user data to the login manager
1457141705798 Sync.Resource DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/info/collections
1457141705798 Sync.Resource DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/info/collections
1457141705799 Sync.Service DEBUG Fetching global metadata record
1457141705799 Sync.RecordManager TRACE Importing record: https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/meta/global
1457141705800 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141705897 Sync.Resource DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/meta/global
1457141705897 Sync.Resource DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/meta/global
1457141705898 Sync.Service DEBUG Weave Version: 1.49.0 Local Storage: 5 Remote Storage: 5
1457141705899 Sync.Service INFO Sync IDs differ. Local is eDc5QZ74Xwr0, remote is F7JSAn8axZaI
1457141705899 Sync.Service INFO Service reset.
1457141705900 Sync.Engine.Clients DEBUG Resetting clients last sync time
1457141705901 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time
1457141705901 Sync.Engine.Forms DEBUG Resetting forms last sync time
1457141705902 Sync.Engine.History DEBUG Resetting history last sync time
1457141705903 Sync.Engine.Passwords DEBUG Resetting passwords last sync time
1457141705904 Sync.Engine.Prefs DEBUG Resetting prefs last sync time
1457141705904 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1457141705905 Sync.Engine.Addons DEBUG Resetting addons last sync time
1457141705906 Sync.Engine.AdblockPlus DEBUG Resetting adblockplus last sync time
1457141705907 Sync.CollectionKeyManager INFO Clearing collection keys...
1457141705907 Sync.Service DEBUG Clear cached values and take syncId: F7JSAn8axZaI
1457141705907 Sync.Service INFO Sync key is up-to-date: no need to upgrade.
1457141705907 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1457141705908 Sync.Service INFO Testing info/collections: {"passwords":1457123270.1,"tabs":1457141649.88,"adblockplus":1457029366.21,"clients":1457141649.68,"crypto":1456728008.4,"forms":1457139516.35,"meta":1456764299.52,"bookmarks":1457138314.86,"history":1457139516.59}
1457141705908 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 0
1457141705908 Sync.Service INFO collection keys reports that a key update is needed.
1457141705909 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706013 Sync.Resource DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/crypto/keys
1457141706013 Sync.Resource DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/crypto/keys
1457141706014 Sync.CollectionKeyManager INFO Updating collection keys...
1457141706016 Sync.CollectionKeyManager INFO Setting collection keys contents. Our last modified: 0, input modified: 1456728008.4.
1457141706016 Sync.BulkKeyBundle INFO BulkKeyBundle being created for [default]
1457141706016 Sync.CollectionKeyManager INFO Processing downloaded per-collection keys.
1457141706017 Sync.CollectionKeyManager INFO Clearing collection keys...
1457141706017 Sync.CollectionKeyManager INFO Saving downloaded keys.
1457141706017 Sync.CollectionKeyManager INFO Bumping last modified to 1456728008.4
1457141706017 Sync.CollectionKeyManager INFO Collection keys updated.
1457141706017 Sync.BrowserIDManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys
1457141706018 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok
1457141706020 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706109 Sync.Resource DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/info/collections
1457141706109 Sync.Resource DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/info/collections
1457141706110 Sync.Service DEBUG Fetching global metadata record
1457141706110 Sync.RecordManager TRACE Importing record: https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/meta/global
1457141706111 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706198 Sync.Resource DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/meta/global
1457141706198 Sync.Resource DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/meta/global
1457141706199 Sync.Service DEBUG Weave Version: 1.49.0 Local Storage: 5 Remote Storage: 5
1457141706200 Sync.Service INFO Sync key is up-to-date: no need to upgrade.
1457141706200 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1457141706200 Sync.Service INFO Testing info/collections: {"passwords":1457123270.1,"tabs":1457141649.88,"adblockplus":1457029366.21,"clients":1457141649.68,"crypto":1456728008.4,"forms":1457139516.35,"meta":1456764299.52,"bookmarks":1457138314.86,"history":1457139516.59}
1457141706200 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1456728008.4
1457141706200 Sync.Status DEBUG Status.login: success.login => success.login
1457141706200 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141706200 Sync.Status DEBUG Status.login: success.login => success.login
1457141706201 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141706203 browserwindow.syncui DEBUG observed: weave:service:login:finish
1457141706204 browserwindow.syncui DEBUG observed: weave:service:login:finish
1457141706205 browserwindow.syncui DEBUG observed: weave:service:sync:start
1457141706206 browserwindow.syncui DEBUG onActivityStart with numActive: 0
1457141706207 browserwindow.syncui DEBUG observed: weave:service:sync:start
1457141706207 browserwindow.syncui DEBUG onActivityStart with numActive: 0
1457141706208 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1457141706221 Sync.Status INFO Resetting Status.
1457141706221 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141706223 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706230 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706230 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706231 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706232 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706236 FirefoxAccounts DEBUG updating existing device details
1457141706245 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706246 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706246 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706246 browserwindow.syncui DEBUG _loginFailed has sync state=success.login
1457141706247 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1457141706334 Sync.Resource DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/info/collections?v=1.49.0
1457141706334 Sync.Resource DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/info/collections?v=1.49.0
1457141706336 Sync.Service DEBUG Fetching global metadata record
1457141706336 Sync.Service DEBUG Weave Version: 1.49.0 Local Storage: 5 Remote Storage: 5
1457141706337 Sync.Service INFO Sync key is up-to-date: no need to upgrade.
1457141706337 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1457141706337 Sync.Service INFO Testing info/collections: {"passwords":1457123270.1,"tabs":1457141649.88,"adblockplus":1457029366.21,"clients":1457141649.68,"crypto":1456728008.4,"forms":1457139516.35,"meta":1456764299.52,"bookmarks":1457138314.86,"history":1457139516.59}
1457141706337 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1456728008.4
1457141706337 Sync.Synchronizer DEBUG Refreshing client list.
1457141706339 Sync.Engine.Clients DEBUG Engine syncIDs: BSjo4G_0OOMX,JgGCRxTcYZxv
1457141706339 Sync.Engine.Clients DEBUG Resetting clients last sync time
1457141706340 Sync.Engine.Clients DEBUG First sync, uploading all items
1457141706340 Sync.Engine.Clients INFO 1 outgoing items pre-reconciliation
1457141706342 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706347 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141706347 Sync.RESTResponse TRACE Processing response headers.
1457141706347 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -347
1457141706348 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141706349 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141706349 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141706355 FirefoxAccounts TRACE finished write of json user data - took: 6
1457141706356 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141706356 FirefoxAccounts TRACE starting write of user data to the login manager
1457141706366 FirefoxAccounts TRACE finished write of user data to the login manager
1457141706437 Sync.Collection DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/clients?full=1
1457141706438 Sync.Collection DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/clients?full=1
1457141706439 Sync.Engine.Clients INFO Records: 3 applied, 3 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1457141706447 FirefoxAccounts DEBUG updating existing device details
1457141706453 Sync.Engine.Clients INFO Posting 1 records of 485 bytes
1457141706454 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706455 Sync.Collection DEBUG POST Length: 485
1457141706550 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141706550 Sync.RESTResponse TRACE Processing response headers.
1457141706551 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -551
1457141706552 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141706554 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141706554 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141706563 FirefoxAccounts TRACE finished write of json user data - took: 9
1457141706563 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141706564 FirefoxAccounts TRACE starting write of user data to the login manager
1457141706574 FirefoxAccounts TRACE finished write of user data to the login manager
1457141706601 Sync.Collection DEBUG mesg: POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/clients
1457141706601 Sync.Collection DEBUG POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/clients
1457141706604 Sync.SyncScheduler DEBUG Client count: 0 -> 4
1457141706608 Sync.Engine.Prefs DEBUG Resetting prefs last sync time
1457141706609 Sync.Engine.Passwords DEBUG Resetting passwords last sync time
1457141706610 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1457141706611 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time
1457141706612 Sync.Engine.Addons DEBUG Resetting addons last sync time
1457141706612 Sync.Engine.Forms DEBUG Resetting forms last sync time
1457141706613 Sync.Engine.History DEBUG Resetting history last sync time
1457141706614 Sync.Synchronizer INFO Updating enabled engines: 4 clients.
1457141706617 Sync.Synchronizer TRACE Engine adblockplus was enabled. Marking as non-declined.
1457141706617 Sync.Synchronizer TRACE adblockplus engine was enabled remotely.
1457141706618 Sync.Synchronizer TRACE The prefs engine was disabled remotely.
1457141706621 Sync.Synchronizer TRACE The addons engine was disabled remotely.
1457141706623 Sync.AddonsReconciler DEBUG Removing change listener.
1457141706623 Sync.AddonsReconciler DEBUG Stopping listening and removing AddonManager listeners.
1457141706624 Sync.Synchronizer INFO Syncing all enabled engines.
1457141706625 Sync.Engine.AdblockPlus DEBUG Engine syncIDs: JmQTtOUgxw7Q,7ZwWvmZFXDOM
1457141706626 Sync.Engine.AdblockPlus DEBUG Resetting adblockplus last sync time
1457141706626 Sync.Engine.AdblockPlus DEBUG First sync, uploading all items
1457141706628 Sync.Engine.AdblockPlus INFO 1 outgoing items pre-reconciliation
1457141706631 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706632 FirefoxAccounts DEBUG updating existing device details
1457141706736 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141706736 Sync.RESTResponse TRACE Processing response headers.
1457141706737 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -737
1457141706738 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141706739 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141706740 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141706743 Sync.Collection DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/adblockplus?full=1
1457141706743 Sync.Collection DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/adblockplus?full=1
1457141706744 Sync.Engine.AdblockPlus INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1457141706750 Sync.Engine.AdblockPlus INFO Posting 1 records of 1263 bytes
1457141706752 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141706753 FirefoxAccounts TRACE finished write of json user data - took: 13
1457141706753 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141706753 FirefoxAccounts TRACE starting write of user data to the login manager
1457141706761 FirefoxAccounts TRACE finished write of user data to the login manager
1457141706762 Sync.Collection DEBUG POST Length: 1263
1457141706889 Sync.Collection DEBUG mesg: POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/adblockplus
1457141706890 Sync.Collection DEBUG POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/adblockplus
1457141706892 Sync.Engine.Passwords DEBUG Engine syncIDs: tf7QmcOp26HI,ywht8BB1AoR9
1457141706892 Sync.Engine.Passwords DEBUG Resetting passwords last sync time
1457141706893 Sync.Engine.Passwords DEBUG First sync, uploading all items
1457141706936 Sync.Engine.Passwords INFO 23 outgoing items pre-reconciliation
1457141706939 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141707264 Sync.Collection DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/passwords?full=1
1457141707264 Sync.Collection DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/passwords?full=1
1457141707265 Sync.Engine.Passwords INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 23 reconciled.
1457141707268 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141707341 Sync.Tracker.Clients DEBUG Saving changed IDs to clients
1457141707387 Sync.Collection DEBUG mesg: DELETE success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/passwords?ids={a71e38ad-892e-4481-b227-29c0ad8819ee}
1457141707387 Sync.Collection DEBUG DELETE success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/passwords?ids={a71e38ad-892e-4481-b227-29c0ad8819ee}
1457141707388 Sync.Engine.Passwords DEBUG Delete result: {"modified": 1457141707.31}
1457141707389 Sync.Engine.Tabs DEBUG Engine syncIDs: 9iAzBQwbWTag,i-U5-C1Lnu0Z
1457141707389 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1457141707390 Sync.Engine.Tabs DEBUG First sync, uploading all items
1457141707391 Sync.Engine.Tabs INFO 1 outgoing items pre-reconciliation
1457141707394 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141707516 Sync.Store.Tabs DEBUG Adding remote tabs from Nightly on Nexus 4
1457141707518 Sync.Store.Tabs DEBUG Adding remote tabs from scott's Firefox on bodhisattva
1457141707524 Sync.Store.Tabs DEBUG Adding remote tabs from scott's Nightly on bodhisattva
1457141707525 Sync.Collection DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/tabs?full=1
1457141707525 Sync.Collection DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/tabs?full=1
1457141707526 Sync.Engine.Tabs INFO Records: 3 applied, 3 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1457141707557 FirefoxAccounts DEBUG updating existing device details
1457141707564 Sync.Engine.Tabs INFO Posting 1 records of 11152 bytes
1457141707565 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141707566 Sync.Collection DEBUG POST Length: 11152
1457141707623 Sync.Tracker.Addons DEBUG Saving changed IDs to addons
1457141707630 Sync.Tracker.AdblockPlus DEBUG Saving changed IDs to adblockplus
1457141707662 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK
1457141707662 Sync.RESTResponse TRACE Processing response headers.
1457141707663 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -663
1457141707664 FirefoxAccounts DEBUG _updateAccountData with items: ["deviceId","isDeviceStale"]
1457141707665 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141707665 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","deviceId","oauthTokens"]
1457141707673 Sync.Collection DEBUG mesg: POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/tabs
1457141707673 Sync.Collection DEBUG POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/tabs
1457141707676 Sync.Engine.Bookmarks DEBUG Engine syncIDs: gphWU-dYLPsB,56yTAuvvQy8-
1457141707676 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time
1457141707676 Sync.Engine.Bookmarks DEBUG First sync, uploading all items
1457141707682 FirefoxAccounts TRACE finished write of json user data - took: 17
1457141707683 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1457141707683 FirefoxAccounts TRACE starting write of user data to the login manager
1457141707690 FirefoxAccounts TRACE finished write of user data to the login manager
1457141707707 Sync.Engine.Bookmarks INFO 35 outgoing items pre-reconciliation
1457141707707 Sync.Engine.Bookmarks DEBUG Bookmarks backup starting.
1457141707748 Sync.Tracker.AdblockPlus.private DEBUG Saving changed IDs to adblockplus.private
1457141707753 Sync.Engine.Bookmarks DEBUG Bookmarks backup done.
1457141707755 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141707937 Sync.Tracker.Passwords DEBUG Saving changed IDs to passwords
1457141708028 Sync.Collection DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/bookmarks?full=1&sort=index
1457141708028 Sync.Collection DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/bookmarks?full=1&sort=index
1457141708029 Sync.Engine.Bookmarks INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 37 reconciled.
1457141708032 Sync.Engine.Forms DEBUG Engine syncIDs: T3Uqrny9r1al,FfnjO5JL-P2t
1457141708032 Sync.Engine.Forms DEBUG Resetting forms last sync time
1457141708033 Sync.Engine.Forms DEBUG First sync, uploading all items
1457141708042 Sync.Engine.Forms INFO 91 outgoing items pre-reconciliation
1457141708043 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141708392 Sync.Collection DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/forms?full=1
1457141708392 Sync.Collection DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/forms?full=1
1457141708393 Sync.Engine.Forms INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 91 reconciled.
1457141708394 Sync.Engine.History DEBUG Engine syncIDs: j2Ml-2n06l5W,kf8qDv_c1XWD
1457141708394 Sync.Engine.History DEBUG Resetting history last sync time
1457141708394 Sync.Engine.History DEBUG First sync, uploading all items
1457141708707 Sync.Tracker.Bookmarks DEBUG Saving changed IDs to bookmarks
1457141708903 Sync.Engine.History INFO 2929 outgoing items pre-reconciliation
1457141708905 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141709042 Sync.Tracker.Forms DEBUG Saving changed IDs to forms
1457141709492 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: QPtytXDe_S_z
1457141709656 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: H1ke-fA7aa3B
1457141709930 Sync.Tracker.History DEBUG Saving changed IDs to history
1457141710868 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: _W5Xin39sXV7
1457141711199 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: ErmesiwDHmFo
1457141711222 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: pmnTvBfWsjD4
1457141711253 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: VZ19q0wca25O
1457141711582 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: KSI1inj5Zepo
1457141711779 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: lzYSpPoBbYOX
1457141711957 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: HQqV8EsmlWzT
1457141712310 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: AptB6tkM2e5Q
1457141712338 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: zbJT2ajiUsUK
1457141712748 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: uLRBaEnj5S5h
1457141712861 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: nJsjWKMXFpEB
1457141712867 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: iJ-DGJXX-rmh
1457141712960 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: joYsznxoi26N
1457141713143 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: Amokw7aRScB4
1457141713164 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: vaYhcMaaudmj
1457141713167 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: AxTMOI6nHWe2
1457141713443 Sync.Engine.History WARN DATA LOSS: Both local and remote changes to record: sOEBYaV_-2XF
1457141713948 Sync.Collection DEBUG mesg: GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/history?full=1&limit=5000
1457141713948 Sync.Collection DEBUG GET success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/history?full=1&limit=5000
1457141713963 Sync.Engine.History INFO Records: 28 applied, 28 successfully, 0 failed to apply, 0 newly failed to apply, 2903 reconciled.
1457141713993 Sync.Engine.History INFO Posting 26 records of 14958 bytes
1457141713993 Sync.BrowserIDManager DEBUG _ensureValidToken already has one
1457141713994 Sync.Collection DEBUG POST Length: 14958
1457141714205 Sync.Collection DEBUG mesg: POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/history
1457141714205 Sync.Collection DEBUG POST success 200 https://sync-270-us-west-2.sync.services.mozilla.com/1.5/41244799/storage/history
1457141714207 Sync.Status DEBUG Status.sync: success.sync => success.sync
1457141714207 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1457141714207 Sync.Synchronizer INFO Sync completed at 2016-03-04 20:35:14 after 7.99 secs.
1457141714208 Sync.Declined DEBUG Handling remote declined: []
1457141714208 Sync.Declined DEBUG Handling local declined: []
1457141714208 Sync.Declined DEBUG Declined changed? false
1457141714209 Sync.Service INFO No change to declined engines. Not reuploading meta/global.
1457141714209 browserwindow.syncui DEBUG observed: weave:service:sync:finish
1457141714209 browserwindow.syncui DEBUG onActivityStop with numActive: 1
1457141714210 browserwindow.syncui DEBUG observed: weave:service:sync:finish
1457141714210 browserwindow.syncui DEBUG onActivityStop with numActive: 1
1457141714212 Sync.SyncScheduler DEBUG Next sync in 90000 ms.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment