Closed Bug 1587662 Opened 4 months ago Closed 4 months ago

Perma fail Tier 2 testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_find_anonymous_children | NoSuchElementException: Unable to locate element: null

Categories

(Firefox Build System :: General, defect, P5)

defect

Tracking

(firefox70 unaffected, firefox71 fixed)

VERIFIED FIXED
mozilla71
Tracking Status
firefox70 --- unaffected
firefox71 --- fixed

People

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

References

(Regression)

Details

Attachments

(1 file)

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=270547175&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/ACd5gSRvRhqyGizAViUMJQ/runs/0/artifacts/public/logs/live_backing.log


[task 2019-10-09T22:30:53.691Z] 22:30:53 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_find_anonymous_children
[task 2019-10-09T22:30:53.703Z] 22:30:53 INFO - 1570660253694 Marionette DEBUG Accepted connection 5 from 127.0.0.1:51078
[task 2019-10-09T22:30:53.703Z] 22:30:53 INFO - 1570660253697 Marionette DEBUG 5 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-10-09T22:30:53.706Z] 22:30:53 INFO - 1570660253702 Marionette TRACE [5] Frame script loaded
[task 2019-10-09T22:30:53.708Z] 22:30:53 INFO - 1570660253704 Marionette TRACE [5] Frame script registered
[task 2019-10-09T22:30:53.715Z] 22:30:53 INFO - 1570660253708 Marionette DEBUG 5 <- [1,1,null,{"sessionId":"184b5bde-ea5d-46ab-b010-b19cd243607e","capabilities":{"browserName":"firefox","browserVersion":"71.0a ... p\tmpyvodnp.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-10-09T22:30:53.720Z] 22:30:53 INFO - 1570660253714 Marionette DEBUG 5 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-10-09T22:30:53.720Z] 22:30:53 INFO - 1570660253716 Marionette DEBUG 5 <- [1,2,null,{"value":null}]
[task 2019-10-09T22:30:53.721Z] 22:30:53 INFO - 1570660253718 Marionette DEBUG 5 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-10-09T22:30:53.724Z] 22:30:53 INFO - 1570660253719 Marionette DEBUG 5 <- [1,3,null,{"value":null}]
[task 2019-10-09T22:30:53.725Z] 22:30:53 INFO - 1570660253722 Marionette DEBUG 5 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-10-09T22:30:53.725Z] 22:30:53 INFO - 1570660253723 Marionette DEBUG 5 <- [1,4,null,{"value":null}]
[task 2019-10-09T22:30:53.731Z] 22:30:53 INFO - 1570660253728 Marionette DEBUG 5 -> [0,5,"WebDriver:GetChromeWindowHandle",{}]
[task 2019-10-09T22:30:53.731Z] 22:30:53 INFO - 1570660253729 Marionette DEBUG 5 <- [1,5,null,{"value":"1"}]
[task 2019-10-09T22:30:53.737Z] 22:30:53 INFO - 1570660253731 Marionette DEBUG 5 -> [0,6,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-10-09T22:30:53.737Z] 22:30:53 INFO - 1570660253731 Marionette DEBUG 5 <- [1,6,null,["1"]]
[task 2019-10-09T22:30:53.738Z] 22:30:53 INFO - 1570660253734 Marionette DEBUG 5 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2019-10-09T22:30:53.739Z] 22:30:53 INFO - 1570660253735 Marionette DEBUG 5 <- [1,7,null,{"value":"5"}]
[task 2019-10-09T22:30:53.742Z] 22:30:53 INFO - 1570660253737 Marionette DEBUG 5 -> [0,8,"WebDriver:GetWindowHandles",{}]
[task 2019-10-09T22:30:53.742Z] 22:30:53 INFO - 1570660253738 Marionette DEBUG 5 <- [1,8,null,["5"]]
[task 2019-10-09T22:30:53.744Z] 22:30:53 INFO - 1570660253741 Marionette DEBUG 5 -> [0,9,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:53.745Z] 22:30:53 INFO - 1570660253742 Marionette DEBUG 5 <- [1,9,null,{"value":null}]
[task 2019-10-09T22:30:53.747Z] 22:30:53 INFO - 1570660253745 Marionette DEBUG 5 -> [0,10,"Marionette:GetContext",{}]
[task 2019-10-09T22:30:53.748Z] 22:30:53 INFO - 1570660253745 Marionette DEBUG 5 <- [1,10,null,{"value":"chrome"}]
[task 2019-10-09T22:30:53.751Z] 22:30:53 INFO - 1570660253748 Marionette DEBUG 5 -> [0,11,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:53.751Z] 22:30:53 INFO - 1570660253748 Marionette DEBUG 5 <- [1,11,null,{"value":null}]
[task 2019-10-09T22:30:53.757Z] 22:30:53 INFO - 1570660253750 Marionette DEBUG 5 -> [0,12,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-10-09T22:30:53.757Z] 22:30:53 INFO - 1570660253751 Marionette DEBUG 5 <- [1,12,null,["1"]]
[task 2019-10-09T22:30:53.758Z] 22:30:53 INFO - 1570660253754 Marionette DEBUG 5 -> [0,13,"WebDriver:GetWindowHandles",{}]
[task 2019-10-09T22:30:53.758Z] 22:30:53 INFO - 1570660253755 Marionette DEBUG 5 <- [1,13,null,["5"]]
[task 2019-10-09T22:30:53.760Z] 22:30:53 INFO - 1570660253756 Marionette DEBUG 5 -> [0,14,"Marionette:GetContext",{}]
[task 2019-10-09T22:30:53.761Z] 22:30:53 INFO - 1570660253757 Marionette DEBUG 5 <- [1,14,null,{"value":"chrome"}]
[task 2019-10-09T22:30:53.762Z] 22:30:53 INFO - 1570660253759 Marionette DEBUG 5 -> [0,15,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:53.762Z] 22:30:53 INFO - 1570660253759 Marionette DEBUG 5 <- [1,15,null,{"value":null}]
[task 2019-10-09T22:30:53.773Z] 22:30:53 INFO - 1570660253767 Marionette DEBUG 5 -> [0,16,"WebDriver:ExecuteAsyncScript",{"scriptTimeout":null,"newSandbox":true,"args":["chrome://marionette/content/test_anonym ... }\n\n resolve(win.windowUtils.outerWindowID);\n })();","sandbox":"default","line":179}]
[task 2019-10-09T22:30:53.793Z] 22:30:53 INFO - 1570660253782 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-10-09T22:30:53.805Z] 22:30:53 INFO - ** Received DOM event activate for [object ChromeWindow]
[task 2019-10-09T22:30:53.805Z] 22:30:53 INFO - ** Received DOM event focus for [object HTMLDocument]
[task 2019-10-09T22:30:53.866Z] 22:30:53 INFO - _create_dc_and_bitmap: The operation completed successfully.
[task 2019-10-09T22:30:53.866Z] 22:30:53 INFO - ** Received DOM event activate for [object ChromeWindow]
[task 2019-10-09T22:30:53.866Z] 22:30:53 INFO - ** Received DOM event focus for [object HTMLDocument]
[task 2019-10-09T22:30:53.866Z] 22:30:53 INFO - 1570660253863 Marionette DEBUG 5 <- [1,16,null,{"value":22}]
[task 2019-10-09T22:30:53.967Z] 22:30:53 INFO - 1570660253965 Marionette DEBUG 5 -> [0,17,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:53.968Z] 22:30:53 INFO - 1570660253966 Marionette DEBUG 5 <- [1,17,null,{"value":null}]
[task 2019-10-09T22:30:54.148Z] 22:30:54 INFO - 1570660254142 Marionette DEBUG 5 -> [0,18,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-10-09T22:30:54.148Z] 22:30:54 INFO - 1570660254143 Marionette DEBUG 5 <- [1,18,null,["1","22"]]
[task 2019-10-09T22:30:54.153Z] 22:30:54 INFO - JavaScript error: chrome://global/content/elements/dialog.js, line 299: TypeError: dialog.getButton is not a function
[task 2019-10-09T22:30:54.153Z] 22:30:54 INFO - JavaScript error: chrome://global/content/elements/dialog.js, line 299: TypeError: dialog.getButton is not a function
[task 2019-10-09T22:30:54.155Z] 22:30:54 INFO - 1570660254153 Marionette DEBUG 5 -> [0,19,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-10-09T22:30:54.155Z] 22:30:54 INFO - 1570660254153 Marionette DEBUG 5 <- [1,19,null,["1","22"]]
[task 2019-10-09T22:30:54.157Z] 22:30:54 INFO - 1570660254155 Marionette DEBUG 5 -> [0,20,"Marionette:GetContext",{}]
[task 2019-10-09T22:30:54.157Z] 22:30:54 INFO - 1570660254156 Marionette DEBUG 5 <- [1,20,null,{"value":"chrome"}]
[task 2019-10-09T22:30:54.163Z] 22:30:54 INFO - 1570660254159 Marionette DEBUG 5 -> [0,21,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:54.163Z] 22:30:54 INFO - 1570660254159 Marionette DEBUG 5 <- [1,21,null,{"value":null}]
[task 2019-10-09T22:30:54.170Z] 22:30:54 INFO - 1570660254165 Marionette DEBUG 5 -> [0,22,"WebDriver:ExecuteScript",{"script":"Components.utils.import("resource://gre/modules/Services.jsm");\n\n ... 8943\build\venv\lib\site-packages\marionette_harness\runner\mixins\window_manager.py","sandbox":"default","line":97}]
[task 2019-10-09T22:30:54.170Z] 22:30:54 INFO - 1570660254168 Marionette DEBUG 5 <- [1,22,null,{"value":true}]
[task 2019-10-09T22:30:54.172Z] 22:30:54 INFO - 1570660254170 Marionette DEBUG 5 -> [0,23,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:54.172Z] 22:30:54 INFO - 1570660254170 Marionette DEBUG 5 <- [1,23,null,{"value":null}]
[task 2019-10-09T22:30:54.173Z] 22:30:54 INFO - 1570660254171 Marionette DEBUG 5 -> [0,24,"Marionette:GetContext",{}]
[task 2019-10-09T22:30:54.175Z] 22:30:54 INFO - 1570660254172 Marionette DEBUG 5 <- [1,24,null,{"value":"chrome"}]
[task 2019-10-09T22:30:54.176Z] 22:30:54 INFO - 1570660254173 Marionette DEBUG 5 -> [0,25,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:54.176Z] 22:30:54 INFO - 1570660254173 Marionette DEBUG 5 <- [1,25,null,{"value":null}]
[task 2019-10-09T22:30:54.176Z] 22:30:54 INFO - 1570660254174 Marionette DEBUG 5 -> [0,26,"WebDriver:SwitchToWindow",{"handle":"22","focus":true,"name":"22"}]
[task 2019-10-09T22:30:54.208Z] 22:30:54 INFO - 1570660254203 Marionette TRACE [24] Frame script loaded
[task 2019-10-09T22:30:54.208Z] 22:30:54 INFO - 1570660254203 Marionette TRACE [24] Frame script registered
[task 2019-10-09T22:30:54.208Z] 22:30:54 INFO - 1570660254204 Marionette DEBUG 5 <- [1,26,null,{"value":null}]
[task 2019-10-09T22:30:54.209Z] 22:30:54 INFO - 1570660254206 Marionette DEBUG 5 -> [0,27,"WebDriver:FindElement",{"using":"id","value":"container3"}]
[task 2019-10-09T22:30:54.211Z] 22:30:54 INFO - 1570660254208 Marionette DEBUG 5 <- [1,27,null,{"value":{"chromeelement-9fc5-4b51-a3c8-01716eedeb04":"0dde0eb2-2b82-49b2-aa94-d4aad234909d"}}]
[task 2019-10-09T22:30:54.212Z] 22:30:54 INFO - 1570660254209 Marionette DEBUG 5 -> [0,28,"WebDriver:FindElement",{"using":"anon","value":null,"element":"0dde0eb2-2b82-49b2-aa94-d4aad234909d"}]
[task 2019-10-09T22:30:54.213Z] 22:30:54 INFO - 1570660254210 Marionette DEBUG 5 <- [1,28,{"error":"no such element","message":"Unable to locate element: null","stacktrace":"WebDriverError@chrome://marionette/ ... entError@chrome://marionette/content/error.js:387:5\nelement.find/</<@chrome://marionette/content/element.js:343:16\n"},null]
[task 2019-10-09T22:30:54.223Z] 22:30:54 INFO - 1570660254216 Marionette DEBUG 5 -> [0,29,"Marionette:GetContext",{}]
[task 2019-10-09T22:30:54.223Z] 22:30:54 INFO - 1570660254216 Marionette DEBUG 5 <- [1,29,null,{"value":"chrome"}]
[task 2019-10-09T22:30:54.223Z] 22:30:54 INFO - 1570660254217 Marionette DEBUG 5 -> [0,30,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:54.223Z] 22:30:54 INFO - 1570660254218 Marionette DEBUG 5 <- [1,30,null,{"value":null}]
[task 2019-10-09T22:30:54.224Z] 22:30:54 INFO - 1570660254219 Marionette DEBUG 5 -> [0,31,"WebDriver:TakeScreenshot",{"full":true,"hash":false,"id":null,"scroll":true}]
[task 2019-10-09T22:30:54.242Z] 22:30:54 INFO - Failed to gather test failure debug: Browsing context has been discarded
[task 2019-10-09T22:30:54.243Z] 22:30:54 INFO - stacktrace:
[task 2019-10-09T22:30:54.243Z] 22:30:54 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-09T22:30:54.243Z] 22:30:54 INFO - NoSuchWindowError@chrome://marionette/content/error.js:409:5
[task 2019-10-09T22:30:54.243Z] 22:30:54 INFO - assert.that/<@chrome://marionette/content/assert.js:428:13
[task 2019-10-09T22:30:54.243Z] 22:30:54 INFO - assert.open@chrome://marionette/content/assert.js:183:72
[task 2019-10-09T22:30:54.244Z] 22:30:54 INFO - GeckoDriver.prototype.getPageSource@chrome://marionette/content/driver.js:1201:22
[task 2019-10-09T22:30:54.244Z] 22:30:54 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2019-10-09T22:30:54.244Z] 22:30:54 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2019-10-09T22:30:54.244Z] 22:30:54 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-10-09T22:30:54.245Z] 22:30:54 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-10-09T22:30:54.245Z] 22:30:54 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-10-09T22:30:54.245Z] 22:30:54 INFO - 1570660254229 Marionette DEBUG 5 <- [1,31,null,{"value":"iVBORw0KGgoAAAANSUhEUgAAAIgAAAA5CAYAAADtLGOCAAACR0lEQVR4nO3Z4W2zMBRGYS+RH57Em9xNPItn8S7sQn9d5Dj2i9M2TSrO ... KBQCIQSAQCiUAgEQgkAoFEIJAIBBKBQCIQSAQCiUAgEQgkAoFEIJAIBBKBQCIQSAQCiUAgEQgkAoFEIJAIBBKBQCIQSF+tffQbqqi6SAAAAABJRU5ErkJggg=="}]
[task 2019-10-09T22:30:54.246Z] 22:30:54 INFO - 1570660254231 Marionette DEBUG 5 -> [0,32,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:54.246Z] 22:30:54 INFO - 1570660254232 Marionette DEBUG 5 <- [1,32,null,{"value":null}]
[task 2019-10-09T22:30:54.246Z] 22:30:54 INFO - 1570660254233 Marionette DEBUG 5 -> [0,33,"Marionette:GetContext",{}]
[task 2019-10-09T22:30:54.247Z] 22:30:54 INFO - 1570660254233 Marionette DEBUG 5 <- [1,33,null,{"value":"chrome"}]
[task 2019-10-09T22:30:54.247Z] 22:30:54 INFO - 1570660254234 Marionette DEBUG 5 -> [0,34,"Marionette:SetContext",{"value":"content"}]
[task 2019-10-09T22:30:54.247Z] 22:30:54 INFO - 1570660254234 Marionette DEBUG 5 <- [1,34,null,{"value":null}]
[task 2019-10-09T22:30:54.247Z] 22:30:54 INFO - 1570660254235 Marionette DEBUG 5 -> [0,35,"WebDriver:GetPageSource",{}]
[task 2019-10-09T22:30:54.248Z] 22:30:54 INFO - 1570660254236 Marionette DEBUG 5 <- [1,35,{"error":"no such window","message":"Browsing context has been discarded","stacktrace":"WebDriverError@chrome://marione ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2019-10-09T22:30:54.248Z] 22:30:54 INFO - 1570660254237 Marionette DEBUG 5 -> [0,36,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:54.248Z] 22:30:54 INFO - 1570660254237 Marionette DEBUG 5 <- [1,36,null,{"value":null}]
[task 2019-10-09T22:30:54.249Z] 22:30:54 ERROR - TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_find_anonymous_children | NoSuchElementException: Unable to locate element: null
[task 2019-10-09T22:30:54.250Z] 22:30:54 INFO - stacktrace:
[task 2019-10-09T22:30:54.251Z] 22:30:54 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-09T22:30:54.251Z] 22:30:54 INFO - NoSuchElementError@chrome://marionette/content/error.js:387:5
[task 2019-10-09T22:30:54.252Z] 22:30:54 INFO - element.find/</<@chrome://marionette/content/element.js:343:16
[task 2019-10-09T22:30:54.252Z] 22:30:54 INFO - Traceback (most recent call last):
[task 2019-10-09T22:30:54.253Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2019-10-09T22:30:54.253Z] 22:30:54 INFO - testMethod()
[task 2019-10-09T22:30:54.254Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py", line 72, in test_find_anonymous_children
[task 2019-10-09T22:30:54.255Z] 22:30:54 INFO - self.assertEquals(HTMLElement, type(start_node.find_element(By.ANON, None)))
[task 2019-10-09T22:30:54.255Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 219, in find_element
[task 2019-10-09T22:30:54.256Z] 22:30:54 INFO - return self.marionette.find_element(method, target, self.id)
[task 2019-10-09T22:30:54.256Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1694, in find_element
[task 2019-10-09T22:30:54.257Z] 22:30:54 INFO - body, key="value")
[task 2019-10-09T22:30:54.257Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2019-10-09T22:30:54.257Z] 22:30:54 INFO - return func(*args, **kwargs)
[task 2019-10-09T22:30:54.258Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 598, in _send_message
[task 2019-10-09T22:30:54.258Z] 22:30:54 INFO - self._handle_error(err)
[task 2019-10-09T22:30:54.259Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 618, in _handle_error
[task 2019-10-09T22:30:54.259Z] 22:30:54 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2019-10-09T22:30:54.259Z] 22:30:54 INFO - TEST-INFO took 555ms
[task 2019-10-09T22:30:54.260Z] 22:30:54 INFO - 1570660254250 Marionette DEBUG 5 -> [0,37,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-10-09T22:30:54.260Z] 22:30:54 INFO - 1570660254251 Marionette DEBUG 5 <- [1,37,null,["1","22"]]
[task 2019-10-09T22:30:54.260Z] 22:30:54 INFO - 1570660254252 Marionette DEBUG 5 -> [0,38,"WebDriver:SwitchToWindow",{"handle":"22","focus":true,"name":"22"}]
[task 2019-10-09T22:30:54.260Z] 22:30:54 INFO - 1570660254253 Marionette DEBUG 5 <- [1,38,null,{"value":null}]
[task 2019-10-09T22:30:54.261Z] 22:30:54 INFO - 1570660254255 Marionette DEBUG 5 -> [0,39,"WebDriver:CloseChromeWindow",{}]
[task 2019-10-09T22:30:54.263Z] 22:30:54 INFO - 1570660254259 Marionette TRACE Received DOM event unload for [object HTMLDocument]
[task 2019-10-09T22:30:54.273Z] 22:30:54 INFO - 1570660254268 Marionette TRACE Received observer notification message-manager-disconnect
[task 2019-10-09T22:30:54.283Z] 22:30:54 INFO - 1570660254278 Marionette DEBUG 5 <- [1,39,null,["1"]]
[task 2019-10-09T22:30:54.288Z] 22:30:54 INFO - 1570660254285 Marionette DEBUG 5 -> [0,40,"WebDriver:SwitchToWindow",{"handle":"1","focus":true,"name":"1"}]
[task 2019-10-09T22:30:54.289Z] 22:30:54 INFO - 1570660254286 Marionette DEBUG 5 <- [1,40,null,{"value":null}]
[task 2019-10-09T22:30:54.289Z] 22:30:54 INFO - 1570660254287 Marionette DEBUG 5 -> [0,41,"WebDriver:GetChromeWindowHandles",{}]
[task 2019-10-09T22:30:54.289Z] 22:30:54 INFO - 1570660254288 Marionette DEBUG 5 <- [1,41,null,["1"]]
[task 2019-10-09T22:30:54.291Z] 22:30:54 INFO - 1570660254289 Marionette DEBUG 5 -> [0,42,"WebDriver:GetWindowHandles",{}]
[task 2019-10-09T22:30:54.291Z] 22:30:54 INFO - 1570660254289 Marionette DEBUG 5 <- [1,42,null,["5"]]
[task 2019-10-09T22:30:54.292Z] 22:30:54 INFO - 1570660254290 Marionette DEBUG 5 -> [0,43,"WebDriver:DeleteSession",{}]
[task 2019-10-09T22:30:54.294Z] 22:30:54 INFO - 1570660254292 Marionette DEBUG 5 <- [1,43,null,{"value":null}]
[task 2019-10-09T22:30:54.296Z] 22:30:54 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_find_anonymous_element_by_attribute
[task 2019-10-09T22:30:54.297Z] 22:30:54 INFO - 1570660254294 Marionette DEBUG Closed connection 5
[task 2019-10-09T22:30:54.303Z] 22:30:54 INFO - 1570660254298 Marionette DEBUG Accepted connection 6 from 127.0.0.1:51079
[task 2019-10-09T22:30:54.303Z] 22:30:54 INFO - 1570660254300 Marionette DEBUG 6 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-10-09T22:30:54.303Z] 22:30:54 INFO - 1570660254302 Marionette TRACE [5] Frame script loaded
[task 2019-10-09T22:30:54.305Z] 22:30:54 INFO - 1570660254303 Marionette TRACE [5] Frame script registered
[task 2019-10-09T22:30:54.307Z] 22:30:54 INFO - 1570660254304 Marionette DEBUG 6 <- [1,1,null,{"sessionId":"efdd02c9-a4fe-48ad-9646-c39615fda963","capabilities":{"browserName":"firefox","browserVersion":"71.0a ... p\tmpyvodnp.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-10-09T22:30:54.309Z] 22:30:54 INFO - 1570660254307 Marionette DEBUG 6 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-10-09T22:30:54.310Z] 22:30:54 INFO - 1570660254307 Marionette DEBUG 6 <- [1,2,null,{"value":null}]
[task 2019-10-09T22:30:54.311Z] 22:30:54 INFO - 1570660254308 Marionette DEBUG 6 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-10-09T22:30:54.311Z] 22:30:54 INFO - 1570660254309 Marionette DEBUG 6 <- [1,3,null,{"value":null}]
...
[task 2019-10-09T22:30:54.768Z] 22:30:54 INFO - 1570660254752 Marionette DEBUG 6 -> [0,36,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:54.770Z] 22:30:54 INFO - Failed to gather test failure debug: Browsing context has been discarded
[task 2019-10-09T22:30:54.771Z] 22:30:54 INFO - stacktrace:
[task 2019-10-09T22:30:54.772Z] 22:30:54 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-09T22:30:54.774Z] 22:30:54 INFO - NoSuchWindowError@chrome://marionette/content/error.js:409:5
[task 2019-10-09T22:30:54.776Z] 22:30:54 INFO - assert.that/<@chrome://marionette/content/assert.js:428:13
[task 2019-10-09T22:30:54.778Z] 22:30:54 INFO - assert.open@chrome://marionette/content/assert.js:183:72
[task 2019-10-09T22:30:54.781Z] 22:30:54 INFO - GeckoDriver.prototype.getPageSource@chrome://marionette/content/driver.js:1201:22
[task 2019-10-09T22:30:54.782Z] 22:30:54 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2019-10-09T22:30:54.784Z] 22:30:54 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2019-10-09T22:30:54.785Z] 22:30:54 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-10-09T22:30:54.787Z] 22:30:54 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-10-09T22:30:54.789Z] 22:30:54 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-10-09T22:30:54.791Z] 22:30:54 INFO - 1570660254752 Marionette DEBUG 6 <- [1,36,null,{"value":null}]
[task 2019-10-09T22:30:54.792Z] 22:30:54 ERROR - TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_find_anonymous_element_by_attribute | NoSuchElementException: Unable to locate anonymous element: {"dlgtype":"accept"}
[task 2019-10-09T22:30:54.795Z] 22:30:54 INFO - stacktrace:
[task 2019-10-09T22:30:54.797Z] 22:30:54 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-09T22:30:54.800Z] 22:30:54 INFO - NoSuchElementError@chrome://marionette/content/error.js:387:5
[task 2019-10-09T22:30:54.803Z] 22:30:54 INFO - element.find/</<@chrome://marionette/content/element.js:343:16
[task 2019-10-09T22:30:54.804Z] 22:30:54 INFO - Traceback (most recent call last):
[task 2019-10-09T22:30:54.806Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2019-10-09T22:30:54.807Z] 22:30:54 INFO - testMethod()
[task 2019-10-09T22:30:54.809Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py", line 55, in test_find_anonymous_element_by_attribute
[task 2019-10-09T22:30:54.810Z] 22:30:54 INFO - button = start_node.find_element(*accept_button)
[task 2019-10-09T22:30:54.812Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 219, in find_element
[task 2019-10-09T22:30:54.813Z] 22:30:54 INFO - return self.marionette.find_element(method, target, self.id)
[task 2019-10-09T22:30:54.817Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1694, in find_element
[task 2019-10-09T22:30:54.819Z] 22:30:54 INFO - body, key="value")
[task 2019-10-09T22:30:54.820Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2019-10-09T22:30:54.821Z] 22:30:54 INFO - return func(*args, **kwargs)
[task 2019-10-09T22:30:54.822Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 598, in _send_message
[task 2019-10-09T22:30:54.824Z] 22:30:54 INFO - self._handle_error(err)
[task 2019-10-09T22:30:54.825Z] 22:30:54 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 618, in _handle_error
[task 2019-10-09T22:30:54.826Z] 22:30:54 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2019-10-09T22:30:54.827Z] 22:30:54 INFO - TEST-INFO took 467ms
...
[task 2019-10-09T22:30:54.850Z] 22:30:54 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_find_anonymous_elements_by_attribute
[task 2019-10-09T22:30:54.851Z] 22:30:54 INFO - 1570660254830 Marionette DEBUG Accepted connection 7 from 127.0.0.1:51080
[task 2019-10-09T22:30:54.852Z] 22:30:54 INFO - 1570660254833 Marionette DEBUG 7 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-10-09T22:30:54.853Z] 22:30:54 INFO - 1570660254835 Marionette TRACE [5] Frame script loaded
[task 2019-10-09T22:30:54.854Z] 22:30:54 INFO - 1570660254836 Marionette TRACE [5] Frame script registered
[task 2019-10-09T22:30:54.855Z] 22:30:54 INFO - 1570660254838 Marionette DEBUG 7 <- [1,1,null,{"sessionId":"8a32f83a-d798-411f-b0ef-b5ed450c019c","capabilities":{"browserName":"firefox","browserVersion":"71.0a ... p\tmpyvodnp.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-10-09T22:30:54.856Z] 22:30:54 INFO - 1570660254843 Marionette DEBUG 7 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-10-09T22:30:54.857Z] 22:30:54 INFO - 1570660254844 Marionette DEBUG 7 <- [1,2,null,{"value":null}]
[task 2019-10-09T22:30:54.857Z] 22:30:54 INFO - 1570660254846 Marionette DEBUG 7 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-10-09T22:30:54.858Z] 22:30:54 INFO - 1570660254847 Marionette DEBUG 7 <- [1,3,null,{"value":null}]
[task 2019-10-09T22:30:54.859Z] 22:30:54 INFO - 1570660254849 Marionette DEBUG 7 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2019-10-09T22:30:54.860Z] 22:30:54 INFO - 1570660254850 Marionette DEBUG 7 <- [1,4,null,{"value":null}]
[task 2019-10-09T22:30:54.862Z] 22:30:54 INFO - 1570660254854 Marionette DEBUG 7 -> [0,5,"WebDriver:GetChromeWindowHandle",{}]
...
[task 2019-10-09T22:30:55.316Z] 22:30:55 INFO - 1570660255304 Marionette DEBUG 7 -> [0,34,"Marionette:SetContext",{"value":"content"}]
[task 2019-10-09T22:30:55.318Z] 22:30:55 INFO - 1570660255304 Marionette DEBUG 7 <- [1,34,null,{"value":null}]
[task 2019-10-09T22:30:55.320Z] 22:30:55 INFO - 1570660255306 Marionette DEBUG 7 -> [0,35,"WebDriver:GetPageSource",{}]
[task 2019-10-09T22:30:55.321Z] 22:30:55 INFO - 1570660255306 Marionette DEBUG 7 <- [1,35,{"error":"no such window","message":"Browsing context has been discarded","stacktrace":"WebDriverError@chrome://marione ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2019-10-09T22:30:55.323Z] 22:30:55 INFO - Failed to gather test failure debug: Browsing context has been discarded
[task 2019-10-09T22:30:55.324Z] 22:30:55 INFO - stacktrace:
[task 2019-10-09T22:30:55.326Z] 22:30:55 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-09T22:30:55.327Z] 22:30:55 INFO - NoSuchWindowError@chrome://marionette/content/error.js:409:5
[task 2019-10-09T22:30:55.329Z] 22:30:55 INFO - assert.that/<@chrome://marionette/content/assert.js:428:13
[task 2019-10-09T22:30:55.330Z] 22:30:55 INFO - assert.open@chrome://marionette/content/assert.js:183:72
[task 2019-10-09T22:30:55.332Z] 22:30:55 INFO - GeckoDriver.prototype.getPageSource@chrome://marionette/content/driver.js:1201:22
[task 2019-10-09T22:30:55.334Z] 22:30:55 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2019-10-09T22:30:55.335Z] 22:30:55 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2019-10-09T22:30:55.336Z] 22:30:55 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-10-09T22:30:55.338Z] 22:30:55 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-10-09T22:30:55.339Z] 22:30:55 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-10-09T22:30:55.340Z] 22:30:55 INFO - 1570660255308 Marionette DEBUG 7 -> [0,36,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:55.342Z] 22:30:55 INFO - 1570660255308 Marionette DEBUG 7 <- [1,36,null,{"value":null}]
[task 2019-10-09T22:30:55.343Z] 22:30:55 ERROR - TEST-UNEXPECTED-FAIL | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_find_anonymous_elements_by_attribute | AssertionError: 1 != 0
[task 2019-10-09T22:30:55.344Z] 22:30:55 INFO - Traceback (most recent call last):
[task 2019-10-09T22:30:55.344Z] 22:30:55 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2019-10-09T22:30:55.346Z] 22:30:55 INFO - testMethod()
[task 2019-10-09T22:30:55.347Z] 22:30:55 INFO - File "C:\tasks\task_1570658943\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py", line 66, in test_find_anonymous_elements_by_attribute
[task 2019-10-09T22:30:55.348Z] 22:30:55 INFO - self.assertEquals(1, len(buttons))
[task 2019-10-09T22:30:55.350Z] 22:30:55 INFO - TEST-INFO took 494ms
...
[task 2019-10-09T22:30:55.377Z] 22:30:55 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_switch_to_anonymous_frame
[task 2019-10-09T22:30:55.398Z] 22:30:55 INFO - 1570660255380 Marionette DEBUG Accepted connection 8 from 127.0.0.1:51081
[task 2019-10-09T22:30:55.399Z] 22:30:55 INFO - 1570660255383 Marionette DEBUG 8 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-10-09T22:30:55.400Z] 22:30:55 INFO - 1570660255384 Marionette TRACE [5] Frame script loaded
[task 2019-10-09T22:30:55.401Z] 22:30:55 INFO - 1570660255385 Marionette TRACE [5] Frame script registered
[task 2019-10-09T22:30:55.403Z] 22:30:55 INFO - 1570660255388 Marionette DEBUG 8 <- [1,1,null,{"sessionId":"28d65b66-de89-4ba3-b73a-67ccad4ad2ea","capabilities":{"browserName":"firefox","browserVersion":"71.0a ... p\tmpyvodnp.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-10-09T22:30:55.404Z] 22:30:55 INFO - 1570660255391 Marionette DEBUG 8 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2019-10-09T22:30:55.405Z] 22:30:55 INFO - 1570660255392 Marionette DEBUG 8 <- [1,2,null,{"value":null}]
[task 2019-10-09T22:30:55.406Z] 22:30:55 INFO - 1570660255394 Marionette DEBUG 8 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2019-10-09T22:30:55.408Z] 22:30:55 INFO - 1570660255394 Marionette DEBUG 8 <- [1,3,null,{"value":null}]
...
[task 2019-10-09T22:30:56.001Z] 22:30:56 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_switch_to_anonymous_iframe
[task 2019-10-09T22:30:56.022Z] 22:30:56 INFO - 1570660256004 Marionette DEBUG Accepted connection 9 from 127.0.0.1:51082
[task 2019-10-09T22:30:56.023Z] 22:30:56 INFO - 1570660256006 Marionette DEBUG 9 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2019-10-09T22:30:56.024Z] 22:30:56 INFO - 1570660256008 Marionette TRACE [5] Frame script loaded
[task 2019-10-09T22:30:56.026Z] 22:30:56 INFO - 1570660256009 Marionette TRACE [5] Frame script registered
[task 2019-10-09T22:30:56.027Z] 22:30:56 INFO - 1570660256010 Marionette DEBUG 9 <- [1,1,null,{"sessionId":"1cca4975-5771-404e-80e4-b20f466b64a6","capabilities":{"browserName":"firefox","browserVersion":"71.0a ... p\tmpyvodnp.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2019-10-09T22:30:56.029Z] 22:30:56 INFO - 1570660256013 Marionette DEBUG 9 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
...
[task 2019-10-09T22:30:56.584Z] 22:30:56 INFO - 1570660256578 Marionette DEBUG 9 -> [0,31,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:56.585Z] 22:30:56 INFO - 1570660256578 Marionette DEBUG 9 <- [1,31,null,{"value":null}]
[task 2019-10-09T22:30:56.586Z] 22:30:56 INFO - 1570660256580 Marionette DEBUG 9 -> [0,32,"WebDriver:TakeScreenshot",{"full":true,"hash":false,"id":null,"scroll":true}]
[task 2019-10-09T22:30:56.598Z] 22:30:56 INFO - 1570660256589 Marionette DEBUG 9 <- [1,32,null,{"value":"iVBORw0KGgoAAAANSUhEUgAAAIgAAAA5CAYAAADtLGOCAAACR0lEQVR4nO3Z4W2zMBRGYS+RH57Em9xNPItn8S7sQn9d5Dj2i9M2TSrO ... KBQCIQSAQCiUAgEQgkAoFEIJAIBBKBQCIQSAQCiUAgEQgkAoFEIJAIBBKBQCIQSAQCiUAgEQgkAoFEIJAIBBKBQCIQSF+tffQbqqi6SAAAAABJRU5ErkJggg=="}]
[task 2019-10-09T22:30:56.599Z] 22:30:56 INFO - 1570660256592 Marionette DEBUG 9 -> [0,33,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:56.601Z] 22:30:56 INFO - 1570660256592 Marionette DEBUG 9 <- [1,33,null,{"value":null}]
[task 2019-10-09T22:30:56.602Z] 22:30:56 INFO - 1570660256594 Marionette DEBUG 9 -> [0,34,"Marionette:GetContext",{}]
[task 2019-10-09T22:30:56.603Z] 22:30:56 INFO - 1570660256594 Marionette DEBUG 9 <- [1,34,null,{"value":"chrome"}]
[task 2019-10-09T22:30:56.604Z] 22:30:56 INFO - 1570660256596 Marionette DEBUG 9 -> [0,35,"Marionette:SetContext",{"value":"content"}]
[task 2019-10-09T22:30:56.605Z] 22:30:56 INFO - 1570660256596 Marionette DEBUG 9 <- [1,35,null,{"value":null}]
[task 2019-10-09T22:30:56.606Z] 22:30:56 INFO - 1570660256598 Marionette DEBUG 9 -> [0,36,"WebDriver:GetPageSource",{}]
[task 2019-10-09T22:30:56.609Z] 22:30:56 INFO - 1570660256599 Marionette DEBUG 9 <- [1,36,{"error":"no such window","message":"Browsing context has been discarded","stacktrace":"WebDriverError@chrome://marione ... t@chrome://marionette/content/server.js:249:9\n_onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20\n"},null]
[task 2019-10-09T22:30:56.610Z] 22:30:56 INFO - 1570660256601 Marionette DEBUG 9 -> [0,37,"Marionette:SetContext",{"value":"chrome"}]
[task 2019-10-09T22:30:56.612Z] 22:30:56 INFO - Failed to gather test failure debug: Browsing context has been discarded
[task 2019-10-09T22:30:56.613Z] 22:30:56 INFO - stacktrace:
[task 2019-10-09T22:30:56.614Z] 22:30:56 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-09T22:30:56.616Z] 22:30:56 INFO - NoSuchWindowError@chrome://marionette/content/error.js:409:5
[task 2019-10-09T22:30:56.617Z] 22:30:56 INFO - assert.that/<@chrome://marionette/content/assert.js:428:13
[task 2019-10-09T22:30:56.618Z] 22:30:56 INFO - assert.open@chrome://marionette/content/assert.js:183:72
[task 2019-10-09T22:30:56.619Z] 22:30:56 INFO - GeckoDriver.prototype.getPageSource@chrome://marionette/content/driver.js:1201:22
[task 2019-10-09T22:30:56.621Z] 22:30:56 INFO - despatch@chrome://marionette/content/server.js:305:40
[task 2019-10-09T22:30:56.623Z] 22:30:56 INFO - execute@chrome://marionette/content/server.js:275:16
[task 2019-10-09T22:30:56.624Z] 22:30:56 INFO - onPacket/<@chrome://marionette/content/server.js:248:20
[task 2019-10-09T22:30:56.626Z] 22:30:56 INFO - onPacket@chrome://marionette/content/server.js:249:9
[task 2019-10-09T22:30:56.627Z] 22:30:56 INFO - _onJSONObjectReady/<@chrome://marionette/content/transport.js:501:20
[task 2019-10-09T22:30:56.629Z] 22:30:56 INFO - 1570660256601 Marionette DEBUG 9 <- [1,37,null,{"value":null}]
[task 2019-10-09T22:30:56.631Z] 22:30:56 ERROR - TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py TestAnonymousNodes.test_switch_to_anonymous_iframe | NoSuchElementException: Unable to locate anonymous element: {"anonid":"iframe"}
[task 2019-10-09T22:30:56.632Z] 22:30:56 INFO - stacktrace:
[task 2019-10-09T22:30:56.634Z] 22:30:56 INFO - WebDriverError@chrome://marionette/content/error.js:175:5
[task 2019-10-09T22:30:56.635Z] 22:30:56 INFO - NoSuchElementError@chrome://marionette/content/error.js:387:5
[task 2019-10-09T22:30:56.637Z] 22:30:56 INFO - element.find/</<@chrome://marionette/content/element.js:343:16
[task 2019-10-09T22:30:56.638Z] 22:30:56 INFO - Traceback (most recent call last):
[task 2019-10-09T22:30:56.640Z] 22:30:56 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
[task 2019-10-09T22:30:56.641Z] 22:30:56 INFO - testMethod()
[task 2019-10-09T22:30:56.643Z] 22:30:56 INFO - File "C:\tasks\task_1570658943\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_anonymous_content.py", line 42, in test_switch_to_anonymous_iframe
[task 2019-10-09T22:30:56.644Z] 22:30:56 INFO - anon_iframe_el = el.find_element(By.ANON_ATTRIBUTE, {"anonid": "iframe"})
[task 2019-10-09T22:30:56.646Z] 22:30:56 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 219, in find_element
[task 2019-10-09T22:30:56.649Z] 22:30:56 INFO - return self.marionette.find_element(method, target, self.id)
[task 2019-10-09T22:30:56.651Z] 22:30:56 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1694, in find_element
[task 2019-10-09T22:30:56.652Z] 22:30:56 INFO - body, key="value")
[task 2019-10-09T22:30:56.653Z] 22:30:56 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2019-10-09T22:30:56.654Z] 22:30:56 INFO - return func(*args, **kwargs)
[task 2019-10-09T22:30:56.655Z] 22:30:56 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 598, in _send_message
[task 2019-10-09T22:30:56.656Z] 22:30:56 INFO - self._handle_error(err)
[task 2019-10-09T22:30:56.657Z] 22:30:56 INFO - File "C:\tasks\task_1570658943\build\venv\lib\site-packages\marionette_driver\marionette.py", line 618, in _handle_error
[task 2019-10-09T22:30:56.658Z] 22:30:56 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace)
[task 2019-10-09T22:30:56.659Z] 22:30:56 INFO - TEST-INFO took 615ms

