Firefox syncserver login error

Discuss

Hi
Since last upgade it seems Firefox syncserver isnt working well. _heartbeat_ page is showing that the server is running well. but i got logs of error from about:sync-log
i reinstalled that just in case but i still hit that line:

Sync.ErrorHandler ERROR Sync encountered a login error

i followed the after-installation notes :

  • logout from firefox sync
  • change URI in about:config, restart
  • login again.

if anyone has pointers :person_shrugging:

after the 0.21.0 update i hit few

Sync.ErrorHandler	ERROR	Sync encountered a login error
1757674003051	Sync.SyncScheduler	DEBUG	Clearing sync triggers and the global score.
1757674003051	Sync.SyncScheduler	DEBUG	Next sync in 3600000 ms. (why=schedule)
1757674003052	Sync.Service	DEBUG	Exception calling WrappedLock: Error: Login failed: success.status_ok(resource://services-sync/service.sys.mjs:1051:15) JS Stack trace: onNotify@service.sys.mjs:1051:15
1757674003052	Sync.Service	DEBUG	Not syncing: login returned false.
1757674003052	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent

Reported tracking 0.21.0 possibly broke login · Issue #100 · YunoHost-Apps/syncserver-rs_ynh · GitHub, Ill have a look soon :trade_mark:.

in the meanwhile you can restore pre-upgrade backup to go back to known good version perhaps?

(progress update)
I’ve pinpointed the issue to difference between MySQL vs MariaDB, looking for solution…

wasnt able to restore to a working version since i reinstalled syncserver-rs, but i can wait -and upgrade until it works again :+1: also thanks for taking time to lookâ„¢

Fix will be landing on testing soon :trade_mark:, it’s available here and it worked for me exactly once :wink:

Aaaand I accidentally released it as 0.21.0~ynh2 :person_shrugging:

Fingers crossed it works as expected :wink:

maybe i’m doing something wrong?

1758071889199	Sync.Status	INFO	Resetting Status.
1758071889199	Sync.Status	DEBUG	Status.service: undefined => success.status_ok
1758071889202	FirefoxAccounts	TRACE	initializing new storage manager
1758071889202	FirefoxAccounts	TRACE	starting fetch of json user data
1758071889203	Sync.RemoteTabs	INFO	Doing a tab sync.
1758071889211	Sync.SyncScheduler	TRACE	Setting SyncScheduler policy values to defaults.
1758071889211	Sync.SyncScheduler	DEBUG	Clearing sync triggers and the global score.
1758071889212	Sync.Service	INFO	Loading Weave 1.144.0
1758071889213	Sync.Engine.Clients	DEBUG	Engine constructed
1758071889215	Sync.Engine.Clients	DEBUG	Resetting clients last sync time
1758071889215	Sync.Service	DEBUG	User-Agent: Firefox/142.0.1 (Linux x86_64) FxSync/1.144.0.20250827004350.desktop
1758071889215	Sync.RemoteTabs	DEBUG	Initializing TabListComponent
1758071889216	Sync.RemoteTabs	INFO	Generating tab list with filter: 
1758071889216	Sync.RemoteTabs	DEBUG	Sync isn't yet ready, so returning an empty tab list
1758071889230	Sync.Engine.Addons	DEBUG	Engine constructed
1758071889267	FirefoxAccounts	TRACE	finished fetch of json user data - took: 65
1758071889267	FirefoxAccounts	DEBUG	reading secure storage with existing: []
1758071889267	FirefoxAccounts	TRACE	starting fetch of user data from the login manager
1758071889307	FirefoxAccounts	DEBUG	secure read fetched items: ["scopedKeys"]
1758071889307	FirefoxAccounts	TRACE	initializing of new storage manager done
1758071889316	Sync.Engine.Addons	DEBUG	SyncEngine initialized: addons
1758071889350	Sync.Engine.Passwords	DEBUG	Engine constructed
1758071889368	Sync.Engine.Passwords	DEBUG	SyncEngine initialized: passwords
1758071889369	Sync.Engine.Prefs	DEBUG	Engine constructed
1758071889376	Sync.Engine.Prefs	DEBUG	SyncEngine initialized: prefs
1758071889378	Sync.Engine.Bookmarks	DEBUG	Engine constructed
1758071889389	Sync.Engine.Bookmarks	DEBUG	SyncEngine initialized: bookmarks
1758071889392	Sync.Engine.Forms	DEBUG	Engine constructed
1758071889400	Sync.Engine.Forms	DEBUG	SyncEngine initialized: forms
1758071889401	Sync.Engine.History	DEBUG	Engine constructed
1758071889406	Sync.Engine.History	DEBUG	SyncEngine initialized: history
1758071889409	Sync.Engine.Tabs	DEBUG	Engine constructed
1758071889417	Sync.Engine.Tabs	DEBUG	SyncEngine initialized: tabs
1758071889428	Sync.Engine.Tabs	INFO	Got a bridged engine!
1758071889429	Sync.Engine.Addresses	DEBUG	Engine constructed
1758071889443	Sync.Engine.Addresses	DEBUG	SyncEngine initialized: addresses
1758071889444	Sync.Engine.CreditCards	DEBUG	Engine constructed
1758071889459	Sync.Engine.CreditCards	DEBUG	SyncEngine initialized: creditcards
1758071889461	Sync.Engine.Extension-Storage	DEBUG	Engine constructed
1758071889472	Sync.Engine.Extension-Storage	DEBUG	SyncEngine initialized: extension-storage
1758071889479	Sync.Engine.Extension-Storage	INFO	Got a bridged engine!
1758071889479	Sync.Service	INFO	Mozilla/5.0 (X11; Linux x86_64; rv:142.0) Gecko/20100101 Firefox/142.0
1758071889479	Sync.AddonsReconciler	INFO	Registering as Add-on Manager listener.
1758071889479	Sync.AddonsReconciler	DEBUG	Adding change listener.
1758071889480	Sync.Engine.History.Tracker	INFO	Adding Places observer.
1758071889480	Sync.Service	INFO	Starting sync at 2025-09-17 03:18:09 in browser session P9bPW2GK0q7x
1758071889480	Sync.Service	DEBUG	In sync: should login.
1758071889480	Sync.Service	INFO	User logged in successfully - verifying login.
1758071889481	FirefoxAccounts	DEBUG	FxAccountsProfileClient: Initialized
1758071889482	FirefoxAccounts	DEBUG	FxAccountsProfileClient: Requested profile
1758071889482	FirefoxAccounts	DEBUG	getOAuthToken enter
1758071889482	FirefoxAccounts	TRACE	getCachedToken returning cached token
1758071889482	FirefoxAccounts	DEBUG	getOAuthToken returning a cached token
1758071889482	Sync.SyncAuthManager	DEBUG	unlockAndVerifyAuthState already has (or can fetch) sync keys
1758071889482	Services.Common.RESTRequest	DEBUG	GET request to https://profile.accounts.firefox.com/v1/profile
1758071889483	Sync.Status	DEBUG	Status.login: success.login => success.status_ok
1758071889483	Sync.Status	DEBUG	Status.service: success.status_ok => error.login.failed
1758071889483	Sync.Service	DEBUG	Fetching unlocked auth state returned success.status_ok
1758071889483	Sync.SyncAuthManager	INFO	Getting sync key
1758071889483	FirefoxAccounts	DEBUG	getOAuthToken enter
1758071889483	FirefoxAccounts	TRACE	getCachedToken returning cached token
1758071889483	FirefoxAccounts	DEBUG	getOAuthToken returning a cached token
1758071889483	Sync.SyncAuthManager	INFO	Getting a sync token from: https://[SERVERNAME]/1.0/sync/1.5
1758071889483	Sync.SyncAuthManager	DEBUG	Getting a token using OAuth
1758071889483	Services.Common.TokenServerClient	DEBUG	Beginning OAuth token exchange: https://[SERVERNAME]/1.0/sync/1.5
1758071889484	Services.Common.RESTRequest	DEBUG	GET request to https://[SERVERNAME]/1.0/sync/1.5
1758071889558	FirefoxAccounts	DEBUG	FxAccountsWebChannel registered: account_updates with origin https://accounts.firefox.com
1758071889706	Services.Common.RESTRequest	DEBUG	GET https://profile.accounts.firefox.com/v1/profile 304
1758071889772	Services.Common.RESTRequest	DEBUG	GET https://[SERVERNAME]/1.0/sync/1.5 200
1758071889772	Services.Common.TokenServerClient	DEBUG	Got token response: 200
1758071889772	Services.Common.TokenServerClient	DEBUG	Successful token response
1758071889774	Sync.BulkKeyBundle	INFO	BulkKeyBundle being created for undefined
1758071889774	Sync.Status	DEBUG	Status.login: success.status_ok => success.login
1758071889774	Sync.Status	DEBUG	Status.service: error.login.failed => success.status_ok
1758071889775	Sync.SyncAuthManager	DEBUG	_findCluster returning https://[SERVERNAME]/1.5/4/
1758071889775	Sync.SyncAuthManager	DEBUG	Cluster value = https://[SERVERNAME]/1.5/4/
1758071889775	Sync.SyncAuthManager	DEBUG	Setting cluster to https://[SERVERNAME]/1.5/4/
1758071889775	Sync.Service	DEBUG	Caching URLs under storage user base: https://[SERVERNAME]/1.5/4/
1758071889776	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent
1758071889776	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent
1758071889778	FirefoxAccounts	INFO	fetching updated device list
1758071889804	Services.Common.RESTRequest	DEBUG	GET request to https://api.accounts.firefox.com/v1/account/attached_clients
1758071889807	Services.Common.RESTRequest	DEBUG	GET request to https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1756257489779
1758071889832	Sync.Resource	DEBUG	GET success 200 https://[SERVERNAME]/1.5/4/info/collections
1758071889832	Sync.Service	DEBUG	Fetching server configuration: https://[SERVERNAME]/1.5/4/info/configuration
1758071889832	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent
1758071889844	Sync.Resource	DEBUG	GET success 200 https://[SERVERNAME]/1.5/4/info/configuration
1758071889844	Sync.Service	DEBUG	Fetching global metadata record
1758071889845	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent
1758071889850	Sync.Service	DEBUG	User-Agent: Firefox/142.0.1 (Linux x86_64) FxSync/1.144.0.20250827004350.desktop
1758071889850	Sync.Service	INFO	Starting sync at 2025-09-17 03:18:09 in browser session P9bPW2GK0q7x
1758071889850	Sync.Service	DEBUG	In sync: should login.
1758071889850	Sync.Service	DEBUG	Exception calling WrappedLock: LockException: Could not acquire lock. Label: "service.js: login".(resource://services-sync/util.sys.mjs:43:5) JS Stack trace: LockException@util.sys.mjs:43:5
throwLockException@util.sys.mjs:127:11
WrappedLock@util.sys.mjs:141:15
WrappedCatch@util.sys.mjs:112:27
login@service.sys.mjs:1062:66
sync/<@service.sys.mjs:1349:26
WrappedCatch@util.sys.mjs:112:27
sync@service.sys.mjs:1357:7
1758071889851	Sync.Service	INFO	Cannot start sync: already syncing?
1758071889851	Sync.Service	DEBUG	Not syncing: login returned false.
1758071889866	Sync.Resource	DEBUG	GET fail 500 https://[SERVERNAME]/1.5/4/storage/meta/global
1758071889866	Sync.Resource	WARN	GET request to https://[SERVERNAME]/1.5/4/storage/meta/global failed with status 500
1758071889866	Sync.Service	DEBUG	Weave Version: 1.144.0 Local Storage: 5 Remote Storage: 
1758071889866	Sync.Service	INFO	One of: no meta, no meta storageVersion, or no meta syncID. Fresh start needed.
1758071889866	Sync.Status	DEBUG	Status.sync: success.sync => error.sync.reason.metarecord_download_fail
1758071889866	Sync.Status	DEBUG	Status.service: success.status_ok => error.sync.failed
1758071889866	Sync.Service	WARN	Unknown error while downloading metadata record. Aborting sync.
1758071889866	Sync.Service	WARN	Remote setup failed.
1758071889866	Sync.ErrorHandler	ERROR	Sync encountered a login error
1758071889866	Sync.SyncScheduler	DEBUG	Clearing sync triggers and the global score.
1758071889867	Sync.SyncScheduler	DEBUG	Next sync in 3600000 ms. (why=schedule)
1758071889867	Sync.Service	DEBUG	Exception calling WrappedLock: Error: Login failed: success.login(resource://services-sync/service.sys.mjs:1051:15) JS Stack trace: onNotify@service.sys.mjs:1051:15
1758071889867	Sync.Service	DEBUG	Not syncing: login returned false.
1758071889868	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent
1758071889869	FirefoxAccounts	INFO	Already fetching device list, return existing promise
1758071889878	Services.Common.RESTRequest	DEBUG	GET request to https://api.accounts.firefox.com/v1/account/attached_clients
1758071889900	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent
1758071889945	FirefoxAccounts	TRACE	not checking freshness of profile as it remains recent
1758071889947	FirefoxAccounts	INFO	Already fetching device list, return existing promise
1758071889963	Services.Common.RESTRequest	DEBUG	GET request to https://api.accounts.firefox.com/v1/account/attached_clients
1758071890034	Services.Common.RESTRequest	DEBUG	GET https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1756257489779 200
1758071890034	Hawk	DEBUG	(Response) /account/devices?filterIdleDevicesTimestamp=1756257489779: code: 200 - Status text: 
1758071890034	Hawk	DEBUG	Clock offset vs https://api.accounts.firefox.com/v1: -34
1758071890035	FirefoxAccounts	INFO	Got new device list: 2d5f56094413a31fc7a91a23c5e5ee12, 4158c839a5b6e7d5e7c086499e1a2789, 73cd90d6d2e90da706781002089c9790, 8b18f2f245b265f5504a470b123407ea, 9fbb591d821311352257801770c05871
1758071890040	FirefoxAccounts	DEBUG	FxAccountsPush loading service
1758071890045	FirefoxAccounts	DEBUG	FxAccountsPush initialized
1758071890047	Services.Common.RESTRequest	DEBUG	GET https://api.accounts.firefox.com/v1/account/attached_clients 200
1758071890047	Hawk	DEBUG	(Response) /account/attached_clients: code: 200 - Status text: 
1758071890047	Hawk	DEBUG	Clock offset vs https://api.accounts.firefox.com/v1: -47
1758071890071	FirefoxAccounts	TRACE	Command queue observer created
1758071890072	FirefoxAccounts	TRACE	Getting command keys: https://identity.mozilla.com/cmd/open-uri
1758071890072	FirefoxAccounts	TRACE	Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1758071890072	FirefoxAccounts	TRACE	Our push subscription looks OK
1758071890072	FirefoxAccounts	INFO	updating the cache
1758071890082	Services.Common.RESTRequest	DEBUG	GET https://api.accounts.firefox.com/v1/account/attached_clients 200
1758071890082	Hawk	DEBUG	(Response) /account/attached_clients: code: 200 - Status text: 
1758071890082	Hawk	DEBUG	Clock offset vs https://api.accounts.firefox.com/v1: -82
1758071890135	Services.Common.RESTRequest	DEBUG	GET https://api.accounts.firefox.com/v1/account/attached_clients 200
1758071890136	Hawk	DEBUG	(Response) /account/attached_clients: code: 200 - Status text: 
1758071890136	Hawk	DEBUG	Clock offset vs https://api.accounts.firefox.com/v1: -136

sorry for pasting raw log, its from about:sync-log and link txt files isn’t allowed

Are you running 0.21.0~ynh2?

Could you post logs from the server? Should be under webadmin->tools->services->syncserver

i do!

here ya go

more info that might matter: have 3 devices using that sync tool, a windows pc (using nightly branch of firefox), a linux laptop and an android phone.

OK, so I screwed up 0.21.0~ynh2 (it was working for me because I built it manually and thought I’m releasing the same thing).

I’ve released 0.21.0~ynh3 with actual fix and fixed upgrade script for (semi-)unrelated issue.

okey, no worries, lets try that.

EDIT: It works! Thank you for looking and fixing it! :sports_medal: