1381343 - Intermittent test_switch_window_content.py TestSwitchToWindowContent.test_switch_to_new_private_browsing_window_has_to_register_browsers | IOError: Process killed because the connection to Marionette server is lost (Connection timed out after 360.0s)

Status: RESOLVED WORKSFORME

(Testing :: Marionette Client and Harness, defect)

Description

[Treeherder Bug Filer]

Filed by: hskupin [at] mozilla.com

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

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

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+1]]

[task 2017-07-16T11:48:20.589907Z] 11:48:20     INFO -  1500205700584	Marionette	TRACE	95 -> [0,31,"switchToWindow",{"focus":true,"name":"195"}]
[task 2017-07-16T11:54:20.691083Z] 11:54:20     INFO -  1500206060686	Marionette	DEBUG	Closed connection 95
[task 2017-07-16T11:55:26.769276Z] 11:55:26     INFO - TEST-UNEXPECTED-ERROR | test_switch_window_content.py TestSwitchToWindowContent.test_switch_to_new_private_browsing_window_has_to_register_browsers | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
[task 2017-07-16T11:55:26.769583Z] 11:55:26     INFO - Traceback (most recent call last):
[task 2017-07-16T11:55:26.770339Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 156, in run
[task 2017-07-16T11:55:26.770957Z] 11:55:26     INFO -     testMethod()
[task 2017-07-16T11:55:26.771522Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_harness/marionette_test/decorators.py", line 150, in skip_wrapper
[task 2017-07-16T11:55:26.772240Z] 11:55:26     INFO -     return test_item(self, *args, **kwargs)
[task 2017-07-16T11:55:26.772953Z] 11:55:26     INFO -   File "/home/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_switch_window_content.py", line 166, in test_switch_to_new_private_browsing_window_has_to_register_browsers
[task 2017-07-16T11:55:26.773513Z] 11:55:26     INFO -     self.marionette.switch_to_window(new_window)
[task 2017-07-16T11:55:26.774162Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1509, in switch_to_window
[task 2017-07-16T11:55:26.774691Z] 11:55:26     INFO -     self._send_message("switchToWindow", body)
[task 2017-07-16T11:55:26.775281Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_driver/decorators.py", line 28, in _
[task 2017-07-16T11:55:26.775791Z] 11:55:26     INFO -     m._handle_socket_failure()
[task 2017-07-16T11:55:26.776408Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_driver/decorators.py", line 23, in _
[task 2017-07-16T11:55:26.777001Z] 11:55:26     INFO -     return func(*args, **kwargs)
[task 2017-07-16T11:55:26.777584Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 731, in _send_message
[task 2017-07-16T11:55:26.778201Z] 11:55:26     INFO -     msg = self.client.request(name, params)
[task 2017-07-16T11:55:26.778746Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_driver/transport.py", line 284, in request
[task 2017-07-16T11:55:26.779243Z] 11:55:26     INFO -     return self.receive()
[task 2017-07-16T11:55:26.779766Z] 11:55:26     INFO -   File "/home/worker/workspace/build/venv/local/lib/python2.7/site-packages/marionette_driver/transport.py", line 211, in receive
[task 2017-07-16T11:55:26.780322Z] 11:55:26     INFO -     raise socket.timeout("Connection timed out after {}s".format(self.socket_timeout))
[task 2017-07-16T11:55:26.780842Z] 11:55:26     INFO - TEST-INFO took 426738ms

The test opens a new chrome window (private browsing window), and wants to switch to it. As such it should hit the following code in driver.js:

https://dxr.mozilla.org/mozilla-central/rev/13a2e506992ccf07c1358d9f22cbf2dfdcb0120f/testing/marionette/driver.js#1533-1548

So we most likely wait for the `browserListening` promise to be resolved:

https://dxr.mozilla.org/mozilla-central/rev/13a2e506992ccf07c1358d9f22cbf2dfdcb0120f/testing/marionette/driver.js#624-635

Maybe my changes for bug 1332122 sometimes can cause this issue. I don't see a reason right now to dig deeper into this given the very low intermittent failure rate.

Comment 2

[Intermittent Failures Robot]

1 failures in 720 pushes (0.001 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1381343&startday=2017-07-10&endday=2017-07-16&tree=all

Comment 3

[David Burns :automatedtester]

Not seen a while so closing

Comment 4

[BMO Automation]

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