Closed Bug 1809526 Opened 1 year ago Closed 1 year ago

Intermittent toolkit/components/cleardata/tests/marionette/test_moved_origin_directory_cleanup.py MovedOriginDirectoryCleanupTestCase.test_ensure_cleanup_at_crashed_restart | marionette_driver.errors.TimeoutException

Categories

(Toolkit :: Data Sanitization, defect, P5)

defect

Tracking

()

RESOLVED FIXED
111 Branch
Tracking Status
firefox111 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: saschanaz)

References

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=401966636&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TvIZX39aSiCcXoe7LSMOTg/runs/0/artifacts/public/logs/live_backing.log


[task 2023-01-10T20:11:16.688Z] 20:11:16     INFO -  TEST-START | toolkit/components/cleardata/tests/marionette/test_moved_origin_directory_cleanup.py MovedOriginDirectoryCleanupTestCase.test_ensure_cleanup_at_crashed_restart
[task 2023-01-10T20:11:16.689Z] 20:11:16     INFO -  1673381476688	Marionette	DEBUG	Accepted connection 4 from 127.0.0.1:52298
[task 2023-01-10T20:11:16.721Z] 20:11:16     INFO -  1673381476722	Marionette	DEBUG	4 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2023-01-10T20:11:16.723Z] 20:11:16     INFO -  1673381476723	Marionette	DEBUG	Waiting for initial application window
[task 2023-01-10T20:11:16.724Z] 20:11:16     INFO -  1673381476724	RemoteAgent	TRACE	[9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=false
[task 2023-01-10T20:11:16.724Z] 20:11:16     INFO -  1673381476724	RemoteAgent	TRACE	[9] ProgressListener Setting unload timer (200ms)
[task 2023-01-10T20:11:16.724Z] 20:11:16     INFO -  1673381476724	RemoteAgent	TRACE	[9] Document already finished loading: about:blank
[task 2023-01-10T20:11:16.724Z] 20:11:16     INFO -  1673381476724	RemoteAgent	TRACE	[9] ProgressListener Stop: has error=false
[task 2023-01-10T20:11:16.726Z] 20:11:16     INFO -  1673381476726	Marionette	DEBUG	4 <- [1,1,null,{"sessionId":"562f23ba-3a87-49ad-8e22-3a16d730e86e","capabilities":{"browserName":"firefox","browserVersion":"110.0a1","platformName":"windows","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","moz:accessibilityChecks":false,"moz:buildID":"20230110191213","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":3728,"moz:profile":"C:\\Users\\task_167337880209386\\AppData\\Local\\Temp\\tmpd5kugmt_.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-01-10T20:11:16.727Z] 20:11:16     INFO -  1673381476727	Marionette	DEBUG	4 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2023-01-10T20:11:16.727Z] 20:11:16     INFO -  1673381476727	Marionette	DEBUG	4 <- [1,2,null,{"value":null}]
[task 2023-01-10T20:11:16.728Z] 20:11:16     INFO -  1673381476728	Marionette	DEBUG	4 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2023-01-10T20:11:16.728Z] 20:11:16     INFO -  1673381476728	Marionette	DEBUG	4 <- [1,3,null,{"value":null}]
[task 2023-01-10T20:11:16.729Z] 20:11:16     INFO -  1673381476729	Marionette	DEBUG	4 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-01-10T20:11:16.729Z] 20:11:16     INFO -  1673381476729	Marionette	DEBUG	4 <- [1,4,null,{"value":null}]
[task 2023-01-10T20:11:16.730Z] 20:11:16     INFO -  1673381476730	Marionette	DEBUG	4 -> [0,5,"Marionette:GetContext",{}]
[task 2023-01-10T20:11:16.730Z] 20:11:16     INFO -  1673381476730	Marionette	DEBUG	4 <- [1,5,null,{"value":"content"}]
[task 2023-01-10T20:11:16.731Z] 20:11:16     INFO -  1673381476731	Marionette	DEBUG	4 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-01-10T20:11:16.731Z] 20:11:16     INFO -  1673381476731	Marionette	DEBUG	4 <- [1,6,null,{"value":null}]
[task 2023-01-10T20:11:16.733Z] 20:11:16     INFO -  1673381476732	Marionette	DEBUG	4 -> [0,7,"WebDriver:ExecuteScript",{"script":"let prefInterface = Components.classes[\"@mozilla.org/preferences-service;1\"]\n                                              .g ... ref).toString();\n                    case prefInterface.PREF_INVALID:\n                        return false;\n                }","args":[],"newSandbox":true,"sandbox":"default","line":908,"filename":"Z:\\task_167337880209386\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2023-01-10T20:11:16.735Z] 20:11:16     INFO -  1673381476735	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 3728
[task 2023-01-10T20:11:16.736Z] 20:11:16     INFO -  1673381476736	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2023-01-10T20:11:16.739Z] 20:11:16     INFO -  1673381476739	Marionette	DEBUG	4 <- [1,7,null,{"value":true}]
[task 2023-01-10T20:11:16.740Z] 20:11:16     INFO -  1673381476740	Marionette	DEBUG	4 -> [0,8,"WebDriver:ExecuteScript",{"script":"let prefInterface = Components.classes[\"@mozilla.org/preferences-service;1\"]\n                                              .g ... ref).toString();\n                    case prefInterface.PREF_INVALID:\n                        return false;\n                }","args":[],"newSandbox":true,"sandbox":"default","line":908,"filename":"Z:\\task_167337880209386\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2023-01-10T20:11:16.741Z] 20:11:16     INFO -  1673381476741	Marionette	DEBUG	4 <- [1,8,null,{"value":false}]
[task 2023-01-10T20:11:16.742Z] 20:11:16     INFO -  1673381476742	Marionette	DEBUG	4 -> [0,9,"Marionette:SetContext",{"value":"content"}]
[task 2023-01-10T20:11:16.742Z] 20:11:16     INFO -  1673381476742	Marionette	DEBUG	4 <- [1,9,null,{"value":null}]
[task 2023-01-10T20:11:16.743Z] 20:11:16     INFO -  1673381476743	Marionette	DEBUG	4 -> [0,10,"Marionette:GetContext",{}]
[task 2023-01-10T20:11:16.743Z] 20:11:16     INFO -  1673381476743	Marionette	DEBUG	4 <- [1,10,null,{"value":"content"}]
[task 2023-01-10T20:11:16.744Z] 20:11:16     INFO -  1673381476744	Marionette	DEBUG	4 -> [0,11,"WebDriver:DeleteSession",{}]
[task 2023-01-10T20:11:16.744Z] 20:11:16     INFO -  1673381476744	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2023-01-10T20:11:16.746Z] 20:11:16     INFO -  1673381476746	Marionette	DEBUG	4 <- [1,11,null,{"value":null}]
[task 2023-01-10T20:11:16.747Z] 20:11:16     INFO -  1673381476746	Marionette	DEBUG	Closed connection 4
[task 2023-01-10T20:11:17.165Z] 20:11:17     INFO -  Application command: Z:\task_167337880209386\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile C:\Users\task_167337880209386\AppData\Local\Temp\tmpd8yt91sq.mozrunner
[task 2023-01-10T20:11:17.312Z] 20:11:17     INFO -  DEBUG: Adding blocker AddonManager: shutting down. for phase profile-before-change
[task 2023-01-10T20:11:17.337Z] 20:11:17     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change) for phase profile-before-change
[task 2023-01-10T20:11:17.344Z] 20:11:17     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change-telemetry) for phase profile-before-change-telemetry
[task 2023-01-10T20:11:17.344Z] 20:11:17     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change) for phase IOUtils: waiting for sendTelemetry IO to complete
[task 2023-01-10T20:11:17.344Z] 20:11:17     INFO -  DEBUG: Adding blocker IOUtils Blocker (xpcom-will-shutdown) for phase xpcom-will-shutdown
[task 2023-01-10T20:11:17.345Z] 20:11:17     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change-telemetry) for phase IOUtils: waiting for xpcomWillShutdown IO to complete
[task 2023-01-10T20:11:17.387Z] 20:11:17     INFO -  DEBUG: Adding blocker Flush WebExtension StartupCache for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:17.461Z] 20:11:17     INFO -  DEBUG: Adding blocker JSON store: writing data for 'addonStartup' for phase AddonManager: Waiting for providers to shut down.
[task 2023-01-10T20:11:17.475Z] 20:11:17     INFO -  DEBUG: Adding blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2023-01-10T20:11:17.478Z] 20:11:17     INFO -  DEBUG: Adding blocker XPIProvider shutdown for phase quit-application-granted
[task 2023-01-10T20:11:17.479Z] 20:11:17     INFO -  DEBUG: Adding blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2023-01-10T20:11:17.480Z] 20:11:17     INFO -  DEBUG: Adding blocker ServiceWorkerRegistrar: Flushing data for phase profile-before-change
[task 2023-01-10T20:11:17.481Z] 20:11:17     INFO -  DEBUG: Adding blocker CrashMonitor: Writing notifications to file after receiving profile-before-change and awaiting all checkpoints written for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:17.492Z] 20:11:17     INFO -  DEBUG: Adding blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2023-01-10T20:11:17.534Z] 20:11:17     INFO -  DEBUG: Adding blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2023-01-10T20:11:17.545Z] 20:11:17     INFO -  DEBUG: Adding blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2023-01-10T20:11:17.548Z] 20:11:17     INFO -  DEBUG: Adding blocker TerminatoryTelemetry: Waiting to submit telemetry for phase IOUtils: waiting for sendTelemetry IO to complete
[task 2023-01-10T20:11:17.550Z] 20:11:17     INFO -  1673381477550	Marionette	INFO	Marionette enabled
[task 2023-01-10T20:11:17.552Z] 20:11:17     INFO -  1673381477552	Marionette	TRACE	Received observer notification final-ui-startup
[task 2023-01-10T20:11:17.553Z] 20:11:17     INFO -  1673381477553	Marionette	INFO	Listening on port 2828
[task 2023-01-10T20:11:17.554Z] 20:11:17     INFO -  1673381477553	Marionette	DEBUG	Marionette is listening
[task 2023-01-10T20:11:17.612Z] 20:11:17     INFO -  DEBUG: Adding blocker PermissionManager: Flushing data for phase xpcom-will-shutdown
[task 2023-01-10T20:11:17.635Z] 20:11:17     INFO -  DEBUG: Completed blocker Update add-on blocklist state into add-on DB for phase AddonManager: Waiting to start provider shutdown.
[task 2023-01-10T20:11:17.641Z] 20:11:17     INFO -  DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2023-01-10T20:11:19.496Z] 20:11:19     INFO -  1673381479501	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:52299
[task 2023-01-10T20:11:19.504Z] 20:11:19     INFO -  1673381479504	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:52302
[task 2023-01-10T20:11:19.625Z] 20:11:19     INFO -  DEBUG: Adding blocker MediaShutdownManager: shutdown for phase profile-before-change
[task 2023-01-10T20:11:19.637Z] 20:11:19     INFO -  1673381479640	Marionette	DEBUG	Closed connection 0
[task 2023-01-10T20:11:19.650Z] 20:11:19     INFO -  1673381479649	Marionette	DEBUG	Closed connection 1
[task 2023-01-10T20:11:19.692Z] 20:11:19     INFO -  DEBUG: Adding blocker ServiceWorkerShutdownBlocker: shutting down Service Workers for phase profile-change-teardown
[task 2023-01-10T20:11:19.698Z] 20:11:19     INFO -  DEBUG: Adding blocker ContentParent: id=22e12200 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:19.699Z] 20:11:19     INFO -  DEBUG: Adding blocker ContentParent: id=22e12200 for phase profile-before-change
[task 2023-01-10T20:11:19.699Z] 20:11:19     INFO -  DEBUG: Adding blocker ContentParent: id=22e12200 for phase quit-application-granted
[task 2023-01-10T20:11:19.705Z] 20:11:19     INFO -  DEBUG: Adding blocker SitePermsAddonProvider for phase AddonManager: Waiting for providers to shut down.
[task 2023-01-10T20:11:19.706Z] 20:11:19     INFO -  DEBUG: Adding blocker GMPProvider for phase AddonManager: Waiting for providers to shut down.
[task 2023-01-10T20:11:19.717Z] 20:11:19     INFO -  DEBUG: Adding blocker JSON store: writing data for 'handlers' for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:19.779Z] 20:11:19     INFO -  DEBUG: Adding blocker ContentParent: id=24db8400 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:19.779Z] 20:11:19     INFO -  DEBUG: Adding blocker ContentParent: id=24db8400 for phase profile-before-change
[task 2023-01-10T20:11:19.779Z] 20:11:19     INFO -  DEBUG: Adding blocker ContentParent: id=24db8400 for phase quit-application-granted
[task 2023-01-10T20:11:19.870Z] 20:11:19     INFO -  1673381479872	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:52303
[task 2023-01-10T20:11:19.884Z] 20:11:19     INFO -  1673381479898	Marionette	DEBUG	Closed connection 2
[task 2023-01-10T20:11:19.898Z] 20:11:19     INFO -  1673381479898	Marionette	DEBUG	Accepted connection 3 from 127.0.0.1:52304
[task 2023-01-10T20:11:19.972Z] 20:11:19     INFO -  1673381479973	Marionette	DEBUG	3 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2023-01-10T20:11:19.980Z] 20:11:19     INFO -  1673381479980	Marionette	DEBUG	Waiting for initial application window
[task 2023-01-10T20:11:20.022Z] 20:11:20     INFO -  DEBUG: Adding blocker CookieBannerDomainPrefService: write content pref before profile-before-change. for phase profile-before-change
[task 2023-01-10T20:11:20.023Z] 20:11:20     INFO -  DEBUG: Adding blocker Sqlite.sys.mjs shutdown blocker for phase profile-before-change
[task 2023-01-10T20:11:20.023Z] 20:11:20     INFO -  DEBUG: Adding blocker content-prefs.sqlite#0: waiting for shutdown for phase Sqlite.sys.mjs: wait until all connections are closed
[task 2023-01-10T20:11:20.024Z] 20:11:20     INFO -  DEBUG: Adding blocker Closing ContentPrefService2 connection. for phase Sqlite.sys.mjs: wait until all clients have completed their task
[task 2023-01-10T20:11:20.025Z] 20:11:20     INFO -  DEBUG: Adding blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:20.551Z] 20:11:20     INFO -  DEBUG: Completed blocker TerminatoryTelemetry: Waiting to submit telemetry for phase IOUtils: waiting for sendTelemetry IO to complete
[task 2023-01-10T20:11:20.704Z] 20:11:20     INFO -  DEBUG: Adding blocker ContentParent: id=26f7e200 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:20.705Z] 20:11:20     INFO -  DEBUG: Adding blocker ContentParent: id=26f7e200 for phase profile-before-change
[task 2023-01-10T20:11:20.705Z] 20:11:20     INFO -  DEBUG: Adding blocker ContentParent: id=26f7e200 for phase quit-application-granted
[task 2023-01-10T20:11:20.713Z] 20:11:20     INFO -  DEBUG: Adding blocker ContentParent: id=26f7cd00 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:20.713Z] 20:11:20     INFO -  DEBUG: Adding blocker ContentParent: id=26f7cd00 for phase profile-before-change
[task 2023-01-10T20:11:20.713Z] 20:11:20     INFO -  DEBUG: Adding blocker ContentParent: id=26f7cd00 for phase quit-application-granted
[task 2023-01-10T20:11:23.624Z] 20:11:23     INFO -  DEBUG: Adding blocker PageActions: purging unregistered actions from cache for phase profile-before-change
[task 2023-01-10T20:11:23.634Z] 20:11:23     INFO -  DEBUG: Adding blocker Places Clients shutdown for phase profile-change-teardown
[task 2023-01-10T20:11:23.634Z] 20:11:23     INFO -  DEBUG: Adding blocker Places Connection shutdown for phase profile-before-change
[task 2023-01-10T20:11:23.657Z] 20:11:23     INFO -  DEBUG: Adding blocker sanitize.js: Sanitize on shutdown for phase Places Clients shutdown
[task 2023-01-10T20:11:23.669Z] 20:11:23     INFO -  DEBUG: Completed blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.674Z] 20:11:23     INFO -  console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167337880209386\\AppData\\Local\\Temp\\tmpd8yt91sq.mozrunner\\search.json.mozlz4", (void 0)))
[task 2023-01-10T20:11:23.687Z] 20:11:23     INFO -  *** You are running in background task mode. ***
[task 2023-01-10T20:11:23.688Z] 20:11:23     INFO -  *** You are running in headless mode.
[task 2023-01-10T20:11:23.696Z] 20:11:23     INFO -  DEBUG: Adding blocker Transaction (1) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.698Z] 20:11:23     INFO -  DEBUG: Adding blocker Transaction (2) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.699Z] 20:11:23     INFO -  DEBUG: Adding blocker Transaction (3) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.716Z] 20:11:23     INFO -  DEBUG: Completed blocker Transaction (1) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.731Z] 20:11:23     INFO -  DEBUG: Adding blocker Transaction (4) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.735Z] 20:11:23     INFO -  DEBUG: Completed blocker Transaction (2) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.746Z] 20:11:23     INFO -  DEBUG: Completed blocker Transaction (3) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.916Z] 20:11:23     INFO -  DEBUG: Completed blocker Transaction (4) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:23.924Z] 20:11:23     INFO -  DEBUG: Adding blocker ContextualIdentityService: writing data for phase profile-before-change
[task 2023-01-10T20:11:23.939Z] 20:11:23     INFO -  DEBUG: Adding blocker JSON store: writing data for 'logins' for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:23.952Z] 20:11:23     INFO -  console.error: "C:\\Users\\task_167337880209386\\AppData\\Local\\Temp\\tmpd8yt91sq.mozrunner" "" 0 ".purge.bg_rm"
[task 2023-01-10T20:11:28.832Z] 20:11:28     INFO -  DEBUG: Adding blocker places.sqlite#0: waiting for shutdown for phase Sqlite.sys.mjs: wait until all connections are closed
[task 2023-01-10T20:11:28.834Z] 20:11:28     INFO -  DEBUG: Adding blocker PlacesUtils wrapped connection closing as part of Places shutdown for phase Places Connection shutdown
[task 2023-01-10T20:11:28.834Z] 20:11:28     INFO -  DEBUG: Adding blocker PlacesUtils wrapped connection must be closed before Sqlite.sys.mjs for phase Sqlite.sys.mjs: wait until all clients have completed their task
[task 2023-01-10T20:11:28.839Z] 20:11:28     INFO -  DEBUG: Adding blocker places.sqlite#0: PlacesUIUtils: maybeAddImportButton (0) for phase places.sqlite#0: waiting for clients
[task 2023-01-10T20:11:28.846Z] 20:11:28     INFO -  DEBUG: Adding blocker Places Expiration: shutdown for phase Places Connection shutdown
[task 2023-01-10T20:11:28.848Z] 20:11:28     INFO -  DEBUG: Adding blocker places.sqlite#0: PlacesExpiration.jsm: setup (1) for phase places.sqlite#0: waiting for clients
[task 2023-01-10T20:11:28.848Z] 20:11:28     INFO -  DEBUG: Completed blocker places.sqlite#0: PlacesUIUtils: maybeAddImportButton (0) for phase places.sqlite#0: waiting for clients
[task 2023-01-10T20:11:28.852Z] 20:11:28     INFO -  DEBUG: Completed blocker places.sqlite#0: PlacesExpiration.jsm: setup (1) for phase places.sqlite#0: waiting for clients
[task 2023-01-10T20:11:28.894Z] 20:11:28     INFO -  DEBUG: Adding blocker ContentParent: id=28b9a200 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:28.895Z] 20:11:28     INFO -  DEBUG: Adding blocker ContentParent: id=28b9a200 for phase profile-before-change
[task 2023-01-10T20:11:28.896Z] 20:11:28     INFO -  DEBUG: Adding blocker ContentParent: id=28b9a200 for phase quit-application-granted
[task 2023-01-10T20:11:29.015Z] 20:11:29     INFO -  1673381489015	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2023-01-10T20:11:29.016Z] 20:11:29     INFO -  DEBUG: Adding blocker ScriptPreloader: Saving bytecode cache for phase xpcom-will-shutdown
[task 2023-01-10T20:11:29.020Z] 20:11:29     INFO -  1673381489019	RemoteAgent	TRACE	[9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=false
[task 2023-01-10T20:11:29.020Z] 20:11:29     INFO -  1673381489020	RemoteAgent	TRACE	[9] ProgressListener Setting unload timer (200ms)
[task 2023-01-10T20:11:29.020Z] 20:11:29     INFO -  1673381489020	RemoteAgent	TRACE	[9] Document already finished loading: about:blank
[task 2023-01-10T20:11:29.021Z] 20:11:29     INFO -  1673381489020	RemoteAgent	TRACE	[9] ProgressListener Stop: has error=false
[task 2023-01-10T20:11:29.032Z] 20:11:29     INFO -  1673381489031	Marionette	DEBUG	3 <- [1,1,null,{"sessionId":"29642077-8f53-483c-9b7b-77f2d1292bf5","capabilities":{"browserName":"firefox","browserVersion":"110.0a1","platformName":"windows","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","moz:accessibilityChecks":false,"moz:buildID":"20230110191213","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":6960,"moz:profile":"C:\\Users\\task_167337880209386\\AppData\\Local\\Temp\\tmpd8yt91sq.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-01-10T20:11:29.043Z] 20:11:29     INFO -  1673381489045	Marionette	DEBUG	3 -> [0,2,"Marionette:SetContext",{"value":"content"}]
[task 2023-01-10T20:11:29.045Z] 20:11:29     INFO -  1673381489045	Marionette	DEBUG	3 <- [1,2,null,{"value":null}]
[task 2023-01-10T20:11:29.168Z] 20:11:29     INFO -  1673381489178	Marionette	DEBUG	3 -> [0,3,"Marionette:GetContext",{}]
[task 2023-01-10T20:11:29.179Z] 20:11:29     INFO -  1673381489178	Marionette	DEBUG	3 <- [1,3,null,{"value":"content"}]
[task 2023-01-10T20:11:29.180Z] 20:11:29     INFO -  1673381489180	Marionette	DEBUG	3 -> [0,4,"WebDriver:DeleteSession",{}]
[task 2023-01-10T20:11:29.182Z] 20:11:29     INFO -  1673381489182	Marionette	DEBUG	3 <- [1,4,null,{"value":null}]
[task 2023-01-10T20:11:29.534Z] 20:11:29     INFO -  Application command: Z:\task_167337880209386\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile C:\Users\task_167337880209386\AppData\Local\Temp\tmpd8yt91sq.mozrunner
[task 2023-01-10T20:11:29.688Z] 20:11:29     INFO -  DEBUG: Adding blocker AddonManager: shutting down. for phase profile-before-change
[task 2023-01-10T20:11:29.716Z] 20:11:29     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change) for phase profile-before-change
[task 2023-01-10T20:11:29.721Z] 20:11:29     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change-telemetry) for phase profile-before-change-telemetry
[task 2023-01-10T20:11:29.722Z] 20:11:29     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change) for phase IOUtils: waiting for sendTelemetry IO to complete
[task 2023-01-10T20:11:29.722Z] 20:11:29     INFO -  DEBUG: Adding blocker IOUtils Blocker (xpcom-will-shutdown) for phase xpcom-will-shutdown
[task 2023-01-10T20:11:29.722Z] 20:11:29     INFO -  DEBUG: Adding blocker IOUtils Blocker (profile-before-change-telemetry) for phase IOUtils: waiting for xpcomWillShutdown IO to complete
[task 2023-01-10T20:11:29.724Z] 20:11:29     INFO -  DEBUG: Adding blocker XPIProvider shutdown for phase quit-application-granted
[task 2023-01-10T20:11:29.725Z] 20:11:29     INFO -  DEBUG: Adding blocker XPIProvider for phase AddonManager: Waiting for providers to shut down.
[task 2023-01-10T20:11:29.725Z] 20:11:29     INFO -  DEBUG: Adding blocker ServiceWorkerRegistrar: Flushing data for phase profile-before-change
[task 2023-01-10T20:11:29.726Z] 20:11:29     INFO -  DEBUG: Adding blocker CrashMonitor: Writing notifications to file after receiving profile-before-change and awaiting all checkpoints written for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:29.738Z] 20:11:29     INFO -  DEBUG: Adding blocker TelemetryController: shutting down for phase profile-before-change-telemetry
[task 2023-01-10T20:11:29.771Z] 20:11:29     INFO -  DEBUG: Adding blocker EnvironmentAddonBuilder for phase AddonManager: Waiting to start provider shutdown.
[task 2023-01-10T20:11:29.782Z] 20:11:29     INFO -  DEBUG: Adding blocker RemoteSettingsClient - finish IDB access. for phase profile-before-change
[task 2023-01-10T20:11:29.785Z] 20:11:29     INFO -  DEBUG: Adding blocker TerminatoryTelemetry: Waiting to submit telemetry for phase IOUtils: waiting for sendTelemetry IO to complete
[task 2023-01-10T20:11:29.787Z] 20:11:29     INFO -  1673381489787	Marionette	INFO	Marionette enabled
[task 2023-01-10T20:11:29.789Z] 20:11:29     INFO -  1673381489789	Marionette	TRACE	Received observer notification final-ui-startup
[task 2023-01-10T20:11:29.790Z] 20:11:29     INFO -  1673381489790	Marionette	INFO	Listening on port 2828
[task 2023-01-10T20:11:29.790Z] 20:11:29     INFO -  1673381489790	Marionette	DEBUG	Marionette is listening
[task 2023-01-10T20:11:29.864Z] 20:11:29     INFO -  DEBUG: Adding blocker PermissionManager: Flushing data for phase xpcom-will-shutdown
[task 2023-01-10T20:11:29.877Z] 20:11:29     INFO -  DEBUG: Adding blocker ClientManagerService: start destroying IPC actors early for phase xpcom-will-shutdown
[task 2023-01-10T20:11:29.890Z] 20:11:29     INFO -  DEBUG: Adding blocker Flush WebExtension StartupCache for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:29.980Z] 20:11:29     INFO -  DEBUG: Adding blocker MediaShutdownManager: shutdown for phase profile-before-change
[task 2023-01-10T20:11:30.076Z] 20:11:30     INFO -  1673381490088	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:52307
[task 2023-01-10T20:11:30.091Z] 20:11:30     INFO -  1673381490091	Marionette	DEBUG	Closed connection 0
[task 2023-01-10T20:11:30.181Z] 20:11:30     INFO -  DEBUG: Adding blocker JSON store: writing data for 'handlers' for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:30.192Z] 20:11:30     INFO -  DEBUG: Adding blocker ContentParent: id=1a7a2f00 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:30.192Z] 20:11:30     INFO -  DEBUG: Adding blocker ContentParent: id=1a7a2f00 for phase profile-before-change
[task 2023-01-10T20:11:30.192Z] 20:11:30     INFO -  DEBUG: Adding blocker ContentParent: id=1a7a2f00 for phase quit-application-granted
[task 2023-01-10T20:11:30.199Z] 20:11:30     INFO -  DEBUG: Adding blocker SitePermsAddonProvider for phase AddonManager: Waiting for providers to shut down.
[task 2023-01-10T20:11:30.200Z] 20:11:30     INFO -  DEBUG: Adding blocker GMPProvider for phase AddonManager: Waiting for providers to shut down.
[task 2023-01-10T20:11:30.274Z] 20:11:30     INFO -  DEBUG: Adding blocker ServiceWorkerShutdownBlocker: shutting down Service Workers for phase profile-change-teardown
[task 2023-01-10T20:11:30.293Z] 20:11:30     INFO -  1673381490293	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:52310
[task 2023-01-10T20:11:30.320Z] 20:11:30     INFO -  1673381490333	Marionette	DEBUG	Closed connection 1
[task 2023-01-10T20:11:30.334Z] 20:11:30     INFO -  1673381490333	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:52311
[task 2023-01-10T20:11:30.399Z] 20:11:30     INFO -  1673381490402	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2023-01-10T20:11:30.407Z] 20:11:30     INFO -  1673381490406	Marionette	DEBUG	Waiting for initial application window
[task 2023-01-10T20:11:30.417Z] 20:11:30     INFO -  DEBUG: Adding blocker CookieBannerDomainPrefService: write content pref before profile-before-change. for phase profile-before-change
[task 2023-01-10T20:11:30.420Z] 20:11:30     INFO -  DEBUG: Adding blocker Sqlite.sys.mjs shutdown blocker for phase profile-before-change
[task 2023-01-10T20:11:30.421Z] 20:11:30     INFO -  DEBUG: Adding blocker content-prefs.sqlite#0: waiting for shutdown for phase Sqlite.sys.mjs: wait until all connections are closed
[task 2023-01-10T20:11:30.421Z] 20:11:30     INFO -  DEBUG: Adding blocker Closing ContentPrefService2 connection. for phase Sqlite.sys.mjs: wait until all clients have completed their task
[task 2023-01-10T20:11:30.421Z] 20:11:30     INFO -  DEBUG: Adding blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:30.422Z] 20:11:30     INFO -  DEBUG: Adding blocker Transaction (1) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:30.423Z] 20:11:30     INFO -  DEBUG: Completed blocker Transaction (0) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:30.425Z] 20:11:30     INFO -  DEBUG: Completed blocker Transaction (1) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:31.165Z] 20:11:31     INFO -  DEBUG: Adding blocker PageActions: purging unregistered actions from cache for phase profile-before-change
[task 2023-01-10T20:11:31.183Z] 20:11:31     INFO -  DEBUG: Adding blocker Places Clients shutdown for phase profile-change-teardown
[task 2023-01-10T20:11:31.185Z] 20:11:31     INFO -  DEBUG: Adding blocker Places Connection shutdown for phase profile-before-change
[task 2023-01-10T20:11:31.206Z] 20:11:31     INFO -  DEBUG: Adding blocker sanitize.js: Sanitize on shutdown for phase Places Clients shutdown
[task 2023-01-10T20:11:31.217Z] 20:11:31     INFO -  console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167337880209386\\AppData\\Local\\Temp\\tmpd8yt91sq.mozrunner\\search.json.mozlz4", (void 0)))
[task 2023-01-10T20:11:31.228Z] 20:11:31     INFO -  *** You are running in background task mode. ***
[task 2023-01-10T20:11:31.228Z] 20:11:31     INFO -  *** You are running in headless mode.
[task 2023-01-10T20:11:31.245Z] 20:11:31     INFO -  DEBUG: Adding blocker Transaction (2) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:31.250Z] 20:11:31     INFO -  DEBUG: Adding blocker Remote Settings profile-before-change for phase profile-before-change
[task 2023-01-10T20:11:31.263Z] 20:11:31     INFO -  DEBUG: Completed blocker Transaction (2) for phase content-prefs.sqlite#0: waiting for clients
[task 2023-01-10T20:11:31.278Z] 20:11:31     INFO -  DEBUG: Adding blocker ContentParent: id=1e39d200 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:31.281Z] 20:11:31     INFO -  DEBUG: Adding blocker ContentParent: id=1e39d200 for phase profile-before-change
[task 2023-01-10T20:11:31.282Z] 20:11:31     INFO -  DEBUG: Adding blocker ContentParent: id=1e39d200 for phase quit-application-granted
[task 2023-01-10T20:11:31.309Z] 20:11:31     INFO -  DEBUG: Adding blocker JSON store: writing data for 'shieldpreferenceexperiments' for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:31.324Z] 20:11:31     INFO -  console.error: "C:\\Users\\task_167337880209386\\AppData\\Local\\Temp\\tmpd8yt91sq.mozrunner" "" 0 ".purge.bg_rm"
[task 2023-01-10T20:11:31.326Z] 20:11:31     INFO -  console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-01-10T20:11:31.355Z] 20:11:31     INFO -  DEBUG: Adding blocker ContextualIdentityService: writing data for phase profile-before-change
[task 2023-01-10T20:11:31.387Z] 20:11:31     INFO -  DEBUG: Adding blocker JSON store: writing data for 'logins' for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:31.571Z] 20:11:31     INFO -  DEBUG: Adding blocker DownloadAutoSaveView: writing data for phase profile-before-change
[task 2023-01-10T20:11:31.605Z] 20:11:31     INFO -  1673381491607	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2023-01-10T20:11:31.608Z] 20:11:31     INFO -  DEBUG: Adding blocker ScriptPreloader: Saving bytecode cache for phase xpcom-will-shutdown
[task 2023-01-10T20:11:31.611Z] 20:11:31     INFO -  1673381491611	RemoteAgent	TRACE	[3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=200 waitForExplicitStart=false
[task 2023-01-10T20:11:31.612Z] 20:11:31     INFO -  1673381491612	RemoteAgent	TRACE	[3] ProgressListener Setting unload timer (200ms)
[task 2023-01-10T20:11:31.612Z] 20:11:31     INFO -  1673381491612	RemoteAgent	TRACE	[3] Document already finished loading: about:blank
[task 2023-01-10T20:11:31.613Z] 20:11:31     INFO -  1673381491612	RemoteAgent	TRACE	[3] ProgressListener Stop: has error=false
[task 2023-01-10T20:11:31.623Z] 20:11:31     INFO -  1673381491622	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"4baf3dcc-1239-4b50-bcf1-e360056b9ae1","capabilities":{"browserName":"firefox","browserVersion":"110.0a1","platformName":"windows","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","moz:accessibilityChecks":false,"moz:buildID":"20230110191213","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":1752,"moz:profile":"C:\\Users\\task_167337880209386\\AppData\\Local\\Temp\\tmpd8yt91sq.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2023-01-10T20:11:31.627Z] 20:11:31     INFO -  1673381491627	Marionette	DEBUG	2 -> [0,2,"Marionette:SetContext",{"value":"content"}]
[task 2023-01-10T20:11:31.627Z] 20:11:31     INFO -  1673381491627	Marionette	DEBUG	2 <- [1,2,null,{"value":null}]
[task 2023-01-10T20:11:32.500Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc7b00 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:32.501Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc7b00 for phase profile-before-change
[task 2023-01-10T20:11:32.501Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc7b00 for phase quit-application-granted
[task 2023-01-10T20:11:32.501Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc8400 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:32.501Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc8400 for phase profile-before-change
[task 2023-01-10T20:11:32.502Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc8400 for phase quit-application-granted
[task 2023-01-10T20:11:32.513Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc8d00 for phase xpcom-will-shutdown
[task 2023-01-10T20:11:32.514Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc8d00 for phase profile-before-change
[task 2023-01-10T20:11:32.515Z] 20:11:32     INFO -  DEBUG: Adding blocker ContentParent: id=1ecc8d00 for phase quit-application-granted
[task 2023-01-10T20:11:32.792Z] 20:11:32     INFO -  DEBUG: Completed blocker TerminatoryTelemetry: Waiting to submit telemetry for phase IOUtils: waiting for sendTelemetry IO to complete
[task 2023-01-10T20:11:36.488Z] 20:11:36     INFO -  DEBUG: Adding blocker JSON store: writing data for 'ExperimentStoreData' for phase IOUtils: waiting for profileBeforeChange IO to complete
[task 2023-01-10T20:11:36.650Z] 20:11:36     INFO -  1673381496651	Marionette	DEBUG	2 -> [0,3,"Marionette:GetContext",{}]
[task 2023-01-10T20:11:36.652Z] 20:11:36     INFO -  1673381496652	Marionette	DEBUG	2 <- [1,3,null,{"value":"content"}]
[task 2023-01-10T20:11:36.653Z] 20:11:36     INFO -  1673381496652	Marionette	DEBUG	2 -> [0,4,"Marionette:SetContext",{"value":"chrome"}]
[task 2023-01-10T20:11:36.653Z] 20:11:36     INFO -  1673381496653	Marionette	DEBUG	2 <- [1,4,null,{"value":null}]
[task 2023-01-10T20:11:36.653Z] 20:11:36     INFO -  1673381496653	Marionette	DEBUG	2 -> [0,5,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2023-01-10T20:11:36.657Z] 20:11:36     INFO -  1673381496657	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 1752
[task 2023-01-10T20:11:36.658Z] 20:11:36     INFO -  1673381496657	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2023-01-10T20:11:36.691Z] 20:11:36     INFO -  1673381496692	Marionette	DEBUG	2 <- [1,5,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAP/CAYAAACf8GEBAAAgAElEQVR4XuzdC5hlZXkn+q/6fuMOcrFFVBCdQURUwIDG63kYfY7iiRpNSKIYY5TEW4yXeZ4xmMk5i ... YAQIECBAgQIAAAQIECBAgQIAAgYKAA7Cwko4ECBAgQIAAAQIECBAgQIAAAQIETgEH4AknRoAAAQIECBAgQIAAAQIECBAgQKAgMGKaFzw+qPXIAAAAAElFTkSuQmCC"}]
[task 2023-01-10T20:11:36.694Z] 20:11:36     INFO -  1673381496693	Marionette	DEBUG	2 -> [0,6,"Marionette:SetContext",{"value":"content"}]
[task 2023-01-10T20:11:36.694Z] 20:11:36     INFO -  1673381496694	Marionette	DEBUG	2 <- [1,6,null,{"value":null}]
[task 2023-01-10T20:11:36.695Z] 20:11:36     INFO -  1673381496694	Marionette	DEBUG	2 -> [0,7,"Marionette:GetContext",{}]
[task 2023-01-10T20:11:36.695Z] 20:11:36     INFO -  1673381496694	Marionette	DEBUG	2 <- [1,7,null,{"value":"content"}]
[task 2023-01-10T20:11:36.695Z] 20:11:36     INFO -  1673381496695	Marionette	DEBUG	2 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2023-01-10T20:11:36.695Z] 20:11:36     INFO -  1673381496695	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2023-01-10T20:11:36.696Z] 20:11:36     INFO -  1673381496696	Marionette	DEBUG	2 -> [0,9,"WebDriver:GetPageSource",{}]
[task 2023-01-10T20:11:36.700Z] 20:11:36     INFO -  1673381496699	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 5832
[task 2023-01-10T20:11:36.700Z] 20:11:36     INFO -  1673381496701	Marionette	TRACE	[3] MarionetteCommands actor created for window id 2147483649
[task 2023-01-10T20:11:36.702Z] 20:11:36     INFO -  1673381496702	Marionette	DEBUG	2 <- [1,9,null,{"value":"<html><head></head><body></body></html>"}]
[task 2023-01-10T20:11:36.703Z] 20:11:36     INFO -  1673381496703	Marionette	DEBUG	2 -> [0,10,"Marionette:SetContext",{"value":"content"}]
[task 2023-01-10T20:11:36.703Z] 20:11:36     INFO -  1673381496703	Marionette	DEBUG	2 <- [1,10,null,{"value":null}]
[task 2023-01-10T20:11:36.709Z] 20:11:36    ERROR -  TEST-UNEXPECTED-ERROR | toolkit/components/cleardata/tests/marionette/test_moved_origin_directory_cleanup.py MovedOriginDirectoryCleanupTestCase.test_ensure_cleanup_at_crashed_restart | marionette_driver.errors.TimeoutException: Timed out after 5.0 seconds with message: to-be-removed subdirectory must disappear
[task 2023-01-10T20:11:36.709Z] 20:11:36     INFO -  Traceback (most recent call last):
[task 2023-01-10T20:11:36.709Z] 20:11:36     INFO -    File "Z:\task_167337880209386\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 198, in run
[task 2023-01-10T20:11:36.709Z] 20:11:36     INFO -      testMethod()
[task 2023-01-10T20:11:36.710Z] 20:11:36     INFO -    File "Z:\task_167337880209386\build\tests\marionette\tests\toolkit\components\cleardata\tests\marionette\test_moved_origin_directory_cleanup.py", line 48, in test_ensure_cleanup_at_crashed_restart
[task 2023-01-10T20:11:36.710Z] 20:11:36     INFO -      Wait(self.marionette).until(
[task 2023-01-10T20:11:36.710Z] 20:11:36     INFO -    File "Z:\task_167337880209386\build\venv\lib\site-packages\marionette_driver\wait.py", line 153, in until
[task 2023-01-10T20:11:36.710Z] 20:11:36     INFO -      raise errors.TimeoutException(
[task 2023-01-10T20:11:36.711Z] 20:11:36     INFO -  TEST-INFO took 20016ms
[task 2023-01-10T20:11:36.711Z] 20:11:36     INFO -  1673381496709	Marionette	DEBUG	2 -> [0,11,"WebDriver:DeleteSession",{}]
[task 2023-01-10T20:11:36.711Z] 20:11:36     INFO -  1673381496710	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2023-01-10T20:11:36.711Z] 20:11:36     INFO -  1673381496710	Marionette	TRACE	[3] MarionetteCommands actor destroyed for window id 2147483649
[task 2023-01-10T20:11:36.712Z] 20:11:36     INFO -  1673381496712	Marionette	DEBUG	2 <- [1,11,null,{"value":null}]
[task 2023-01-10T20:11:36.712Z] 20:11:36     INFO -  1673381496712	Marionette	DEBUG	Closed connection 2
[task 2023-01-10T20:11:36.713Z] 20:11:36     INFO -  TEST-START | toolkit/components/cleardata/tests/marionette/test_moved_origin_directory_cleanup.py MovedOriginDirectoryCleanupTestCase.test_ensure_cleanup_by_quit

Hi Kagami! Can you please take a look at this? I think this might be regressed by your recent changes from Bug 1804617.
Thank you!

Flags: needinfo?(krosylight)

Only on Windows? Interesting...

Flags: needinfo?(krosylight)
Assignee: nobody → krosylight
Attachment #9312400 - Attachment description: WIP: Bug 1809526 - Add additional check for `privacy.sanitize.pending` → Bug 1809526 - Add additional check for `privacy.sanitize.pending` r=smaug
Status: NEW → ASSIGNED
Pushed by krosylight@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/3f77f482d349
Add additional check for `privacy.sanitize.pending` r=smaug
Status: ASSIGNED → RESOLVED
Closed: 1 year ago
Resolution: --- → FIXED
Target Milestone: --- → 111 Branch
See Also: → 1814281
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: