Closed Bug 1895836 Opened 1 year ago Closed 20 days ago

Intermittent toolkit/components/cleardata/tests/marionette/test_moved_origin_directory_cleanup.py | single tracking bug

Categories

(Toolkit :: Data Sanitization, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2024-05-09T05:17:35.486Z] 05:17:35     INFO -  TEST-START | toolkit/components/cleardata/tests/marionette/test_moved_origin_directory_cleanup.py MovedOriginDirectoryCleanupTestCase.test_ensure_cleanup_at_crashed_restart
[task 2024-05-09T05:17:35.487Z] 05:17:35     INFO -  1715231855487	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:52734
[task 2024-05-09T05:17:35.489Z] 05:17:35     INFO -  1715231855488	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-05-09T05:17:35.489Z] 05:17:35     INFO -  1715231855489	Marionette	DEBUG	Waiting for initial application window
[task 2024-05-09T05:17:35.490Z] 05:17:35     INFO -  1715231855489	RemoteAgent	TRACE	[9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2024-05-09T05:17:35.490Z] 05:17:35     INFO -  1715231855490	RemoteAgent	TRACE	[9] ProgressListener Setting unload timer (5000ms)
[task 2024-05-09T05:17:35.490Z] 05:17:35     INFO -  1715231855490	RemoteAgent	TRACE	[9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-05-09T05:17:35.491Z] 05:17:35     INFO -  1715231855490	RemoteAgent	TRACE	[9] Document already finished loading: about:blank
[task 2024-05-09T05:17:35.491Z] 05:17:35     INFO -  1715231855490	RemoteAgent	TRACE	[9] ProgressListener Stop: has error=false
[task 2024-05-09T05:17:35.493Z] 05:17:35     INFO -  1715231855492	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"2c5ec3b5-41e0-4e31-a5d4-dc313d06dfd1","capabilities":{"browserName":"firefox","browserVersion":"127.0a1","platformName":"windows","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0","moz:accessibilityChecks":false,"moz:buildID":"20240509041138","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":4456,"moz:profile":"C:\\Users\\task_171523053376631\\AppData\\Local\\Temp\\tmpcl13399h.mozrunner","moz:shutdownTimeout":60000,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-05-09T05:17:35.493Z] 05:17:35     INFO -  1715231855493	Marionette	DEBUG	2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2024-05-09T05:17:35.493Z] 05:17:35     INFO -  1715231855494	Marionette	DEBUG	2 <- [1,2,null,{"value":null}]
[task 2024-05-09T05:17:35.494Z] 05:17:35     INFO -  1715231855495	Marionette	DEBUG	2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2024-05-09T05:17:35.495Z] 05:17:35     INFO -  1715231855495	Marionette	DEBUG	2 <- [1,3,null,{"value":null}]
[task 2024-05-09T05:17:35.496Z] 05:17:35     INFO -  1715231855496	Marionette	DEBUG	2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2024-05-09T05:17:35.496Z] 05:17:35     INFO -  1715231855496	Marionette	DEBUG	2 <- [1,4,null,{"value":null}]
[task 2024-05-09T05:17:35.497Z] 05:17:35     INFO -  1715231855497	Marionette	DEBUG	2 -> [0,5,"Marionette:GetContext",{}]
[task 2024-05-09T05:17:35.497Z] 05:17:35     INFO -  1715231855497	Marionette	DEBUG	2 <- [1,5,null,{"value":"content"}]
[task 2024-05-09T05:17:35.498Z] 05:17:35     INFO -  1715231855498	Marionette	DEBUG	2 -> [0,6,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-09T05:17:35.499Z] 05:17:35     INFO -  1715231855498	Marionette	DEBUG	2 <- [1,6,null,{"value":null}]
[task 2024-05-09T05:17:35.501Z] 05:17:35     INFO -  1715231855501	Marionette	DEBUG	2 -> [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":1021,"filename":"D:\\task_171523053376631\\build\\venv\\Lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2024-05-09T05:17:35.508Z] 05:17:35     INFO -  1715231855507	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 4456
[task 2024-05-09T05:17:35.508Z] 05:17:35     INFO -  1715231855509	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2024-05-09T05:17:35.513Z] 05:17:35     INFO -  1715231855513	Marionette	DEBUG	2 <- [1,7,null,{"value":true}]
[task 2024-05-09T05:17:35.516Z] 05:17:35     INFO -  1715231855515	Marionette	DEBUG	2 -> [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":1021,"filename":"D:\\task_171523053376631\\build\\venv\\Lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2024-05-09T05:17:35.518Z] 05:17:35     INFO -  1715231855518	Marionette	DEBUG	2 <- [1,8,null,{"value":false}]
[task 2024-05-09T05:17:35.519Z] 05:17:35     INFO -  1715231855519	Marionette	DEBUG	2 -> [0,9,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:17:35.519Z] 05:17:35     INFO -  1715231855519	Marionette	DEBUG	2 <- [1,9,null,{"value":null}]
[task 2024-05-09T05:17:35.520Z] 05:17:35     INFO -  1715231855520	Marionette	DEBUG	2 -> [0,10,"Marionette:GetContext",{}]
[task 2024-05-09T05:17:35.521Z] 05:17:35     INFO -  1715231855520	Marionette	DEBUG	2 <- [1,10,null,{"value":"content"}]
[task 2024-05-09T05:17:35.521Z] 05:17:35     INFO -  1715231855521	Marionette	DEBUG	2 -> [0,11,"WebDriver:DeleteSession",{}]
[task 2024-05-09T05:17:35.522Z] 05:17:35     INFO -  1715231855522	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2024-05-09T05:17:35.523Z] 05:17:35     INFO -  1715231855523	Marionette	DEBUG	2 <- [1,11,null,{"value":null}]
[task 2024-05-09T05:17:35.537Z] 05:17:35     INFO -  1715231855524	Marionette	DEBUG	Closed connection 2
[task 2024-05-09T05:17:35.609Z] 05:17:35     INFO -  Application command: D:\task_171523053376631\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile C:\Users\task_171523053376631\AppData\Local\Temp\tmp77_uj5p3.mozrunner
[task 2024-05-09T05:17:36.078Z] 05:17:36     INFO -  1715231856093	Marionette	INFO	Marionette enabled
[task 2024-05-09T05:17:36.095Z] 05:17:36     INFO -  1715231856095	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-05-09T05:17:36.266Z] 05:17:36     INFO -  1715231856277	Marionette	INFO	Listening on port 2828
[task 2024-05-09T05:17:36.279Z] 05:17:36     INFO -  1715231856279	Marionette	DEBUG	Marionette is listening
[task 2024-05-09T05:17:36.298Z] 05:17:36     INFO -  1715231856304	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:52735
[task 2024-05-09T05:17:36.311Z] 05:17:36     INFO -  1715231856311	Marionette	DEBUG	Closed connection 0
[task 2024-05-09T05:17:36.877Z] 05:17:36     INFO -  1715231856880	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:52739
[task 2024-05-09T05:17:36.893Z] 05:17:36     INFO -  1715231856901	Marionette	DEBUG	Closed connection 1
[task 2024-05-09T05:17:36.903Z] 05:17:36     INFO -  1715231856903	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:52740
[task 2024-05-09T05:17:36.955Z] 05:17:36     INFO -  1715231856968	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-05-09T05:17:36.972Z] 05:17:36     INFO -  1715231856974	Marionette	DEBUG	Waiting for initial application window
[task 2024-05-09T05:17:37.787Z] 05:17:37     INFO -  console.log: Sanitizer: "Pending sanitizations:" []
[task 2024-05-09T05:17:37.819Z] 05:17:37     INFO -  *** You are running in background task mode. ***
[task 2024-05-09T05:17:37.819Z] 05:17:37     INFO -  *** You are running in headless mode.
[task 2024-05-09T05:17:38.039Z] 05:17:38     INFO -  console.error: "C:\\Users\\task_171523053376631\\AppData\\Local\\Temp\\tmp77_uj5p3.mozrunner" "" 0 ".purge.bg_rm" ""
[task 2024-05-09T05:17:38.044Z] 05:17:38     INFO -  console.warn: "Skipping Glean as no metrics id is passed"
[task 2024-05-09T05:17:39.089Z] 05:17:39     INFO -  1715231859088	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2024-05-09T05:17:39.098Z] 05:17:39     INFO -  1715231859097	RemoteAgent	TRACE	[9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2024-05-09T05:17:39.098Z] 05:17:39     INFO -  1715231859098	RemoteAgent	TRACE	[9] ProgressListener Setting unload timer (5000ms)
[task 2024-05-09T05:17:39.104Z] 05:17:39     INFO -  1715231859103	RemoteAgent	TRACE	[9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-05-09T05:17:39.104Z] 05:17:39     INFO -  1715231859103	RemoteAgent	TRACE	[9] Document already finished loading: about:blank
[task 2024-05-09T05:17:39.104Z] 05:17:39     INFO -  1715231859103	RemoteAgent	TRACE	[9] ProgressListener Stop: has error=false
[task 2024-05-09T05:17:39.123Z] 05:17:39     INFO -  1715231859121	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"0105fbff-5a0d-4164-ac25-3424af290eb1","capabilities":{"browserName":"firefox","browserVersion":"127.0a1","platformName":"windows","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0","moz:accessibilityChecks":false,"moz:buildID":"20240509041138","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":4616,"moz:profile":"C:\\Users\\task_171523053376631\\AppData\\Local\\Temp\\tmp77_uj5p3.mozrunner","moz:shutdownTimeout":60000,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-05-09T05:17:39.135Z] 05:17:39     INFO -  1715231859135	Marionette	DEBUG	2 -> [0,2,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:17:39.136Z] 05:17:39     INFO -  1715231859135	Marionette	DEBUG	2 <- [1,2,null,{"value":null}]
[task 2024-05-09T05:17:39.137Z] 05:17:39     INFO -  1715231859137	Marionette	DEBUG	2 -> [0,3,"Marionette:GetContext",{}]
[task 2024-05-09T05:17:39.138Z] 05:17:39     INFO -  1715231859137	Marionette	DEBUG	2 <- [1,3,null,{"value":"content"}]
[task 2024-05-09T05:17:39.138Z] 05:17:39     INFO -  1715231859138	Marionette	DEBUG	2 -> [0,4,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-09T05:17:39.138Z] 05:17:39     INFO -  1715231859138	Marionette	DEBUG	2 <- [1,4,null,{"value":null}]
[task 2024-05-09T05:17:39.141Z] 05:17:39     INFO -  1715231859140	Marionette	DEBUG	2 -> [0,5,"WebDriver:ExecuteScript",{"script":"let promise = new Promise(resolve => {\n                    function observer() {\n                        Services.obs.removeO ...  Ci.nsICookie.SAMESITE_NONE,\n                    Ci.nsICookie.SCHEME_UNSET\n                );\n                return promise;","args":[],"newSandbox":true,"sandbox":"default","line":32,"filename":"D:\\task_171523053376631\\build\\tests\\marionette\\tests\\toolkit\\components\\cleardata\\tests\\marionette\\test_moved_origin_directory_cleanup.py"}]
[task 2024-05-09T05:17:39.147Z] 05:17:39     INFO -  1715231859146	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 4616
[task 2024-05-09T05:17:39.148Z] 05:17:39     INFO -  1715231859148	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2024-05-09T05:17:39.405Z] 05:17:39     INFO -  1715231859405	Marionette	DEBUG	2 <- [1,5,null,{"value":null}]
[task 2024-05-09T05:17:39.407Z] 05:17:39     INFO -  console.error: BackupService: "Failed to measure for resource: miscellaneous" (new NotFoundError("Could not stat `C:\\Users\\task_171523053376631\\AppData\\Local\\Temp\\tmp77_uj5p3.mozrunner\\storage\\permanent\\chrome\\idb\\2918063365piupsah.sqlite-journal': file does not exist (NS_ERROR_FILE_NOT_FOUND)", (void 0)))
[task 2024-05-09T05:17:39.407Z] 05:17:39     INFO -  1715231859407	Marionette	DEBUG	2 -> [0,6,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:17:39.407Z] 05:17:39     INFO -  1715231859407	Marionette	DEBUG	2 <- [1,6,null,{"value":null}]
[task 2024-05-09T05:17:39.409Z] 05:17:39     INFO -  1715231859408	Marionette	DEBUG	2 -> [0,7,"Marionette:GetContext",{}]
[task 2024-05-09T05:17:39.409Z] 05:17:39     INFO -  1715231859409	Marionette	DEBUG	2 <- [1,7,null,{"value":"content"}]
[task 2024-05-09T05:17:39.410Z] 05:17:39     INFO -  1715231859410	Marionette	DEBUG	2 -> [0,8,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-09T05:17:39.410Z] 05:17:39     INFO -  1715231859410	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2024-05-09T05:17:39.412Z] 05:17:39     INFO -  1715231859411	Marionette	DEBUG	2 -> [0,9,"WebDriver:ExecuteScript",{"script":"const { Preferences } = ChromeUtils.importESModule(\n                  \"resource://gre/modules/Preferences.sys.mjs\"\n           ...  Preferences({defaultBranch: defaultBranch});\n                return prefs.get(pref, null, Components.interfaces[valueType]);","args":["privacy.sanitize.pending",false,"unspecified"],"newSandbox":true,"sandbox":"default","line":905,"filename":"D:\\task_171523053376631\\build\\venv\\Lib\\site-packages\\marionette_driver\\marionette.py"}]
[task 2024-05-09T05:17:39.415Z] 05:17:39     INFO -  1715231859414	Marionette	DEBUG	2 <- [1,9,null,{"value":"[{\"id\":\"shutdown\",\"itemsToClear\":[\"cache\",\"cookies\",\"offlineApps\",\"history\",\"formdata\",\"downloads\",\"sessions\"],\"options\":{}},{\"id\":\"newtab-container\",\"itemsToClear\":[],\"options\":{}}]"}]
[task 2024-05-09T05:17:39.423Z] 05:17:39     INFO -  1715231859422	Marionette	DEBUG	2 -> [0,10,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:17:39.423Z] 05:17:39     INFO -  1715231859422	Marionette	DEBUG	2 <- [1,10,null,{"value":null}]
[task 2024-05-09T05:17:39.424Z] 05:17:39     INFO -  1715231859424	Marionette	DEBUG	2 -> [0,11,"Marionette:GetContext",{}]
[task 2024-05-09T05:17:39.424Z] 05:17:39     INFO -  1715231859424	Marionette	DEBUG	2 <- [1,11,null,{"value":"content"}]
[task 2024-05-09T05:17:39.425Z] 05:17:39     INFO -  1715231859425	Marionette	DEBUG	2 -> [0,12,"WebDriver:DeleteSession",{}]
[task 2024-05-09T05:17:39.426Z] 05:17:39     INFO -  1715231859426	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2024-05-09T05:17:39.428Z] 05:17:39     INFO -  1715231859428	Marionette	DEBUG	2 <- [1,12,null,{"value":null}]
[task 2024-05-09T05:17:39.429Z] 05:17:39     INFO -  1715231859429	Marionette	DEBUG	Closed connection 2
[task 2024-05-09T05:17:39.532Z] 05:17:39     INFO -  Application command: D:\task_171523053376631\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile C:\Users\task_171523053376631\AppData\Local\Temp\tmp77_uj5p3.mozrunner
[task 2024-05-09T05:17:39.923Z] 05:17:39     INFO -  1715231859930	Marionette	INFO	Marionette enabled
[task 2024-05-09T05:17:39.932Z] 05:17:39     INFO -  1715231859932	Marionette	TRACE	Received observer notification final-ui-startup
[task 2024-05-09T05:17:40.111Z] 05:17:40     INFO -  1715231860120	Marionette	INFO	Listening on port 2828
[task 2024-05-09T05:17:40.121Z] 05:17:40     INFO -  1715231860120	Marionette	DEBUG	Marionette is listening
[task 2024-05-09T05:17:40.132Z] 05:17:40     INFO -  1715231860140	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:52743
[task 2024-05-09T05:17:40.144Z] 05:17:40     INFO -  1715231860144	Marionette	DEBUG	Closed connection 0
[task 2024-05-09T05:17:40.298Z] 05:17:40     INFO -  1715231860307	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:52746
[task 2024-05-09T05:17:40.319Z] 05:17:40     INFO -  1715231860323	Marionette	DEBUG	Closed connection 1
[task 2024-05-09T05:17:40.326Z] 05:17:40     INFO -  1715231860326	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:52747
[task 2024-05-09T05:17:40.392Z] 05:17:40     INFO -  1715231860401	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-05-09T05:17:40.406Z] 05:17:40     INFO -  1715231860405	Marionette	DEBUG	Waiting for initial application window
[task 2024-05-09T05:17:41.646Z] 05:17:41     INFO -  console.log: Sanitizer: "Pending sanitizations:" []
[task 2024-05-09T05:17:41.678Z] 05:17:41     INFO -  *** You are running in background task mode. ***
[task 2024-05-09T05:17:41.687Z] 05:17:41     INFO -  *** You are running in headless mode.
[task 2024-05-09T05:17:41.914Z] 05:17:41     INFO -  console.error: "C:\\Users\\task_171523053376631\\AppData\\Local\\Temp\\tmp77_uj5p3.mozrunner" "" 0 ".purge.bg_rm" ""
[task 2024-05-09T05:17:41.920Z] 05:17:41     INFO -  console.warn: "Skipping Glean as no metrics id is passed"
[task 2024-05-09T05:17:42.322Z] 05:17:42     INFO -  1715231862328	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2024-05-09T05:17:42.334Z] 05:17:42     INFO -  1715231862333	RemoteAgent	TRACE	[3] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=5000 waitForExplicitStart=false
[task 2024-05-09T05:17:42.334Z] 05:17:42     INFO -  1715231862333	RemoteAgent	TRACE	[3] ProgressListener Setting unload timer (5000ms)
[task 2024-05-09T05:17:42.334Z] 05:17:42     INFO -  1715231862334	RemoteAgent	TRACE	[3] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-05-09T05:17:42.335Z] 05:17:42     INFO -  1715231862334	RemoteAgent	TRACE	[3] Document already finished loading: about:blank
[task 2024-05-09T05:17:42.335Z] 05:17:42     INFO -  1715231862334	RemoteAgent	TRACE	[3] ProgressListener Stop: has error=false
[task 2024-05-09T05:17:42.347Z] 05:17:42     INFO -  1715231862347	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"f4ac717c-a98a-4932-931b-4d336907254b","capabilities":{"browserName":"firefox","browserVersion":"127.0a1","platformName":"windows","acceptInsecureCerts":false,"pageLoadStrategy":"normal","setWindowRect":true,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"strictFileInteractability":true,"unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0","moz:accessibilityChecks":false,"moz:buildID":"20240509041138","moz:headless":false,"moz:platformVersion":"10.0","moz:processID":8232,"moz:profile":"C:\\Users\\task_171523053376631\\AppData\\Local\\Temp\\tmp77_uj5p3.mozrunner","moz:shutdownTimeout":60000,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-05-09T05:17:42.357Z] 05:17:42     INFO -  1715231862354	Marionette	DEBUG	2 -> [0,2,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:17:42.359Z] 05:17:42     INFO -  1715231862355	Marionette	DEBUG	2 <- [1,2,null,{"value":null}]
[task 2024-05-09T05:18:11.859Z] 05:18:11     INFO -  1715231891858	addons.xpi	ERROR	System addon update list error SyntaxError: XMLHttpRequest.open: 'http://%(server)s/dummy-system-addons.xml' is not a valid URL.
[task 2024-05-09T05:18:12.422Z] 05:18:12     INFO -  1715231892421	Marionette	DEBUG	2 -> [0,3,"Marionette:GetContext",{}]
[task 2024-05-09T05:18:12.422Z] 05:18:12     INFO -  1715231892422	Marionette	DEBUG	2 <- [1,3,null,{"value":"content"}]
[task 2024-05-09T05:18:12.423Z] 05:18:12     INFO -  1715231892423	Marionette	DEBUG	2 -> [0,4,"Marionette:SetContext",{"value":"chrome"}]
[task 2024-05-09T05:18:12.423Z] 05:18:12     INFO -  1715231892423	Marionette	DEBUG	2 <- [1,4,null,{"value":null}]
[task 2024-05-09T05:18:12.424Z] 05:18:12     INFO -  1715231892424	Marionette	DEBUG	2 -> [0,5,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2024-05-09T05:18:12.427Z] 05:18:12     INFO -  1715231892428	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 8232
[task 2024-05-09T05:18:12.429Z] 05:18:12     INFO -  1715231892428	Marionette	TRACE	[1] MarionetteCommands actor created for window id 2
[task 2024-05-09T05:18:12.473Z] 05:18:12     INFO -  1715231892473	Marionette	DEBUG	2 <- [1,5,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAPQCAYAAABpCtbiAAAgAElEQVR4XuzdCbRlZXUn8K+gqqiCAgcwQUHEhCkJgiAKtCRRo8uoHTXROLWSFcc4a+LQdisqppdBT ... A+AQIECBAgAABAgQIECBAgAABAm0BB2C7X+kIECBAgAABAgQIECBAgAABAgTOBRyA5wMQnwABAgQIECBAgAABAgQIECBAoC0wkG5satIqMzUAAAAASUVORK5CYII="}]
[task 2024-05-09T05:18:12.475Z] 05:18:12     INFO -  1715231892475	Marionette	DEBUG	2 -> [0,6,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:18:12.475Z] 05:18:12     INFO -  1715231892475	Marionette	DEBUG	2 <- [1,6,null,{"value":null}]
[task 2024-05-09T05:18:12.476Z] 05:18:12     INFO -  1715231892476	Marionette	DEBUG	2 -> [0,7,"Marionette:GetContext",{}]
[task 2024-05-09T05:18:12.476Z] 05:18:12     INFO -  1715231892476	Marionette	DEBUG	2 <- [1,7,null,{"value":"content"}]
[task 2024-05-09T05:18:12.477Z] 05:18:12     INFO -  1715231892477	Marionette	DEBUG	2 -> [0,8,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:18:12.477Z] 05:18:12     INFO -  1715231892477	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2024-05-09T05:18:12.478Z] 05:18:12     INFO -  1715231892478	Marionette	DEBUG	2 -> [0,9,"WebDriver:GetPageSource",{}]
[task 2024-05-09T05:18:12.482Z] 05:18:12     INFO -  1715231892481	RemoteAgent	TRACE	WebDriverProcessData actor created for PID 1376
[task 2024-05-09T05:18:12.483Z] 05:18:12     INFO -  1715231892483	Marionette	TRACE	[3] MarionetteCommands actor created for window id 2147483649
[task 2024-05-09T05:18:12.485Z] 05:18:12     INFO -  1715231892485	Marionette	DEBUG	2 <- [1,9,null,{"value":"<html><head></head><body></body></html>"}]
[task 2024-05-09T05:18:12.487Z] 05:18:12     INFO -  1715231892486	Marionette	DEBUG	2 -> [0,10,"Marionette:SetContext",{"value":"content"}]
[task 2024-05-09T05:18:12.487Z] 05:18:12     INFO -  1715231892486	Marionette	DEBUG	2 <- [1,10,null,{"value":null}]
[task 2024-05-09T05:18:12.489Z] 05:18:12    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 30.1 seconds with message: to-be-removed subdirectory must disappear
[task 2024-05-09T05:18:12.489Z] 05:18:12     INFO -  Traceback (most recent call last):
[task 2024-05-09T05:18:12.489Z] 05:18:12     INFO -    File "D:\task_171523053376631\build\venv\Lib\site-packages\marionette_harness\marionette_test\testcases.py", line 198, in run
[task 2024-05-09T05:18:12.489Z] 05:18:12     INFO -      testMethod()
[task 2024-05-09T05:18:12.490Z] 05:18:12     INFO -    File "D:\task_171523053376631\build\tests\marionette\tests\toolkit\components\cleardata\tests\marionette\test_moved_origin_directory_cleanup.py", line 110, in test_ensure_cleanup_at_crashed_restart
[task 2024-05-09T05:18:12.491Z] 05:18:12     INFO -      Wait(self.marionette, timeout=30).until(
[task 2024-05-09T05:18:12.491Z] 05:18:12     INFO -    File "D:\task_171523053376631\build\venv\Lib\site-packages\marionette_driver\wait.py", line 153, in until
[task 2024-05-09T05:18:12.491Z] 05:18:12     INFO -      raise errors.TimeoutException(
[task 2024-05-09T05:18:12.491Z] 05:18:12     INFO -  TEST-INFO took 37001ms
[task 2024-05-09T05:18:12.491Z] 05:18:12     INFO -  1715231892489	Marionette	DEBUG	2 -> [0,11,"WebDriver:DeleteSession",{}]
[task 2024-05-09T05:18:12.492Z] 05:18:12     INFO -  1715231892490	Marionette	TRACE	[1] MarionetteCommands actor destroyed for window id 2
[task 2024-05-09T05:18:12.492Z] 05:18:12     INFO -  1715231892490	Marionette	TRACE	[3] MarionetteCommands actor destroyed for window id 2147483649
[task 2024-05-09T05:18:12.493Z] 05:18:12     INFO -  1715231892492	Marionette	DEBUG	2 <- [1,11,null,{"value":null}]
[task 2024-05-09T05:18:12.493Z] 05:18:12     INFO -  1715231892493	Marionette	DEBUG	Closed connection 2
[task 2024-05-09T05:18:12.494Z] 05:18:12     INFO -  TEST-START | toolkit/components/cleardata/tests/marionette/test_moved_origin_directory_cleanup.py MovedOriginDirectoryCleanupTestCase.test_ensure_cleanup_by_quit
Status: NEW → RESOLVED
Closed: 20 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.