1595173 - Intermittent testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py TestEnforcePreferences.test_preferences_are_set | IOError: Process killed after 120s because no connection to Marionette server could be established.

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=275392629&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/IWUBnMFtRNu2-VB2y16SWw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-11-08T19:18:39.283Z] 19:18:39 INFO - Application command: Z:\task_1573237887\build\application\firefox\firefox.exe -no-remote -marionette --wait-for-browser -profile c:\users\task_1573237887\appdata\local\temp\tmprinbrw.mozrunner
[task 2019-11-08T19:18:39.768Z] 19:18:39 INFO - 1573240719762 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-11-08T19:18:39.768Z] 19:18:39 INFO - 1573240719762 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: telemetry
[task 2019-11-08T19:18:39.768Z] 19:18:39 INFO - 1573240719762 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-11-08T19:18:39.768Z] 19:18:39 INFO - 1573240719762 [e-mail] WARN Loading extension '[e-mail]': Reading manifest: Invalid extension permission: about:reader*
[task 2019-11-08T19:18:39.929Z] 19:18:39 INFO - 1573240719926 Marionette TRACE Received observer notification profile-after-change
[task 2019-11-08T19:18:40.014Z] 19:18:40 INFO - 1573240720009 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-11-08T19:18:40.059Z] 19:18:40 INFO - 1573240720056 Marionette TRACE Received observer notification command-line-startup
[task 2019-11-08T19:18:40.119Z] 19:18:40 INFO - 1573240720109 Marionette TRACE Received observer notification toplevel-window-ready
[task 2019-11-08T19:20:39.566Z] 19:20:39 ERROR - TEST-UNEXPECTED-ERROR | testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py TestEnforcePreferences.test_preferences_are_set | IOError: Process killed after 120s because no connection to Marionette server could be established. Check gecko.log for errors
[task 2019-11-08T19:20:39.566Z] 19:20:39 INFO - Traceback (most recent call last):
[task 2019-11-08T19:20:39.566Z] 19:20:39 INFO - File "Z:\task_1573237887\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 190, in run
[task 2019-11-08T19:20:39.566Z] 19:20:39 INFO - self.tearDown()
[task 2019-11-08T19:20:39.566Z] 19:20:39 INFO - File "Z:\task_1573237887\build\tests\marionette\tests\testing\marionette\harness\marionette_harness\tests\unit\test_prefs_enforce.py", line 25, in tearDown
[task 2019-11-08T19:20:39.566Z] 19:20:39 INFO - super(TestEnforcePreferences, self).tearDown()
[task 2019-11-08T19:20:39.566Z] 19:20:39 INFO - File "Z:\task_1573237887\build\venv\lib\site-packages\marionette_harness\marionette_test\testcases.py", line 338, in tearDown
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - self.marionette.start_session()
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - File "Z:\task_1573237887\build\venv\lib\site-packages\marionette_driver\decorators.py", line 26, in _
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - return func(*args, **kwargs)
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - File "Z:\task_1573237887\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1103, in start_session
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - self.start_binary(timeout)
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - File "Z:\task_1573237887\build\venv\lib\site-packages\marionette_driver\marionette.py", line 491, in start_binary
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - reraise(IOError, msg.format(timeout), tb)
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - File "Z:\task_1573237887\build\venv\lib\site-packages\marionette_driver\marionette.py", line 482, in start_binary
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - self.raise_for_port(timeout=timeout)
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - File "Z:\task_1573237887\build\venv\lib\site-packages\marionette_driver\marionette.py", line 569, in raise_for_port
[task 2019-11-08T19:20:39.567Z] 19:20:39 INFO - self.host, self.port))
[task 2019-11-08T19:20:39.568Z] 19:20:39 INFO - TEST-INFO took 123605ms

Comment 1

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

Missing marionette-startup-requested notification.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1595173&startday=2019-11-04&endday=2019-11-10&tree=all

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 4

[BMO Automation]

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