1692095109523 FirefoxAccounts TRACE initializing new storage manager 1692095109523 FirefoxAccounts TRACE starting fetch of json user data 1692095109554 FirefoxAccounts TRACE initializing of new storage manager done 1692095109647 FirefoxAccounts DEBUG FxAccountsWebChannel registered: account_updates with origin https://accounts.firefox.com 1692095142985 Sync.Status INFO Resetting Status. 1692095142986 Sync.Status DEBUG Status.service: undefined => success.status_ok 1692095142986 Sync.SyncScheduler TRACE Setting SyncScheduler policy values to defaults. 1692095142986 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1692095142986 Sync.Status DEBUG Status.login: success.login => error.login.reason.no_username 1692095142986 Sync.Status DEBUG Status.service: success.status_ok => service.client_not_configured 1692095142986 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured 1692095142986 Sync.Service INFO Loading Weave 1.119.0 1692095142987 Sync.Engine.Clients DEBUG Engine constructed 1692095142987 Sync.Engine.Clients DEBUG Resetting clients last sync time 1692095142991 Sync.Engine.Addons DEBUG Engine constructed 1692095143005 Sync.Engine.Addons DEBUG SyncEngine initialized: addons 1692095143015 Sync.AddonsReconciler DEBUG No data seen in loaded file: addonsreconciler 1692095143016 Sync.Engine.Passwords DEBUG Engine constructed 1692095143018 Sync.Engine.Passwords DEBUG SyncEngine initialized: passwords 1692095143019 Sync.Engine.Prefs DEBUG Engine constructed 1692095143019 Sync.Engine.Prefs DEBUG SyncEngine initialized: prefs 1692095143021 Sync.Engine.Bookmarks DEBUG Engine constructed 1692095143021 Sync.Engine.Bookmarks DEBUG SyncEngine initialized: bookmarks 1692095143022 Sync.Engine.Forms DEBUG Engine constructed 1692095143022 Sync.Engine.Forms DEBUG SyncEngine initialized: forms 1692095143023 Sync.Engine.History DEBUG Engine constructed 1692095143024 Sync.Engine.History DEBUG SyncEngine initialized: history 1692095143025 Sync.Engine.Tabs DEBUG Engine constructed 1692095143026 Sync.Engine.Tabs DEBUG SyncEngine initialized: tabs 1692095143030 Sync.Engine.Tabs INFO Got a bridged engine! 1692095143031 Sync.Engine.Addresses DEBUG Engine constructed 1692095143032 Sync.Engine.Addresses DEBUG SyncEngine initialized: addresses 1692095143032 Sync.Engine.CreditCards DEBUG Engine constructed 1692095143032 Sync.Engine.CreditCards DEBUG SyncEngine initialized: creditcards 1692095143033 Sync.Engine.Extension-Storage DEBUG Engine constructed 1692095143034 Sync.Engine.Extension-Storage DEBUG SyncEngine initialized: extension-storage 1692095143034 Sync.Service INFO Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/117.0 1692095143035 Sync.Status DEBUG Status.login: error.login.reason.no_username => error.login.reason.no_username 1692095143035 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured 1692095143035 Sync.Status DEBUG Status.service: service.client_not_configured => service.client_not_configured 1692095148012 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:fxa_status 1692095148012 FirefoxAccounts DEBUG fxa_status received 1692095148012 FirefoxAccounts DEBUG service: sync 1692095148012 FirefoxAccounts DEBUG is private browsing: false 1692095148040 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:loaded 1692095148041 FirefoxAccounts WARN Unrecognized FxAccountsWebChannel command: fxaccounts:loaded 1692095156326 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:can_link_account 1692095156326 FirefoxAccounts DEBUG FxAccountsWebChannel response: {"command":"fxaccounts:can_link_account","messageId":"16920951563262","data":{"ok":true}} 1692095178757 FirefoxAccounts DEBUG FxAccountsWebChannel message received: fxaccounts:login 1692095178757 FirefoxAccounts DEBUG Webchannel is logging a user in. 1692095178758 FirefoxAccounts DEBUG setSignedInUser - aborting any existing flows 1692095178758 FirefoxAccounts DEBUG removing account data 1692095178758 FirefoxAccounts TRACE starting write of json user data: null 1692095178780 FirefoxAccounts TRACE finished write of json user data - took: 22 1692095178780 FirefoxAccounts TRACE storage set finished clearing account data 1692095178780 FirefoxAccounts DEBUG account data reset 1692095178780 FirefoxAccounts TRACE StorageManager finalizing 1692095178781 FirefoxAccounts TRACE StorageManager finalized 1692095178781 FirefoxAccounts TRACE initializing new storage manager 1692095178781 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified"] 1692095178781 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified"] 1692095178783 FirefoxAccounts TRACE finished write of json user data - took: 2 1692095178783 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"] 1692095178783 FirefoxAccounts TRACE starting write of user data to the login manager 1692095178801 FirefoxAccounts TRACE finished write of user data to the login manager 1692095178801 FirefoxAccounts TRACE initializing of new storage manager done 1692095178802 FirefoxAccounts DEBUG startVerifiedCheck: false 1692095178802 FirefoxAccounts DEBUG whenVerified promise starts polling for verified email 1692095178802 FirefoxAccounts DEBUG entering startPollEmailStatus: start 1692095178802 FirefoxAccounts DEBUG entering pollEmailStatus: start 1692095178804 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onlogin 1692095178804 Sync.SyncAuthManager DEBUG observed fxaccounts:onlogin 1692095178804 Sync.SyncAuthManager INFO Sync is not configured, so ignoring the notification 1692095178804 FirefoxAccounts DEBUG startVerifiedCheck: false 1692095178805 FirefoxAccounts DEBUG FxAccountsProfileClient: Initialized 1692095178805 FirefoxAccounts DEBUG FxAccountsPush loading service 1692095178805 FirefoxAccounts DEBUG FxAccountsPush initialized 1692095178805 FirefoxAccounts TRACE FxAccountsPush registerPushEndpoint 1692095178805 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile 1692095178805 FirefoxAccounts DEBUG getOAuthToken enter 1692095178805 FirefoxAccounts ERROR FxA rejecting with error UNVERIFIED_ACCOUNT, details: undefined 1692095178805 FirefoxAccounts ERROR Background refresh of initial profile failed: Error: UNVERIFIED_ACCOUNT(resource://gre/modules/FxAccounts.sys.mjs:1522:18) JS Stack trace: _error@FxAccounts.sys.mjs:1522:18 withSessionToken@FxAccounts.sys.mjs:777:18 1692095178806 FirefoxAccounts DEBUG startVerifiedCheck: false 1692095178806 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile 1692095178806 FirefoxAccounts DEBUG getOAuthToken enter 1692095178806 FirefoxAccounts ERROR FxA rejecting with error UNVERIFIED_ACCOUNT, details: undefined 1692095178806 FirefoxAccounts ERROR Background refresh of initial profile failed: Error: UNVERIFIED_ACCOUNT(resource://gre/modules/FxAccounts.sys.mjs:1522:18) JS Stack trace: _error@FxAccounts.sys.mjs:1522:18 withSessionToken@FxAccounts.sys.mjs:777:18 1692095178807 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/recovery_email/status?reason=start 1692095178925 FirefoxAccounts DEBUG FxAccountsPush got subscription 1692095178926 FirefoxAccounts INFO Generating and persisting encrypted sendtab keys 1692095178926 FirefoxAccounts INFO Could not find sendtab keys, generating them 1692095178931 FirefoxAccounts DEBUG _updateAccountData with items: ["device"] 1692095178931 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device"] 1692095178931 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device"] 1692095178931 FirefoxAccounts INFO Can't get keys; user is not verified 1692095178931 FirefoxAccounts INFO Can't fetch keys, so unable to determine sendtab keys 1692095178931 FirefoxAccounts INFO registering with available commands: [] 1692095178931 FirefoxAccounts DEBUG registering new device details 1692095178932 FirefoxAccounts TRACE finished write of json user data - took: 1 1692095178932 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"] 1692095178932 FirefoxAccounts TRACE starting write of user data to the login manager 1692095178936 FirefoxAccounts TRACE finished write of user data to the login manager 1692095178936 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/device 1692095178936 Services.Common.RESTRequest DEBUG POST Length: 447 1692095179396 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/recovery_email/status?reason=start 200 1692095179396 Hawk DEBUG (Response) /recovery_email/status?reason=start: code: 200 - Status text: OK 1692095179396 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 2604 1692095179396 FirefoxAccounts DEBUG checkEmailStatus -> {"email":"boxizi69@gmail.com","verified":false,"sessionVerified":false,"emailVerified":true} 1692095179396 FirefoxAccounts DEBUG polling with timeout = 60000 1692095179404 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/device 200 1692095179404 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK 1692095179404 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 2596 1692095179404 FirefoxAccounts DEBUG _updateAccountData with items: ["device"] 1692095179404 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device"] 1692095179404 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device"] 1692095179404 FirefoxAccounts DEBUG Webchannel is enabling sync 1692095179404 FirefoxAccounts DEBUG startVerifiedCheck: false 1692095179404 FirefoxAccounts INFO Generating and persisting encrypted sendtab keys 1692095179404 Sync.Service INFO Configuring sync with current FxA user 1692095179405 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile 1692095179405 FirefoxAccounts DEBUG getOAuthToken enter 1692095179405 Sync.SyncAuthManager DEBUG observed weave:connected 1692095179405 Sync.SyncAuthManager INFO Sync has been connected to a logged in user 1692095179405 FirefoxAccounts INFO Can't get keys; user is not verified 1692095179406 FirefoxAccounts DEBUG startVerifiedCheck: false 1692095179406 FirefoxAccounts DEBUG startVerifiedCheck: false 1692095179406 FirefoxAccounts INFO Can't fetch keys, so unable to determine sendtab keys 1692095179406 FirefoxAccounts ERROR FxA rejecting with error UNVERIFIED_ACCOUNT, details: undefined 1692095179406 FirefoxAccounts ERROR Background refresh of initial profile failed: Error: UNVERIFIED_ACCOUNT(resource://gre/modules/FxAccounts.sys.mjs:1522:18) JS Stack trace: _error@FxAccounts.sys.mjs:1522:18 withSessionToken@FxAccounts.sys.mjs:777:18 1692095179406 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile 1692095179406 FirefoxAccounts DEBUG getOAuthToken enter 1692095179406 Sync.SyncAuthManager INFO The user is not verified 1692095179406 FirefoxAccounts ERROR FxA rejecting with error UNVERIFIED_ACCOUNT, details: undefined 1692095179407 FirefoxAccounts ERROR Background refresh of initial profile failed: Error: UNVERIFIED_ACCOUNT(resource://gre/modules/FxAccounts.sys.mjs:1522:18) JS Stack trace: _error@FxAccounts.sys.mjs:1522:18 withSessionToken@FxAccounts.sys.mjs:777:18 1692095179407 FirefoxAccounts ERROR Background refresh of initial profile failed: Error: UNVERIFIED_ACCOUNT(resource://gre/modules/FxAccounts.sys.mjs:1522:18) JS Stack trace: _error@FxAccounts.sys.mjs:1522:18 withSessionToken@FxAccounts.sys.mjs:777:18 1692095179415 FirefoxAccounts TRACE finished write of json user data - took: 11 1692095179415 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"] 1692095179415 FirefoxAccounts TRACE starting write of user data to the login manager 1692095179418 FirefoxAccounts TRACE finished write of user data to the login manager 1692095240809 FirefoxAccounts DEBUG entering pollEmailStatus: start 1692095240812 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/recovery_email/status?reason=start 1692095241397 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/recovery_email/status?reason=start 200 1692095241397 Hawk DEBUG (Response) /recovery_email/status?reason=start: code: 200 - Status text: OK 1692095241397 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 2603 1692095241397 FirefoxAccounts DEBUG checkEmailStatus -> {"email":"boxizi69@gmail.com","verified":false,"sessionVerified":false,"emailVerified":true} 1692095241397 FirefoxAccounts DEBUG polling with timeout = 60000 1692095287149 FirefoxAccounts TRACE observed topic=push-message, data=chrome://fxa-device-update, subject=[xpconnect wrapped nsISupports] 1692095287149 FirefoxAccounts TRACE FxAccountsPushService _onPushMessage 1692095287149 FirefoxAccounts DEBUG empty push message - checking account status 1692095287149 FirefoxAccounts TRACE checkVerificationStatus 1692095287149 FirefoxAccounts TRACE checkVerificationStatus - forcing verification status check 1692095287149 FirefoxAccounts DEBUG entering startPollEmailStatus: push 1692095287149 FirefoxAccounts DEBUG startPollEmailStatus starting while existing timer is running 1692095287149 FirefoxAccounts DEBUG entering pollEmailStatus: push 1692095287150 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/recovery_email/status?reason=push 1692095287266 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/recovery_email/status?reason=push 200 1692095287266 Hawk DEBUG (Response) /recovery_email/status?reason=push: code: 200 - Status text: OK 1692095287266 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 1734 1692095287266 FirefoxAccounts DEBUG checkEmailStatus -> {"email":"boxizi69@gmail.com","verified":true,"sessionVerified":true,"emailVerified":true} 1692095287266 FirefoxAccounts DEBUG _updateAccountData with items: ["verified"] 1692095287266 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device"] 1692095287266 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device"] 1692095287267 FirefoxAccounts INFO the user became verified 1692095287267 FirefoxAccounts DEBUG Notifying observers of fxaccounts:onverified 1692095287288 Sync.SyncAuthManager DEBUG observed fxaccounts:onverified 1692095287288 Sync.SyncAuthManager INFO The user became verified 1692095287288 Sync.Status DEBUG Status.login: error.login.reason.no_username => success.login 1692095287288 Sync.Status DEBUG Status.service: service.client_not_configured => success.status_ok 1692095287288 Sync.SyncAuthManager INFO Doing initial sync actions 1692095287289 Sync.AddonsReconciler INFO Registering as Add-on Manager listener. 1692095287289 Sync.AddonsReconciler DEBUG Adding change listener. 1692095287289 Sync.Engine.History.Tracker INFO Adding Places observer. 1692095287290 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095287290 Sync.Service INFO Starting sync at 2023-08-15 06:28:07 in browser session ItJ6XSTzsucL 1692095287290 Sync.Service DEBUG In sync: should login. 1692095287290 Sync.Service INFO User logged in successfully - verifying login. 1692095287290 FirefoxAccounts INFO Generating and persisting encrypted sendtab keys 1692095287291 FirefoxAccounts DEBUG FxAccountsProfileClient: Requested profile 1692095287291 FirefoxAccounts DEBUG getOAuthToken enter 1692095287291 Sync.SyncAuthManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys 1692095287291 FirefoxAccounts INFO fetching updated device list 1692095287292 Sync.Status DEBUG Status.login: success.login => success.status_ok 1692095287292 Sync.Status DEBUG Status.service: success.status_ok => error.login.failed 1692095287292 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok 1692095287293 Sync.SyncAuthManager INFO Getting sync key 1692095287299 FirefoxAccounts TRACE finished write of json user data - took: 32 1692095287299 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"] 1692095287299 FirefoxAccounts TRACE starting write of user data to the login manager 1692095287302 FirefoxAccounts TRACE finished write of user data to the login manager 1692095287305 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1690280887292 1692095287305 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/oauth/token 1692095287305 Services.Common.RESTRequest DEBUG POST Length: 81 1692095287306 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/scoped-key-data 1692095287306 Services.Common.RESTRequest DEBUG POST Length: 84 1692095287421 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/account/devices?filterIdleDevicesTimestamp=1690280887292 200 1692095287421 Hawk DEBUG (Response) /account/devices?filterIdleDevicesTimestamp=1690280887292: code: 200 - Status text: OK 1692095287421 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 2579 1692095287421 FirefoxAccounts INFO Got new device list: 077793c212b1d8d9bc913d422a30dbca 1692095287422 FirefoxAccounts INFO Generating and persisting encrypted sendtab keys 1692095287640 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/oauth/token 200 1692095287641 Hawk DEBUG (Response) /oauth/token: code: 200 - Status text: OK 1692095287641 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 2359 1692095287641 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"] 1692095287641 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens"] 1692095287641 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens"] 1692095287641 Services.Common.RESTRequest DEBUG GET request to https://profile.accounts.firefox.com/v1/profile 1692095287642 FirefoxAccounts TRACE finished write of json user data - took: 1 1692095287642 FirefoxAccounts DEBUG writing secure storage: ["keyFetchToken","unwrapBKey"] 1692095287642 FirefoxAccounts TRACE starting write of user data to the login manager 1692095287645 FirefoxAccounts TRACE finished write of user data to the login manager 1692095287862 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/scoped-key-data 200 1692095287862 Hawk DEBUG (Response) /account/scoped-key-data: code: 200 - Status text: OK 1692095287862 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 2138 1692095287862 FirefoxAccounts DEBUG Fetching keys with token true from https://api.accounts.firefox.com/v1 1692095287863 Services.Common.RESTRequest DEBUG GET request to https://api.accounts.firefox.com/v1/account/keys 1692095287980 Services.Common.RESTRequest DEBUG GET https://api.accounts.firefox.com/v1/account/keys 200 1692095287980 Hawk DEBUG (Response) /account/keys: code: 200 - Status text: OK 1692095287980 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 2020 1692095287981 FirefoxAccounts DEBUG Keys Obtained: https://identity.mozilla.com/apps/oldsync 1692095287981 FirefoxAccounts DEBUG _updateAccountData with items: ["scopedKeys","keyFetchToken","unwrapBKey"] 1692095287981 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens"] 1692095287981 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens"] 1692095287982 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined 1692095287982 Sync.SyncAuthManager INFO Getting a sync token from: https://token.services.mozilla.com/1.0/sync/1.5 1692095287982 Sync.SyncAuthManager DEBUG Getting a token using OAuth 1692095287982 FirefoxAccounts DEBUG getOAuthToken enter 1692095287982 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined 1692095287983 FirefoxAccounts DEBUG _updateAccountData with items: ["encryptedSendTabKeys"] 1692095287983 FirefoxAccounts TRACE FxAccountsPush registerPushEndpoint 1692095287984 FirefoxAccounts TRACE finished write of json user data - took: 3 1692095287984 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"] 1692095287984 FirefoxAccounts TRACE starting write of user data to the login manager 1692095287987 FirefoxAccounts TRACE finished write of user data to the login manager 1692095287987 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095287987 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095287987 FirefoxAccounts DEBUG _updateAccountData with items: ["encryptedSendTabKeys"] 1692095287987 FirefoxAccounts WARN Our commands need to be updated on the server 1692095287987 FirefoxAccounts TRACE FxAccountsPush registerPushEndpoint 1692095287988 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/oauth/token 1692095287988 Services.Common.RESTRequest DEBUG POST Length: 127 1692095287988 FirefoxAccounts TRACE finished write of json user data - took: 1 1692095287988 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"] 1692095287988 FirefoxAccounts TRACE starting write of user data to the login manager 1692095287991 FirefoxAccounts TRACE finished write of user data to the login manager 1692095287991 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095287991 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095287992 FirefoxAccounts DEBUG FxAccountsPush got subscription 1692095287992 FirefoxAccounts INFO registering with available commands: ["https://identity.mozilla.com/cmd/open-uri"] 1692095287992 FirefoxAccounts DEBUG updating existing device details 1692095287992 FirefoxAccounts DEBUG FxAccountsPush got subscription 1692095287992 FirefoxAccounts INFO registering with available commands: ["https://identity.mozilla.com/cmd/open-uri"] 1692095287992 FirefoxAccounts DEBUG updating existing device details 1692095287993 FirefoxAccounts TRACE finished write of json user data - took: 2 1692095287993 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"] 1692095287993 FirefoxAccounts TRACE starting write of user data to the login manager 1692095287998 FirefoxAccounts TRACE finished write of user data to the login manager 1692095287998 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/device 1692095287998 Services.Common.RESTRequest DEBUG POST Length: 888 1692095287999 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/device 1692095287999 Services.Common.RESTRequest DEBUG POST Length: 888 1692095288113 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/oauth/token 200 1692095288114 Hawk DEBUG (Response) /oauth/token: code: 200 - Status text: OK 1692095288114 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 1886 1692095288114 FirefoxAccounts DEBUG _updateAccountData with items: ["oauthTokens"] 1692095288114 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095288114 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095288114 Services.Common.TokenServerClient DEBUG Beginning OAuth token exchange: https://token.services.mozilla.com/1.0/sync/1.5 1692095288114 Services.Common.RESTRequest DEBUG GET request to https://token.services.mozilla.com/1.0/sync/1.5 1692095288115 FirefoxAccounts TRACE finished write of json user data - took: 1 1692095288115 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"] 1692095288115 FirefoxAccounts TRACE starting write of user data to the login manager 1692095288118 FirefoxAccounts TRACE finished write of user data to the login manager 1692095288127 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/device 200 1692095288127 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK 1692095288127 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 1873 1692095288127 FirefoxAccounts DEBUG _updateAccountData with items: ["device"] 1692095288127 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095288127 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095288128 FirefoxAccounts TRACE finished write of json user data - took: 1 1692095288128 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"] 1692095288128 FirefoxAccounts TRACE starting write of user data to the login manager 1692095288131 FirefoxAccounts TRACE finished write of user data to the login manager 1692095288145 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/device 200 1692095288145 Hawk DEBUG (Response) /account/device: code: 200 - Status text: OK 1692095288145 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 1855 1692095288145 FirefoxAccounts DEBUG _updateAccountData with items: ["device"] 1692095288145 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095288145 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys"] 1692095288145 FirefoxAccounts INFO updating the cache 1692095288146 FirefoxAccounts TRACE finished write of json user data - took: 0 1692095288146 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"] 1692095288146 FirefoxAccounts TRACE starting write of user data to the login manager 1692095288148 FirefoxAccounts TRACE finished write of user data to the login manager 1692095288498 Services.Common.RESTRequest DEBUG GET https://token.services.mozilla.com/1.0/sync/1.5 200 1692095288498 Services.Common.TokenServerClient DEBUG Got token response: 200 1692095288498 Services.Common.TokenServerClient DEBUG Successful token response 1692095288498 Sync.BulkKeyBundle INFO BulkKeyBundle being created for undefined 1692095288498 Sync.Status DEBUG Status.login: success.status_ok => success.login 1692095288498 Sync.Status DEBUG Status.service: error.login.failed => success.status_ok 1692095288498 Sync.SyncAuthManager DEBUG _findCluster returning https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/ 1692095288498 Sync.SyncAuthManager DEBUG Cluster value = https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/ 1692095288498 Sync.SyncAuthManager DEBUG Setting cluster to https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/ 1692095288498 Sync.Service DEBUG Caching URLs under storage user base: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/ 1692095288688 Services.Common.RESTRequest DEBUG GET https://profile.accounts.firefox.com/v1/profile 200 1692095288688 FirefoxAccounts DEBUG _updateAccountData with items: ["profileCache"] 1692095288688 FirefoxAccounts DEBUG writing plain storage: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys","profileCache"] 1692095288688 FirefoxAccounts TRACE starting write of json user data: ["email","sessionToken","uid","verified","device","oauthTokens","encryptedSendTabKeys","profileCache"] 1692095288688 FirefoxAccounts DEBUG notifying profile changed for user 0fe46cb94fde40a3ab740ad6c945f61a 1692095288688 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095288689 FirefoxAccounts TRACE finished write of json user data - took: 1 1692095288689 FirefoxAccounts DEBUG writing secure storage: ["scopedKeys"] 1692095288689 FirefoxAccounts TRACE starting write of user data to the login manager 1692095288692 FirefoxAccounts TRACE finished write of user data to the login manager 1692095288758 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/collections 1692095288758 Sync.Service DEBUG Fetching server configuration: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/configuration 1692095288758 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095288844 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/configuration 1692095288844 Sync.Service DEBUG Fetching global metadata record 1692095288844 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095288947 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/meta/global 1692095288948 Sync.Service DEBUG Clearing cached meta record. metaModified is undefined, setting to 1597924262.42 1692095288948 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289059 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/meta/global 1692095289059 Sync.Service DEBUG Weave Version: 1.119.0 Local Storage: 5 Remote Storage: 5 1692095289060 Sync.Service INFO Sync IDs differ. Local is UBRfO1rxPCj6, remote is 2CRyREgi-3Bt 1692095289060 Sync.Service INFO Service reset. 1692095289060 Sync.Engine.Clients DEBUG Resetting clients last sync time 1692095289060 Sync.Engine.Addons DEBUG Resetting addons last sync time 1692095289061 Sync.Engine.Passwords DEBUG Resetting passwords last sync time 1692095289061 Sync.Engine.Prefs DEBUG Resetting prefs last sync time 1692095289061 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time 1692095289098 Sync.Engine.Forms DEBUG Resetting forms last sync time 1692095289098 Sync.Engine.History DEBUG Resetting history last sync time 1692095289099 Sync.Engine.Tabs DEBUG Resetting tabs last sync time 1692095289130 Sync.Engine.Extension-Storage DEBUG Resetting extension-storage last sync time 1692095289134 Sync.CollectionKeyManager INFO Clearing collection keys... 1692095289134 Sync.Service DEBUG Clear cached values and take syncId: 2CRyREgi-3Bt 1692095289134 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1692095289134 Sync.Service INFO Testing info/collections: {"crypto":1530110007.7,"history":1673955908.83,"clients":1673955905.58,"passwords":1666230435.6,"meta":1597924262.42,"bookmarks":1666230439.42,"addons":1666230453.56,"forms":1666230449.46,"tabs":1673955905.85,"prefs":1666230452.95} 1692095289134 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 0 1692095289134 Sync.Service INFO collection keys reports that a key update is needed. 1692095289134 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289233 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/crypto/keys 1692095289233 Sync.CollectionKeyManager INFO Updating collection keys... 1692095289234 Sync.CollectionKeyManager INFO Setting collection keys contents. Our last modified: 0, input modified: 1530110007.7. 1692095289234 Sync.BulkKeyBundle INFO BulkKeyBundle being created for [default] 1692095289234 Sync.CollectionKeyManager INFO Processing downloaded per-collection keys. 1692095289234 Sync.CollectionKeyManager INFO Clearing collection keys... 1692095289234 Sync.CollectionKeyManager INFO Saving downloaded keys. 1692095289234 Sync.CollectionKeyManager INFO Bumping last modified to 1530110007.7 1692095289234 Sync.CollectionKeyManager INFO Collection keys updated. 1692095289234 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289234 Sync.SyncAuthManager DEBUG unlockAndVerifyAuthState already has (or can fetch) sync keys 1692095289234 Sync.Status DEBUG Status.login: success.login => success.status_ok 1692095289234 Sync.Status DEBUG Status.service: success.status_ok => error.login.failed 1692095289234 Sync.Service DEBUG Fetching unlocked auth state returned success.status_ok 1692095289234 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289349 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/collections 1692095289349 Sync.Service DEBUG Fetching server configuration: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/configuration 1692095289349 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289435 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/configuration 1692095289436 Sync.Service DEBUG Fetching global metadata record 1692095289436 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289538 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/meta/global 1692095289538 Sync.Service DEBUG Weave Version: 1.119.0 Local Storage: 5 Remote Storage: 5 1692095289538 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1692095289538 Sync.Service INFO Testing info/collections: {"tabs":1673955905.85,"crypto":1530110007.7,"prefs":1666230452.95,"bookmarks":1666230439.42,"passwords":1666230435.6,"clients":1673955905.58,"forms":1666230449.46,"addons":1666230453.56,"history":1673955908.83,"meta":1597924262.42} 1692095289538 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1530110007.7 1692095289538 Sync.Status DEBUG Status.login: success.status_ok => success.login 1692095289538 Sync.Status DEBUG Status.service: error.login.failed => success.status_ok 1692095289538 Sync.Status DEBUG Status.login: success.login => success.login 1692095289538 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1692095289538 Sync.Service DEBUG Caching URLs under storage user base: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/ 1692095289538 Sync.SyncScheduler DEBUG Clearing sync triggers and the global score. 1692095289539 Sync.Status INFO Resetting Status. 1692095289539 Sync.Status DEBUG Status.service: success.status_ok => success.status_ok 1692095289540 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289581 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095289642 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/collections?v=1.119.0 1692095289642 Sync.Service DEBUG Fetching server configuration: https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/configuration 1692095289643 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289695 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095289698 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. (why=schedule) 1692095289725 Sync.Resource DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/info/configuration 1692095289725 Sync.Service DEBUG Fetching global metadata record 1692095289725 Sync.Service DEBUG Weave Version: 1.119.0 Local Storage: 5 Remote Storage: 5 1692095289725 Sync.Service DEBUG Fetching and verifying -- or generating -- symmetric keys. 1692095289725 Sync.Service INFO Testing info/collections: {"meta":1597924262.42,"prefs":1666230452.95,"addons":1666230453.56,"bookmarks":1666230439.42,"forms":1666230449.46,"clients":1673955905.58,"passwords":1666230435.6,"crypto":1530110007.7,"history":1673955908.83,"tabs":1673955905.85} 1692095289725 Sync.CollectionKeyManager INFO Testing for updateNeeded. Last modified: 1530110007.7 1692095289725 Sync.Synchronizer DEBUG Refreshing client list. 1692095289727 Sync.Engine.Clients DEBUG Engine syncIDs: 4zrfl7gT59sd, 1692095289727 Sync.Engine.Clients DEBUG First sync, uploading all items 1692095289727 Sync.Engine.Clients DEBUG Resetting clients last sync time 1692095289727 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289841 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/clients?full=1&limit=1000 1692095289841 Sync.Engine.Clients INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1692095289841 FirefoxAccounts INFO Device list cache is fresh, re-using it 1692095289841 Sync.Engine.Clients DEBUG Refreshing the known stale clients list 1692095289842 Sync.Engine.Clients INFO Uploading 1 outgoing records 1692095289843 Sync.Engine.Clients INFO Posting 1 records of 570 bytes with batch=true 1692095289843 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095289844 Sync.Collection DEBUG POST Length: 570 1692095289981 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/clients?batch=true&commit=true 1692095289981 Sync.Engine.Clients INFO Uploaded our client record for the first time, notifying other clients. 1692095289982 Sync.SyncScheduler DEBUG Client count: 0 -> 1 1692095289982 Sync.Engine.Prefs DEBUG Resetting prefs last sync time 1692095289982 Sync.Engine.Passwords DEBUG Resetting passwords last sync time 1692095289982 Sync.Engine.Tabs DEBUG Resetting tabs last sync time 1692095289983 Services.Common.RESTRequest DEBUG POST request to https://api.accounts.firefox.com/v1/account/devices/notify 1692095289983 Services.Common.RESTRequest DEBUG POST Length: 184 1692095289990 Sync.Engine.Bookmarks DEBUG Resetting bookmarks last sync time 1692095290011 Sync.Engine.Addons DEBUG Resetting addons last sync time 1692095290011 Sync.Engine.Forms DEBUG Resetting forms last sync time 1692095290011 Sync.Engine.History DEBUG Resetting history last sync time 1692095290012 Sync.Engine.Extension-Storage DEBUG Resetting extension-storage last sync time 1692095290016 Sync.Synchronizer INFO Updating enabled engines: 1 clients. 1692095290017 Sync.Synchronizer INFO Syncing all enabled engines. 1692095290017 Sync.Engine.Prefs DEBUG Engine syncIDs: thnRaiib0e_g, 1692095290017 Sync.Engine.Prefs DEBUG First sync, uploading all items 1692095290018 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095290109 Services.Common.RESTRequest DEBUG POST https://api.accounts.firefox.com/v1/account/devices/notify 200 1692095290109 Hawk DEBUG (Response) /account/devices/notify: code: 200 - Status text: OK 1692095290109 Hawk DEBUG Clock offset vs https://api.accounts.firefox.com/v1: 1891 1692095290125 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/prefs?full=1&limit=1000 1692095290128 Sync.Engine.Prefs WARN DATA LOSS: Both local and remote changes to record: e2VjODAzMGY3LWMyMGEtNDY0Zi05YjBlLTEzYTNhOWU5NzM4NH0= 1692095290136 Sync.Engine.Prefs INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1692095290136 Sync.Engine.Prefs INFO Uploading 1 outgoing records 1692095290156 Sync.AddonsReconciler DEBUG Add-on change: onEnabled to default-theme@mozilla.org 1692095290156 Sync.AddonsReconciler DEBUG Rectifying state for addon System theme — auto (version=1.3, id=default-theme@mozilla.org) 1692095290156 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: default-theme@mozilla.org 1692095290156 Sync.AddonsReconciler INFO Change recorded for default-theme@mozilla.org 1692095290157 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 default-theme@mozilla.org 1692095290157 Sync.Engine.Addons.Store DEBUG default-theme@mozilla.org not syncable: not installed in profile. 1692095290157 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: default-theme@mozilla.org 1692095290158 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095290161 Sync.Engine.Prefs INFO Posting 1 records of 16383 bytes with batch=true 1692095290161 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095290162 Sync.AddonsReconciler DEBUG Add-on change: onDisabled to firefox-compact-dark@mozilla.org 1692095290162 Sync.AddonsReconciler DEBUG Rectifying state for addon Dark (version=1.2, id=firefox-compact-dark@mozilla.org) 1692095290162 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: firefox-compact-dark@mozilla.org 1692095290162 Sync.AddonsReconciler INFO Change recorded for firefox-compact-dark@mozilla.org 1692095290162 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 firefox-compact-dark@mozilla.org 1692095290162 Sync.Engine.Addons.Store DEBUG firefox-compact-dark@mozilla.org not syncable: not installed in profile. 1692095290162 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: firefox-compact-dark@mozilla.org 1692095290162 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095290162 Sync.Collection DEBUG POST Length: 16383 1692095290265 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095290265 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095290265 Sync.Service INFO Starting sync at 2023-08-15 06:28:10 in browser session ItJ6XSTzsucL 1692095290265 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095290265 Sync.Service INFO Cannot start sync: already syncing? 1692095290309 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/prefs?batch=true&commit=true 1692095290311 Sync.Engine.Passwords DEBUG First sync, uploading all items 1692095290313 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095290425 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/passwords?full=1&limit=1000 1692095290429 Sync.Engine.Passwords DEBUG Switching local ID to incoming: {d18f9f27-2812-4e2b-a99b-884073cd89ea} -> {7a5adcd7-c1a9-4568-ae9b-d2483ec95071} 1692095290434 Sync.Engine.Passwords DEBUG Local item after duplication: age=106.65899991989136; modified=true; exists=true 1692095290435 Sync.Engine.Passwords WARN DATA LOSS: Both local and remote changes to record: {7a5adcd7-c1a9-4568-ae9b-d2483ec95071} 1692095290444 Sync.Engine.Passwords INFO Records: 4 applied, 4 successfully, 0 failed to apply, 0 newly failed to apply, 1 reconciled. 1692095290445 Sync.Engine.Passwords INFO Uploading 1 outgoing records 1692095290446 Sync.Engine.Passwords INFO Posting 1 records of 645 bytes with batch=true 1692095290446 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095290446 Sync.Collection DEBUG POST Length: 645 1692095290557 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095290557 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095290557 Sync.Service INFO Starting sync at 2023-08-15 06:28:10 in browser session ItJ6XSTzsucL 1692095290557 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095290557 Sync.Service INFO Cannot start sync: already syncing? 1692095290571 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/passwords?batch=true&commit=true 1692095290572 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095290704 Sync.Collection DEBUG DELETE success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/passwords?ids={d18f9f27-2812-4e2b-a99b-884073cd89ea} 1692095290718 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095290823 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/tabs?full=1&limit=1000 1692095290829 Sync.Engine.Tabs INFO Records: 1 applied, 1 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1692095290829 Sync.Engine.Tabs INFO Uploading 1 outgoing records 1692095290830 Sync.Engine.Tabs INFO Posting 1 records of 1175 bytes with batch=true 1692095290830 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095290830 Sync.Collection DEBUG POST Length: 1175 1692095290962 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/tabs?batch=true&commit=true 1692095290968 Sync.Engine.Bookmarks DEBUG Checking if server sync ID lSET3tMuR6kc matches existing 1692095290968 Sync.Engine.Bookmarks.BookmarkSyncUtils INFO Taking new bookmarks sync ID: {"newSyncId":"lSET3tMuR6kc"} 1692095290969 Sync.Engine.Bookmarks.Mirror INFO Sync ID changed from to lSET3tMuR6kc; resetting mirror 1692095290978 Sync.Engine.Bookmarks DEBUG Bookmarks backup starting 1692095290982 Sync.Engine.Bookmarks DEBUG Bookmarks backup done 1692095290982 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095291154 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095291174 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/bookmarks?full=1&sort=oldest&limit=1000 1692095291179 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095291385 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/bookmarks?full=1&sort=oldest&limit=1000&offset=1000 1692095291388 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095291594 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/bookmarks?full=1&sort=oldest&limit=1000&offset=2000 1692095291597 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095291809 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/bookmarks?full=1&sort=oldest&limit=1000&offset=3000 1692095291812 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095292044 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/bookmarks?full=1&sort=oldest&limit=1000&offset=4000 1692095292046 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095292169 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/bookmarks?full=1&sort=oldest&limit=1000&offset=5000 1692095292183 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095293832 Sync.Engine.Bookmarks INFO Records: 5032 applied, 5032 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1692095293833 Sync.Engine.Bookmarks.Mirror DEBUG Merging bookmarks in Rust 1692095293835 Sync.Engine.Bookmarks.Mirror DEBUG Building local tree 1692095293835 Sync.Engine.Bookmarks.Mirror DEBUG Building remote tree 1692095293847 Sync.Engine.Bookmarks.Mirror DEBUG Building merged tree 1692095293849 Sync.Engine.Bookmarks.Mirror DEBUG Applying merged tree 1692095293849 Sync.Engine.Bookmarks.Mirror DEBUG Updating local items in Places 1692095293849 Sync.Engine.Bookmarks.Mirror DEBUG Cleaning up observer notifications left from last sync 1692095293849 Sync.Engine.Bookmarks.Mirror DEBUG Inserting Places for new items 1692095293854 Sync.Engine.Bookmarks.Mirror DEBUG Updating origins for new URLs 1692095293878 Sync.Engine.Bookmarks.Mirror DEBUG Staging apply remote item ops 1692095293888 Sync.Engine.Bookmarks.Mirror DEBUG Staging change GUID ops 1692095293888 Sync.Engine.Bookmarks.Mirror DEBUG Staging apply new local structure ops 1692095293893 Sync.Engine.Bookmarks.Mirror DEBUG Removing tombstones for revived items 1692095293893 Sync.Engine.Bookmarks.Mirror DEBUG Inserting new tombstones for non-syncable and invalid items 1692095293893 Sync.Engine.Bookmarks.Mirror DEBUG Removing local items 1692095293893 Sync.Engine.Bookmarks.Mirror DEBUG Changing GUIDs 1692095293893 Sync.Engine.Bookmarks.Mirror DEBUG Applying remote items 1692095293893 Sync.Engine.Bookmarks.Mirror DEBUG Recording item added notifications for new items 1692095293894 Sync.Engine.Bookmarks.Mirror DEBUG Recording item changed notifications for existing items 1692095293894 Sync.Engine.Bookmarks.Mirror DEBUG Removing old keywords 1692095293894 Sync.Engine.Bookmarks.Mirror DEBUG Removing old tags 1692095293894 Sync.Engine.Bookmarks.Mirror DEBUG Upserting new items 1692095293903 Sync.Engine.Bookmarks.Mirror DEBUG Flagging frecencies for recalculation 1692095293903 Sync.Engine.Bookmarks.Mirror DEBUG Inserting new keywords for new URLs 1692095293903 Sync.Engine.Bookmarks.Mirror DEBUG Inserting new tags for new URLs 1692095293903 Sync.Engine.Bookmarks.Mirror DEBUG Updating origins for changed URLs 1692095293921 Sync.Engine.Bookmarks.Mirror DEBUG Applying new local structure 1692095293927 Sync.Engine.Bookmarks.Mirror DEBUG Resetting change counters for items that shouldn't be uploaded 1692095293927 Sync.Engine.Bookmarks.Mirror DEBUG Bumping change counters for items that should be uploaded 1692095293927 Sync.Engine.Bookmarks.Mirror DEBUG Flagging applied remote items as merged 1692095293939 Sync.Engine.Bookmarks.Mirror DEBUG Staging items to upload 1692095293940 Sync.Engine.Bookmarks.Mirror DEBUG Cleaning up staged items left from last sync 1692095293940 Sync.Engine.Bookmarks.Mirror DEBUG Staging items with older local dates added 1692095293940 Sync.Engine.Bookmarks.Mirror DEBUG Staging remaining locally changed items for upload 1692095293940 Sync.Engine.Bookmarks.Mirror DEBUG Staging structure to upload 1692095293940 Sync.Engine.Bookmarks.Mirror DEBUG Staging tags to upload 1692095293941 Sync.Engine.Bookmarks.Mirror DEBUG Staging tombstones to upload 1692095293948 Sync.Engine.Bookmarks.Mirror DEBUG Merging bookmarks in Rust took 115.681ms 1692095293948 Sync.Engine.Bookmarks.Mirror DEBUG Notifying Places observers 1692095293971 Sync.Engine.Bookmarks.Mirror DEBUG Notifying Places observers took 22.626ms 1692095293971 Sync.Engine.Bookmarks.Mirror DEBUG Fetching records for local items to upload 1692095293972 Sync.Engine.Bookmarks.Mirror DEBUG Fetching records for local items to upload took 1.052ms 1692095293972 Sync.Engine.Bookmarks INFO Uploading 10 outgoing records 1692095293976 Sync.Engine.Bookmarks INFO Posting 10 records of 5473 bytes with batch=true 1692095293976 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095293977 Sync.Collection DEBUG POST Length: 5473 1692095294071 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095294071 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095294071 Sync.Service INFO Starting sync at 2023-08-15 06:28:14 in browser session ItJ6XSTzsucL 1692095294071 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095294071 Sync.Service INFO Cannot start sync: already syncing? 1692095294120 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/bookmarks?batch=true&commit=true 1692095294141 Sync.Engine.Bookmarks.BookmarkSyncUtils DEBUG pushChanges: Processed change records: {"weak":0,"skipped":0,"updated":10} 1692095294141 Sync.Engine.Addons DEBUG Refreshing reconciler state 1692095294141 Sync.AddonsReconciler INFO Refreshing global state from AddonManager. 1692095294141 Sync.AddonsReconciler DEBUG Rectifying state for addon Form Autofill (version=1.0.1, id=formautofill@mozilla.org) 1692095294141 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: formautofill@mozilla.org 1692095294141 Sync.AddonsReconciler INFO Change recorded for formautofill@mozilla.org 1692095294141 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 formautofill@mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG formautofill@mozilla.org not syncable: vetoed by the addon manager. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: formautofill@mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Picture-In-Picture (version=1.0.0, id=pictureinpicture@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: pictureinpicture@mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for pictureinpicture@mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 pictureinpicture@mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG pictureinpicture@mozilla.org not syncable: vetoed by the addon manager. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: pictureinpicture@mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Firefox Screenshots (version=39.0.1, id=screenshots@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: screenshots@mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for screenshots@mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 screenshots@mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG screenshots@mozilla.org not syncable: vetoed by the addon manager. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: screenshots@mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon WebCompat Reporter (version=1.5.1, id=webcompat-reporter@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: webcompat-reporter@mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for webcompat-reporter@mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 webcompat-reporter@mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG webcompat-reporter@mozilla.org not syncable: vetoed by the addon manager. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: webcompat-reporter@mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Web Compatibility Interventions (version=117.0.0, id=webcompat@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: webcompat@mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for webcompat@mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 webcompat@mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG webcompat@mozilla.org not syncable: vetoed by the addon manager. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: webcompat@mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon System theme — auto (version=1.3, id=default-theme@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Add-ons Search Detection (version=2.0.0, id=addons-search-detection@mozilla.com) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: addons-search-detection@mozilla.com 1692095294142 Sync.AddonsReconciler INFO Change recorded for addons-search-detection@mozilla.com 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 addons-search-detection@mozilla.com 1692095294142 Sync.Engine.Addons.Store DEBUG addons-search-detection@mozilla.com not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: addons-search-detection@mozilla.com 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Google (version=1.4, id=google@search.mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: google@search.mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for google@search.mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 google@search.mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG google@search.mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: google@search.mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Wikipedia (en) (version=1.3, id=wikipedia@search.mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: wikipedia@search.mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for wikipedia@search.mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 wikipedia@search.mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG wikipedia@search.mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: wikipedia@search.mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Bing (version=1.6, id=bing@search.mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: bing@search.mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for bing@search.mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 bing@search.mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG bing@search.mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: bing@search.mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon DuckDuckGo (version=1.4, id=ddg@search.mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: ddg@search.mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for ddg@search.mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 ddg@search.mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG ddg@search.mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: ddg@search.mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Light (version=1.2, id=firefox-compact-light@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: firefox-compact-light@mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for firefox-compact-light@mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 firefox-compact-light@mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG firefox-compact-light@mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: firefox-compact-light@mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Dark (version=1.2, id=firefox-compact-dark@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Firefox Alpenglow (version=1.4, id=firefox-alpenglow@mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: firefox-alpenglow@mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for firefox-alpenglow@mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 firefox-alpenglow@mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG firefox-alpenglow@mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: firefox-alpenglow@mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Amazon.co.uk (version=1.12, id=amazon@search.mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: amazon@search.mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for amazon@search.mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 amazon@search.mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG amazon@search.mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: amazon@search.mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon eBay (version=1.4, id=ebay@search.mozilla.org) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: ebay@search.mozilla.org 1692095294142 Sync.AddonsReconciler INFO Change recorded for ebay@search.mozilla.org 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 ebay@search.mozilla.org 1692095294142 Sync.Engine.Addons.Store DEBUG ebay@search.mozilla.org not syncable: not installed in profile. 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: ebay@search.mozilla.org 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon OpenH264 Video Codec provided by Cisco Systems, Inc. (version=2.3.2, id=gmp-gmpopenh264) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: gmp-gmpopenh264 1692095294142 Sync.AddonsReconciler INFO Change recorded for gmp-gmpopenh264 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 gmp-gmpopenh264 1692095294142 Sync.Engine.Addons.Store DEBUG gmp-gmpopenh264 not syncable: type not in allowed list: plugin 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: gmp-gmpopenh264 1692095294142 Sync.AddonsReconciler DEBUG Rectifying state for addon Widevine Content Decryption Module provided by Google Inc. (version=4.10.2557.0, id=gmp-widevinecdm) 1692095294142 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: gmp-widevinecdm 1692095294142 Sync.AddonsReconciler INFO Change recorded for gmp-widevinecdm 1692095294142 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 gmp-widevinecdm 1692095294142 Sync.Engine.Addons.Store DEBUG gmp-widevinecdm not syncable: type not in allowed list: plugin 1692095294142 Sync.Engine.Addons.Tracker DEBUG Ignoring change because add-on isn't syncable: gmp-widevinecdm 1692095294143 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095294143 Sync.Engine.Addons DEBUG Engine syncIDs: iFr7vKNpwlgx, 1692095294143 Sync.Engine.Addons DEBUG First sync, uploading all items 1692095294143 Sync.Engine.Addons.Store DEBUG default-theme@mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG firefox-compact-dark@mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG formautofill@mozilla.org not syncable: vetoed by the addon manager. 1692095294143 Sync.Engine.Addons.Store DEBUG pictureinpicture@mozilla.org not syncable: vetoed by the addon manager. 1692095294143 Sync.Engine.Addons.Store DEBUG screenshots@mozilla.org not syncable: vetoed by the addon manager. 1692095294143 Sync.Engine.Addons.Store DEBUG webcompat-reporter@mozilla.org not syncable: vetoed by the addon manager. 1692095294143 Sync.Engine.Addons.Store DEBUG webcompat@mozilla.org not syncable: vetoed by the addon manager. 1692095294143 Sync.Engine.Addons.Store DEBUG addons-search-detection@mozilla.com not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG google@search.mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG wikipedia@search.mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG bing@search.mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG ddg@search.mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG firefox-compact-light@mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG firefox-alpenglow@mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG amazon@search.mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG ebay@search.mozilla.org not syncable: not installed in profile. 1692095294143 Sync.Engine.Addons.Store DEBUG gmp-gmpopenh264 not syncable: type not in allowed list: plugin 1692095294143 Sync.Engine.Addons.Store DEBUG gmp-widevinecdm not syncable: type not in allowed list: plugin 1692095294144 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095294250 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/addons?full=1&limit=1000 1692095294406 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095294406 Sync.Engine.Addons.Store WARN Failed to apply incoming record 0KmphFvex4C5: Error: Add-on not found after install: google@hitachi.com(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095294540 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095294540 Sync.AddonUtils INFO Add-on "{DDC359D1-844A-42a7-9AA1-88A850A938A8}" is able to be installed 1692095294540 Sync.AddonUtils INFO Trying to install add-on from search result: {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095294540 Sync.AddonUtils DEBUG Obtaining install for {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095294540 Sync.AddonUtils INFO Installing {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095295039 Sync.AddonUtils INFO Setting syncGUID of DownThemAll!: 4OOthAQBvXDz 1692095295049 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095295049 Sync.AddonsReconciler DEBUG Rectifying state for addon DownThemAll! (version=4.10.1, id={DDC359D1-844A-42a7-9AA1-88A850A938A8}) 1692095295049 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095295049 Sync.AddonsReconciler INFO Change recorded for {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095295049 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095295050 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095295050 Sync.Engine.Addons.Store INFO Add-on installed: {DDC359D1-844A-42a7-9AA1-88A850A938A8} 1692095295123 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write - bumping score 1692095295127 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095295175 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095295175 Sync.Engine.Addons.Store WARN Failed to apply incoming record Ae0y0mPPDcOo: Error: Add-on not found after install: imgflashblocker@shimon.chohen(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095295224 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095295224 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095295224 Sync.Service INFO Starting sync at 2023-08-15 06:28:15 in browser session ItJ6XSTzsucL 1692095295224 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095295225 Sync.Service INFO Cannot start sync: already syncing? 1692095295297 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095295297 Sync.Engine.Addons.Store WARN Failed to apply incoming record BiSqza57b0GY: Error: Add-on not found after install: {73007fef-a6e0-47d3-b4e7-dfc116ed6f65}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095295433 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095295433 Sync.Engine.Addons.Store WARN Failed to apply incoming record CyWlmXj2nNzI: Error: Add-on not found after install: {ab4b5718-3998-4a2c-91ae-18a7c2db513e}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095295574 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095295574 Sync.AddonUtils INFO Add-on "jid1-dwtGBwQjx3SUQc@jetpack" is able to be installed 1692095295575 Sync.AddonUtils INFO Trying to install add-on from search result: jid1-dwtGBwQjx3SUQc@jetpack 1692095295575 Sync.AddonUtils DEBUG Obtaining install for jid1-dwtGBwQjx3SUQc@jetpack 1692095295575 Sync.AddonUtils INFO Installing jid1-dwtGBwQjx3SUQc@jetpack 1692095295718 Sync.AddonUtils INFO Setting syncGUID of AdBlocker for Facebook™: ELRPvnojbrjL 1692095295724 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to jid1-dwtGBwQjx3SUQc@jetpack 1692095295724 Sync.AddonsReconciler DEBUG Rectifying state for addon AdBlocker for Facebook™ (version=0.2.1, id=jid1-dwtGBwQjx3SUQc@jetpack) 1692095295724 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: jid1-dwtGBwQjx3SUQc@jetpack 1692095295724 Sync.AddonsReconciler INFO Change recorded for jid1-dwtGBwQjx3SUQc@jetpack 1692095295724 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 jid1-dwtGBwQjx3SUQc@jetpack 1692095295725 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095295725 Sync.Engine.Addons.Store INFO Add-on installed: jid1-dwtGBwQjx3SUQc@jetpack 1692095295857 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095295857 Sync.AddonUtils INFO Add-on "support@todoist.com" is able to be installed 1692095295857 Sync.AddonUtils INFO Trying to install add-on from search result: support@todoist.com 1692095295857 Sync.AddonUtils DEBUG Obtaining install for support@todoist.com 1692095295857 Sync.AddonUtils INFO Installing support@todoist.com 1692095296011 Sync.AddonUtils INFO Setting syncGUID of Todoist: EXtSA52FHIWs 1692095296011 Sync.AddonUtils INFO Marking add-on as disabled for install: Todoist 1692095296018 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to support@todoist.com 1692095296018 Sync.AddonsReconciler DEBUG Rectifying state for addon Todoist (version=11.1, id=support@todoist.com) 1692095296018 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: support@todoist.com 1692095296018 Sync.AddonsReconciler INFO Change recorded for support@todoist.com 1692095296018 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 support@todoist.com 1692095296019 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095296019 Sync.Engine.Addons.Store INFO Add-on installed: support@todoist.com 1692095296141 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095296141 Sync.AddonUtils INFO Add-on "imageblock@hemantvats.com" is able to be installed 1692095296141 Sync.AddonUtils INFO Trying to install add-on from search result: imageblock@hemantvats.com 1692095296141 Sync.AddonUtils DEBUG Obtaining install for imageblock@hemantvats.com 1692095296141 Sync.AddonUtils INFO Installing imageblock@hemantvats.com 1692095296319 Sync.AddonUtils INFO Setting syncGUID of ImageBlock: Ejldpa5wOJjk 1692095296323 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to imageblock@hemantvats.com 1692095296323 Sync.AddonsReconciler DEBUG Rectifying state for addon ImageBlock (version=5.0, id=imageblock@hemantvats.com) 1692095296323 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: imageblock@hemantvats.com 1692095296323 Sync.AddonsReconciler INFO Change recorded for imageblock@hemantvats.com 1692095296323 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 imageblock@hemantvats.com 1692095296324 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095296324 Sync.Engine.Addons.Store INFO Add-on installed: imageblock@hemantvats.com 1692095296441 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095296442 Sync.Engine.Addons.Store WARN Failed to apply incoming record GDOViVcOha4p: Error: Add-on not found after install: jid1-7AraoB504A7Uqw@jetpack(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095296564 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095296564 Sync.Engine.Addons.Store WARN Failed to apply incoming record K_qcfwnffmTS: Error: Add-on not found after install: {9458ca25-39fd-4ba8-9520-acc5c0d877b6}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095296685 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095296697 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095296697 Sync.AddonUtils INFO Add-on "jid1-dswFGkUjb2SIHv@jetpack" is able to be installed 1692095296697 Sync.AddonUtils INFO Trying to install add-on from search result: jid1-dswFGkUjb2SIHv@jetpack 1692095296697 Sync.AddonUtils DEBUG Obtaining install for jid1-dswFGkUjb2SIHv@jetpack 1692095296697 Sync.AddonUtils INFO Installing jid1-dswFGkUjb2SIHv@jetpack 1692095296795 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095296795 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095296795 Sync.Service INFO Starting sync at 2023-08-15 06:28:16 in browser session ItJ6XSTzsucL 1692095296795 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095296795 Sync.Service INFO Cannot start sync: already syncing? 1692095297012 Sync.AddonUtils INFO Setting syncGUID of AdBlocker for Gmail™: Kv8_UxGtZfq_ 1692095297016 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to jid1-dswFGkUjb2SIHv@jetpack 1692095297016 Sync.AddonsReconciler DEBUG Rectifying state for addon AdBlocker for Gmail™ (version=0.1.8, id=jid1-dswFGkUjb2SIHv@jetpack) 1692095297016 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: jid1-dswFGkUjb2SIHv@jetpack 1692095297016 Sync.AddonsReconciler INFO Change recorded for jid1-dswFGkUjb2SIHv@jetpack 1692095297016 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 jid1-dswFGkUjb2SIHv@jetpack 1692095297016 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095297017 Sync.Engine.Addons.Store INFO Add-on installed: jid1-dswFGkUjb2SIHv@jetpack 1692095297140 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095297141 Sync.Engine.Addons.Store WARN Failed to apply incoming record QZmNY7pSSwNo: Error: Add-on not found after install: jid1-ReWlW1efOwaQJQ@jetpack(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095297263 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095297263 Sync.Engine.Addons.Store WARN Failed to apply incoming record RYDO-OodBUUh: Error: Add-on not found after install: {b1df372d-8b32-4c7d-b6b4-9c5b78cf6fb1}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095297405 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095297405 Sync.Engine.Addons.Store WARN Failed to apply incoming record V3ozF5DNyYcH: Error: Add-on not found after install: {75CEEE46-9B64-46f8-94BF-54012DE155F0}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095297561 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095297561 Sync.AddonUtils INFO Add-on "{b9db16a4-6edc-47ec-a1f4-b86292ed211d}" is able to be installed 1692095297561 Sync.AddonUtils INFO Trying to install add-on from search result: {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297561 Sync.AddonUtils DEBUG Obtaining install for {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297561 Sync.AddonUtils INFO Installing {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297837 Sync.AddonUtils INFO Setting syncGUID of Video DownloadHelper: VAojRb_XPmwl 1692095297845 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297845 Sync.AddonsReconciler DEBUG Rectifying state for addon Video DownloadHelper (version=8.0.0.0, id={b9db16a4-6edc-47ec-a1f4-b86292ed211d}) 1692095297845 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297845 Sync.AddonsReconciler INFO Change recorded for {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297845 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297845 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095297845 Sync.Engine.Addons.Store INFO Add-on installed: {b9db16a4-6edc-47ec-a1f4-b86292ed211d} 1692095297897 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write - bumping score 1692095297901 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095297967 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095297967 Sync.AddonUtils INFO Add-on "jid1-BYcQOfYfmBMd9A@jetpack" is able to be installed 1692095297967 Sync.AddonUtils INFO Trying to install add-on from search result: jid1-BYcQOfYfmBMd9A@jetpack 1692095297967 Sync.AddonUtils DEBUG Obtaining install for jid1-BYcQOfYfmBMd9A@jetpack 1692095297967 Sync.AddonUtils INFO Installing jid1-BYcQOfYfmBMd9A@jetpack 1692095298005 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095298006 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095298006 Sync.Service INFO Starting sync at 2023-08-15 06:28:18 in browser session ItJ6XSTzsucL 1692095298006 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095298006 Sync.Service INFO Cannot start sync: already syncing? 1692095298195 Sync.AddonUtils INFO Setting syncGUID of Pushbullet: VF3_TNO3mx42 1692095298195 Sync.AddonUtils INFO Marking add-on as disabled for install: Pushbullet 1692095298202 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to jid1-BYcQOfYfmBMd9A@jetpack 1692095298202 Sync.AddonsReconciler DEBUG Rectifying state for addon Pushbullet (version=366, id=jid1-BYcQOfYfmBMd9A@jetpack) 1692095298202 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: jid1-BYcQOfYfmBMd9A@jetpack 1692095298202 Sync.AddonsReconciler INFO Change recorded for jid1-BYcQOfYfmBMd9A@jetpack 1692095298202 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 jid1-BYcQOfYfmBMd9A@jetpack 1692095298203 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095298203 Sync.Engine.Addons.Store INFO Add-on installed: jid1-BYcQOfYfmBMd9A@jetpack 1692095298322 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095298322 Sync.Engine.Addons.Store WARN Failed to apply incoming record VnulDSB-Hl1q: Error: Add-on not found after install: {02450914-cdd9-410f-b1da-db004e18c671}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095298440 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095298440 Sync.Engine.Addons.Store WARN Failed to apply incoming record _-kmk35Xc9Wv: Error: Add-on not found after install: firebug@software.joehewitt.com(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095298570 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095298570 Sync.Engine.Addons.Store WARN Failed to apply incoming record gPkNVy6csshQ: Error: Add-on not found after install: {06997db0-c027-4d5f-bd37-b0d9230226ea}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095298581 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095298691 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095298692 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095298692 Sync.Engine.Addons.Store WARN Failed to apply incoming record n5pzt7nhTuZh: Error: Add-on not found after install: jid1-ach2kaGSshPJCg@jetpack(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095298692 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095298692 Sync.Service INFO Starting sync at 2023-08-15 06:28:18 in browser session ItJ6XSTzsucL 1692095298692 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095298692 Sync.Service INFO Cannot start sync: already syncing? 1692095298754 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write - bumping score 1692095298758 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095298825 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095298825 Sync.AddonUtils INFO Add-on "{d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}" is able to be installed 1692095298825 Sync.AddonUtils INFO Trying to install add-on from search result: {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095298825 Sync.AddonUtils DEBUG Obtaining install for {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095298825 Sync.AddonUtils INFO Installing {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095298855 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095298855 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095298855 Sync.Service INFO Starting sync at 2023-08-15 06:28:18 in browser session ItJ6XSTzsucL 1692095298855 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095298855 Sync.Service INFO Cannot start sync: already syncing? 1692095298961 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095298963 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095298963 Sync.Service INFO Starting sync at 2023-08-15 06:28:18 in browser session ItJ6XSTzsucL 1692095298963 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095298963 Sync.Service INFO Cannot start sync: already syncing? 1692095299297 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095299399 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095299400 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095299400 Sync.Service INFO Starting sync at 2023-08-15 06:28:19 in browser session ItJ6XSTzsucL 1692095299400 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095299400 Sync.Service INFO Cannot start sync: already syncing? 1692095299494 Sync.AddonUtils INFO Setting syncGUID of Adblock Plus - free ad blocker: n8iEUxp1cZ6j 1692095299517 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095299517 Sync.AddonsReconciler DEBUG Rectifying state for addon Adblock Plus - free ad blocker (version=3.17.1, id={d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d}) 1692095299517 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095299517 Sync.AddonsReconciler INFO Change recorded for {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095299518 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095299518 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095299518 Sync.Engine.Addons.Store INFO Add-on installed: {d10d0bf8-f5b5-c8b4-a8b2-2b9879e08c5d} 1692095299638 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095299639 Sync.AddonUtils INFO Add-on "{4cc4a13b-94a6-7568-370d-5f9de54a9c7f}" is able to be installed 1692095299639 Sync.AddonUtils INFO Trying to install add-on from search result: {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095299639 Sync.AddonUtils DEBUG Obtaining install for {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095299639 Sync.AddonUtils INFO Installing {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095299742 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write - bumping score 1692095299750 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095299913 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095299921 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095299921 Sync.Service INFO Starting sync at 2023-08-15 06:28:19 in browser session ItJ6XSTzsucL 1692095299921 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095299921 Sync.Service INFO Cannot start sync: already syncing? 1692095299933 Sync.AddonUtils INFO Setting syncGUID of Empty Cache Button: nRXwq5fQiBTI 1692095299933 Sync.AddonUtils INFO Marking add-on as disabled for install: Empty Cache Button 1692095299938 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095299938 Sync.AddonsReconciler DEBUG Rectifying state for addon Empty Cache Button (version=3.4, id={4cc4a13b-94a6-7568-370d-5f9de54a9c7f}) 1692095299938 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095299938 Sync.AddonsReconciler INFO Change recorded for {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095299938 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095299939 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095299939 Sync.Engine.Addons.Store INFO Add-on installed: {4cc4a13b-94a6-7568-370d-5f9de54a9c7f} 1692095300071 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095300071 Sync.Engine.Addons.Store WARN Failed to apply incoming record nTVuTNImtU2F: Error: Add-on not found after install: {1b8cc170-8c85-11db-b606-0800200c9a66}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095300077 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write - bumping score 1692095300080 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095300184 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095300184 Sync.Engine.Addons.Store WARN Failed to apply incoming record oPNsMmRyAsdD: Error: Add-on not found after install: yslow@yahoo-inc.com(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095300206 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095300206 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095300206 Sync.Service INFO Starting sync at 2023-08-15 06:28:20 in browser session ItJ6XSTzsucL 1692095300206 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095300206 Sync.Service INFO Cannot start sync: already syncing? 1692095300311 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095300311 Sync.Engine.Addons.Store WARN Failed to apply incoming record oT1CSUtUxqTj: Error: Add-on not found after install: phrasehighlighter@techtoast.co.uk(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095300432 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095300432 Sync.Engine.Addons.Store WARN Failed to apply incoming record ppMXDReiTUvH: Error: Add-on not found after install: add-to-searchbox@maltekraus.de(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095300452 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095300560 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095300561 Sync.Engine.Addons.Store WARN Failed to apply incoming record s-BAkvqjgEmq: Error: Add-on not found after install: sendtokindle@amazon.com(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095300564 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095300565 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095300565 Sync.Service INFO Starting sync at 2023-08-15 06:28:20 in browser session ItJ6XSTzsucL 1692095300565 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095300565 Sync.Service INFO Cannot start sync: already syncing? 1692095300680 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095300680 Sync.Engine.Addons.Store WARN Failed to apply incoming record tDRxRXp7WXLy: Error: Add-on not found after install: {bb117431-63c1-4a4d-8e4e-47f02268b2c6}(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095300804 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095300805 Sync.Engine.Addons.Store WARN Failed to apply incoming record tT-_kd9MLszU: Error: Add-on not found after install: colorPicker@colorPicker(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095300931 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095300931 Sync.AddonUtils INFO Add-on "{6AC85730-7D0F-4de0-B3FA-21142DD85326}" is able to be installed 1692095300931 Sync.AddonUtils INFO Trying to install add-on from search result: {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095300931 Sync.AddonUtils DEBUG Obtaining install for {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095300931 Sync.AddonUtils INFO Installing {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095301216 Sync.AddonUtils INFO Setting syncGUID of ColorZilla: x29VMXvtRB26 1692095301216 Sync.AddonUtils INFO Marking add-on as disabled for install: ColorZilla 1692095301220 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095301221 Sync.AddonsReconciler DEBUG Rectifying state for addon ColorZilla (version=3.3, id={6AC85730-7D0F-4de0-B3FA-21142DD85326}) 1692095301221 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095301221 Sync.AddonsReconciler INFO Change recorded for {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095301221 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095301221 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095301221 Sync.Engine.Addons.Store INFO Add-on installed: {6AC85730-7D0F-4de0-B3FA-21142DD85326} 1692095301344 Sync.AddonUtils INFO Found 0 / 1 add-ons during repository search. 1692095301344 Sync.Engine.Addons.Store WARN Failed to apply incoming record {a70b5848-6862-46b8-8fee-3c5fb569ccb2}: Error: Add-on not found after install: anticontainer@downthemall.net(resource://services-sync/engines/addons.sys.mjs:359:13) JS Stack trace: create@addons.sys.mjs:359:13 1692095301483 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095301483 Sync.AddonUtils INFO Add-on "@contain-facebook" is able to be installed 1692095301483 Sync.AddonUtils INFO Trying to install add-on from search result: @contain-facebook 1692095301483 Sync.AddonUtils DEBUG Obtaining install for @contain-facebook 1692095301483 Sync.AddonUtils INFO Installing @contain-facebook 1692095301650 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095301650 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095301650 Sync.Service INFO Starting sync at 2023-08-15 06:28:21 in browser session ItJ6XSTzsucL 1692095301650 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095301650 Sync.Service INFO Cannot start sync: already syncing? 1692095301822 Sync.AddonUtils INFO Setting syncGUID of Facebook Container: {bb9eae93-5618-4de5-a684-a42e34072e7e} 1692095301828 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to @contain-facebook 1692095301828 Sync.AddonsReconciler DEBUG Rectifying state for addon Facebook Container (version=2.3.11, id=@contain-facebook) 1692095301828 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: @contain-facebook 1692095301828 Sync.AddonsReconciler INFO Change recorded for @contain-facebook 1692095301828 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 @contain-facebook 1692095301828 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095301829 Sync.Engine.Addons.Store INFO Add-on installed: @contain-facebook 1692095301950 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095301950 Sync.AddonUtils INFO Add-on "{bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf}" is able to be installed 1692095301950 Sync.AddonUtils INFO Trying to install add-on from search result: {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095301950 Sync.AddonUtils DEBUG Obtaining install for {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095301950 Sync.AddonUtils INFO Installing {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095302142 Sync.AddonUtils INFO Setting syncGUID of Zoom Extension: {bda28c82-e5af-497c-bfaa-9d535f31b4c8} 1692095302148 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095302148 Sync.AddonsReconciler DEBUG Rectifying state for addon Zoom Extension (version=2.1.46, id={bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf}) 1692095302148 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095302148 Sync.AddonsReconciler INFO Change recorded for {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095302148 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095302148 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095302149 Sync.Engine.Addons.Store INFO Add-on installed: {bf855ead-d7c3-4c7b-9f88-9a7e75c0efdf} 1692095302268 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095302268 Sync.AddonUtils INFO Add-on "firefoxdav@icloud.com" is able to be installed 1692095302268 Sync.AddonUtils INFO Trying to install add-on from search result: firefoxdav@icloud.com 1692095302268 Sync.AddonUtils DEBUG Obtaining install for firefoxdav@icloud.com 1692095302268 Sync.AddonUtils INFO Installing firefoxdav@icloud.com 1692095302422 Sync.SyncScheduler DEBUG Global Score threshold hit, triggering sync. 1692095302426 Sync.Service DEBUG User-Agent: Firefox/117.0 (Windows NT 10.0; Win64; x64) FxSync/1.119.0.20230813180142.desktop 1692095302426 Sync.Service INFO Starting sync at 2023-08-15 06:28:22 in browser session ItJ6XSTzsucL 1692095302426 Sync.Service DEBUG Exception calling anonymous function: LockException: Could not acquire lock. Label: "service.js: sync".(resource://services-sync/util.sys.mjs:45:5) JS Stack trace: LockException@util.sys.mjs:45:5 throwLockException@util.sys.mjs:129:11 WrappedLock@util.sys.mjs:143:15 _lockedSync@service.sys.mjs:1369:6 sync/<@service.sys.mjs:1334:18 WrappedCatch@util.sys.mjs:114:27 sync@service.sys.mjs:1335:7 1692095302426 Sync.Service INFO Cannot start sync: already syncing? 1692095302429 Sync.AddonUtils INFO Setting syncGUID of iCloud Bookmarks: {d446ab83-7790-4fc3-a111-7c0ab1d5daf4} 1692095302429 Sync.AddonUtils INFO Marking add-on as disabled for install: iCloud Bookmarks 1692095302433 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to firefoxdav@icloud.com 1692095302433 Sync.AddonsReconciler DEBUG Rectifying state for addon iCloud Bookmarks (version=2.3.40, id=firefoxdav@icloud.com) 1692095302433 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: firefoxdav@icloud.com 1692095302433 Sync.AddonsReconciler INFO Change recorded for firefoxdav@icloud.com 1692095302433 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 firefoxdav@icloud.com 1692095302434 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095302434 Sync.Engine.Addons.Store INFO Add-on installed: firefoxdav@icloud.com 1692095302555 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095302555 Sync.AddonUtils INFO Add-on "{4a17faf9-99de-4959-a5ed-b3fa74a98434}" is able to be installed 1692095302555 Sync.AddonUtils INFO Trying to install add-on from search result: {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302555 Sync.AddonUtils DEBUG Obtaining install for {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302555 Sync.AddonUtils INFO Installing {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302773 Sync.AddonUtils INFO Setting syncGUID of IPTC Photo Metadata inspector: {db62aad0-a583-4f99-88ff-bf9f0fadad1d} 1692095302777 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302777 Sync.AddonsReconciler DEBUG Rectifying state for addon IPTC Photo Metadata inspector (version=0.3, id={4a17faf9-99de-4959-a5ed-b3fa74a98434}) 1692095302777 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302777 Sync.AddonsReconciler INFO Change recorded for {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302777 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302778 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095302778 Sync.Engine.Addons.Store INFO Add-on installed: {4a17faf9-99de-4959-a5ed-b3fa74a98434} 1692095302905 Sync.AddonUtils INFO Found 1 / 1 add-ons during repository search. 1692095302905 Sync.AddonUtils INFO Add-on "wrc@avast.com" is able to be installed 1692095302905 Sync.AddonUtils INFO Trying to install add-on from search result: wrc@avast.com 1692095302905 Sync.AddonUtils DEBUG Obtaining install for wrc@avast.com 1692095302905 Sync.AddonUtils INFO Installing wrc@avast.com 1692095302998 Sync.Engine.Tabs.Tracker DEBUG Detected a tab change, but conditions aren't met for a quick write or a score bump 1692095303292 Sync.AddonUtils INFO Setting syncGUID of Avast Online Security & Privacy: {f548711b-d4fe-41b4-811e-316089f71d55} 1692095303293 Sync.AddonUtils INFO Marking add-on as disabled for install: Avast Online Security & Privacy 1692095303305 Sync.AddonsReconciler DEBUG Add-on change: onInstalled to wrc@avast.com 1692095303305 Sync.AddonsReconciler DEBUG Rectifying state for addon Avast Online Security & Privacy (version=22.11.180, id=wrc@avast.com) 1692095303305 Sync.AddonsReconciler DEBUG Adding change because add-on not present locally: wrc@avast.com 1692095303305 Sync.AddonsReconciler INFO Change recorded for wrc@avast.com 1692095303305 Sync.Engine.Addons.Tracker DEBUG changeListener invoked: 1 wrc@avast.com 1692095303306 Sync.AddonsReconciler INFO Saving reconciler state to file: addonsreconciler 1692095303307 Sync.Engine.Addons.Store INFO Add-on installed: wrc@avast.com 1692095303307 Sync.Engine.Addons INFO Records: 36 applied, 15 successfully, 21 failed to apply, 21 newly failed to apply, 4 reconciled. 1692095303307 Sync.Status DEBUG Status for engine addons: error.engine.reason.apply_fail 1692095303307 Sync.Status DEBUG Status.service: success.status_ok => error.sync.failed_partial 1692095303307 Sync.ErrorHandler DEBUG addons failed to apply some records. 1692095303307 Sync.SyncScheduler ERROR Engine addons found 21 new records that failed to apply 1692095303307 Sync.Engine.Addons INFO Uploading 0 outgoing records 1692095303320 Sync.Engine.Forms DEBUG Engine syncIDs: 5l2Qdj4N-M71, 1692095303320 Sync.Engine.Forms DEBUG First sync, uploading all items 1692095303322 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095303433 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/forms?full=1&limit=1000 1692095303526 Sync.Engine.Forms INFO Records: 112 applied, 112 successfully, 0 failed to apply, 0 newly failed to apply, 3 reconciled. 1692095303526 Sync.Engine.Forms INFO Uploading 1 outgoing records 1692095303527 Sync.Engine.Forms INFO Posting 1 records of 298 bytes with batch=true 1692095303527 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095303527 Sync.Collection DEBUG POST Length: 298 1692095303656 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/forms?batch=true&commit=true 1692095303657 Sync.Engine.History DEBUG Checking if server sync ID U8jUupIBNlh2 matches existing 1692095303657 Sync.Engine.History.HistorySyncUtils INFO History sync ID changed; resetting metadata: {"existingSyncId":"","newSyncId":"U8jUupIBNlh2"} 1692095303658 Sync.Engine.History DEBUG First sync, uploading all items 1692095303661 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095303773 Sync.Collection DEBUG GET success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/history?full=1&sort=newest&limit=1000 1692095303773 Sync.Engine.History INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1692095303773 Sync.Engine.History INFO Uploading 15 outgoing records 1692095303781 Sync.Engine.History INFO Posting 15 records of 7485 bytes with batch=true 1692095303782 FirefoxAccounts TRACE not checking freshness of profile as it remains recent 1692095303782 Sync.Collection DEBUG POST Length: 7485 1692095303919 Sync.Collection DEBUG POST success 200 https://sync-1-us-west1-g.sync.services.mozilla.com/1.5/166046715/storage/history?batch=true&commit=true 1692095303923 Sync.Engine.Extension-Storage INFO Records: 0 applied, 0 successfully, 0 failed to apply, 0 newly failed to apply, 0 reconciled. 1692095303924 Sync.Engine.Extension-Storage INFO Uploading 0 outgoing records 1692095303925 Sync.Doctor INFO Skipping check of prefs - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping check of passwords - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping check of tabs - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping check of bookmarks - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping check of addons - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping check of forms - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping check of history - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping check of extension-storage - disabled via preferences 1692095303925 Sync.Doctor INFO Skipping validation: no engines qualify 1692095303925 Sync.Synchronizer INFO Sync completed at 2023-08-15 06:28:23 after 14.39 secs. 1692095303925 Sync.Declined DEBUG Handling remote declined: [] 1692095303925 Sync.Declined DEBUG Handling local declined: [] 1692095303925 Sync.Declined DEBUG Declined changed? false 1692095303925 Sync.Service INFO No change to declined engines. Not reuploading meta/global. 1692095303925 Sync.ErrorHandler ERROR Some engines did not sync correctly. 1692095303926 Sync.SyncScheduler DEBUG Next sync in 3600000 ms. (why=schedule)