Filed by: ncsoregi [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=168490283&repo=mozilla-central https://queue.taskcluster.net/v1/task/T6-Wjb4wRBOMNVnmDQPwXQ/runs/0/artifacts/public/logs/live_backing.log [task 2018-03-16T13:13:12.272Z] 13:13:12 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /tmp/tmpRtEELB.mozrunner [task 2018-03-16T13:13:12.349Z] 13:13:12 INFO - *** You are running in headless mode. [task 2018-03-16T13:17:13.435Z] 13:17:13 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_timeouts.py TestTimeouts.test_reset_timeout | 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!) [task 2018-03-16T13:17:13.435Z] 13:17:13 INFO - Traceback (most recent call last): [task 2018-03-16T13:17:13.435Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run [task 2018-03-16T13:17:13.437Z] 13:17:13 INFO - testMethod() [task 2018-03-16T13:17:13.437Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/decorators.py", line 84, in skip_wrapper [task 2018-03-16T13:17:13.437Z] 13:17:13 INFO - return test_item(self, *args, **kwargs) [task 2018-03-16T13:17:13.439Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/decorators.py", line 152, in skip_wrapper [task 2018-03-16T13:17:13.439Z] 13:17:13 INFO - return test_item(self, *args, **kwargs) [task 2018-03-16T13:17:13.440Z] 13:17:13 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_timeouts.py", line 83, in test_reset_timeout [task 2018-03-16T13:17:13.440Z] 13:17:13 INFO - do_check(self.marionette.restart) [task 2018-03-16T13:17:13.441Z] 13:17:13 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_timeouts.py", line 75, in do_check [task 2018-03-16T13:17:13.441Z] 13:17:13 INFO - callback() [task 2018-03-16T13:17:13.443Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 31, in _ [task 2018-03-16T13:17:13.444Z] 13:17:13 INFO - m._handle_socket_failure() [task 2018-03-16T13:17:13.445Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 830, in _handle_socket_failure [task 2018-03-16T13:17:13.446Z] 13:17:13 INFO - reraise(IOError, message.format(returncode=returncode, reason=val), tb) [task 2018-03-16T13:17:13.447Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _ [task 2018-03-16T13:17:13.447Z] 13:17:13 INFO - return func(*args, **kwargs) [task 2018-03-16T13:17:13.449Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1163, in restart [task 2018-03-16T13:17:13.450Z] 13:17:13 INFO - self.raise_for_port(timeout=self.DEFAULT_STARTUP_TIMEOUT) [task 2018-03-16T13:17:13.451Z] 13:17:13 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 716, in raise_for_port [task 2018-03-16T13:17:13.452Z] 13:17:13 INFO - self.host, self.port)) [task 2018-03-16T13:17:13.452Z] 13:17:13 INFO - TEST-INFO took 241390ms [task 2018-03-16T13:17:13.455Z] 13:17:13 ERROR - test_end for testing/marionette/harness/marionette_harness/tests/unit/test_timeouts.py TestTimeouts.test_reset_timeout logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_timeouts.TestTimeouts", "method_name": "test_reset_timeout"}, "expected": "PASS", "test": "testing/marionette/harness/marionette_harness/tests/unit/test_timeouts.py TestTimeouts.test_reset_timeout", "message": "MarionetteException: Please start a session", "stack": "Traceback (most recent call last):\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 190, in run\n self.tearDown()\n File \"/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_timeouts.py\", line 20, in tearDown\n self.marionette.timeout.reset()\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/timeout.py\", line 104, in reset\n self.script = DEFAULT_SCRIPT_TIMEOUT\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/timeout.py\", line 57, in script\n self._set(\"script\", sec)\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/timeout.py\", line 33, in _set\n self._marionette._send_message(\"setTimeouts\", {name: ms})\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py\", line 26, in _\n return func(*args, **kwargs)\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 735, in _send_message\n raise errors.MarionetteException(\"Please start a session\")\n"}

Please note that this is a hang during startup of Firefox.

All recent failures are startup hangs for Linux64 ASAN builds. They need a fix for bug 1487243.

The start-up hangs for ASAN builds are gone now with the patch on bug 1495404, but there are still hangs for opt/debug.

(In reply to Henrik Skupin (:whimboo) from comment #26) > The start-up hangs for ASAN builds are gone now with the patch on bug > 1495404, but there are still hangs for opt/debug. Well, the last non ASAN failure happened on 2018-09-19. Lets mark this bug as WFM.

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