967712 - Firefox Accounts (Sync) - Invalid timestamp immediately after signing in

Status: RESOLVED WORKSFORME

(Firefox for Android Graveyard :: Android Sync, defect)

Description

[Aaron Train [:aaronmt]]

I/FxAccounts( 4295): fennec_aurora :: FxAccountSetupTask :: Got success.
I/FxAccounts( 4295): fennec_aurora :: FxAccountAbstractSetupActivity :: Got success response; adding Android account.
I/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: Syncing FxAccount account named like [e-mail] for authority org.mozilla.fennec_aurora.db.browser with instance org.mozilla.gecko.fxa.sync.FxAccountSyncAdapter@419f2e90.
I/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: handleTransition: LogMessage('keys succeeded') to Cohabiting
I/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: handleTransition: LogMessage('sign succeeded') to Married
I/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: handleFinal: in Married
W/FxAccounts( 4295): fennec_aurora :: TokenServerClient :: {"location":"body","description":"Unauthorized","name":""}
W/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: Remote verifier returned failure: {"reason":"assertion has expired","status":"failure"}
E/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: Failed to get token.
E/FxAccounts( 4295): org.mozilla.gecko.tokenserver.TokenServerException$TokenServerInvalidCredentialsException: {"status":"invalid-timestamp","errors":[{"location":"body","description":"Unauthorized","name":""}]}
E/FxAccounts( 4295): 	at org.mozilla.gecko.tokenserver.TokenServerClient.processResponse(TokenServerClient.java:155)
E/FxAccounts( 4295): 	at org.mozilla.gecko.tokenserver.TokenServerClient$TokenFetchResourceDelegate.handleHttpResponse(TokenServerClient.java:236)
E/FxAccounts( 4295): 	at org.mozilla.gecko.sync.net.BaseResource.execute(BaseResource.java:276)
E/FxAccounts( 4295): 	at org.mozilla.gecko.sync.net.BaseResource.go(BaseResource.java:311)
E/FxAccounts( 4295): 	at org.mozilla.gecko.sync.net.BaseResource.get(BaseResource.java:317)
E/FxAccounts( 4295): 	at org.mozilla.gecko.fxa.sync.FxAccountSyncAdapter.syncWithAssertion(FxAccountSyncAdapter.java:227)
E/FxAccounts( 4295): 	at org.mozilla.gecko.fxa.sync.FxAccountSyncAdapter$2.handleFinal(FxAccountSyncAdapter.java:389)
E/FxAccounts( 4295): 	at org.mozilla.gecko.fxa.login.FxAccountLoginStateMachine$ExecuteDelegate.handleTransition(FxAccountLoginStateMachine.java:64)
E/FxAccounts( 4295): 	at org.mozilla.gecko.fxa.login.Cohabiting$1.handleSuccess(Cohabiting.java:24)
E/FxAccounts( 4295): 	at org.mozilla.gecko.background.fxa.FxAccountClient10$10.handleSuccess$2637ceed(FxAccountClient10.java:717)
E/FxAccounts( 4295): 	at org.mozilla.gecko.background.fxa.FxAccountClient10$ResourceDelegate$2.run(FxAccountClient10.java:238)
E/FxAccounts( 4295): 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076)
E/FxAccounts( 4295): 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569)
E/FxAccounts( 4295): 	at java.lang.Thread.run(Thread.java:838)
W/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: Global session failed.
E/FxAccounts( 4295): fennec_aurora :: FxAccountSyncAdapter :: Got exception syncing.

Immediately after sign-in, I get an invalid-timestamp. There shouldn't be any problems right after signing in.

--
Nightly (02/04)
Aurora (02/04)

Comment 1

[Nick Alexander :nalexander [he/him]]

Known issue.  The first request to the token server (after starting the package) almost always fails, since we haven't seen the server before, and therefore we don't know it's timestamp.  We could work around this by being more clever with try-update timestamp-retry.  Or we could persist our computed timestamps across package restarts.

Comment 2

[Aaron Train [:aaronmt]]

Wish I recorded which device I saw this one. I don't see this anymore on my frequently used devices.