1509557 - Intermittent TestWindowRect.test_set_position | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com

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

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

18:33:48     INFO -  [Parent 3892, StreamTrans #106] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 434
18:33:48     INFO -  [Parent 3892, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 1142
18:34:45     INFO -  1542998085768	Marionette	DEBUG	Closed connection 98
18:35:56    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_window_rect.py TestWindowRect.test_set_position | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
18:35:56     INFO -  Traceback (most recent call last):
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
18:35:56     INFO -      testMethod()
18:35:56     INFO -    File "Z:\task_1542996837\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_window_rect.py", line 78, in test_set_position
18:35:56     INFO -      new_position = self.marionette.set_window_rect(x=wanted_position["x"], y=wanted_position["y"])
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1386, in set_window_rect
18:35:56     INFO -      body)
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_driver\decorators.py", line 36, in _
18:35:56     INFO -      m._handle_socket_failure()
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_driver\marionette.py", line 844, in _handle_socket_failure
18:35:56     INFO -      reraise(IOError, message.format(returncode=returncode, reason=val), tb)
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
18:35:56     INFO -      return func(*args, **kwargs)
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_driver\marionette.py", line 756, in _send_message
18:35:56     INFO -      msg = self.client.request(name, params)
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_driver\transport.py", line 273, in request
18:35:56     INFO -      return self.receive()
18:35:56     INFO -    File "Z:\task_1542996837\build\venv\lib\site-packages\marionette_driver\transport.py", line 183, in receive
18:35:56     INFO -      raise socket.timeout("Connection timed out after {}s".format(self.socket_timeout))
18:35:56     INFO -  TEST-INFO took 431247ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1509557&startday=2018-11-19&endday=2018-11-25&tree=all

Comment 2

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

18:28:45     INFO -  1542997725706	Marionette	DEBUG	[2147483899] Frame script registered
18:28:45     INFO -  1542997725712	Marionette	TRACE	98 <- [1,1,null,{"sessionId":"4f0029b1-9730-4e03-86bb-d333d98fa177","capabilities":{"browserName":"firefox","browserVersion":"65.0a ... p\\tmpg_g1te.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
18:28:45     INFO -  1542997725716	Marionette	TRACE	98 -> [0,2,"WebDriver:SetTimeouts",{"script":30000}]
18:28:45     INFO -  1542997725717	Marionette	TRACE	98 <- [1,2,null,{"value":null}]
18:28:45     INFO -  1542997725719	Marionette	TRACE	98 -> [0,3,"WebDriver:SetTimeouts",{"pageLoad":300000}]
18:28:45     INFO -  1542997725721	Marionette	TRACE	98 <- [1,3,null,{"value":null}]
18:28:45     INFO -  1542997725725	Marionette	TRACE	98 -> [0,4,"WebDriver:SetTimeouts",{"implicit":0}]
18:28:45     INFO -  1542997725727	Marionette	TRACE	98 <- [1,4,null,{"value":null}]
18:28:45     INFO -  1542997725733	Marionette	TRACE	98 -> [0,5,"WebDriver:GetWindowRect",{}]
18:28:45     INFO -  1542997725735	Marionette	TRACE	98 <- [1,5,null,{"x":-8,"y":-8,"width":1040,"height":784,"state":"normal"}]
18:28:45     INFO -  1542997725737	Marionette	TRACE	98 -> [0,6,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"Z:\\task_1542996837\\build\\test ... width: window.screen.availWidth,\n              height: window.screen.availHeight,\n            }","sandbox":null,"line":22}]
18:28:45     INFO -  1542997725754	Marionette	TRACE	98 <- [1,6,null,{"value":{"width":1024,"height":768}}]
18:28:45     INFO -  1542997725755	Marionette	TRACE	98 -> [0,7,"WebDriver:SetWindowRect",{"y":null,"x":null,"width":1040,"height":784}]
18:28:45     INFO -  1542997725757	Marionette	TRACE	98 <- [1,7,null,{"x":-8,"y":-8,"width":1040,"height":784,"state":"normal"}]
18:28:45     INFO -  1542997725759	Marionette	TRACE	98 -> [0,8,"WebDriver:GetWindowRect",{}]
18:28:45     INFO -  1542997725760	Marionette	TRACE	98 <- [1,8,null,{"x":-8,"y":-8,"width":1040,"height":784,"state":"normal"}]
18:28:45     INFO -  1542997725762	Marionette	TRACE	98 -> [0,9,"WebDriver:SetWindowRect",{"y":2,"x":2,"width":null,"height":null}]
18:28:48     INFO -  [Parent 3892, StreamTrans #101] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 434
18:28:48     INFO -  [Parent 3892, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 1142
18:31:11     INFO -  [Parent 3892, Lazy Idle] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/widget/windows/WinUtils.cpp, line 1461
18:33:48     INFO -  [Parent 3892, StreamTrans #106] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 434
18:33:48     INFO -  [Parent 3892, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 1142
18:34:45     INFO -  1542998085768	Marionette	DEBUG	Closed connection 98
18:35:56    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_window_rect.py TestWindowRect.test_set_position | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Connection timed out after 360.0s)
18:35:56     INFO -  Traceback (most recent call last):

Comment 3

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

Needs bug 1489955 to be fixed.

Comment 4

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 5

[BMO Automation]

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