One more log for you: this one is from an additional (fresh) profile
where I sign-in to the existing account:
1429655294401 Sync.Service INFO Loading Weave 1.39.0
1429655294409 Sync.Engine.Clients DEBUG Engine initialized
1429655294411 Sync.Engine.Clients DEBUG Resetting clients last sync time
1429655294420 Sync.Engine.Bookmarks DEBUG Engine initialized
1429655294427 Sync.Engine.Forms DEBUG Engine initialized
1429655294434 Sync.Engine.History DEBUG Engine initialized
1429655294442 Sync.Engine.Passwords DEBUG Engine initialized
1429655294448 Sync.Engine.Prefs DEBUG Engine initialized
1429655294453 Sync.Engine.Tabs DEBUG Engine initialized
1429655294455 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1429655294468 Sync.Engine.Addons DEBUG Engine initialized
1429655294471 Sync.Service INFO Mozilla/5.0 (X11; Linux x86_64;
rv:37.0) Gecko/20100101 Firefox/37.0
1429655294476 Sync.SyncScheduler DEBUG Clearing sync triggers and the
global score.
1429655294478 Sync.Status DEBUG Status.login: success.login =>
error.login.reason.no_username
1429655294478 Sync.Status DEBUG Status.service: success.status_ok =>
service.client_not_configured
1429655294478 Sync.Status DEBUG Status.login:
error.login.reason.no_username => error.login.reason.no_username
1429655294478 Sync.Status DEBUG Status.service:
service.client_not_configured => service.client_not_configured
1429655294497 Sync.Status DEBUG Status.login:
error.login.reason.no_username => error.login.reason.no_username
1429655294497 Sync.Status DEBUG Status.service:
service.client_not_configured => service.client_not_configured
1429655294499 Sync.Status DEBUG Status.login:
error.login.reason.no_username => error.login.reason.no_username
1429655294500 Sync.Status DEBUG Status.service:
service.client_not_configured => service.client_not_configured
1429655294500 FirefoxAccounts DEBUG setSignedInUser - aborting any
existing flows
1429655294504 Sync.Tracker.Clients WARN Changed IDs file clients
contains non-object value.
1429655294545 Sync.Tracker.Bookmarks WARN Changed IDs file bookmarks
contains non-object value.
1429655294571 Sync.Tracker.Forms WARN Changed IDs file forms contains
non-object value.
1429655294575 Sync.Tracker.History WARN Changed IDs file history
contains non-object value.
1429655294586 Sync.Tracker.Passwords WARN Changed IDs file passwords
contains non-object value.
1429655294591 Sync.Tracker.Addons WARN Changed IDs file addons
contains non-object value.
1429655294604 FirefoxAccounts INFO An accountState promise was
resolved, but was actually rejected due to a different user being
signed in. Originally resolved with: null
1429655294626 FirefoxAccounts DEBUG Notifying observers of
fxaccounts:onlogin
1429655294626 Sync.BrowserIDManager DEBUG observed fxaccounts:onlogin
1429655294627 Sync.BrowserIDManager INFO Username changed. Removing
stored credentials.
1429655294627 Sync.BrowserIDManager INFO Waiting for user to be verified.
1429655294627 FirefoxAccounts DEBUG already verified
1429655294629 Sync.BrowserIDManager INFO Starting fetch for key bundle.
1429655294629 FirefoxAccounts DEBUG already verified
1429655294630 Sync.BrowserIDManager INFO Fetching new keys
1429655294630 FirefoxAccounts DEBUG fetchKeys: true
1429655294788 Hawk DEBUG (Response) /account/keys: code: 200 - Status
text: OK
1429655294789 Hawk DEBUG Clock offset vs
https://fxa.example.com:9900/v1: -789
1429655294792 FirefoxAccounts DEBUG Keys Obtained: kA=true, kB=true
1429655294821 FirefoxAccounts DEBUG Notifying observers of
fxaccounts:onverified
1429655294828 Sync.BrowserIDManager INFO Getting an assertion from:
http://fxa.example.com:5000/token/1.0/sync/1.5
1429655294829 FirefoxAccounts DEBUG enter getAssertion()
1429655294877 FirefoxAccounts DEBUG got keyPair
1429655294878 FirefoxAccounts DEBUG getCertificateSigned: true true
1429655294953 Hawk DEBUG (Response) /certificate/sign: code: 200 -
Status text: OK
1429655294953 Hawk DEBUG Clock offset vs
https://fxa.example.com:9900/v1: -953
1429655294954 FirefoxAccounts DEBUG getCertificate got a new one: true
1429655294954 FirefoxAccounts DEBUG getAssertionFromCert
1429655294971 FirefoxAccounts DEBUG getAssertionFromCert returning
signed: true
1429655294972 Sync.BrowserIDManager DEBUG Getting a token
1429655294973 Common.TokenServerClient DEBUG Beginning BID assertion
exchange: http://fxa.example.com:5000/token/1.0/sync/1.5
1429655295073 Common.TokenServerClient DEBUG Got token response: 503
1429655295074 Common.TokenServerClient INFO Server-reported error:
{"location":"body","name":"","description":"Resource is not available"}
1429655295074 Sync.BrowserIDManager ERROR Non-authentication error in
_fetchTokenForUser:
TokenServerClientServerError({"now":"2015-04-21T22:28:15.074Z","message":"Server error.","cause":"general","response_body":"{\"status\": \"error\", \"errors\": [{\"location\": \"body\", \"name\": \"\", \"description\": \"Resource is not available\"}]}","response_headers":{"server":"PasteWSGIServer/0.5 Python/2.7.9","date":"Tue, 21 Apr 2015 22:28:15 GMT","content-length":"109","content-type":"application/json; charset=UTF-8","x-weave-timestamp":"1429655295.07","x-timestamp":"1429655295"},"response_status":503}) (resource://gre/modules/services-common/tokenserverclient.js:36:35) JS Stack trace: @tokenserverclient.js:36:36 < @browserid_identity.js:14:1 < @status.js:15:1 < @service.js:35:1 < lazyImport/getter/<@main.js:20:5 < [email protected]:83:5 < [email protected]:96:5 < [email protected]:170:5 < [email protected]:227:9 < [email protected]:128:9 < .dispatchCommand@
co.uk:3030/scripts/lib/channels/fx-desktop.js:51:7 < [email protected]:3030/scripts/lib/channels/mixins/postmessage_receiver.js:83:9 < [email protected]:3030/bower_components/p/p.js:840:4 < denodeify/<@co.uk:3030/bower_components/p/p.js:832:4 < [email protected]:3030/scripts/models/auth_brokers/mixins/channel.js:24:14 < FxDesktopAuthenticationBroker<[email protected]:3030/scripts/models/auth_brokers/fx-desktop.js:114:14 < FxDesktopAuthenticationBroker<[email protected]:3030/scripts/models/auth_brokers/fx-desktop.js:62:14 < View<[email protected]:3030/scripts/views/sign_in.js:178:14 < View<._signIn/<@co.uk:3030/scripts/views/sign_in.js:152:18 < [email protected]:3030/bower_components/p/p.js:506:8 < [email protected]:3030/bower_components/p/p.js:498:4 <
[email protected]:3030/bower_components/p/p.js:204:4
1429655295076 Sync.Status DEBUG Status.login:
error.login.reason.no_username => error.login.reason.network
1429655295076 Sync.Status DEBUG Status.service:
service.client_not_configured => error.login.failed
1429655295076 Sync.SyncScheduler DEBUG Clearing sync triggers and the
global score.
1429655295077 Sync.SyncScheduler DEBUG Next sync in 3600000 ms.
Here I'm intrigued by the "...due to a different user being signed in"
line. Is this some kind of race-condition? Or just a red herring?
Cheers,
Robin Bankhead
_______________________________________________
Sync-dev mailing list
[email protected]
https://mail.mozilla.org/listinfo/sync-dev
