Closed Bug 1542903 Opened 6 years ago Closed 6 years ago

Intermittent tier 2 telemetry/marionette/tests/client/test_main_tab_scalars.py TestMainTabScalars.test_main_tab_scalars | IOError: Process has been unexpectedly closed (Exit code: 1) (Reason: Process unexpectedly quit without restarting (exit code: 1))

Categories

(Toolkit :: Telemetry, defect, P5)

Product:
Toolkit
Component:
Telemetry
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

#[markdown(off)]
Filed by: rmaries [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=238896839&repo=mozilla-central

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

[task 2019-04-08T20:16:57.990Z] 20:16:57 INFO - TEST-START | telemetry/marionette/tests/client/test_main_tab_scalars.py TestMainTabScalars.test_main_tab_scalars

[task 2019-04-08T20:20:11.841Z] 20:20:11 INFO - TEST-UNEXPECTED-ERROR | telemetry/marionette/tests/client/test_main_tab_scalars.py TestMainTabScalars.test_main_tab_scalars | IOError: Process has been unexpectedly closed (Exit code: 1) (Reason: Process unexpectedly quit without restarting (exit code: 1))
[task 2019-04-08T20:20:11.843Z] 20:20:11 INFO - Traceback (most recent call last):
[task 2019-04-08T20:20:11.844Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 159, in run
[task 2019-04-08T20:20:11.844Z] 20:20:11 INFO - testMethod()
[task 2019-04-08T20:20:11.845Z] 20:20:11 INFO - File "/builds/worker/workspace/build/tests/telemetry/marionette/tests/client/test_main_tab_scalars.py", line 30, in test_main_tab_scalars
[task 2019-04-08T20:20:11.846Z] 20:20:11 INFO - ping = self.wait_for_ping(self.restart_browser, MAIN_SHUTDOWN_PING)
[task 2019-04-08T20:20:11.846Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/telemetry_harness/testcase.py", line 127, in wait_for_ping
[task 2019-04-08T20:20:11.846Z] 20:20:11 INFO - [ping] = self.wait_for_pings(action_func, ping_filter, 1)
[task 2019-04-08T20:20:11.846Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/telemetry_harness/testcase.py", line 114, in wait_for_pings
[task 2019-04-08T20:20:11.846Z] 20:20:11 INFO - action_func()
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/telemetry_harness/testcase.py", line 132, in restart_browser
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - return self.marionette.restart(clean=False, in_app=True)
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 36, in _
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - m._handle_socket_failure()
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 678, in _handle_socket_failure
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - reraise(IOError, message.format(returncode=returncode, reason=val), tb)
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/decorators.py", line 26, in _
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - return func(*args, **kwargs)
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1042, in restart
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - reraise(exc, msg.format(self.instance.runner.returncode), tb)
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1025, in restart
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - check_process_status=False)
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py", line 569, in raise_for_port
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - self.host, self.port))
[task 2019-04-08T20:20:11.847Z] 20:20:11 INFO - TEST-INFO took 193850ms
[task 2019-04-08T20:20:11.847Z] 20:20:11 ERROR - test_end for telemetry/marionette/tests/client/test_main_tab_scalars.py TestMainTabScalars.test_main_tab_scalars logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_main_tab_scalars.TestMainTabScalars", "method_name": "test_main_tab_scalars"}, "expected": "PASS", "test": "telemetry/marionette/tests/client/test_main_tab_scalars.py TestMainTabScalars.test_main_tab_scalars", "message": "InvalidSessionIdException: 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/venv/lib/python2.7/site-packages/telemetry_harness/testcase.py\", line 170, in tearDown\n super(TelemetryTestCase, self).tearDown()\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/mixins/window_manager.py\", line 25, in tearDown\n if len(self.marionette.chrome_window_handles) > len(self.start_windows):\n File \"/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_driver/marionette.py\", line 1257, in chrome_window_handles\n return self._send_message(\"WebDriver:GetChromeWindowHandles\")\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 587, in _send_message\n raise errors.InvalidSessionIdException(\"Please start a session\")\n"}
[task 2019-04-08T20:20:11.955Z] 20:20:11 ERROR - Failure during harness execution
[task 2019-04-08T20:20:11.955Z] 20:20:11 ERROR - Traceback (most recent call last):
[task 2019-04-08T20:20:11.956Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.956Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runtests.py", line 91, in cli
[task 2019-04-08T20:20:11.957Z] 20:20:11 ERROR - failed = harness_instance.run()
[task 2019-04-08T20:20:11.957Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.958Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runtests.py", line 71, in run
[task 2019-04-08T20:20:11.958Z] 20:20:11 ERROR - runner.run_tests(tests)
[task 2019-04-08T20:20:11.959Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.959Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/base.py", line 907, in run_tests
[task 2019-04-08T20:20:11.960Z] 20:20:11 ERROR - self.run_test_sets()
[task 2019-04-08T20:20:11.960Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.960Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/base.py", line 1110, in run_test_sets
[task 2019-04-08T20:20:11.961Z] 20:20:11 ERROR - self.run_test_set(self.tests)
[task 2019-04-08T20:20:11.961Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.961Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/base.py", line 1088, in run_test_set
[task 2019-04-08T20:20:11.962Z] 20:20:11 ERROR - self.run_test(test['filepath'], test['expected'])
[task 2019-04-08T20:20:11.963Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.963Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/base.py", line 1049, in run_test
[task 2019-04-08T20:20:11.964Z] 20:20:11 ERROR - **self.test_kwargs)
[task 2019-04-08T20:20:11.965Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.965Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/marionette_test/testcases.py", line 328, in add_tests_to_suite
[task 2019-04-08T20:20:11.965Z] 20:20:11 ERROR - **kwargs))
[task 2019-04-08T20:20:11.967Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.967Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/telemetry_harness/testcase.py", line 31, in __init__
[task 2019-04-08T20:20:11.967Z] 20:20:11 ERROR - self.testvars["server_root"], self.testvars["server_url"]
[task 2019-04-08T20:20:11.967Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.967Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/telemetry_harness/ping_server.py", line 56, in __init__
[task 2019-04-08T20:20:11.967Z] 20:20:11 ERROR - self._httpd = httpd.FixtureServer(server_root, url=url)
[task 2019-04-08T20:20:11.968Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.968Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/marionette_harness/runner/httpd.py", line 117, in __init__
[task 2019-04-08T20:20:11.968Z] 20:20:11 ERROR - key_file=ssl_key)
[task 2019-04-08T20:20:11.968Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.969Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/wptserve/server.py", line 667, in __init__
[task 2019-04-08T20:20:11.970Z] 20:20:11 ERROR - http2=http2)
[task 2019-04-08T20:20:11.971Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - File "/builds/worker/workspace/build/venv/lib/python2.7/site-packages/wptserve/server.py", line 174, in __init__
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - BaseHTTPServer.HTTPServer.__init__(self, hostname_port, request_handler_cls, **kwargs)
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - File "/usr/lib/python2.7/SocketServer.py", line 417, in __init__
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - self.server_bind()
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - File "/usr/lib/python2.7/BaseHTTPServer.py", line 108, in server_bind
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - SocketServer.TCPServer.server_bind(self)
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - File "/usr/lib/python2.7/SocketServer.py", line 431, in server_bind
[task 2019-04-08T20:20:11.972Z] 20:20:11 ERROR - self.socket.bind(self.server_address)
[task 2019-04-08T20:20:11.973Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.973Z] 20:20:11 ERROR - File "/usr/lib/python2.7/socket.py", line 228, in meth
[task 2019-04-08T20:20:11.973Z] 20:20:11 ERROR - return getattr(self._sock,name)(*args)
[task 2019-04-08T20:20:11.973Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.974Z] 20:20:11 ERROR - error: [Errno 98] Address already in use
[task 2019-04-08T20:20:11.974Z] 20:20:11 ERROR -
[task 2019-04-08T20:20:11.997Z] 20:20:11 ERROR - Return code: 1

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

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.