03/27 09:48:02.629 INFO |run_chromeos_tests:0052| [310/1161] telemetry.internal.backends.chrome_inspector.inspector_page_unittest.InspectorPageTest.testPageNavigateToNormalUrl failed unexpectedly 5.7524s:
03/27 09:48:02.630 INFO |run_chromeos_tests:0052| Failed with DevtoolsTargetCrashException while starting the browser backend.
03/27 09:48:02.631 INFO |run_chromeos_tests:0052| Traceback (most recent call last):
03/27 09:48:02.632 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/testing/browser_test_case.py", line 78, in setUpClass
03/27 09:48:02.633 INFO |run_chromeos_tests:0052| cls._browser = cls._possible_browser.Create()
03/27 09:48:02.634 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_finder.py", line 102, in Create
03/27 09:48:02.635 INFO |run_chromeos_tests:0052| browser_backend, self._platform_backend, startup_args)
03/27 09:48:02.636 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/browser/browser.py", line 49, in __init__
03/27 09:48:02.637 INFO |run_chromeos_tests:0052| self._browser_backend.Start(startup_args, startup_url=startup_url)
03/27 09:48:02.638 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
03/27 09:48:02.639 INFO |run_chromeos_tests:0052| return func(*args, **kwargs)
03/27 09:48:02.640 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/cros_browser_backend.py", line 104, in Start
03/27 09:48:02.641 INFO |run_chromeos_tests:0052| not self.browser_options.disable_gaia_services)
03/27 09:48:02.642 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
03/27 09:48:02.643 INFO |run_chromeos_tests:0052| return func(*args, **kwargs)
03/27 09:48:02.644 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/oobe.py", line 71, in NavigateFakeLogin
03/27 09:48:02.645 INFO |run_chromeos_tests:0052| enterprise_enroll)
03/27 09:48:02.646 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
03/27 09:48:02.647 INFO |run_chromeos_tests:0052| return func(*args, **kwargs)
03/27 09:48:02.648 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/oobe.py", line 37, in _ExecuteOobeApi
03/27 09:48:02.649 INFO |run_chromeos_tests:0052| "typeof Oobe == 'function' && Oobe.readyForTesting", timeout=120)
03/27 09:48:02.650 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
03/27 09:48:02.651 INFO |run_chromeos_tests:0052| return func(*args, **kwargs)
03/27 09:48:02.652 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 239, in WaitForJavaScriptCondition
03/27 09:48:02.653 INFO |run_chromeos_tests:0052| return self._inspector_backend.WaitForJavaScriptCondition(*args, **kwargs)
03/27 09:48:02.654 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
03/27 09:48:02.655 INFO |run_chromeos_tests:0052| return func(*args, **kwargs)
03/27 09:48:02.656 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 289, in WaitForJavaScriptCondition
03/27 09:48:02.657 INFO |run_chromeos_tests:0052| return py_utils.WaitFor(IsJavaScriptExpressionTrue, timeout)
03/27 09:48:02.657 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_utils/py_utils/__init__.py", line 136, in WaitFor
03/27 09:48:02.658 INFO |run_chromeos_tests:0052| res = condition()
03/27 09:48:02.659 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 286, in IsJavaScriptExpressionTrue
03/27 09:48:02.660 INFO |run_chromeos_tests:0052| return self._EvaluateJavaScript(condition, context_id, timeout)
03/27 09:48:02.661 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
03/27 09:48:02.661 INFO |run_chromeos_tests:0052| return func(*args, **kwargs)
03/27 09:48:02.662 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 41, in Inner
03/27 09:48:02.663 INFO |run_chromeos_tests:0052| inspector_backend._ConvertExceptionFromInspectorWebsocket(e)
03/27 09:48:02.664 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
03/27 09:48:02.665 INFO |run_chromeos_tests:0052| return func(*args, **kwargs)
03/27 09:48:02.666 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 38, in Inner
03/27 09:48:02.667 INFO |run_chromeos_tests:0052| return func(inspector_backend, *args, **kwargs)
03/27 09:48:02.668 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 519, in _EvaluateJavaScript
03/27 09:48:02.669 INFO |run_chromeos_tests:0052| return self._runtime.Evaluate(expression, context_id, timeout)
03/27 09:48:02.670 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_runtime.py", line 44, in Evaluate
03/27 09:48:02.671 INFO |run_chromeos_tests:0052| res = self._inspector_websocket.SyncRequest(request, timeout)
03/27 09:48:02.672 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 116, in SyncRequest
03/27 09:48:02.673 INFO |run_chromeos_tests:0052| res = self._Receive(timeout)
03/27 09:48:02.674 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 155, in _Receive
03/27 09:48:02.675 INFO |run_chromeos_tests:0052| data = self._socket.recv()
03/27 09:48:02.676 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 293, in recv
03/27 09:48:02.677 INFO |run_chromeos_tests:0052| opcode, data = self.recv_data()
03/27 09:48:02.678 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 310, in recv_data
03/27 09:48:02.679 INFO |run_chromeos_tests:0052| opcode, frame = self.recv_data_frame(control_frame)
03/27 09:48:02.680 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 323, in recv_data_frame
03/27 09:48:02.681 INFO |run_chromeos_tests:0052| frame = self.recv_frame()
03/27 09:48:02.682 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 357, in recv_frame
03/27 09:48:02.683 INFO |run_chromeos_tests:0052| return self.frame_buffer.recv_frame()
03/27 09:48:02.684 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_abnf.py", line 336, in recv_frame
03/27 09:48:02.684 INFO |run_chromeos_tests:0052| self.recv_header()
03/27 09:48:02.685 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_abnf.py", line 286, in recv_header
03/27 09:48:02.686 INFO |run_chromeos_tests:0052| header = self.recv_strict(2)
03/27 09:48:02.687 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_abnf.py", line 371, in recv_strict
03/27 09:48:02.688 INFO |run_chromeos_tests:0052| bytes_ = self.recv(min(16384, shortage))
03/27 09:48:02.689 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 427, in _recv
03/27 09:48:02.690 INFO |run_chromeos_tests:0052| return recv(self.sock, bufsize)
03/27 09:48:02.691 INFO |run_chromeos_tests:0052| File "/usr/local/telemetry/src/third_party/catapult/telemetry/third_party/websocket-client/websocket/_socket.py", line 93, in recv
03/27 09:48:02.692 INFO |run_chromeos_tests:0052| "Connection is already closed.")
03/27 09:48:02.693 INFO |run_chromeos_tests:0052| DevtoolsTargetCrashException: Devtools target crashed
03/27 09:48:02.694 INFO |run_chromeos_tests:0052| ********************************************************************************
03/27 09:48:02.695 INFO |run_chromeos_tests:0052| (/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:492 _ConvertExceptionFromInspectorWebsocket) Original exception:
03/27 09:48:02.696 INFO |run_chromeos_tests:0052| Connection is already closed.
03/27 09:48:02.697 INFO |run_chromeos_tests:0052| ********************************************************************************
03/27 09:48:02.698 INFO |run_chromeos_tests:0052| (/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:513 _AddDebuggingInformation) Received a socket error in the browser connection and the tab no longer exists. The tab probably crashed.
03/27 09:48:02.699 INFO |run_chromeos_tests:0052| ********************************************************************************
03/27 09:48:02.700 INFO |run_chromeos_tests:0052| (/usr/local/telemetry/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:514 _AddDebuggingInformation) Debugger url: ws://127.0.0.1:40395/devtools/page/13736309133BF0BF8091AD19C96250B8
03/27 09:48:02.701 INFO |run_chromeos_tests:0052| Found Minidump: False
03/27 09:48:02.702 INFO |run_chromeos_tests:0052| Stack Trace:
03/27 09:48:02.703 INFO |run_chromeos_tests:0052| ********************************************************************************
03/27 09:48:02.704 INFO |run_chromeos_tests:0052| Cannot get stack trace on CrOS
03/27 09:48:02.705 INFO |run_chromeos_tests:0052| ********************************************************************************
03/27 09:48:02.706 INFO |run_chromeos_tests:0052| Standard output:
03/27 09:48:02.706 INFO |run_chromeos_tests:0052| ********************************************************************************
03/27 09:48:02.707 INFO |run_chromeos_tests:0052| Cannot get standard output on CrOS
03/27 09:48:02.708 INFO |run_chromeos_tests:0052| ********************************************************************************
03/27 09:48:02.709 INFO |run_chromeos_tests:0052| System log:
03/27 09:48:02.710 INFO |run_chromeos_tests:0052| (Not implemented)
Comment 1 by afakhry@chromium.org
, Mar 27 2018