Filed by: rmaries [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=207441772&repo=mozilla-central https://queue.taskcluster.net/v1/task/SUx1nINmRa2bLglXG84saQ/runs/0/artifacts/public/logs/live_backing.log 06:03:22 INFO - TEST-START | testing\marionette\harness\marionette_harness\tests\unit\test_switch_window_chrome.py TestSwitchToWindowContent.test_switch_from_content_to_chrome_window_should_not_change_selected_tab 06:03:22 INFO - 1540361002739 Marionette DEBUG Accepted connection 27 from 127.0.0.1:51058 06:03:22 INFO - 1540361002739 Marionette TRACE 27 -> [0,1,"WebDriver:NewSession",{}] 06:03:22 INFO - 1540361002741 Marionette DEBUG [2147483649] Frame script loaded 06:03:22 INFO - 1540361002742 Marionette DEBUG [2147483649] Frame script registered 06:03:22 INFO - 1540361002743 Marionette TRACE 27 <- [1,1,null,{"sessionId":"ddb528df-0d33-4dc5-848d-7685b6fe2429","capabilities":{"browserName":"firefox","browserVersion":"65.0a ... p\\tmpc3rj14.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] 06:03:22 INFO - 1540361002744 Marionette TRACE 27 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}] 06:03:22 INFO - 1540361002744 Marionette TRACE 27 <- [1,2,null,{"value":null}] 06:03:22 INFO - 1540361002745 Marionette TRACE 27 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}] 06:03:22 INFO - 1540361002745 Marionette TRACE 27 <- [1,3,null,{"value":null}] 06:03:22 INFO - 1540361002746 Marionette TRACE 27 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}] 06:03:22 INFO - 1540361002746 Marionette TRACE 27 <- [1,4,null,{"value":null}] 06:03:22 INFO - 1540361002747 Marionette TRACE 27 -> [0,5,"WebDriver:GetChromeWindowHandle",{}] 06:03:22 INFO - 1540361002748 Marionette TRACE 27 <- [1,5,null,{"value":"3"}] 06:03:22 INFO - 1540361002748 Marionette TRACE 27 -> [0,6,"WebDriver:GetChromeWindowHandles",{}] 06:03:22 INFO - 1540361002749 Marionette TRACE 27 <- [1,6,null,["3"]] 06:03:22 INFO - 1540361002751 Marionette TRACE 27 -> [0,7,"WebDriver:GetWindowHandle",{}] 06:03:22 INFO - 1540361002752 Marionette TRACE 27 <- [1,7,null,{"value":"2147483649"}] 06:03:22 INFO - 1540361002752 Marionette TRACE 27 -> [0,8,"WebDriver:GetWindowHandles",{}] 06:03:22 INFO - 1540361002753 Marionette TRACE 27 <- [1,8,null,["2147483649"]] 06:03:22 INFO - 1540361002753 Marionette TRACE 27 -> [0,9,"Marionette:GetContext",{}] 06:03:22 INFO - 1540361002754 Marionette TRACE 27 <- [1,9,null,{"value":"content"}] 06:03:22 INFO - 1540361002754 Marionette TRACE 27 -> [0,10,"Marionette:SetContext",{"value":"chrome"}] 06:03:22 INFO - 1540361002755 Marionette TRACE 27 <- [1,10,null,{"value":null}] 06:03:22 INFO - 1540361002756 Marionette TRACE 27 -> [0,11,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"Z:\\task_1540360085\\build\\tes ... rowser.selectedTab) {\n return i;\n }\n }","sandbox":"default","line":70}] 06:03:22 INFO - 1540361002757 Marionette TRACE 27 <- [1,11,null,{"value":0}] 06:03:22 INFO - 1540361002759 Marionette TRACE 27 -> [0,12,"Marionette:SetContext",{"value":"content"}] 06:03:22 INFO - 1540361002759 Marionette TRACE 27 <- [1,12,null,{"value":null}] 06:03:22 INFO - 1540361002760 Marionette TRACE 27 -> [0,13,"Marionette:GetContext",{}] 06:03:22 INFO - 1540361002760 Marionette TRACE 27 <- [1,13,null,{"value":"content"}] 06:03:22 INFO - 1540361002761 Marionette TRACE 27 -> [0,14,"Marionette:SetContext",{"value":"content"}] 06:03:22 INFO - 1540361002761 Marionette TRACE 27 <- [1,14,null,{"value":null}] 06:03:22 INFO - 1540361002762 Marionette TRACE 27 -> [0,15,"WebDriver:Navigate",{"url":"http://127.0.0.1:49846/windowHandles.html"}] 06:03:22 INFO - 1540361002765 Marionette DEBUG [2147483649] Received DOM event beforeunload for http://127.0.0.1:49846/formPage.html 06:03:22 INFO - 1540361002771 Marionette DEBUG [2147483649] Received DOM event pagehide for http://127.0.0.1:49846/formPage.html 06:03:22 INFO - 1540361002771 Marionette DEBUG [2147483649] Received DOM event unload for http://127.0.0.1:49846/formPage.html 06:03:22 INFO - 1540361002777 Marionette DEBUG [2147483649] Received DOM event DOMContentLoaded for http://127.0.0.1:49846/windowHandles.html 06:03:22 INFO - 1540361002782 Marionette DEBUG [2147483649] Received DOM event pageshow for http://127.0.0.1:49846/windowHandles.html 06:03:22 INFO - 1540361002785 Marionette TRACE 27 <- [1,15,null,{"value":null}] 06:03:22 INFO - 1540361002787 Marionette TRACE 27 -> [0,16,"Marionette:SetContext",{"value":"content"}] 06:03:22 INFO - 1540361002788 Marionette TRACE 27 <- [1,16,null,{"value":null}] 06:03:22 INFO - 1540361002792 Marionette TRACE 27 -> [0,17,"WebDriver:GetWindowHandles",{}] 06:03:22 INFO - 1540361002792 Marionette TRACE 27 <- [1,17,null,["2147483649"]] 06:03:22 INFO - 1540361002793 Marionette TRACE 27 -> [0,18,"Marionette:GetContext",{}] 06:03:22 INFO - 1540361002793 Marionette TRACE 27 <- [1,18,null,{"value":"content"}] 06:03:22 INFO - 1540361002794 Marionette TRACE 27 -> [0,19,"Marionette:SetContext",{"value":"content"}] 06:03:22 INFO - 1540361002794 Marionette TRACE 27 <- [1,19,null,{"value":null}] 06:03:22 INFO - 1540361002795 Marionette TRACE 27 -> [0,20,"WebDriver:FindElement",{"using":"id","value":"new-tab"}] 06:03:22 INFO - 1540361002797 Marionette TRACE 27 <- [1,20,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"20e11c83-d81b-417d-895e-13cdd1a68e0b"}}] 06:03:22 INFO - 1540361002798 Marionette TRACE 27 -> [0,21,"WebDriver:ElementClick",{"id":"20e11c83-d81b-417d-895e-13cdd1a68e0b"}] 06:09:33 INFO - 1540361373069 Marionette TRACE 27 <- [1,21,null,{"value":null}] 06:09:33 INFO - 1540361373096 Marionette DEBUG [2147483789] Frame script loaded 06:09:33 INFO - 1540361373129 Marionette DEBUG Closed connection 27 06:09:33 INFO - 1540361373136 Marionette ERROR [2147483789] No reply from Marionette:Register 06:09:33 INFO - JavaScript error: chrome://marionette/content/listener.js, line 456: TypeError: reply[0] is undefined; can't access its "outerWindowID" property 06:14:03 INFO - Traceback (most recent call last): 06:14:03 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\mozprocess\processhandler.py", line 531, in _wait 06:14:03 INFO - self.MAX_PROCESS_KILL_DELAY) 06:14:03 INFO - File "c:\mozilla-build\python\Lib\Queue.py", line 176, in get 06:14:16 INFO - raise Empty 06:14:16 INFO - Empty 06:14:16 INFO - IO Completion Port unexpectedly closed 06:14:16 ERROR - TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_switch_window_chrome.py TestSwitchToWindowContent.test_switch_from_content_to_chrome_window_should_not_change_selected_tab | InvalidSessionIdException: Failed to trigger opening a new tab: Please start a session 06:14:16 INFO - Traceback (most recent call last): 06:14:16 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run 06:14:16 INFO - testMethod() 06:14:16 INFO - File "Z:\task_1540360085\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_switch_window_content.py", line 143, in test_switch_from_content_to_chrome_window_should_not_change_selected_tab 06:14:16 INFO - new_tab = self.open_tab(self.open_tab_in_foreground) 06:14:16 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\marionette_harness\runner\mixins\window_manager.py", line 74, in open_tab 06:14:16 INFO - reraise(exc, 'Failed to trigger opening a new tab: {}'.format(val), tb) 06:14:16 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\marionette_harness\runner\mixins\window_manager.py", line 68, in open_tab 06:14:16 INFO - trigger() 06:14:16 INFO - File "Z:\task_1540360085\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_switch_window_content.py", line 82, in open_tab_in_foreground 06:14:16 INFO - link.click() 06:14:16 INFO - File "c:\mozilla-build\python\Lib\contextlib.py", line 35, in __exit__ 06:14:16 INFO - self.gen.throw(type, value, traceback) 06:14:16 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1501, in using_context 06:14:16 INFO - self.set_context(scope) 06:14:16 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1478, in set_context 06:14:16 INFO - {"value": context}) 06:14:16 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _ 06:14:16 INFO - return func(*args, **kwargs) 06:14:16 INFO - File "Z:\task_1540360085\build\venv\lib\site-packages\marionette_driver\marionette.py", line 753, in _send_message 06:14:16 INFO - raise errors.InvalidSessionIdException("Please start a session")

We failed to register the frame script here. It's the first time I see this failure.

https://bugzilla.mozilla.org/show_bug.cgi?id=1311041 might help.

Moving bug to Testing::Marionette Client and Harness component per bug 1815831.