Emma, could you, please, take a look?

Is this related to the changes from bug 1586344 ?

Flags: needinfo?(emalysz)

Also seeing intermittent occurences on these runs:
TEST-UNEXPECTED-FAIL | testing\marionette\harness\marionette_harness\tests\unit\test_elementsize_chrome.py TestElementSizeChrome.testShouldReturnTheSizeOfAnInput | AssertionError: False is not true

TEST-UNEXPECTED-FAIL | testing\marionette\harness\marionette_harness\tests\unit\test_element_state_chrome.py TestElementState.test_enabled | AssertionError: True is not false

Those failures are a bit weird. In CI and specifically mozilla-central it only seems that tests on windows Aarch64 are affected and it's all started with the following merge:

https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&searchStr=marionette&revision=be9a6289486a6f366e431782b84a0c0633f8fec2

But locally it also fails for me on MacOS, and also try builds from yesterday morning aren't happy across all the platforms:

https://treeherder.mozilla.org/#/jobs?repo=try&revision=2b9c8cd00d3940dfba500c7245b602b9950e5663

But this try build was based on artifacts builds, and maybe that's the difference compared to integration branches.

Anyway, I have more the suspicion that this is a regression from bug Bug 1510785. XBL might be disabled, and as such the Marionette tests fail.

