Closed Bug 1757102 Opened 4 years ago Closed 4 years ago

Perma [tier 2] toolkit/xre/test/marionette/test_win32k_enrollment.py TestWin32kAutostart.test_1 | marionette_driver.errors.JavascriptException: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]"

Categories

(Toolkit :: Startup and Profile System, defect, P5)

defect

Tracking

()

RESOLVED FIXED
99 Branch
Tracking Status
firefox-esr91 --- unaffected
firefox97 --- unaffected
firefox98 --- unaffected
firefox99 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

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


[task 2022-02-24T23:48:38.908Z] 23:48:38     INFO -  TEST-START | toolkit/xre/test/marionette/test_win32k_enrollment.py TestWin32kAutostart.test_1
[task 2022-02-24T23:48:38.910Z] 23:48:38     INFO -  1645746518909	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:53585
[task 2022-02-24T23:48:38.918Z] 23:48:38     INFO -  1645746518918	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-02-24T23:48:38.922Z] 23:48:38     INFO -  1645746518922	RemoteAgent	TRACE	[19] Document already finished loading: about:blank
[task 2022-02-24T23:48:38.924Z] 23:48:38     INFO -  1645746518923	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"c8fd0ab0-78b1-4586-88ff-db1236f5541c","capabilities":{"browserName":"firefox","browserVersion":"99.0a ... 8.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2022-02-24T23:48:38.924Z] 23:48:38     INFO -  1645746518924	Marionette	DEBUG	2 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2022-02-24T23:48:38.925Z] 23:48:38     INFO -  1645746518925	Marionette	DEBUG	2 <- [1,2,null,{"value":null}]
[task 2022-02-24T23:48:38.926Z] 23:48:38     INFO -  1645746518926	Marionette	DEBUG	2 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2022-02-24T23:48:38.927Z] 23:48:38     INFO -  1645746518927	Marionette	DEBUG	2 <- [1,3,null,{"value":null}]
[task 2022-02-24T23:48:38.928Z] 23:48:38     INFO -  1645746518928	Marionette	DEBUG	2 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2022-02-24T23:48:38.929Z] 23:48:38     INFO -  1645746518929	Marionette	DEBUG	2 <- [1,4,null,{"value":null}]
[task 2022-02-24T23:48:38.931Z] 23:48:38     INFO -  1645746518931	Marionette	DEBUG	2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:38.931Z] 23:48:38     INFO -  1645746518932	Marionette	DEBUG	2 <- [1,5,null,{"value":null}]
[task 2022-02-24T23:48:38.933Z] 23:48:38     INFO -  1645746518933	Marionette	DEBUG	2 -> [0,6,"Marionette:GetContext",{}]
[task 2022-02-24T23:48:38.933Z] 23:48:38     INFO -  1645746518934	Marionette	DEBUG	2 <- [1,6,null,{"value":"chrome"}]
[task 2022-02-24T23:48:38.934Z] 23:48:38     INFO -  1645746518935	Marionette	DEBUG	2 -> [0,7,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:38.935Z] 23:48:38     INFO -  1645746518935	Marionette	DEBUG	2 <- [1,7,null,{"value":null}]
[task 2022-02-24T23:48:38.938Z] 23:48:38     INFO -  1645746518938	Marionette	DEBUG	2 -> [0,8,"WebDriver:ExecuteScript",{"script":"// We're running in a function, in a sandbox, that inherits from an\n          // X ... e":"C:\\Users\\task_1645744163\\build\\tests\\marionette\\tests\\toolkit\\xre\\test\\marionette\\test_win32k_enrollment.py"}]
[task 2022-02-24T23:48:38.940Z] 23:48:38     INFO -  1645746518941	Marionette	TRACE	[7] MarionetteCommands actor created for window id 2
[task 2022-02-24T23:48:38.943Z] 23:48:38     INFO -  1645746518943	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2022-02-24T23:48:38.944Z] 23:48:38     INFO -  1645746518944	Marionette	DEBUG	2 -> [0,9,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:38.944Z] 23:48:38     INFO -  1645746518944	Marionette	DEBUG	2 <- [1,9,null,{"value":null}]
[task 2022-02-24T23:48:38.945Z] 23:48:38     INFO -  1645746518945	Marionette	DEBUG	2 -> [0,10,"Marionette:GetContext",{}]
[task 2022-02-24T23:48:38.945Z] 23:48:38     INFO -  1645746518946	Marionette	DEBUG	2 <- [1,10,null,{"value":"chrome"}]
[task 2022-02-24T23:48:38.946Z] 23:48:38     INFO -  1645746518946	Marionette	DEBUG	2 -> [0,11,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:38.946Z] 23:48:38     INFO -  1645746518947	Marionette	DEBUG	2 <- [1,11,null,{"value":null}]
[task 2022-02-24T23:48:38.948Z] 23:48:38     INFO -  1645746518948	Marionette	DEBUG	2 -> [0,12,"WebDriver:ExecuteScript",{"script":"return Services.prefs.getBoolPref(\"security.sandbox.content.win32k-disable\");"," ... e":"C:\\Users\\task_1645744163\\build\\tests\\marionette\\tests\\toolkit\\xre\\test\\marionette\\test_win32k_enrollment.py"}]
[task 2022-02-24T23:48:38.951Z] 23:48:38     INFO -  1645746518951	Marionette	DEBUG	2 <- [1,12,{"error":"javascript error","message":"[Exception... \"Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED ... ommandsChild.jsm:223:21\nreceiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:95:31\n"},null]
[task 2022-02-24T23:48:38.952Z] 23:48:38     INFO -  1645746518952	Marionette	DEBUG	2 -> [0,13,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:38.952Z] 23:48:38     INFO -  1645746518952	Marionette	DEBUG	2 <- [1,13,null,{"value":null}]
[task 2022-02-24T23:48:38.954Z] 23:48:38     INFO -  1645746518954	Marionette	DEBUG	2 -> [0,14,"Marionette:GetContext",{}]
[task 2022-02-24T23:48:38.954Z] 23:48:38     INFO -  1645746518954	Marionette	DEBUG	2 <- [1,14,null,{"value":"chrome"}]
[task 2022-02-24T23:48:38.955Z] 23:48:38     INFO -  1645746518955	Marionette	DEBUG	2 -> [0,15,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:38.955Z] 23:48:38     INFO -  1645746518955	Marionette	DEBUG	2 <- [1,15,null,{"value":null}]
[task 2022-02-24T23:48:38.956Z] 23:48:38     INFO -  1645746518956	Marionette	DEBUG	2 -> [0,16,"WebDriver:TakeScreenshot",{"id":null,"full":true,"hash":false,"scroll":true}]
[task 2022-02-24T23:48:39.016Z] 23:48:39     INFO -  1645746519015	Marionette	DEBUG	2 <- [1,16,null,{"value":"iVBORw0KGgoAAAANSUhEUgAABQAAAAQHCAYAAACjlsT7AAAgAElEQVR4XuzdC5RkdX0n8H/DzMDA8FDQoAyIyghkAREQiPh+7CHmrOJG ... ABAgQIECBAgAABAgQIECDQBRyA3U6SAAECBAgQIECAAAECBAgQIECAwL2AA/B+IgUJECBAgAABAgQIECBAgAABAgQIdIEBwXb/gFpDBsgAAAAASUVORK5CYII="}]
[task 2022-02-24T23:48:39.018Z] 23:48:39     INFO -  1645746519018	Marionette	DEBUG	2 -> [0,17,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:39.018Z] 23:48:39     INFO -  1645746519018	Marionette	DEBUG	2 <- [1,17,null,{"value":null}]
[task 2022-02-24T23:48:39.019Z] 23:48:39     INFO -  1645746519019	Marionette	DEBUG	2 -> [0,18,"Marionette:GetContext",{}]
[task 2022-02-24T23:48:39.020Z] 23:48:39     INFO -  1645746519020	Marionette	DEBUG	2 <- [1,18,null,{"value":"chrome"}]
[task 2022-02-24T23:48:39.021Z] 23:48:39     INFO -  1645746519021	Marionette	DEBUG	2 -> [0,19,"Marionette:SetContext",{"value":"content"}]
[task 2022-02-24T23:48:39.021Z] 23:48:39     INFO -  1645746519021	Marionette	DEBUG	2 <- [1,19,null,{"value":null}]
[task 2022-02-24T23:48:39.022Z] 23:48:39     INFO -  1645746519022	Marionette	DEBUG	2 -> [0,20,"WebDriver:GetPageSource",{}]
[task 2022-02-24T23:48:39.028Z] 23:48:39     INFO -  1645746519028	Marionette	TRACE	[19] MarionetteCommands actor created for window id 2147483649
[task 2022-02-24T23:48:39.029Z] 23:48:39     INFO -  1645746519029	Marionette	DEBUG	2 <- [1,20,null,{"value":"<html><head></head><body></body></html>"}]
[task 2022-02-24T23:48:39.030Z] 23:48:39     INFO -  1645746519030	Marionette	DEBUG	2 -> [0,21,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-02-24T23:48:39.031Z] 23:48:39     INFO -  1645746519031	Marionette	DEBUG	2 <- [1,21,null,{"value":null}]
[task 2022-02-24T23:48:39.033Z] 23:48:39    ERROR -  TEST-UNEXPECTED-ERROR | toolkit/xre/test/marionette/test_win32k_enrollment.py TestWin32kAutostart.test_1 | marionette_driver.errors.JavascriptException: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]"  nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)"  location: "JS frame :: C:\Users\task_1645744163\build\tests\marionette\tests\toolkit\xre\test\marionette\test_win32k_enrollment.py :: <TOP_LEVEL> :: line 52"  data: no]
[task 2022-02-24T23:48:39.034Z] 23:48:39     INFO -  stacktrace:
[task 2022-02-24T23:48:39.034Z] 23:48:39     INFO -  	@C:\Users\task_1645744163\build\tests\marionette\tests\toolkit\xre\test\marionette\test_win32k_enrollment.py:52:29
[task 2022-02-24T23:48:39.035Z] 23:48:39     INFO -  	@C:\Users\task_1645744163\build\tests\marionette\tests\toolkit\xre\test\marionette\test_win32k_enrollment.py:53:8
[task 2022-02-24T23:48:39.035Z] 23:48:39     INFO -  	evaluate.sandbox/promise<@chrome://remote/content/marionette/evaluate.js:157:10
[task 2022-02-24T23:48:39.035Z] 23:48:39     INFO -  	evaluate.sandbox@chrome://remote/content/marionette/evaluate.js:133:17
[task 2022-02-24T23:48:39.036Z] 23:48:39     INFO -  	executeScript@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:223:21
[task 2022-02-24T23:48:39.036Z] 23:48:39     INFO -  	receiveMessage@chrome://remote/content/marionette/actors/MarionetteCommandsChild.jsm:95:31
[task 2022-02-24T23:48:39.036Z] 23:48:39     INFO -  Traceback (most recent call last):
[task 2022-02-24T23:48:39.037Z] 23:48:39     INFO -    File "C:\Users\task_1645744163\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 183, in run
[task 2022-02-24T23:48:39.037Z] 23:48:39     INFO -      self.setUp()
[task 2022-02-24T23:48:39.038Z] 23:48:39     INFO -    File "C:\Users\task_1645744163\build\tests\marionette\tests\toolkit\xre\test\marionette\test_win32k_enrollment.py", line 181, in setUp
[task 2022-02-24T23:48:39.038Z] 23:48:39     INFO -      self.default_is = self.execute_script(prefJS)
[task 2022-02-24T23:48:39.038Z] 23:48:39     INFO -    File "C:\Users\task_1645744163\build\tests\marionette\tests\toolkit\xre\test\marionette\test_win32k_enrollment.py", line 51, in execute_script
[task 2022-02-24T23:48:39.039Z] 23:48:39     INFO -      code, new_sandbox=False, sandbox=self.SANDBOX_NAME, *args, **kwargs
[task 2022-02-24T23:48:39.039Z] 23:48:39     INFO -    File "C:\Users\task_1645744163\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1693, in execute_script
[task 2022-02-24T23:48:39.039Z] 23:48:39     INFO -      rv = self._send_message("WebDriver:ExecuteScript", body, key="value")
[task 2022-02-24T23:48:39.040Z] 23:48:39     INFO -    File "C:\Users\task_1645744163\build\venv\lib\site-packages\marionette_driver\decorators.py", line 27, in _
[task 2022-02-24T23:48:39.040Z] 23:48:39     INFO -      return func(*args, **kwargs)
[task 2022-02-24T23:48:39.041Z] 23:48:39     INFO -    File "C:\Users\task_1645744163\build\venv\lib\site-packages\marionette_driver\marionette.py", line 668, in _send_message
[task 2022-02-24T23:48:39.041Z] 23:48:39     INFO -      self._handle_error(err)
[task 2022-02-24T23:48:39.041Z] 23:48:39     INFO -    File "C:\Users\task_1645744163\build\venv\lib\site-packages\marionette_driver\marionette.py", line 680, in _handle_error
[task 2022-02-24T23:48:39.042Z] 23:48:39     INFO -      raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2022-02-24T23:48:39.042Z] 23:48:39     INFO -  TEST-INFO took 123ms
[task 2022-02-24T23:48:39.042Z] 23:48:39     INFO -  1645746519034	Marionette	DEBUG	2 -> [0,22,"WebDriver:DeleteSession",{}]
[task 2022-02-24T23:48:39.042Z] 23:48:39     INFO -  1645746519036	Marionette	DEBUG	2 <- [1,22,null,{"value":null}]
[task 2022-02-24T23:48:39.043Z] 23:48:39     INFO -  TEST-START | toolkit/xre/test/marionette/test_win32k_enrollment.py TestWin32kAutostart.test_10

Hello Bob, can you please take a look at this?

Flags: needinfo?(bobowencode)

Set release status flags based on info from the regressing bug 1750742

(In reply to Cristian Tuns from comment #1)

Hello Bob, can you please take a look at this?

Yes I'll pick this up now.

Assignee: nobody → bobowencode
Status: NEW → ASSIGNED
Flags: needinfo?(bobowencode)

I guess the issue here is that ccov doesn't have the sandbox enabled.
What I really don't understand is why this wasn't failing before we flipped the pref to turn on win32k lockdown.

Got it ... when we enabled win32k lockdown on Nightly we removed the Nightly experiment and moved the pref back into the MOZ_SANDBOX #if.
The Nightly experiments can't have #ifs.

Anyway I guess we should just skip this when the sandbox is disabled.

Has Regression Range: --- → yes

CCov builds don't enable the sandbox so win32k test is not relevant.

Pushed by bobowencode@gmail.com: https://hg.mozilla.org/integration/autoland/rev/fe2be2f374cd Skip test_win32k_enrollment.py for CCov build. r=tjr
Status: ASSIGNED → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
Target Milestone: --- → 99 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: