Closed Bug 1512342 Opened 6 years ago Closed 5 years ago

Intermittent /test_window_handles_chrome.py TestWindowHandles.test_chrome_window_handles_with_scopes | IOError: Failed to trigger opening a new window: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason:

Categories

(Testing :: Marionette Client and Harness, defect, P5)

Version 3
defect

Tracking

(firefox64 unaffected, firefox65 fixed, firefox66 fixed)

RESOLVED FIXED
mozilla66
Tracking Status
firefox64 --- unaffected
firefox65 --- fixed
firefox66 --- fixed

People

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

References

Details

(Keywords: intermittent-failure)

Filed by: nbeleuzu [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=215678722&repo=autoland

https://queue.taskcluster.net/v1/task/eQhcdDY5TvSlm41HXv0CKQ/runs/0/artifacts/public/logs/live_backing.log

[task 2018-12-06T00:49:51.919Z] 00:49:51     INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_window_handles_chrome.py TestWindowHandles.test_chrome_window_handles_with_scopes
[task 2018-12-06T00:49:51.936Z] 00:49:51     INFO -  1544057391920	Marionette	DEBUG	Accepted connection 71 from 127.0.0.1:34410
[task 2018-12-06T00:49:51.936Z] 00:49:51     INFO -  1544057391922	Marionette	DEBUG	Closed connection 70
[task 2018-12-06T00:49:51.937Z] 00:49:51     INFO -  1544057391923	Marionette	DEBUG	71 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2018-12-06T00:49:51.938Z] 00:49:51     INFO -  1544057391926	Marionette	TRACE	[2147483649] Frame script loaded
[task 2018-12-06T00:49:51.939Z] 00:49:51     INFO -  1544057391928	Marionette	TRACE	[2147483649] Frame script registered
[task 2018-12-06T00:49:51.940Z] 00:49:51     INFO -  1544057391930	Marionette	DEBUG	71 <- [1,1,null,{"sessionId":"3cc84bbb-dadf-4a8d-bd47-31022a6d5d0f","capabilities":{"browserName":"firefox","browserVersion":"65.0a ... mp/tmpqFgICF.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2018-12-06T00:49:51.957Z] 00:49:51     INFO -  1544057391944	Marionette	DEBUG	71 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
[task 2018-12-06T00:49:51.958Z] 00:49:51     INFO -  1544057391945	Marionette	DEBUG	71 <- [1,2,null,{"value":null}]
[task 2018-12-06T00:49:51.959Z] 00:49:51     INFO -  1544057391947	Marionette	DEBUG	71 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
[task 2018-12-06T00:49:51.960Z] 00:49:51     INFO -  1544057391948	Marionette	DEBUG	71 <- [1,3,null,{"value":null}]
[task 2018-12-06T00:49:51.961Z] 00:49:51     INFO -  1544057391949	Marionette	DEBUG	71 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2018-12-06T00:49:51.962Z] 00:49:51     INFO -  1544057391951	Marionette	DEBUG	71 <- [1,4,null,{"value":null}]
[task 2018-12-06T00:49:51.964Z] 00:49:51     INFO -  1544057391952	Marionette	DEBUG	71 -> [0,5,"WebDriver:GetChromeWindowHandle",{}]
[task 2018-12-06T00:49:51.965Z] 00:49:51     INFO -  1544057391953	Marionette	DEBUG	71 <- [1,5,null,{"value":"1"}]
[task 2018-12-06T00:49:51.966Z] 00:49:51     INFO -  1544057391954	Marionette	DEBUG	71 -> [0,6,"WebDriver:GetChromeWindowHandles",{}]
[task 2018-12-06T00:49:51.967Z] 00:49:51     INFO -  1544057391954	Marionette	DEBUG	71 <- [1,6,null,["1"]]
[task 2018-12-06T00:49:51.968Z] 00:49:51     INFO -  1544057391956	Marionette	DEBUG	71 -> [0,7,"WebDriver:GetWindowHandle",{}]
[task 2018-12-06T00:49:51.976Z] 00:49:51     INFO -  1544057391969	Marionette	DEBUG	71 <- [1,7,null,{"value":"2147483649"}]
[task 2018-12-06T00:49:51.978Z] 00:49:51     INFO -  1544057391971	Marionette	DEBUG	71 -> [0,8,"WebDriver:GetWindowHandles",{}]
[task 2018-12-06T00:49:51.979Z] 00:49:51     INFO -  1544057391972	Marionette	DEBUG	71 <- [1,8,null,["2147483649"]]
[task 2018-12-06T00:49:51.979Z] 00:49:51     INFO -  1544057391973	Marionette	DEBUG	71 -> [0,9,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-12-06T00:49:51.982Z] 00:49:51     INFO -  1544057391974	Marionette	DEBUG	71 <- [1,9,null,{"value":null}]
[task 2018-12-06T00:49:51.982Z] 00:49:51     INFO -  1544057391975	Marionette	DEBUG	71 -> [0,10,"WebDriver:GetChromeWindowHandles",{}]
[task 2018-12-06T00:49:51.983Z] 00:49:51     INFO -  1544057391976	Marionette	DEBUG	71 <- [1,10,null,["1"]]
[task 2018-12-06T00:49:51.989Z] 00:49:51     INFO -  1544057391982	Marionette	DEBUG	71 -> [0,11,"WebDriver:GetWindowHandles",{}]
[task 2018-12-06T00:49:51.990Z] 00:49:51     INFO -  1544057391985	Marionette	DEBUG	71 <- [1,11,null,["2147483649"]]
[task 2018-12-06T00:49:51.991Z] 00:49:51     INFO -  1544057391986	Marionette	DEBUG	71 -> [0,12,"WebDriver:NewWindow",{"type":"window","focus":false}]
[task 2018-12-06T00:49:52.053Z] 00:49:52     INFO -  1544057392050	Marionette	TRACE	Received DOM event activate for [object ChromeWindow]
[task 2018-12-06T00:49:52.054Z] 00:49:52     INFO -  1544057392050	Marionette	TRACE	Received DOM event focus for [object XULDocument]
[task 2018-12-06T00:49:52.216Z] 00:49:52     INFO -  1544057392203	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2018-12-06T00:55:52.096Z] 00:55:52     INFO -  1544057752087	Marionette	DEBUG	Closed connection 71
[task 2018-12-06T00:57:03.169Z] 00:57:03     INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_window_handles_chrome.py TestWindowHandles.test_chrome_window_handles_with_scopes | IOError: Failed to trigger opening a new window: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
[task 2018-12-06T00:57:03.172Z] 00:57:03     INFO - Traceback (most recent call last):
[task 2018-12-06T00:57:03.172Z] 00:57:03     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2018-12-06T00:57:03.172Z] 00:57:03     INFO -     testMethod()
[task 2018-12-06T00:57:03.173Z] 00:57:03     INFO -   File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_window_handles_chrome.py", line 43, in test_chrome_window_handles_with_scopes
[task 2018-12-06T00:57:03.174Z] 00:57:03     INFO -     new_browser = self.open_window()
[task 2018-12-06T00:57:03.174Z] 00:57:03     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/mixins/window_manager.py", line 110, in open_window
[task 2018-12-06T00:57:03.176Z] 00:57:03     INFO -     reraise(exc, 'Failed to trigger opening a new window: {}'.format(val), tb)
[task 2018-12-06T00:57:03.177Z] 00:57:03     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/mixins/window_manager.py", line 103, in open_window
[task 2018-12-06T00:57:03.177Z] 00:57:03     INFO -     result = self.marionette.open(type="window", focus=focus)
[task 2018-12-06T00:57:03.178Z] 00:57:03     INFO -   File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1443, in open
It's a hang in the `New Window` command as implemented on bug 1504756. Specifically when not focusing the new window, which is the default. As it looks like the further call to `focus()` didn't trigger any `focus` nor `activate` events.
Blocks: 1514597
The backout of bug 1504756 made this failure disappear.
Assignee: nobody → hskupin
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla66
Product: Testing → Remote Protocol
Moving bug to Testing::Marionette Client and Harness component per bug 1815831.
Component: Marionette → Marionette Client and Harness
Product: Remote Protocol → Testing
You need to log in before you can comment on or make changes to this bug.