Flags: needinfo?(bdahl)

Indeed, when I check the local mozinfo.json file, it reports "xbl": false. So it's clearly bug 1510785 which regressed that.

Flags: needinfo?(emalysz)
Regressed by: 1510785

Based on https://hg.mozilla.org/integration/autoland/rev/36b84f0fb2d1 XBL should only be disabled on Android but not on desktop. So why isn't that the case, and we also disable it on desktop?

Component: Marionette → General
Flags: needinfo?(bdahl)
Product: Testing → Firefox Build System
Version: Version 3 → unspecified

I remember thinking that the Windows aarch64 build is a pretty wonky build: it pulls artifacts at build times for Widevine/EME support. I didn't expect it to have !COMPILE_ENVIRONMENT. See https://bugzilla.mozilla.org/show_bug.cgi?id=1503366 and https://searchfox.org/mozilla-central/source/Makefile.in#153-158.

I really have no idea how to handle this more gracefully: it's just hard to ensure that the build flags set for XUL are the same in the artifact build.

See Also: → 1587627

The MOZ_XBL define is also used in app constants and needs to be defined
for artifact builds.

Pushed by bdahl@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/967dec7ea9e6
Define MOZ_XBL for non-compiled environments too. r=nalexander

thanks a lot for fixing this :)

Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla71

Works fine for me locally on MacOS with an artifact build. Thanks Brendan!

Status: RESOLVED → VERIFIED
Duplicate of this bug: 1587674
Duplicate of this bug: 1587484
Duplicate of this bug: 1587465
Duplicate of this bug: 1587669
You need to log in before you can comment on or make changes to this bug.