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