1446756601208 Sync.LogManager DEBUG Flushing file log
1446756601208 Sync.LogManager TRACE Beginning stream copy to error-sync-1446756601208.txt: 1446756601208
1446756601209 Sync.Service DEBUG Exception: TokenServerClientServerError({"now":"2015-11-05T20:50:01.199Z","message":"Server error.","cause":"general","response_body":"{\"status\": \"error\", \"errors\": [\"The public_url setting doesn't match the application url.\\nThis will almost certainly cause authentication failures!\\n public_url setting is: http://localhost:5000\\n application url is: http://xxxxxxxxxxxxxxx:5000\\nYou can disable this check by setting the force_wsgi_environ\\noption in your config file, but do so at your own risk.\"]}","response_headers":{"server":"gunicorn/19.1.1","date":"Thu, 05 Nov 2015 20:49:49 GMT","connection":"keep-alive","content-length":"383","content-type":"application/json; charset=UTF-8","x-weave-timestamp":"1446756589.73","x-timestamp":"1446756589"},"response_status":500}) (resource://gre/modules/services-common/tokenserverclient.js:36:36) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < SyncServiceObserver.init@sync.js:76:62 < @sync.js:459:1 < require@bootstrap.js:137:7 < @main.js:30:1 < require@bootstrap.js:137:7 < startup@bootstrap.js:21:3 < XPI_callBootstrapMethod@XPIProvider.jsm:4774:9 < XPI_startup@XPIProvider.jsm:2484:13 < callProvider@AddonManager.jsm:221:12 < _startProvider@AddonManager.jsm:828:5 < AMI_startup@AddonManager.jsm:999:9 < AMP_startup@AddonManager.jsm:2672:5 < AMC_observe@addonManager.js:58:7
1446756601209 Sync.Service DEBUG Not syncing: login returned false.
1446756601218 browserwindow.syncui DEBUG observed: weave:ui:login:error
1446756601218 browserwindow.syncui DEBUG onLoginError: login=error.login.reason.network, sync=success.sync
1446756601218 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network
1446756601253 Sync.LogManager TRACE finished copy to: C:\Users\Paulo\AppData\Roaming\Mozilla\Firefox\Profiles\ykjtb10q.default-1437419271764\weave\logs\error-sync-1446756601208.txt
1446756601253 Sync.LogManager TRACE onCopyComplete: 1446756601253
1446756601254 Sync.LogManager TRACE Scheduling cleanup.
1446756601254 Sync.LogManager DEBUG Log cleanup threshold time: 1445892601254
1446756601349 Sync.LogManager DEBUG Done deleting files.
1446756602054 FirefoxAccounts DEBUG fetched profile matches cached copy
1446756704984 FirefoxAccounts DEBUG removing account data
1446756704984 FirefoxAccounts TRACE starting write of json user data: null
1446756704992 FirefoxAccounts TRACE finished write of json user data - took: 7
1446756704999 FirefoxAccounts TRACE storage set finished clearing account data
1446756704999 FirefoxAccounts DEBUG account data reset
1446756704999 FirefoxAccounts TRACE StorageManager finalizing
1446756704999 FirefoxAccounts TRACE StorageManager finalized
1446756705000 FirefoxAccounts TRACE initializing new storage manager
1446756705000 FirefoxAccounts TRACE starting fetch of json user data
1446756705002 FirefoxAccounts TRACE finished fetch of json user data - took: 2
1446756705002 FirefoxAccounts TRACE initializing of new storage manager done
1446756705006 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onlogout
1446756705006 Sync.BrowserIDManager DEBUG observed fxaccounts:onlogout
1446756705007 Sync.Status INFO Resetting Status.
1446756705007 Sync.Status DEBUG Status.service: error.login.failed => success.status_ok
1446756705007 Sync.Service DEBUG Skipping client data removal: no cluster URL.
1446756705007 Sync.Service INFO Service.startOver dropping sync key and logging out.
1446756705007 Sync.Status DEBUG Status.login: success.login => error.login.reason.no_recoverykey
1446756705007 Sync.Status DEBUG Status.service: success.status_ok => service.client_not_configured
1446756705007 Sync.Service INFO Logging out
1446756705008 browserwindow.syncui DEBUG observed: weave:service:logout:finish
1446756705008 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1446756705008 browserwindow.syncui DEBUG observed: weave:service:start-over
1446756705008 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1446756705009 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1446756705009 Sync.Service INFO Service reset.
1446756705010 Sync.Engine.Clients DEBUG Resetting clients last sync time
1446756705010 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time
1446756705010 Sync.Engine.Forms DEBUG Resetting forms last sync time
1446756705011 Sync.Engine.History DEBUG Resetting history last sync time
1446756705011 Sync.Engine.Passwords DEBUG Resetting passwords last sync time
1446756705011 Sync.Engine.Prefs DEBUG Resetting prefs last sync time
1446756705012 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1446756705012 Sync.Engine.Addons DEBUG Resetting addons last sync time
1446756705012 Sync.Engine.AdblockPlus DEBUG Resetting adblockplus last sync time
1446756705012 Sync.CollectionKeyManager INFO Clearing collection keys...
1446756705013 Sync.Tracker.Clients DEBUG client.name preference changed
1446756705013 Sync.Tracker.Clients WARN Attempted to add undefined ID to tracker
1446756705020 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1446756705022 Sync.BrowserIDManager INFO initializeWithCurrentIdentity has no user logged in
1446756705022 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1446756705022 Sync.BrowserIDManager ERROR Could not authenticate: no user is logged in
1446756705031 browserwindow.syncui DEBUG observed: weave:service:start-over:finish
1446756705032 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.no_recoverykey
1446756705834 Hawk DEBUG (Response) /session/destroy: code: 200 - Status text: OK
1446756705834 Sync.RESTResponse TRACE Processing response headers.
1446756705835 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -11835
1446756705835 FirefoxAccounts DEBUG FxAccountsOAuthGrantClient Initialized
1446756706643 FirefoxAccounts DEBUG Notifying observers of testhelper-fxa-signout-complete
1446757253641 FirefoxAccounts DEBUG setSignedInUser - aborting any existing flows
1446757253642 FirefoxAccounts TRACE StorageManager finalizing
1446757253642 FirefoxAccounts TRACE StorageManager finalized
1446757253643 FirefoxAccounts TRACE initializing new storage manager
1446757253643 FirefoxAccounts WARN Unknown FxA field name in user data, treating as in-memory: declinedSyncEngines
1446757253644 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified"]
1446757253644 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified"]
1446757253652 FirefoxAccounts TRACE finished write of json user data - took: 8
1446757253652 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"]
1446757253653 FirefoxAccounts TRACE starting write of user data to the login manager
1446757253659 FirefoxAccounts TRACE finished write of user data to the login manager
1446757253659 FirefoxAccounts TRACE initializing of new storage manager done
1446757253660 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onlogin
1446757253660 Sync.BrowserIDManager DEBUG observed fxaccounts:onlogin
1446757253664 Sync.BrowserIDManager INFO Username changed. Removing stored credentials.
1446757253664 Sync.BrowserIDManager INFO Waiting for user to be verified.
1446757253664 FirefoxAccounts DEBUG already verified
1446757253665 Sync.Tracker.Clients DEBUG client.name preference changed
1446757253666 Sync.Tracker.Clients WARN Attempted to add undefined ID to tracker
1446757253666 FirefoxAccounts DEBUG FxAccountsProfileClient: Initialized
1446757253667 Sync.BrowserIDManager INFO Starting fetch for key bundle.
1446757253667 FirefoxAccounts DEBUG already verified
1446757253667 Sync.BrowserIDManager INFO Fetching new keys
1446757253668 FirefoxAccounts DEBUG fetchKeys: true
1446757253671 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1446757253671 FirefoxAccounts DEBUG getOAuthToken enter
1446757253672 FirefoxAccounts DEBUG FxAccountsOAuthGrantClient Initialized
1446757253672 FirefoxAccounts DEBUG getOAuthToken fetching new token from: https://oauth.accounts.firefox.com/v1
1446757253672 FirefoxAccounts DEBUG enter getAssertion()
1446757253695 FirefoxAccounts DEBUG got keyPair
1446757253695 FirefoxAccounts DEBUG getCertificateSigned: true true
1446757253898 Hawk DEBUG (Response) /account/keys: code: 200 - Status text: OK
1446757253898 Sync.RESTResponse TRACE Processing response headers.
1446757253898 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -11898
1446757253900 FirefoxAccounts DEBUG Keys Obtained: kA=true, kB=true
1446757253900 FirefoxAccounts DEBUG _updateAccountData with items: ["kA","kB","keyFetchToken","unwrapBKey"]
1446757253900 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onverified
1446757253904 Sync.BrowserIDManager INFO Getting an assertion from: http://xxxxxxxxxxxxxxxxx:5000/token/1.0/sync/1.5
1446757253904 FirefoxAccounts DEBUG enter getAssertion()
1446757253906 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified"]
1446757253906 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified"]
1446757253928 FirefoxAccounts DEBUG got keyPair
1446757253928 FirefoxAccounts DEBUG getCertificateSigned: true true
1446757253951 FirefoxAccounts TRACE finished write of json user data - took: 45
1446757253951 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1446757253952 FirefoxAccounts TRACE starting write of user data to the login manager
1446757253960 FirefoxAccounts TRACE finished write of user data to the login manager
1446757254146 Hawk DEBUG (Response) /certificate/sign: code: 200 - Status text: OK
1446757254146 Sync.RESTResponse TRACE Processing response headers.
1446757254146 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -12146
1446757254147 FirefoxAccounts DEBUG getCertificate got a new one: true
1446757254147 FirefoxAccounts DEBUG _updateAccountData with items: ["keyPair","cert"]
1446757254147 FirefoxAccounts DEBUG getAssertionFromCert
1446757254148 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified"]
1446757254148 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified"]
1446757254149 FirefoxAccounts DEBUG getAssertionFromCert returning signed: true
1446757254149 Sync.BrowserIDManager DEBUG Getting a token
1446757254150 Common.TokenServerClient DEBUG Beginning BID assertion exchange: http://xxxxxxxxxxxxxx.pt:5000/token/1.0/sync/1.5
1446757254155 FirefoxAccounts TRACE finished write of json user data - took: 7
1446757254155 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1446757254156 FirefoxAccounts TRACE starting write of user data to the login manager
1446757254164 FirefoxAccounts TRACE finished write of user data to the login manager
1446757254236 Hawk DEBUG (Response) /certificate/sign: code: 200 - Status text: OK
1446757254237 Sync.RESTResponse TRACE Processing response headers.
1446757254237 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -12237
1446757254237 FirefoxAccounts DEBUG getCertificate got a new one: true
1446757254237 FirefoxAccounts DEBUG _updateAccountData with items: ["keyPair","cert"]
1446757254238 FirefoxAccounts DEBUG getAssertionFromCert
1446757254238 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified"]
1446757254238 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified"]
1446757254240 FirefoxAccounts DEBUG getAssertionFromCert returning signed: true
1446757254244 FirefoxAccounts TRACE finished write of json user data - took: 5
1446757254245 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1446757254245 FirefoxAccounts TRACE starting write of user data to the login manager
1446757254253 FirefoxAccounts TRACE finished write of user data to the login manager
1446757254980 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"]
1446757254981 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens"]
1446757254981 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens"]
1446757254994 FirefoxAccounts TRACE finished write of json user data - took: 13
1446757254994 FirefoxAccounts DEBUG writing secure storage: ["kA","kB"]
1446757254994 FirefoxAccounts TRACE starting write of user data to the login manager
1446757255002 FirefoxAccounts TRACE finished write of user data to the login manager
1446757255841 FirefoxAccounts DEBUG notifying profile changed for user 9184df6285aa48269d430d5e8d15581d
1446757255843 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1446757255843 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1446757256246 Sync.BrowserIDManager ERROR Non-authentication error in _fetchTokenForUser: TokenServerClientNetworkError({"error":{}}) (resource://gre/modules/services-common/tokenserverclient.js:36:36) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < SyncServiceObserver.init@sync.js:76:62 < @sync.js:459:1 < require@bootstrap.js:137:7 < @main.js:30:1 < require@bootstrap.js:137:7 < startup@bootstrap.js:21:3 < XPI_callBootstrapMethod@XPIProvider.jsm:4774:9 < XPI_startup@XPIProvider.jsm:2484:13 < callProvider@AddonManager.jsm:221:12 < _startProvider@AddonManager.jsm:828:5 < AMI_startup@AddonManager.jsm:999:9 < AMP_startup@AddonManager.jsm:2672:5 < AMC_observe@addonManager.js:58:7
1446757256247 Sync.Status DEBUG Status.login: error.login.reason.no_recoverykey => error.login.reason.network
1446757256247 Sync.Status DEBUG Status.service: service.client_not_configured => error.login.failed
1446757256247 browserwindow.syncui DEBUG observed: weave:ui:login:error
1446757256247 browserwindow.syncui DEBUG onLoginError: login=error.login.reason.network, sync=success.sync
1446757256247 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network
1446757256249 Sync.BrowserIDManager ERROR Background fetch for key bundle failed: TokenServerClientNetworkError({"error":{}}) (resource://gre/modules/services-common/tokenserverclient.js:36:36) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < SyncServiceObserver.init@sync.js:76:62 < @sync.js:459:1 < require@bootstrap.js:137:7 < @main.js:30:1 < require@bootstrap.js:137:7 < startup@bootstrap.js:21:3 < XPI_callBootstrapMethod@XPIProvider.jsm:4774:9 < XPI_startup@XPIProvider.jsm:2484:13 < callProvider@AddonManager.jsm:221:12 < _startProvider@AddonManager.jsm:828:5 < AMI_startup@AddonManager.jsm:999:9 < AMP_startup@AddonManager.jsm:2672:5 < AMC_observe@addonManager.js:58:7
1446757256250 Sync.BrowserIDManager ERROR Could not authenticate: TokenServerClientNetworkError({"error":{}}) (resource://gre/modules/services-common/tokenserverclient.js:36:36) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < SyncServiceObserver.init@sync.js:76:62 < @sync.js:459:1 < require@bootstrap.js:137:7 < @main.js:30:1 < require@bootstrap.js:137:7 < startup@bootstrap.js:21:3 < XPI_callBootstrapMethod@XPIProvider.jsm:4774:9 < XPI_startup@XPIProvider.jsm:2484:13 < callProvider@AddonManager.jsm:221:12 < _startProvider@AddonManager.jsm:828:5 < AMI_startup@AddonManager.jsm:999:9 < AMP_startup@AddonManager.jsm:2672:5 < AMC_observe@addonManager.js:58:7
1446757256251 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1446757260943 Sync.ErrorHandler DEBUG Beginning user-triggered sync.
1446757260944 Sync.Service DEBUG User-Agent: Firefox/42.0 FxSync/1.44.0.20151029151421.
1446757260944 Sync.Service INFO Starting sync at 2015-11-05 21:01:00
1446757260944 Sync.Service DEBUG In sync: should login.
1446757260944 browserwindow.syncui DEBUG observed: weave:service:login:start
1446757260944 browserwindow.syncui DEBUG onActivityStart with numActive: 0
1446757260944 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network
1446757260944 Sync.BrowserIDManager INFO currentAuthState returning error.login.reason.network due to previous failure
1446757260945 Sync.Status DEBUG Status.login: error.login.reason.network => error.login.reason.network
1446757260945 Sync.Status DEBUG Status.service: error.login.failed => error.login.failed
1446757260945 Sync.BrowserIDManager INFO currentAuthState returning error.login.reason.network due to previous failure
1446757260945 Sync.Status DEBUG Status.login: error.login.reason.network => error.login.reason.network
1446757260945 Sync.Status DEBUG Status.service: error.login.failed => error.login.failed
1446757260946 Sync.BrowserIDManager INFO Waiting for user to be verified.
1446757260946 FirefoxAccounts DEBUG already verified
1446757260946 Sync.BrowserIDManager INFO Starting fetch for key bundle.
1446757260947 FirefoxAccounts DEBUG already verified
1446757260947 Sync.BrowserIDManager INFO Getting an assertion from: http://xxxxxxxxxxxxxxxxxxxxxx.pt:5000/token/1.0/sync/1.5
1446757260947 FirefoxAccounts DEBUG enter getAssertion()
1446757260949 FirefoxAccounts DEBUG getKeypairAndCertificate: already have keyPair and certificate
1446757260949 FirefoxAccounts DEBUG getAssertionFromCert
1446757260967 FirefoxAccounts DEBUG getAssertionFromCert returning signed: true
1446757260967 Sync.BrowserIDManager DEBUG Getting a token
1446757260968 Common.TokenServerClient DEBUG Beginning BID assertion exchange: http://xxxxxxxxxxxxxxxxxx:5000/token/1.0/sync/1.5
1446757264993 Sync.BrowserIDManager ERROR Non-authentication error in _fetchTokenForUser: TokenServerClientNetworkError({"error":{}}) (resource://gre/modules/services-common/tokenserverclient.js:36:36) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < SyncServiceObserver.init@sync.js:76:62 < @sync.js:459:1 < require@bootstrap.js:137:7 < @main.js:30:1 < require@bootstrap.js:137:7 < startup@bootstrap.js:21:3 < XPI_callBootstrapMethod@XPIProvider.jsm:4774:9 < XPI_startup@XPIProvider.jsm:2484:13 < callProvider@AddonManager.jsm:221:12 < _startProvider@AddonManager.jsm:828:5 < AMI_startup@AddonManager.jsm:999:9 < AMP_startup@AddonManager.jsm:2672:5 < AMC_observe@addonManager.js:58:7
1446757264993 Sync.Status DEBUG Status.login: error.login.reason.network => error.login.reason.network
1446757264993 Sync.Status DEBUG Status.service: error.login.failed => error.login.failed
1446757264993 browserwindow.syncui DEBUG observed: weave:ui:login:error
1446757264993 browserwindow.syncui DEBUG onLoginError: login=error.login.reason.network, sync=success.sync
1446757264994 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network
1446757264995 Sync.BrowserIDManager ERROR Background fetch for key bundle failed: TokenServerClientNetworkError({"error":{}}) (resource://gre/modules/services-common/tokenserverclient.js:36:36) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < SyncServiceObserver.init@sync.js:76:62 < @sync.js:459:1 < require@bootstrap.js:137:7 < @main.js:30:1 < require@bootstrap.js:137:7 < startup@bootstrap.js:21:3 < XPI_callBootstrapMethod@XPIProvider.jsm:4774:9 < XPI_startup@XPIProvider.jsm:2484:13 < callProvider@AddonManager.jsm:221:12 < _startProvider@AddonManager.jsm:828:5 < AMI_startup@AddonManager.jsm:999:9 < AMP_startup@AddonManager.jsm:2672:5 < AMC_observe@addonManager.js:58:7
1446757264995 Sync.BrowserIDManager ERROR Could not authenticate: TokenServerClientNetworkError({"error":{}}) (resource://gre/modules/services-common/tokenserverclient.js:36:36) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < SyncServiceObserver.init@sync.js:76:62 < @sync.js:459:1 < require@bootstrap.js:137:7 < @main.js:30:1 < require@bootstrap.js:137:7 < startup@bootstrap.js:21:3 < XPI_callBootstrapMethod@XPIProvider.jsm:4774:9 < XPI_startup@XPIProvider.jsm:2484:13 < callProvider@AddonManager.jsm:221:12 < _startProvider@AddonManager.jsm:828:5 < AMI_startup@AddonManager.jsm:999:9 < AMP_startup@AddonManager.jsm:2672:5 < AMC_observe@addonManager.js:58:7
1446757264996 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1446757264998 browserwindow.syncui DEBUG observed: weave:service:login:error
1446757264998 browserwindow.syncui DEBUG onActivityStop with numActive: 1
1446757264998 browserwindow.syncui DEBUG _loginFailed has sync state=error.login.reason.network
1446757264999 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1446757264999 Sync.SyncScheduler DEBUG Next sync in 3600000 ms.
1446757265000 Sync.ErrorHandler ERROR Sync encountered a login error