Filed by: csabou [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=163379659&repo=autoland https://queue.taskcluster.net/v1/task/c_Pmf_sZSzuF3UVtZVdXmA/runs/0/artifacts/public/logs/live_backing.log [task 2018-02-21T06:26:12.886Z] 06:26:12 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_keeps_same_profile [task 2018-02-21T06:26:12.903Z] 06:26:12 INFO - 1519194372898 Marionette DEBUG Closed connection 1 [task 2018-02-21T06:26:12.906Z] 06:26:12 INFO - 1519194372900 Marionette DEBUG Accepted connection 2 from 127.0.0.1:34960 [task 2018-02-21T06:26:12.944Z] 06:26:12 INFO - 1519194372932 Marionette TRACE 2 -> [0,1,"newSession",{}] [task 2018-02-21T06:26:12.947Z] 06:26:12 INFO - 1519194372942 Marionette DEBUG Register listener.js for window 2147483649 [task 2018-02-21T06:26:12.968Z] 06:26:12 INFO - 1519194372962 Marionette TRACE 2 <- [1,1,null,{"sessionId":"298aaaa3-1b45-4a8c-baa9-8866013a3c11","capabilities":{"browserName":"firefox","browserVersion":"60.0a ... ssID":3024,"moz:profile":"/tmp/tmpw08vR9.mozrunner","moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] [task 2018-02-21T06:26:13.011Z] 06:26:13 INFO - 1519194373008 Marionette TRACE 2 -> [0,2,"setTimeouts",{"script":30000}] [task 2018-02-21T06:26:13.014Z] 06:26:13 INFO - 1519194373010 Marionette TRACE 2 <- [1,2,null,{}] [task 2018-02-21T06:26:13.032Z] 06:26:13 INFO - 1519194373022 Marionette TRACE 2 -> [0,3,"setTimeouts",{"pageLoad":300000}] [task 2018-02-21T06:26:13.034Z] 06:26:13 INFO - 1519194373024 Marionette TRACE 2 <- [1,3,null,{}] [task 2018-02-21T06:26:13.035Z] 06:26:13 INFO - 1519194373028 Marionette TRACE 2 -> [0,4,"setTimeouts",{"implicit":0}] [task 2018-02-21T06:26:13.037Z] 06:26:13 INFO - 1519194373032 Marionette TRACE 2 <- [1,4,null,{}] [task 2018-02-21T06:26:13.042Z] 06:26:13 INFO - 1519194373040 Marionette TRACE 2 -> [0,5,"getContext",{}] [task 2018-02-21T06:26:13.044Z] 06:26:13 INFO - 1519194373042 Marionette TRACE 2 <- [1,5,null,{"value":"content"}] [task 2018-02-21T06:26:13.069Z] 06:26:13 INFO - 1519194373064 Marionette TRACE 2 -> [0,6,"deleteSession",{}] [task 2018-02-21T06:26:13.072Z] 06:26:13 INFO - 1519194373068 Marionette TRACE 2 <- [1,6,null,{}] [task 2018-02-21T06:26:13.161Z] 06:26:13 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /tmp/tmpw08vR9.mozrunner [task 2018-02-21T06:26:13.224Z] 06:26:13 INFO - *** You are running in headless mode. [task 2018-02-21T06:30:14.277Z] 06:30:14 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestQuitRestartWithoutWorkspace.test_restart_keeps_same_profile | 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-02-21T06:30:14.279Z] 06:30:14 INFO - Traceback (most recent call last): [task 2018-02-21T06:30:14.280Z] 06:30:14 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run [task 2018-02-21T06:30:14.282Z] 06:30:14 INFO - testMethod() [task 2018-02-21T06:30:14.283Z] 06:30:14 INFO - File "/builds/worker/workspace/build/tests/marionette/tests/testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py", line 96, in test_restart_keeps_same_profile [task 2018-02-21T06:30:14.284Z] 06:30:14 INFO - self.marionette.restart() [task 2018-02-21T06:30:14.285Z] 06:30:14 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 31, in _ [task 2018-02-21T06:30:14.286Z] 06:30:14 INFO - m._handle_socket_failure() [task 2018-02-21T06:30:14.287Z] 06:30:14 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 830, in _handle_socket_failure [task 2018-02-21T06:30:14.288Z] 06:30:14 INFO - reraise(IOError, message.format(returncode=returncode, reason=val), tb) [task 2018-02-21T06:30:14.289Z] 06:30:14 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _ [task 2018-02-21T06:30:14.290Z] 06:30:14 INFO - return func(*args, **kwargs) [task 2018-02-21T06:30:14.291Z] 06:30:14 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1163, in restart [task 2018-02-21T06:30:14.292Z] 06:30:14 INFO - self.raise_for_port(timeout=self.DEFAULT_STARTUP_TIMEOUT) [task 2018-02-21T06:30:14.293Z] 06:30:14 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 716, in raise_for_port [task 2018-02-21T06:30:14.294Z] 06:30:14 INFO - self.host, self.port)) [task 2018-02-21T06:30:14.295Z] 06:30:14 INFO - TEST-INFO took 241393ms

Hang during startup. Might be dependent on bug 1414495.

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