New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 826413 link

Starred by 1 user

Issue metadata

Status: Untriaged
Owner: ----
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 1
Type: Bug



Sign in to add a comment

testPageNavigateToNormalUrl telemetry test is failing

Project Member Reported by afakhry@chromium.org, Mar 27 2018

Issue description

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)
 
Cc: alemate@chromium.org
There are a bunch of OOBE errors in the logs, but I'm not sure if they are related: 

[715:715:0327/093027.418413:ERROR:CONSOLE(0)] "Unchecked runtime.lastError while running webViewInternal.executeScript: Cannot access contents of the page. Extension manifest must request permission to access the respective host.", source: chrome://oobe/oobe (0)

Comment 2 by benhenry@google.com, Jan 16 (6 days ago)

Components: Test>Telemetry

Comment 3 by benhenry@google.com, Jan 16 (6 days ago)

Components: -Tests>Telemetry

Sign in to add a comment