1439335 - Intermittent test_prefs_enforce.py TestEnforcePreferences.test_change_preference | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on localhost:2828!)

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=162987035&repo=mozilla-inbound

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

11:47:10     INFO -  Application command: Z:\task_1519040332\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\genericworker\appdata\local\temp\tmpgo3wm_.mozrunner
11:47:10     INFO -  *** You are running in headless mode.
11:47:10     INFO -  1519040830684	Marionette	DEBUG	Received observer notification profile-after-change
11:51:11    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py TestEnforcePreferences.test_change_preference | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on localhost:2828!)
11:51:11     INFO -  Traceback (most recent call last):
11:51:11     INFO -    File "Z:\task_1519040332\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
11:51:11     INFO -      testMethod()
11:51:11     INFO -    File "Z:\task_1519040332\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py", line 35, in test_change_preference
11:51:11     INFO -      self.marionette.enforce_gecko_prefs({"marionette.test.bool": False})
11:51:11     INFO -    File "Z:\task_1519040332\build\venv\lib\site-packages\marionette_driver\decorators.py", line 31, in _
11:51:11     INFO -      m._handle_socket_failure()
11:51:11     INFO -    File "Z:\task_1519040332\build\venv\lib\site-packages\marionette_driver\marionette.py", line 830, in _handle_socket_failure
11:51:11     INFO -      reraise(IOError, message.format(returncode=returncode, reason=val), tb)
11:51:11     INFO -    File "Z:\task_1519040332\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
11:51:11     INFO -      return func(*args, **kwargs)
11:51:11     INFO -    File "Z:\task_1519040332\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1003, in enforce_gecko_prefs
11:51:11     INFO -      self.raise_for_port()
11:51:11     INFO -    File "Z:\task_1519040332\build\venv\lib\site-packages\marionette_driver\marionette.py", line 716, in raise_for_port
11:51:11     INFO -      self.host, self.port))
11:51:11     INFO -  TEST-INFO took 244019ms

Comment 1

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

Missing sessionstore-windows-restored notification.

Comment 2

[Intermittent Failures Robot]

2 failures in 675 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 2

Platform breakdown:
* windows10-64: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1439335&startday=2018-02-19&endday=2018-02-25&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 831 pushes (0.001 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://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1439335&startday=2018-02-26&endday=2018-03-04&tree=all

Comment 4

[Firefox Bug Husbandry Bot]

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

Comment 5

[Andrei Ciure[:aciure]]

Reopened for this failure:
https://treeherder.mozilla.org/logviewer.html#?job_id=174901989&repo=mozilla-inbound&lineNumber=35707

03:21:16     INFO -  Application command: Z:\task_1524280291\build\application\firefox\firefox.exe -no-remote -marionette -profile c:\users\task_1524280291\appdata\local\temp\tmpw9ddff.mozrunner
03:21:16     INFO -  1524280876737	Marionette	DEBUG	Received observer notification profile-after-change
03:25:17    ERROR -  TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py TestEnforcePreferences.test_change_preference | IOError: Process killed because the connection to Marionette server is lost. Check gecko.log for errors (Reason: Timed out waiting for connection on localhost:2828!)
03:25:17     INFO -  Traceback (most recent call last):
03:25:17     INFO -    File "Z:\task_1524280291\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 159, in run
03:25:17     INFO -      testMethod()
03:25:17     INFO -    File "Z:\task_1524280291\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py", line 35, in test_change_preference
03:25:17     INFO -      self.marionette.enforce_gecko_prefs({"marionette.test.bool": False})
03:25:17     INFO -    File "Z:\task_1524280291\build\venv\lib\site-packages\marionette_driver\decorators.py", line 31, in _
03:25:17     INFO -      m._handle_socket_failure()
03:25:17     INFO -    File "Z:\task_1524280291\build\venv\lib\site-packages\marionette_driver\marionette.py", line 825, in _handle_socket_failure
03:25:17     INFO -      reraise(IOError, message.format(returncode=returncode, reason=val), tb)
03:25:17     INFO -    File "Z:\task_1524280291\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
03:25:17     INFO -      return func(*args, **kwargs)
03:25:17     INFO -    File "Z:\task_1524280291\build\venv\lib\site-packages\marionette_driver\marionette.py", line 999, in enforce_gecko_prefs
03:25:17     INFO -      self.raise_for_port()
03:25:17     INFO -    File "Z:\task_1524280291\build\venv\lib\site-packages\marionette_driver\marionette.py", line 711, in raise_for_port
03:25:17     INFO -      self.host, self.port))
03:25:17     INFO -  TEST-INFO took 244119ms
03:25:17     INFO -  test_end for testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py TestEnforcePreferences.test_change_preference logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_prefs_enforce.TestEnforcePreferences", "method_name": "test_change_preference"}, "expected": "PASS", "test": "testing\\marionette\\harness\\marionette_harness\\tests\\unit\\test_prefs_enforce.py TestEnforcePreferences.test_change_preference", "message": "MarionetteException: Please start a session", "stack": "Traceback (most recent call last):\n  File \"Z:\\task_1524280291\\build\\venv\\lib\\site-packages\\marionette_harness\\marionette_test\\testcases.py\", line 190, in run\n    self.tearDown()\n  File \"Z:\\task_1524280291\\build\\tests\\marionette\\tests\\testing\\marionette\\harness\\marionette_harness\\tests\\unit\\test_prefs_enforce.py\", line 23, in tearDown\n    self.marionette.quit(clean=True)\n  File \"Z:\\task_1524280291\\build\\venv\\lib\\site-packages\\marionette_driver\\decorators.py\", line 26, in _\n    return func(*args, **kwargs)\n  File \"Z:\\task_1524280291\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 1106, in quit\n    self.delete_session()\n  File \"Z:\\task_1524280291\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 1269, in delete_session\n    self._send_message(\"WebDriver:DeleteSession\")\n  File \"Z:\\task_1524280291\\build\\venv\\lib\\site-packages\\marionette_driver\\decorators.py\", line 26, in _\n    return func(*args, **kwargs)\n  File \"Z:\\task_1524280291\\build\\venv\\lib\\site-packages\\marionette_driver\\marionette.py\", line 730, in _send_message\n    raise errors.MarionetteException(\"Please start a session\")\n"}

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1439335&startday=2018-04-16&endday=2018-04-22&tree=trunk

Comment 7

[Firefox Bug Husbandry Bot]

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

Comment 8

[Cosmin Sabou [:CosminS]]

New failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=180413591&repo=autoland&lineNumber=36625

Comment 9

[Intermittent Failures Robot]

1 failures in 632 pushes (0.002 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=1439335&startday=2018-05-21&endday=2018-05-27&tree=trunk

Comment 10

[Firefox Bug Husbandry Bot]

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

Comment 11

[BMO Automation]

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