1749063331564 FirefoxAccounts TRACE initializing new storage manager
1749063331564 FirefoxAccounts TRACE starting fetch of json user data
1749063331566 FirefoxAccounts TR… (Lesen Sie mehr)
1749063331564 FirefoxAccounts TRACE initializing new storage manager
1749063331564 FirefoxAccounts TRACE starting fetch of json user data
1749063331566 FirefoxAccounts TRACE initializing of new storage manager done
1749063333910 FirefoxAccounts DEBUG FxAccountsWebChannel registered: account_updates with origin https://accounts.firefox.com
1749063415672 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:fxa_status
1749063415672 FirefoxAccounts TRACE _receiveMessage for command fxaccounts:fxa_status
1749063415672 FirefoxAccounts DEBUG fxa_status received
1749063415673 FirefoxAccounts DEBUG fxa status ok=true - private=false, service=sync, context=oauth_webchannel_v1, pairing=false
1749063441870 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:can_link_account
1749063441870 FirefoxAccounts TRACE _receiveMessage for command fxaccounts:can_link_account
1749063457782 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:login
1749063457782 FirefoxAccounts TRACE _receiveMessage for command fxaccounts:login
1749063457782 FirefoxAccounts DEBUG Webchannel is logging new a user in.
1749063457783 FirefoxAccounts DEBUG storing info for services sync
1749063457784 FirefoxAccounts DEBUG setSignedInUser - aborting any existing flows
1749063457784 FirefoxAccounts DEBUG removing account data
1749063457784 FirefoxAccounts TRACE starting write of json user data: null
1749063457785 FirefoxAccounts TRACE finished write of json user data - took: 1
1749063457786 FirefoxAccounts TRACE storage set finished clearing account data
1749063457786 FirefoxAccounts DEBUG account data reset
1749063457786 FirefoxAccounts TRACE StorageManager finalizing
1749063457786 FirefoxAccounts TRACE StorageManager finalized
1749063457786 FirefoxAccounts TRACE initializing new storage manager
1749063457786 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","requestedServices"]
1749063457786 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","requestedServices"]
1749063457788 FirefoxAccounts TRACE finished write of json user data - took: 2
1749063457788 FirefoxAccounts DEBUG writing secure storage: []
1749063457788 FirefoxAccounts TRACE starting write of user data to the login manager
1749063457792 FirefoxAccounts TRACE finished write of user data to the login manager
1749063457792 FirefoxAccounts TRACE initializing of new storage manager done
1749063457807 Sync.Status INFO Resetting Status.
1749063457807 Sync.Status DEBUG Status.service: undefined => success.status_ok
1749063457807 Sync.SyncScheduler TRACE Setting SyncScheduler policy values to defaults.
1749063457808 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1749063457808 Sync.Status DEBUG Status.login: success.login => error.login.reason.no_username
1749063457808 Sync.Status DEBUG Status.service: success.status_ok => service.client_not_configured
1749063457808 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1749063457808 Sync.Service INFO Loading Weave 1.141.0
1749063457809 Sync.Engine.Clients DEBUG Engine constructed
1749063457809 Sync.Engine.Clients DEBUG Resetting clients last sync time
1749063457812 Sync.Engine.Addons DEBUG Engine constructed
1749063457814 Sync.Engine.Addons DEBUG SyncEngine initialized: addons
1749063457815 Sync.AddonsReconciler DEBUG No data seen in loaded file: addonsreconciler
1749063457816 Sync.Engine.Passwords DEBUG Engine constructed
1749063457817 Sync.Engine.Passwords DEBUG SyncEngine initialized: passwords
1749063457818 Sync.Engine.Prefs DEBUG Engine constructed
1749063457819 Sync.Engine.Prefs DEBUG SyncEngine initialized: prefs
1749063457820 Sync.Engine.Bookmarks DEBUG Engine constructed
1749063457822 Sync.Engine.Bookmarks DEBUG SyncEngine initialized: bookmarks
1749063457823 Sync.Engine.Forms DEBUG Engine constructed
1749063457823 Sync.Engine.Forms DEBUG SyncEngine initialized: forms
1749063457825 Sync.Engine.History DEBUG Engine constructed
1749063457825 Sync.Engine.History DEBUG SyncEngine initialized: history
1749063457827 Sync.Engine.Tabs DEBUG Engine constructed
1749063457828 Sync.Engine.Tabs DEBUG SyncEngine initialized: tabs
1749063457831 Sync.Engine.Tabs INFO Got a bridged engine!
1749063457832 Sync.Engine.Addresses DEBUG Engine constructed
1749063457833 Sync.Engine.Addresses DEBUG SyncEngine initialized: addresses
1749063457833 Sync.Engine.CreditCards DEBUG Engine constructed
1749063457833 Sync.Engine.CreditCards DEBUG SyncEngine initialized: creditcards
1749063457834 Sync.Engine.Extension-Storage DEBUG Engine constructed
1749063457835 Sync.Engine.Extension-Storage DEBUG SyncEngine initialized: extension-storage
1749063457856 Sync.Engine.Extension-Storage INFO Got a bridged engine!
1749063457857 Sync.Service INFO Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:139.0) Gecko/20100101 Firefox/139.0
1749063457857 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username
1749063457857 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1749063457857 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured
1749063457857 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onlogin
1749063457857 Sync.SyncAuthManager DEBUG observed fxaccounts:onlogin
1749063457858 Sync.SyncAuthManager INFO Sync is not configured, so ignoring the notification
1749063457860 FirefoxAccounts DEBUG FxAccountsProfileClient: Initialized
1749063457860 FirefoxAccounts DEBUG FxAccountsPush loading service
1749063457860 FirefoxAccounts DEBUG FxAccountsPush initialized
1749063457860 FirefoxAccounts TRACE FxAccountsPush registerPushEndpoint
1749063457861 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile
1749063457861 FirefoxAccounts DEBUG getOAuthToken enter
1749063457872 FirefoxAccounts TRACE Command queue observer created
1749063457872 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063457872 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063457872 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063457872 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063457872 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063457873 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063457874 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063457874 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063457874 FirefoxAccounts TRACE FxAccountsPush registerPushEndpoint
1749063457876 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/oauth/token
1749063457876 Services.Common.RESTRequest DEBUG POST Length: 81
1749063458036 FirefoxAccounts DEBUG FxAccountsPush got subscription
1749063458036 FirefoxAccounts DEBUG FxAccountsPush got subscription
1749063458036 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063458037 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063458037 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063458037 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063458037 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063458037 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063458037 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063458037 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063458037 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063458037 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063458037 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063458037 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063458037 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063458037 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063458037 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063458037 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063458037 FirefoxAccounts INFO registering with available commands: []
1749063458037 FirefoxAccounts DEBUG registering new device details
1749063458037 FirefoxAccounts INFO registering with available commands: []
1749063458037 FirefoxAccounts DEBUG registering new device details
1749063458039 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/device
1749063458039 Services.Common.RESTRequest DEBUG POST Length: 439
1749063458040 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/device
1749063458040 Services.Common.RESTRequest DEBUG POST Length: 439
1749063458166 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/oauth/token 200
1749063458167 Hawk DEBUG (Response) /oauth/token: code: 200 - Status text:
1749063458167 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -167
1749063458167 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"]
1749063458167 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","requestedServices","oauthTokens"]
1749063458167 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","requestedServices","oauthTokens"]
1749063458167 Services.Common.RESTRequest DEBUG GET request to https://profile.accounts.firefox.com/v1/profile
1749063458168 FirefoxAccounts TRACE finished write of json user data - took: 1
1749063458168 FirefoxAccounts DEBUG writing secure storage: []
1749063458168 FirefoxAccounts TRACE starting write of user data to the login manager
1749063458176 FirefoxAccounts TRACE finished write of user data to the login manager
1749063458234 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/device 400
1749063458234 Hawk DEBUG (Response) /account/device: code: 400 - Status text:
1749063458234 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -234
1749063458234 FirefoxAccounts ERROR error POSTing /account/device: {"code":400,"errno":124,"error":"Bad Request","message":"Session already registered by another device","info":"https://mozilla.github.io/ecosystem-platform/api#section/Response-format","deviceId":"f54554eaa00cd01c67248aa3d248ae6b"}
1749063458234 FirefoxAccounts WARN device session conflict, attempting to ascertain the correct device id
1749063458235 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1747249058234
1749063458242 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/device 200
1749063458243 Hawk DEBUG (Response) /account/device: code: 200 - Status text:
1749063458243 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -243
1749063458243 FirefoxAccounts DEBUG _updateAccountData with items: ["device"]
1749063458243 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device"]
1749063458243 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device"]
1749063458243 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063458243 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063458243 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063458243 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063458243 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063458243 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063458243 FirefoxAccounts INFO Can't get keys; no key material or tokens available
1749063458243 FirefoxAccounts INFO Can't fetch keys, so unable to determine command keys
1749063458244 FirefoxAccounts TRACE finished write of json user data - took: 1
1749063458244 FirefoxAccounts DEBUG writing secure storage: []
1749063458244 FirefoxAccounts TRACE starting write of user data to the login manager
1749063458250 FirefoxAccounts TRACE finished write of user data to the login manager
1749063458478 Services.Common.RESTRequest DEBUG GET https://profile.accounts.firefox.com/v1/profile 200
1749063458478 FirefoxAccounts DEBUG _updateAccountData with items: ["profileCache"]
1749063458478 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device","profileCache"]
1749063458478 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device","profileCache"]
1749063458479 FirefoxAccounts DEBUG notifying profile changed for user e953a4fc9fb34e509827b8e85bf257dd
1749063458479 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063458480 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063458481 FirefoxAccounts TRACE finished write of json user data - took: 3
1749063458481 FirefoxAccounts DEBUG writing secure storage: []
1749063458481 FirefoxAccounts TRACE starting write of user data to the login manager
1749063458487 FirefoxAccounts TRACE finished write of user data to the login manager
1749063458560 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1747249058234 200
1749063458560 Hawk DEBUG (Response) /account/devices?filterIdleDevicesTimestamp=1747249058234: code: 200 - Status text:
1749063458560 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -560
1749063458560 FirefoxAccounts DEBUG _updateAccountData with items: ["device","encryptedSendTabKeys"]
1749063458560 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device","profileCache"]
1749063458560 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device","profileCache"]
1749063458560 FirefoxAccounts DEBUG Webchannel finished logging a user in.
1749063458561 FirefoxAccounts TRACE finished write of json user data - took: 1
1749063458561 FirefoxAccounts DEBUG writing secure storage: []
1749063458561 FirefoxAccounts TRACE starting write of user data to the login manager
1749063458567 FirefoxAccounts TRACE finished write of user data to the login manager
1749063458927 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:oauth_login
1749063458927 FirefoxAccounts TRACE _receiveMessage for command fxaccounts:oauth_login
1749063458927 FirefoxAccounts DEBUG Webchannel is completing the oauth flow
1749063458932 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/oauth/token
1749063458932 Services.Common.RESTRequest DEBUG POST Length: 202
1749063459189 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/oauth/token 200
1749063459189 Hawk DEBUG (Response) /oauth/token: code: 200 - Status text:
1749063459189 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -189
1749063459192 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/oauth/destroy
1749063459192 Services.Common.RESTRequest DEBUG POST Length: 107
1749063459354 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/oauth/destroy 200
1749063459354 Hawk DEBUG (Response) /oauth/destroy: code: 200 - Status text:
1749063459354 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -354
1749063459354 FirefoxAccounts DEBUG _updateAccountData with items: ["scopedKeys"]
1749063459354 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device","profileCache"]
1749063459354 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","requestedServices","oauthTokens","device","profileCache"]
1749063459354 FirefoxAccounts DEBUG services requested are sync
1749063459354 FirefoxAccounts DEBUG Did not receive any engine selection information
1749063459354 FirefoxAccounts DEBUG Webchannel is enabling sync
1749063459354 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459354 Sync.Service INFO Configuring sync with current FxA user
1749063459356 Sync.SyncAuthManager DEBUG observed weave:connected
1749063459356 Sync.SyncAuthManager INFO Sync has been connected to a logged in user
1749063459356 FirefoxAccounts DEBUG updateUserAccountData called with fields: ["uid","requestedServices"]
1749063459356 FirefoxAccounts DEBUG _updateAccountData with items: ["requestedServices"]
1749063459356 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459356 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459357 Sync.SyncAuthManager INFO The user became verified
1749063459357 Sync.Status DEBUG Status.login: error.login.reason.no_username => success.login
1749063459357 Sync.Status DEBUG Status.service: service.client_not_configured => success.status_ok
1749063459357 Sync.SyncAuthManager INFO Doing initial sync actions
1749063459357 Sync.AddonsReconciler INFO Registering as Add-on Manager listener.
1749063459357 Sync.AddonsReconciler DEBUG Adding change listener.
1749063459357 Sync.Engine.History.Tracker INFO Adding Places observer.
1749063459359 Sync.Service DEBUG User-Agent: Firefox/139.0.1 (Windows NT 10.0; Win64; x64) FxSync/1.141.0.20250529122813.desktop
1749063459359 Sync.Service INFO Starting sync at 2025-06-05 04:57:39 in browser session Qf4ub5seql3a
1749063459359 Sync.Service DEBUG In sync: should login.
1749063459359 Sync.Service INFO User logged in successfully - verifying login.
1749063459360 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onverified
1749063459376 Sync.SyncAuthManager DEBUG observed fxaccounts:onverified
1749063459376 Sync.SyncAuthManager INFO The user became verified
1749063459376 Sync.Status DEBUG Status.login: success.login => success.login
1749063459376 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1749063459376 Sync.Service DEBUG User-Agent: Firefox/139.0.1 (Windows NT 10.0; Win64; x64) FxSync/1.141.0.20250529122813.desktop
1749063459377 Sync.Service INFO Starting sync at 2025-06-05 04:57:39 in browser session Qf4ub5seql3a
1749063459377 Sync.Service DEBUG In sync: should login.
1749063459377 FirefoxAccounts DEBUG Webchannel completed oauth flows
1749063459377 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
1749063459377 Sync.Service INFO Cannot start sync: already syncing?
1749063459377 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063459377 Sync.Service DEBUG Not syncing: login returned false.
1749063459378 FirefoxAccounts INFO fetching updated device list
1749063459383 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump
1749063459386 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write - bumping score
1749063459389 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459391 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459391 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063459392 Sync.SyncAuthManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys
1749063459392 Sync.Status DEBUG Status.login: success.login => success.status_ok
1749063459392 Sync.Status DEBUG Status.service: success.status_ok => error.login.failed
1749063459392 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok
1749063459394 FirefoxAccounts INFO Already fetching device list, return existing promise
1749063459395 FirefoxAccounts INFO Could not find command keys, generating them
1749063459397 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459398 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459399 Sync.SyncAuthManager INFO Getting sync key
1749063459399 FirefoxAccounts DEBUG getOAuthToken enter
1749063459455 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063459468 FirefoxAccounts TRACE finished write of json user data - took: 114
1749063459468 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459468 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459475 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459475 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache"]
1749063459475 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache"]
1749063459478 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/account/attached_clients
1749063459478 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1747249059391
1749063459479 FirefoxAccounts TRACE writing to sendTabKeys for command https://identity.mozilla.com/cmd/open-uri
1749063459479 FirefoxAccounts DEBUG _updateAccountData with items: ["device"]
1749063459480 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined
1749063459480 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/account/attached_clients
1749063459483 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/oauth/token
1749063459483 Services.Common.RESTRequest DEBUG POST Length: 127
1749063459485 FirefoxAccounts TRACE finished write of json user data - took: 10
1749063459485 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459485 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459492 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459492 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache"]
1749063459492 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache"]
1749063459493 FirefoxAccounts DEBUG _updateAccountData with items: ["encryptedSendTabKeys"]
1749063459493 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063459493 FirefoxAccounts INFO Generating and persisting encrypted key (false, false)
1749063459493 FirefoxAccounts INFO Could not find command keys, generating them
1749063459494 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. (why=schedule)
1749063459495 FirefoxAccounts TRACE writing to closeTabKeys for command https://identity.mozilla.com/cmd/close-uri/v1
1749063459495 FirefoxAccounts DEBUG _updateAccountData with items: ["device"]
1749063459495 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined
1749063459496 FirefoxAccounts TRACE finished write of json user data - took: 4
1749063459496 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459496 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459502 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459502 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys"]
1749063459502 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys"]
1749063459503 FirefoxAccounts DEBUG _updateAccountData with items: ["encryptedCloseTabKeys"]
1749063459503 FirefoxAccounts TRACE FxAccountsPush registerPushEndpoint
1749063459503 FirefoxAccounts TRACE finished write of json user data - took: 1
1749063459503 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459503 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459511 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459511 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459511 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459511 FirefoxAccounts DEBUG FxAccountsPush got subscription
1749063459511 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063459511 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063459511 FirefoxAccounts INFO registering with available commands: ["https://identity.mozilla.com/cmd/close-uri/v1","https://identity.mozilla.com/cmd/open-uri"]
1749063459511 FirefoxAccounts DEBUG updating existing device details
1749063459513 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/device
1749063459513 Services.Common.RESTRequest DEBUG POST Length: 1303
1749063459514 FirefoxAccounts TRACE finished write of json user data - took: 3
1749063459514 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459514 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459522 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459522 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459522 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459524 FirefoxAccounts TRACE finished write of json user data - took: 2
1749063459524 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459524 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459530 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459647 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1747249059391 200
1749063459647 Hawk DEBUG (Response) /account/devices?filterIdleDevicesTimestamp=1747249059391: code: 200 - Status text:
1749063459647 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -647
1749063459647 FirefoxAccounts INFO Got new device list: c5ef5ab301051eca82af6c9d2201c8b1, f54554eaa00cd01c67248aa3d248ae6b
1749063459648 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063459648 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063459648 FirefoxAccounts WARN Our commands need to be updated on the server
1749063459648 FirefoxAccounts TRACE FxAccountsPush registerPushEndpoint
1749063459648 FirefoxAccounts DEBUG FxAccountsPush got subscription
1749063459648 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063459648 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063459648 FirefoxAccounts INFO registering with available commands: ["https://identity.mozilla.com/cmd/close-uri/v1","https://identity.mozilla.com/cmd/open-uri"]
1749063459648 FirefoxAccounts DEBUG updating existing device details
1749063459649 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/device
1749063459649 Services.Common.RESTRequest DEBUG POST Length: 1303
1749063459652 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/account/attached_clients 200
1749063459652 Hawk DEBUG (Response) /account/attached_clients: code: 200 - Status text:
1749063459652 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -652
1749063459663 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/oauth/token 200
1749063459663 Hawk DEBUG (Response) /oauth/token: code: 200 - Status text:
1749063459663 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -663
1749063459663 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"]
1749063459663 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459663 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459664 Sync.SyncAuthManager INFO Getting a sync token from: https://token.services.mozilla.com/1.0/sync/1.5
1749063459664 Sync.SyncAuthManager DEBUG Getting a token using OAuth
1749063459664 Services.Common.TokenServerClient DEBUG Beginning OAuth token exchange: https://token.services.mozilla.com/1.0/sync/1.5
1749063459664 Services.Common.RESTRequest DEBUG GET request to https://token.services.mozilla.com/1.0/sync/1.5
1749063459665 FirefoxAccounts TRACE finished write of json user data - took: 2
1749063459665 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459665 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459670 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459722 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/account/attached_clients 200
1749063459722 Hawk DEBUG (Response) /account/attached_clients: code: 200 - Status text:
1749063459722 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -722
1749063459821 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/device 200
1749063459821 Hawk DEBUG (Response) /account/device: code: 200 - Status text:
1749063459821 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -821
1749063459821 FirefoxAccounts DEBUG _updateAccountData with items: ["device"]
1749063459821 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459821 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459821 FirefoxAccounts INFO updating the cache
1749063459822 FirefoxAccounts TRACE finished write of json user data - took: 1
1749063459822 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459822 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459828 FirefoxAccounts TRACE finished write of user data to the login manager
1749063459859 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/device 200
1749063459859 Hawk DEBUG (Response) /account/device: code: 200 - Status text:
1749063459859 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -859
1749063459859 FirefoxAccounts DEBUG _updateAccountData with items: ["device"]
1749063459859 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459859 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","oauthTokens","device","profileCache","encryptedSendTabKeys","encryptedCloseTabKeys"]
1749063459860 FirefoxAccounts TRACE finished write of json user data - took: 1
1749063459861 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"]
1749063459861 FirefoxAccounts TRACE starting write of user data to the login manager
1749063459866 FirefoxAccounts TRACE finished write of user data to the login manager
1749063460072 Services.Common.RESTRequest DEBUG GET https://token.services.mozilla.com/1.0/sync/1.5 200
1749063460072 Services.Common.TokenServerClient DEBUG Got token response: 200
1749063460072 Services.Common.TokenServerClient DEBUG Successful token response
1749063460072 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined
1749063460072 Sync.Status DEBUG Status.login: success.status_ok => success.login
1749063460072 Sync.Status DEBUG Status.service: error.login.failed => success.status_ok
1749063460073 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063460073 Sync.SyncAuthManager DEBUG _findCluster returning https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/
1749063460073 Sync.SyncAuthManager DEBUG Cluster value = https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/
1749063460073 Sync.SyncAuthManager DEBUG Setting cluster to https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/
1749063460074 Sync.Service DEBUG Caching URLs under storage user base: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/
1749063460074 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063460074 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063460074 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063460256 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/collections
1749063460256 Sync.Service DEBUG Fetching server configuration: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/configuration
1749063460257 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063460400 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/configuration
1749063460400 Sync.Service DEBUG Fetching global metadata record
1749063460401 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063460577 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/meta/global
1749063460577 Sync.Service DEBUG Clearing cached meta record. metaModified is undefined, setting to 1713431542.5
1749063460578 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063460737 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/meta/global
1749063460738 Sync.Service DEBUG Weave Version: 1.141.0 Local Storage: 5 Remote Storage: 5
1749063460738 Sync.Service INFO Sync IDs differ. Local is PvWiN8nElPKy, remote is glqdB8e7y9A8
1749063460738 Sync.Service INFO Service reset.
1749063460738 Sync.Engine.Clients DEBUG Resetting clients last sync time
1749063460739 Sync.Engine.Addons DEBUG Resetting addons last sync time
1749063460740 Sync.Engine.Passwords DEBUG Resetting passwords last sync time
1749063460740 Sync.Engine.Prefs DEBUG Resetting prefs last sync time
1749063460740 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time
1749063460794 Sync.Engine.Forms DEBUG Resetting forms last sync time
1749063460795 Sync.Engine.History DEBUG Resetting history last sync time
1749063460795 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1749063460826 Sync.Engine.Addresses DEBUG Resetting addresses last sync time
1749063460827 Sync.Engine.CreditCards DEBUG Resetting creditcards last sync time
1749063460827 Sync.Engine.Extension-Storage DEBUG Resetting extension-storage last sync time
1749063460832 Sync.CollectionKeyManager INFO Clearing collection keys...
1749063460832 Sync.Service DEBUG Clear cached values and take syncId: glqdB8e7y9A8
1749063460832 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1749063460832 Sync.Service INFO Testing info/collections: {"bookmarks":1748945942.91,"clients":1748945940.92,"prefs":1748945941.38,"meta":1713431542.5,"forms":1748949741.2,"tabs":1748950759.4,"crypto":1713431537.29,"passwords":1748949740.76,"history":1748950754.14,"addons":1748950753.9}
1749063460832 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 0
1749063460832 Sync.Service INFO collection keys reports that a key update is needed.
1749063460833 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063460995 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/crypto/keys
1749063460995 Sync.CollectionKeyManager INFO Updating collection keys...
1749063460996 Sync.CollectionKeyManager INFO Setting collection keys contents. Our last modified: 0, input modified: 1713431537.29.
1749063460996 Sync.BulkKeyBundle INFO BulkKeyBundle being created for [default]
1749063460996 Sync.CollectionKeyManager INFO Processing downloaded per-collection keys.
1749063460996 Sync.CollectionKeyManager INFO Clearing collection keys...
1749063460996 Sync.CollectionKeyManager INFO Saving downloaded keys.
1749063460996 Sync.CollectionKeyManager INFO Bumping last modified to 1713431537.29
1749063460996 Sync.CollectionKeyManager INFO Collection keys updated.
1749063460996 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063460996 Sync.SyncAuthManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys
1749063460996 Sync.Status DEBUG Status.login: success.login => success.status_ok
1749063460996 Sync.Status DEBUG Status.service: success.status_ok => error.login.failed
1749063460996 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok
1749063460996 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063461154 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/collections
1749063461155 Sync.Service DEBUG Fetching server configuration: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/configuration
1749063461155 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063461298 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/configuration
1749063461299 Sync.Service DEBUG Fetching global metadata record
1749063461299 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063461456 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/meta/global
1749063461456 Sync.Service DEBUG Weave Version: 1.141.0 Local Storage: 5 Remote Storage: 5
1749063461456 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1749063461456 Sync.Service INFO Testing info/collections: {"addons":1748950753.9,"history":1748950754.14,"bookmarks":1748945942.91,"prefs":1748945941.38,"meta":1713431542.5,"clients":1748945940.92,"crypto":1713431537.29,"passwords":1748949740.76,"tabs":1748950759.4,"forms":1748949741.2}
1749063461456 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1713431537.29
1749063461456 Sync.Status DEBUG Status.login: success.status_ok => success.login
1749063461456 Sync.Status DEBUG Status.service: error.login.failed => success.status_ok
1749063461456 Sync.Status DEBUG Status.login: success.login => success.login
1749063461456 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1749063461456 Sync.Service DEBUG Caching URLs under storage user base: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/
1749063461456 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score.
1749063461458 Sync.Status INFO Resetting Status.
1749063461458 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1749063461458 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063461621 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/collections?v=1.141.0
1749063461622 Sync.Service DEBUG Fetching server configuration: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/configuration
1749063461622 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063461766 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/info/configuration
1749063461766 Sync.Service DEBUG Fetching global metadata record
1749063461766 Sync.Service DEBUG Weave Version: 1.141.0 Local Storage: 5 Remote Storage: 5
1749063461766 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys.
1749063461766 Sync.Service INFO Testing info/collections: {"crypto":1713431537.29,"bookmarks":1748945942.91,"clients":1748945940.92,"history":1748950754.14,"meta":1713431542.5,"forms":1748949741.2,"prefs":1748945941.38,"addons":1748950753.9,"passwords":1748949740.76,"tabs":1748950759.4}
1749063461766 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1713431537.29
1749063461766 Sync.Synchronizer DEBUG Refreshing client list.
1749063461767 Sync.Engine.Clients DEBUG Engine syncIDs differ (old="", new="hN0Btj7pMSwm") - resetting the engine
1749063461767 Sync.Engine.Clients DEBUG Resetting clients last sync time
1749063461768 Sync.Engine.Clients DEBUG First sync, uploading all items
1749063461768 Sync.Engine.Clients DEBUG Resetting clients last sync time
1749063461769 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063461932 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/clients?full=1&limit=1000
1749063461933 Sync.Engine.Clients INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063461933 FirefoxAccounts INFO Device list cache is fresh, re-using it
1749063461933 Sync.Engine.Clients DEBUG Refreshing the known stale clients list
1749063461933 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063461933 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063461934 Sync.Engine.Clients INFO Uploading 1 outgoing records
1749063461934 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063461934 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063461935 Sync.Engine.Clients INFO Posting 1 records of 550 bytes with batch=true
1749063461936 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063461936 Sync.Collection DEBUG POST Length: 550
1749063462113 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/clients?batch=true&commit=true
1749063462113 Sync.Engine.Clients INFO Uploaded our client record for the first time, notifying other clients.
1749063462113 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063462113 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063462115 FirefoxAccounts TRACE CommandQueue observed topic=weave:engine:sync:finish, data=clients, subject=null
1749063462115 Sync.SyncScheduler DEBUG Client count: 0 -> 2
1749063462115 Sync.Engine.Prefs DEBUG Resetting prefs last sync time
1749063462116 Sync.Engine.Passwords DEBUG Resetting passwords last sync time
1749063462116 Sync.Engine.Tabs DEBUG Resetting tabs last sync time
1749063462116 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/devices/notify
1749063462117 Services.Common.RESTRequest DEBUG POST Length: 184
1749063462123 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time
1749063462147 Sync.Engine.Addons DEBUG Resetting addons last sync time
1749063462147 Sync.Engine.Forms DEBUG Resetting forms last sync time
1749063462147 Sync.Engine.History DEBUG Resetting history last sync time
1749063462148 Sync.Engine.Extension-Storage DEBUG Resetting extension-storage last sync time
1749063462159 Sync.Synchronizer INFO Updating enabled engines: 2 clients.
1749063462161 Sync.Synchronizer INFO Syncing all enabled engines.
1749063462161 Sync.Engine.Prefs DEBUG Engine syncIDs differ (old="", new="N71yzSklgNm0") - resetting the engine
1749063462161 Sync.Engine.Prefs DEBUG Resetting prefs last sync time
1749063462161 Sync.Engine.Prefs DEBUG First sync, uploading all items
1749063462162 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063462331 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/devices/notify 200
1749063462331 Hawk DEBUG (Response) /account/devices/notify: code: 200 - Status text:
1749063462331 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: -331
1749063462333 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/prefs?full=1&limit=1000
1749063462337 Sync.Engine.Prefs WARN DATA LOSS: Both local and remote changes to record: e2VjODAzMGY3LWMyMGEtNDY0Zi05YjBlLTEzYTNhOWU5NzM4NH0=
1749063462338 Sync.Engine.Prefs.Store DEBUG Adjusting preference "privacy.clearOnShutdown_v2.formdata" to the incoming value
1749063462340 Sync.Engine.Prefs INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063462340 Sync.Engine.Prefs INFO Uploading 1 outgoing records
1749063462343 Sync.Engine.Prefs INFO Posting 1 records of 17087 bytes with batch=true
1749063462343 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063462344 Sync.Collection DEBUG POST Length: 17087
1749063462567 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/prefs?batch=true&commit=true
1749063462568 FirefoxAccounts TRACE CommandQueue observed topic=weave:engine:sync:finish, data=prefs, subject=null
1749063462570 Sync.Engine.Passwords DEBUG First sync, uploading all items
1749063462572 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063462736 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/passwords?full=1&limit=1000
1749063462746 Sync.Engine.Passwords INFO Records: 2 applied, 2 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063462746 Sync.Engine.Passwords INFO Uploading 0 outgoing records
1749063462746 FirefoxAccounts TRACE CommandQueue observed topic=weave:engine:sync:finish, data=passwords, subject=null
1749063462764 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/open-uri
1749063462764 FirefoxAccounts TRACE Getting command keys: https://identity.mozilla.com/cmd/close-uri/v1
1749063462767 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063462846 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync.
1749063462846 Sync.Service DEBUG User-Agent: Firefox/139.0.1 (Windows NT 10.0; Win64; x64) FxSync/1.141.0.20250529122813.desktop
1749063462847 Sync.Service INFO Starting sync at 2025-06-05 04:57:42 in browser session Qf4ub5seql3a
1749063462847 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(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
_lockedSync@service.sys.mjs:1382:6
sync/<@service.sys.mjs:1356:18
WrappedCatch@util.sys.mjs:112:27
sync@service.sys.mjs:1357:7
1749063462847 Sync.Service INFO Cannot start sync: already syncing?
1749063462927 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/tabs?full=1&limit=1000
1749063462934 Sync.Engine.Tabs INFO Records: 3 applied, 3 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063462934 Sync.Engine.Tabs INFO Uploading 1 outgoing records
1749063462935 Sync.Engine.Tabs INFO Posting 1 records of 707 bytes with batch=true
1749063462935 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063462936 Sync.Collection DEBUG POST Length: 707
1749063463118 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/tabs?batch=true&commit=true
1749063463125 FirefoxAccounts TRACE CommandQueue observed topic=weave:engine:sync:finish, data=tabs, subject=null
1749063463125 Sync.Engine.Bookmarks DEBUG Checking if server sync ID EuSLnDHsnKPf matches existing
1749063463125 Sync.Engine.Bookmarks.BookmarkSyncUtils INFO Taking new bookmarks sync ID: {"newSyncId":"EuSLnDHsnKPf"}
1749063463127 Sync.Engine.Bookmarks.Mirror INFO Sync ID changed from to EuSLnDHsnKPf; resetting mirror
1749063463138 Sync.Engine.Bookmarks DEBUG Bookmarks backup starting
1749063463144 Sync.Engine.Bookmarks DEBUG Bookmarks backup done
1749063463145 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063463302 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/bookmarks?full=1&sort=oldest&limit=1000
1749063463334 Sync.Engine.Bookmarks INFO Records: 10 applied, 10 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063463335 Sync.Engine.Bookmarks.Mirror DEBUG Merging bookmarks in Rust
1749063463335 Sync.Engine.Bookmarks.Mirror DEBUG Building local tree
1749063463336 Sync.Engine.Bookmarks.Mirror DEBUG Building remote tree
1749063463336 Sync.Engine.Bookmarks.Mirror DEBUG Building merged tree
1749063463336 Sync.Engine.Bookmarks.Mirror DEBUG Applying merged tree
1749063463336 Sync.Engine.Bookmarks.Mirror DEBUG Updating local items in Places
1749063463336 Sync.Engine.Bookmarks.Mirror DEBUG Cleaning up observer notifications left from last sync
1749063463336 Sync.Engine.Bookmarks.Mirror DEBUG Inserting Places for new items
1749063463336 Sync.Engine.Bookmarks.Mirror DEBUG Staging apply remote item ops
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Staging change GUID ops
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Staging apply new local structure ops
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Removing tombstones for revived items
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Inserting new tombstones for non-syncable and invalid items
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Removing local items
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Changing GUIDs
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Applying remote items
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Recording item added notifications for new items
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Recording item changed notifications for existing items
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Removing old keywords
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Removing old tags
1749063463337 Sync.Engine.Bookmarks.Mirror DEBUG Upserting new items
1749063463338 Sync.Engine.Bookmarks.Mirror DEBUG Flagging frecencies for recalculation
1749063463338 Sync.Engine.Bookmarks.Mirror DEBUG Inserting new keywords for new URLs
1749063463338 Sync.Engine.Bookmarks.Mirror DEBUG Inserting new tags for new URLs
1749063463338 Sync.Engine.Bookmarks.Mirror DEBUG Applying new local structure
1749063463339 Sync.Engine.Bookmarks.Mirror DEBUG Resetting change counters for items that shouldn't be uploaded
1749063463339 Sync.Engine.Bookmarks.Mirror DEBUG Bumping change counters for items that should be uploaded
1749063463339 Sync.Engine.Bookmarks.Mirror DEBUG Flagging applied remote items as merged
1749063463339 Sync.Engine.Bookmarks.Mirror DEBUG Staging items to upload
1749063463339 Sync.Engine.Bookmarks.Mirror DEBUG Cleaning up staged items left from last sync
1749063463340 Sync.Engine.Bookmarks.Mirror DEBUG Staging items with older local dates added
1749063463340 Sync.Engine.Bookmarks.Mirror DEBUG Staging remaining locally changed items for upload
1749063463340 Sync.Engine.Bookmarks.Mirror DEBUG Staging structure to upload
1749063463340 Sync.Engine.Bookmarks.Mirror DEBUG Staging tags to upload
1749063463340 Sync.Engine.Bookmarks.Mirror DEBUG Staging tombstones to upload
1749063463359 Sync.Engine.Bookmarks.Mirror DEBUG Merging bookmarks in Rust took 24.916ms
1749063463360 Sync.Engine.Bookmarks.Mirror DEBUG Notifying Places observers
1749063463365 Sync.Engine.Bookmarks.Mirror DEBUG Notifying Places observers took 5.352ms
1749063463365 Sync.Engine.Bookmarks.Mirror DEBUG Fetching records for local items to upload
1749063463366 Sync.Engine.Bookmarks.Mirror DEBUG Fetching records for local items to upload took 1.167ms
1749063463366 Sync.Engine.Bookmarks INFO Uploading 9 outgoing records
1749063463372 Sync.Engine.Bookmarks INFO Posting 9 records of 4095 bytes with batch=true
1749063463373 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063463373 Sync.Collection DEBUG POST Length: 4095
1749063463463 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync.
1749063463463 Sync.Service DEBUG User-Agent: Firefox/139.0.1 (Windows NT 10.0; Win64; x64) FxSync/1.141.0.20250529122813.desktop
1749063463463 Sync.Service INFO Starting sync at 2025-06-05 04:57:43 in browser session Qf4ub5seql3a
1749063463463 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(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
_lockedSync@service.sys.mjs:1382:6
sync/<@service.sys.mjs:1356:18
WrappedCatch@util.sys.mjs:112:27
sync@service.sys.mjs:1357:7
1749063463463 Sync.Service INFO Cannot start sync: already syncing?
1749063463558 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/bookmarks?batch=true&commit=true
1749063463594 Sync.Engine.Bookmarks.BookmarkSyncUtils DEBUG pushChanges: Processed change records: {"weak":0,"skipped":0,"updated":9}
1749063463595 Sync.Engine.Addons DEBUG Refreshing reconciler state
1749063463595 Sync.AddonsReconciler INFO Refreshing global state from AddonManager.
1749063463595 Sync.AddonsReconciler DEBUG Rectifying state for addon Form Autofill (version=1.0.1, id=formautofill@mozilla.org)
1749063463595 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: formautofill@mozilla.org
1749063463595 Sync.AddonsReconciler INFO Change recorded for formautofill@mozilla.org
1749063463595 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 formautofill@mozilla.org
1749063463595 Sync.Engine.Addons.Store DEBUG formautofill@mozilla.org not syncable: not installed in profile.
1749063463595 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: formautofill@mozilla.org
1749063463595 Sync.AddonsReconciler DEBUG Rectifying state for addon Picture-In-Picture (version=1.0.0, id=pictureinpicture@mozilla.org)
1749063463595 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: pictureinpicture@mozilla.org
1749063463595 Sync.AddonsReconciler INFO Change recorded for pictureinpicture@mozilla.org
1749063463595 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 pictureinpicture@mozilla.org
1749063463595 Sync.Engine.Addons.Store DEBUG pictureinpicture@mozilla.org not syncable: not installed in profile.
1749063463595 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: pictureinpicture@mozilla.org
1749063463595 Sync.AddonsReconciler DEBUG Rectifying state for addon Add-ons Search Detection (version=2.0.0, id=addons-search-detection@mozilla.com)
1749063463595 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: addons-search-detection@mozilla.com
1749063463595 Sync.AddonsReconciler INFO Change recorded for addons-search-detection@mozilla.com
1749063463595 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 addons-search-detection@mozilla.com
1749063463595 Sync.Engine.Addons.Store DEBUG addons-search-detection@mozilla.com not syncable: not installed in profile.
1749063463595 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: addons-search-detection@mozilla.com
1749063463595 Sync.AddonsReconciler DEBUG Rectifying state for addon System theme — auto (version=1.4.1, id=default-theme@mozilla.org)
1749063463595 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: default-theme@mozilla.org
1749063463595 Sync.AddonsReconciler INFO Change recorded for default-theme@mozilla.org
1749063463595 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 default-theme@mozilla.org
1749063463595 Sync.Engine.Addons.Store DEBUG default-theme@mozilla.org not syncable: not installed in profile.
1749063463595 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: default-theme@mozilla.org
1749063463595 Sync.AddonsReconciler DEBUG Rectifying state for addon Light (version=1.3.2, id=firefox-compact-light@mozilla.org)
1749063463595 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: firefox-compact-light@mozilla.org
1749063463595 Sync.AddonsReconciler INFO Change recorded for firefox-compact-light@mozilla.org
1749063463595 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 firefox-compact-light@mozilla.org
1749063463596 Sync.Engine.Addons.Store DEBUG firefox-compact-light@mozilla.org not syncable: not installed in profile.
1749063463596 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: firefox-compact-light@mozilla.org
1749063463596 Sync.AddonsReconciler DEBUG Rectifying state for addon Dark (version=1.3.3, id=firefox-compact-dark@mozilla.org)
1749063463596 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: firefox-compact-dark@mozilla.org
1749063463596 Sync.AddonsReconciler INFO Change recorded for firefox-compact-dark@mozilla.org
1749063463596 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 firefox-compact-dark@mozilla.org
1749063463596 Sync.Engine.Addons.Store DEBUG firefox-compact-dark@mozilla.org not syncable: not installed in profile.
1749063463596 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: firefox-compact-dark@mozilla.org
1749063463596 Sync.AddonsReconciler DEBUG Rectifying state for addon Firefox Alpenglow (version=1.5, id=firefox-alpenglow@mozilla.org)
1749063463596 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: firefox-alpenglow@mozilla.org
1749063463596 Sync.AddonsReconciler INFO Change recorded for firefox-alpenglow@mozilla.org
1749063463596 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 firefox-alpenglow@mozilla.org
1749063463596 Sync.Engine.Addons.Store DEBUG firefox-alpenglow@mozilla.org not syncable: not installed in profile.
1749063463596 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: firefox-alpenglow@mozilla.org
1749063463596 Sync.AddonsReconciler DEBUG Rectifying state for addon Web Compatibility Interventions (version=140.7.20250522.151919, id=webcompat@mozilla.org)
1749063463596 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: webcompat@mozilla.org
1749063463596 Sync.AddonsReconciler INFO Change recorded for webcompat@mozilla.org
1749063463596 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 webcompat@mozilla.org
1749063463596 Sync.Engine.Addons.Store DEBUG webcompat@mozilla.org not syncable: vetoed by the addon manager.
1749063463596 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: webcompat@mozilla.org
1749063463596 Sync.AddonsReconciler DEBUG Rectifying state for addon Privacy Badger (version=2025.5.14, id=jid1-MnnxcxisBPnSXQ@jetpack)
1749063463596 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: jid1-MnnxcxisBPnSXQ@jetpack
1749063463596 Sync.AddonsReconciler INFO Change recorded for jid1-MnnxcxisBPnSXQ@jetpack
1749063463596 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 jid1-MnnxcxisBPnSXQ@jetpack
1749063463596 Sync.AddonsReconciler DEBUG Rectifying state for addon OpenH264 Video Codec provided by Cisco Systems, Inc. (version=2.6.0, id=gmp-gmpopenh264)
1749063463596 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: gmp-gmpopenh264
1749063463596 Sync.AddonsReconciler INFO Change recorded for gmp-gmpopenh264
1749063463596 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 gmp-gmpopenh264
1749063463596 Sync.Engine.Addons.Store DEBUG gmp-gmpopenh264 not syncable: type not in allowed list: plugin
1749063463596 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: gmp-gmpopenh264
1749063463596 Sync.AddonsReconciler DEBUG Rectifying state for addon Widevine Content Decryption Module provided by Google Inc. (version=4.10.2891.0, id=gmp-widevinecdm)
1749063463596 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: gmp-widevinecdm
1749063463596 Sync.AddonsReconciler INFO Change recorded for gmp-widevinecdm
1749063463596 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 gmp-widevinecdm
1749063463596 Sync.Engine.Addons.Store DEBUG gmp-widevinecdm not syncable: type not in allowed list: plugin
1749063463596 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: gmp-widevinecdm
1749063463597 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler
1749063463598 Sync.Engine.Addons DEBUG Engine syncIDs differ (old="", new="T9XS3VLOAZhI") - resetting the engine
1749063463598 Sync.Engine.Addons DEBUG Resetting addons last sync time
1749063463598 Sync.Engine.Addons DEBUG First sync, uploading all items
1749063463598 Sync.Engine.Addons.Store DEBUG formautofill@mozilla.org not syncable: not installed in profile.
1749063463598 Sync.Engine.Addons.Store DEBUG pictureinpicture@mozilla.org not syncable: not installed in profile.
1749063463598 Sync.Engine.Addons.Store DEBUG addons-search-detection@mozilla.com not syncable: not installed in profile.
1749063463598 Sync.Engine.Addons.Store DEBUG default-theme@mozilla.org not syncable: not installed in profile.
1749063463598 Sync.Engine.Addons.Store DEBUG firefox-compact-light@mozilla.org not syncable: not installed in profile.
1749063463598 Sync.Engine.Addons.Store DEBUG firefox-compact-dark@mozilla.org not syncable: not installed in profile.
1749063463598 Sync.Engine.Addons.Store DEBUG firefox-alpenglow@mozilla.org not syncable: not installed in profile.
1749063463598 Sync.Engine.Addons.Store DEBUG webcompat@mozilla.org not syncable: vetoed by the addon manager.
1749063463598 Sync.Engine.Addons.Store DEBUG gmp-gmpopenh264 not syncable: type not in allowed list: plugin
1749063463598 Sync.Engine.Addons.Store DEBUG gmp-widevinecdm not syncable: type not in allowed list: plugin
1749063463599 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063463697 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync.
1749063463697 Sync.Service DEBUG User-Agent: Firefox/139.0.1 (Windows NT 10.0; Win64; x64) FxSync/1.141.0.20250529122813.desktop
1749063463697 Sync.Service INFO Starting sync at 2025-06-05 04:57:43 in browser session Qf4ub5seql3a
1749063463697 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(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
_lockedSync@service.sys.mjs:1382:6
sync/<@service.sys.mjs:1356:18
WrappedCatch@util.sys.mjs:112:27
sync@service.sys.mjs:1357:7
1749063463697 Sync.Service INFO Cannot start sync: already syncing?
1749063463758 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/addons?full=1&limit=1000
1749063463766 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search.
1749063463766 Sync.AddonUtils INFO Add-on "enhancerforyoutube@maximerf.addons.mozilla.org" is able to be installed
1749063463766 Sync.AddonUtils INFO Trying to install add-on from search result: enhancerforyoutube@maximerf.addons.mozilla.org
1749063463766 Sync.AddonUtils DEBUG Obtaining install for enhancerforyoutube@maximerf.addons.mozilla.org
1749063463767 Sync.AddonUtils INFO Installing enhancerforyoutube@maximerf.addons.mozilla.org
1749063463842 Sync.AddonUtils INFO Setting syncGUID of Enhancer for YouTube™: {79b9e2d0-9d81-495c-85b4-caa23c881a93}
1749063463851 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to enhancerforyoutube@maximerf.addons.mozilla.org
1749063463851 Sync.AddonsReconciler DEBUG Rectifying state for addon Enhancer for YouTube™ (version=2.0.130.1, id=enhancerforyoutube@maximerf.addons.mozilla.org)
1749063463851 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: enhancerforyoutube@maximerf.addons.mozilla.org
1749063463851 Sync.AddonsReconciler INFO Change recorded for enhancerforyoutube@maximerf.addons.mozilla.org
1749063463851 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 enhancerforyoutube@maximerf.addons.mozilla.org
1749063463852 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler
1749063463852 Sync.Engine.Addons.Store INFO Add-on installed: enhancerforyoutube@maximerf.addons.mozilla.org
1749063463896 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change: scheduling a quick-write in 5000ms
1749063463900 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but a quick-write is already scheduled
1749063463909 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but a quick-write is already scheduled
1749063463913 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but a quick-write is already scheduled
1749063464088 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search.
1749063464088 Sync.AddonUtils INFO Add-on "{2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}" is able to be installed
1749063464088 Sync.AddonUtils INFO Trying to install add-on from search result: {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464088 Sync.AddonUtils DEBUG Obtaining install for {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464088 Sync.AddonUtils INFO Installing {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464150 Sync.AddonUtils INFO Setting syncGUID of Search by Image: {af3a7031-d052-4bc6-aa3c-b1773aef92ba}
1749063464159 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464159 Sync.AddonsReconciler DEBUG Rectifying state for addon Search by Image (version=8.2.3, id={2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c})
1749063464159 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464159 Sync.AddonsReconciler INFO Change recorded for {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464159 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464160 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler
1749063464160 Sync.Engine.Addons.Store INFO Add-on installed: {2e5ff8c8-32fe-46d0-9fc8-6b8986621f3c}
1749063464161 Sync.Engine.Addons INFO Records: 2 applied, 2 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063464161 Sync.Engine.Addons INFO Uploading 1 outgoing records
1749063464164 Sync.Engine.Addons INFO Posting 1 records of 459 bytes with batch=true
1749063464164 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063464178 Sync.Collection DEBUG POST Length: 459
1749063464359 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/addons?batch=true&commit=true
1749063464360 Sync.Engine.Addresses DEBUG Engine syncIDs differ (old="", new="Je6k5AIFwTXq") - resetting the engine
1749063464360 Sync.Engine.Addresses DEBUG Resetting addresses last sync time
1749063464361 Sync.Engine.Addresses DEBUG First sync, uploading all items
1749063464361 Sync.Engine.Addresses INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063464361 Sync.Engine.Addresses INFO Uploading 0 outgoing records
1749063464361 Sync.Engine.CreditCards DEBUG Engine syncIDs differ (old="", new="JqeLe0qERyOM") - resetting the engine
1749063464361 Sync.Engine.CreditCards DEBUG Resetting creditcards last sync time
1749063464361 Sync.Engine.CreditCards DEBUG First sync, uploading all items
1749063464362 Sync.Engine.CreditCards INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063464362 Sync.Engine.CreditCards INFO Uploading 0 outgoing records
1749063464362 Sync.Engine.Forms DEBUG Engine syncIDs differ (old="", new="7GGL02JcysWf") - resetting the engine
1749063464362 Sync.Engine.Forms DEBUG Resetting forms last sync time
1749063464362 Sync.Engine.Forms DEBUG First sync, uploading all items
1749063464363 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063464401 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync.
1749063464402 Sync.Service DEBUG User-Agent: Firefox/139.0.1 (Windows NT 10.0; Win64; x64) FxSync/1.141.0.20250529122813.desktop
1749063464402 Sync.Service INFO Starting sync at 2025-06-05 04:57:44 in browser session Qf4ub5seql3a
1749063464402 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(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
_lockedSync@service.sys.mjs:1382:6
sync/<@service.sys.mjs:1356:18
WrappedCatch@util.sys.mjs:112:27
sync@service.sys.mjs:1357:7
1749063464402 Sync.Service INFO Cannot start sync: already syncing?
1749063464522 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/forms?full=1&limit=1000
1749063464524 Sync.Engine.Forms DEBUG Switching local ID to incoming: XD3izqrtR6u0rWhQ -> XIdXUvWZQA6yHVLI
1749063464541 Sync.Engine.Forms DEBUG Local item after duplication: age=1749063464.524; modified=true; exists=true
1749063464553 Sync.Engine.Forms INFO Records: 2 applied, 2 successfully, 0 failed to apply, 0 newly failed to apply, 1 reconciled.
1749063464554 Sync.Engine.Forms INFO Uploading 0 outgoing records
1749063464554 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063464580 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but a quick-write is already scheduled
1749063464674 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync.
1749063464675 Sync.Service DEBUG User-Agent: Firefox/139.0.1 (Windows NT 10.0; Win64; x64) FxSync/1.141.0.20250529122813.desktop
1749063464675 Sync.Service INFO Starting sync at 2025-06-05 04:57:44 in browser session Qf4ub5seql3a
1749063464675 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(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
_lockedSync@service.sys.mjs:1382:6
sync/<@service.sys.mjs:1356:18
WrappedCatch@util.sys.mjs:112:27
sync@service.sys.mjs:1357:7
1749063464675 Sync.Service INFO Cannot start sync: already syncing?
1749063464970 Sync.Collection DEBUG DELETE success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/forms?ids=XD3izqrtR6u0rWhQ
1749063464970 Sync.Engine.History DEBUG Checking if server sync ID xDswPZCZ1xlB matches existing
1749063464970 Sync.Engine.History.HistorySyncUtils INFO History sync ID changed; resetting metadata: {"existingSyncId":"","newSyncId":"xDswPZCZ1xlB"}
1749063464972 Sync.Engine.History DEBUG First sync, uploading all items
1749063464975 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063465144 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/history?full=1&sort=newest&limit=1000
1749063465175 Sync.Engine.History.Store DEBUG adding 33 records to history
1749063465184 Sync.Engine.History INFO Records: 33 applied, 33 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063465185 Sync.Engine.History INFO Uploading 8 outgoing records
1749063465191 Sync.Engine.History INFO Posting 8 records of 6401 bytes with batch=true
1749063465192 FirefoxAccounts TRACE not checking freshness of profile as it remains recent
1749063465192 Sync.Collection DEBUG POST Length: 6401
1749063465370 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/213755557/storage/history?batch=true&commit=true
1749063465378 Sync.Engine.Extension-Storage INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled.
1749063465379 Sync.Engine.Extension-Storage INFO Uploading 0 outgoing records
1749063465380 Sync.Doctor INFO Skipping check of prefs - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of passwords - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of tabs - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of bookmarks - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of addons - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of addresses - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of creditcards - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of forms - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of history - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping check of extension-storage - disabled via preferences
1749063465380 Sync.Doctor INFO Skipping validation: no engines qualify
1749063465380 Sync.Status DEBUG Status.sync: success.sync => success.sync
1749063465380 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok
1749063465381 Sync.Synchronizer INFO Sync completed at 2025-06-05 04:57:45 after 3.92 secs.
1749063465381 Sync.Declined DEBUG Handling remote declined: []
1749063465381 Sync.Declined DEBUG Handling local declined: []
1749063465381 Sync.Declined DEBUG Declined changed? false
1749063465381 Sync.Service INFO No change to declined engines. Not reuploading meta/global.