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

Issue 839382 link

Starred by 2 users
Status: Assigned
Owner:
kouhei@chromium.org
Cc:
sullivan@google.com
sadrul@chromium.org
█ nednguyen@chromium.org
chiniforooshan@chromium.org
dgozman@chromium.org
EstimatedDays: ----
NextAction: ----
OS: Android
Pri: 2
Type: ----



Sign in to add a comment

Flaky crash due to navigation being stuck on rendering.mobile/youtube_desktop on chromium.perf/Android Nexus5X Perf

Project Member Reported by sheriff-...@appspot.gserviceaccount.com, May 3 2018 
Filed by sheriff-o-matic@appspot.gserviceaccount.com on behalf of sullivan@google.com

This failed on 4 of the last 6 builds. Disabling and kicking off a bisect.

Builders failed on: 
- Android Nexus5X Perf: 
  https://ci.chromium.org/buildbot/chromium.perf/Android%20Nexus5X%20Perf

Example log:
https://logs.chromium.org/v/?s=chrome%2Fbb%2Fchromium.perf%2FAndroid_Nexus5X_Perf%2F1683%2F%2B%2Frecipes%2Fsteps%2Frendering.mobile_on_Android%2F0%2Fstdout
(WARNING) 2018-05-02 23:46:34,109 artifact_results.AddArtifact:128  Moving artifact file '/b/swarming/w/itKsizVd/tmpd8GzUU.png' to '/b/swarming/w/itKsizVd/tmphQsBoctelemetry/artifacts'
Traceback (most recent call last):
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/story_runner.py", line 123, in _RunStoryAndProcessErrorIfNeeded
    state.RunStory(results)
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 52, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/page/shared_page_state.py", line 311, in RunStory
    self._current_page.Run(self)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/page/__init__.py", line 91, in Run
    shared_state.page_test.RunNavigateSteps(self, current_tab)
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 75, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/page/legacy_page_test.py", line 152, in RunNavigateSteps
    page.RunNavigateSteps(action_runner)
  File "/b/swarming/w/ir/tools/perf/page_sets/top_pages.py", line 184, in RunNavigateSteps
    super(YoutubePage, self).RunNavigateSteps(action_runner)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/page/__init__.py", line 100, in RunNavigateSteps
    url, script_to_evaluate_on_commit=self.script_to_evaluate_on_commit)
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 75, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/actions/action_runner.py", line 186, in Navigate
    timeout_in_seconds=timeout_in_seconds))
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 75, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/actions/action_runner.py", line 62, in _RunAction
    action.RunAction(self._tab)
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 75, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/actions/navigate.py", line 23, in RunAction
    self._timeout_in_seconds)
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 75, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 291, in Navigate
    self._inspector_backend.Navigate(url, script_to_evaluate_on_commit, timeout)
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 75, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 41, in Inner
    inspector_backend._ConvertExceptionFromInspectorWebsocket(e)
  File "/b/swarming/w/ir/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py", line 75, in traced_function
    return func(*args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 38, in Inner
    return func(inspector_backend, *args, **kwargs)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 185, in Navigate
    self._page.Navigate(url, script_to_evaluate_on_commit, timeout)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_page.py", line 125, in Navigate
    self.WaitForNavigate(timeout)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_page.py", line 94, in WaitForNavigate
    self._inspector_websocket.DispatchNotifications(remaining_time)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 140, in DispatchNotifications
    self._Receive(timeout)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 155, in _Receive
    data = self._socket.recv()
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 293, in recv
    opcode, data = self.recv_data()
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 310, in recv_data
    opcode, frame = self.recv_data_frame(control_frame)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 323, in recv_data_frame
    frame = self.recv_frame()
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 357, in recv_frame
    return self.frame_buffer.recv_frame()
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_abnf.py", line 336, in recv_frame
    self.recv_header()
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_abnf.py", line 286, in recv_header
    header = self.recv_strict(2)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_abnf.py", line 371, in recv_strict
    bytes_ = self.recv(min(16384, shortage))
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_core.py", line 427, in _recv
    return recv(self.sock, bufsize)
  File "/b/swarming/w/ir/third_party/catapult/telemetry/third_party/websocket-client/websocket/_socket.py", line 83, in recv
    raise WebSocketTimeoutException(message)
TimeoutException: 
********************************************************************************
(/b/swarming/w/ir/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py:487 _ConvertExceptionFromInspectorWebsocket) The app is probably crashed:
Found Minidump: True
Stack Trace:
********************************************************************************
	UI dump
	- (no package):
	  - (no id)
	- org.chromium.chrome:
	  - (no id)
	  - android:id/content
	  - android:id/navigationBarBackground
	  - android:id/statusBarBackground
	  - org.chromium.chrome:id/action_bar_root
	  - org.chromium.chrome:id/bottom_container
	  - org.chromium.chrome:id/button_primary['Save']
	  - org.chromium.chrome:id/button_secondary['Never']
	  - org.chromium.chrome:id/compositor_view_holder
	  - org.chromium.chrome:id/control_container
	  - org.chromium.chrome:id/coordinator
	  - org.chromium.chrome:id/infobar_close_button
	  - org.chromium.chrome:id/infobar_icon
	  - org.chromium.chrome:id/infobar_message['Do you want Google Chrome to save your password for https://accounts.google.com?']
	  - org.chromium.chrome:id/location_bar
	  - org.chromium.chrome:id/location_bar_icon
	  - org.chromium.chrome:id/menu_button
	  - org.chromium.chrome:id/menu_button_wrapper
	  - org.chromium.chrome:id/security_button
	  - org.chromium.chrome:id/tab_switcher_button
	  - org.chromium.chrome:id/toolbar
	  - org.chromium.chrome:id/toolbar_buttons
	  - org.chromium.chrome:id/toolbar_container
	  - org.chromium.chrome:id/toolbar_shadow
	  - org.chromium.chrome:id/url_bar['youtube.com']
	********************************************************************************
	Logcat
	--------- beginning of main
	05-02 23:45:22.774  8559  8559 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 0 <<<<<<
	05-02 23:45:22.779  8559  8559 D AndroidRuntime: CheckJNI is OFF
	05-02 23:45:22.810  8559  8559 D ICU     : No timezone override file found: /data/misc/zoneinfo/current/icu/icu_tzdata.dat
	05-02 23:45:22.841  8559  8559 I Radio-JNI: register_android_hardware_Radio DONE
	05-02 23:45:22.858  8559  8559 D AndroidRuntime: Calling main entry com.android.commands.am.Am
	--------- beginning of system
	05-02 23:45:22.861   907  3458 I ActivityManager: START u0 {dat=about:blank flg=0x10000000 cmp=org.chromium.chrome/com.google.android.apps.chrome.Main (has extras)} from uid 0 on display 0
	05-02 23:45:22.884   907  3532 I ActivityManager: Start proc 8567:org.chromium.chrome/u0a82 for activity org.chromium.chrome/com.google.android.apps.chrome.Main
	05-02 23:45:22.953  8567  8567 I cr_InstantAppsHandler: Not handling with Instant Apps because Chrome is not default or there's a specialized handler
	05-02 23:45:22.960   907  3534 I ActivityManager: START u0 {dat=about:blank flg=0x14002000 cmp=org.chromium.chrome/.browser.ChromeTabbedActivity (has extras)} from uid 10082 on display 0
	05-02 23:45:23.058  8567  8583 W GooglePlayServicesUtil: Google Play services out of date.  Requires 11200000 but found 8186448
	05-02 23:45:23.061  8567  8590 I cr_LibraryLoader: Using linker: LegacyLinker
	05-02 23:45:23.082  8567  8590 I cr_LibraryLoader: Loading chrome
	05-02 23:45:23.136  8567  8567 I cr_base : Android Locale: en_US requires .pak files: [en-GB, en-US]
	05-02 23:45:23.142  8567  8591 I cr_base : Extracting resource /data/user/0/org.chromium.chrome/app_chrome/paks/en-GB.pak@163233b76ce
	05-02 23:45:23.144  8567  8591 I cr_base : Extracting resource /data/user/0/org.chromium.chrome/app_chrome/paks/en-US.pak@163233b76ce
	05-02 23:45:23.144  8567  8592 W cr_tabmodel: Starting to perform legacy migration.
	05-02 23:45:23.146  8567  8592 W cr_tabmodel: Finished performing legacy migration.
	05-02 23:45:23.147  8567  8592 W cr_tabmodel: Starting to perform multi-instance migration.
	05-02 23:45:23.147  8567  8592 W cr_tabmodel: Finished performing multi-instance migration.
	05-02 23:45:23.148  8567  8593 I cr_tabmodel: Starting to fetch tab list for tab_state0
	05-02 23:45:23.148  8567  8593 I cr_tabmodel: State file does not exist.
	05-02 23:45:23.149  8567  8594 I cr_tabmodel: Starting to fetch tab list for tab_state_browser_actions
	05-02 23:45:23.150  8567  8594 I cr_tabmodel: State file does not exist.
	05-02 23:45:23.151  8567  8595 D OpenGLRenderer: Use EGL_SWAP_BEHAVIOR_PRESERVED: true
	05-02 23:45:23.185  8567  8590 I cr_LibraryLoader: Time to load native libraries: 124 ms (timestamps 1774-1898)
	05-02 23:45:23.185  8567  8590 I chromium: [INFO:library_loader_hooks.cc(36)] Chromium logging enabled: level = 0, default verbosity = 0
	05-02 23:45:23.186  8567  8590 I cr_LibraryLoader: Expected native library version number "68.0.3418.0", actual native library version number "68.0.3418.0"
	05-02 23:45:23.186  8567  8581 W chromium: [WARNING:library_prefetcher.cc(256)] Incorrect code ordering
	05-02 23:45:23.186  8567  8581 W chromium: [WARNING:library_prefetcher.cc(195)] Incorrect code ordering
	05-02 23:45:23.187  8567  8581 W cr_LibraryLoader: Forking a process to prefetch the native library failed.
	05-02 23:45:23.205  8567  8595 I Adreno  : QUALCOMM build                   : 63c06b2, I8366cd0437
	05-02 23:45:23.205  8567  8595 I Adreno  : Build Date                       : 10/21/15
	05-02 23:45:23.205  8567  8595 I Adreno  : OpenGL ES Shader Compiler Version: XE031.05.13.02
	05-02 23:45:23.205  8567  8595 I Adreno  : Local Branch                     : 
	05-02 23:45:23.205  8567  8595 I Adreno  : Remote Branch                    : quic/LA.BF64.1.2.9_v2
	05-02 23:45:23.205  8567  8595 I Adreno  : Remote Branch                    : NONE
	05-02 23:45:23.205  8567  8595 I Adreno  : Reconstruct Branch               : NOTHING
	05-02 23:45:23.214  8567  8595 I OpenGLRenderer: Initialized EGL, version 1.4
	05-02 23:45:23.323  8567  8597 W cr_ChildProcLH: Create a new ChildConnectionAllocator with package name = org.chromium.chrome, sandboxed = true
	05-02 23:45:23.324   907   926 I ActivityManager: Displayed org.chromium.chrome/.browser.ChromeTabbedActivity: +354ms (total +452ms)
	05-02 23:45:23.325  8559  8559 D AndroidRuntime: Shutting down VM
	05-02 23:45:23.334  3299  3299 I Keyboard.Facilitator: onFinishInput()
	05-02 23:45:23.347  8567  8567 I cr_BrowserStartup: Initializing chromium process, singleProcess=false
	05-02 23:45:23.348   907  3535 I ActivityManager: Start proc 8598:org.chromium.chrome:sandboxed_process0/u0i7 for service org.chromium.chrome/org.chromium.content.app.SandboxedProcessService0
	05-02 23:45:23.384  8598  8598 I cr_ChildProcessService: Creating new ChildProcessService pid=8598
	05-02 23:45:23.399  8567  8612 W chromium: [WARNING:dns_config_service_posix.cc(351)] Failed to read DnsConfig.
	05-02 23:45:23.419  8567  8567 W GooglePlayServicesUtil: Google Play services out of date.  Requires 11200000 but found 8186448
	05-02 23:45:23.432   907  3528 I ActivityManager: Start proc 8628:org.chromium.chrome:privileged_process0/u0a82 for service org.chromium.chrome/org.chromium.content.app.PrivilegedProcessService0
	05-02 23:45:23.438  8567  8567 W GooglePlayServicesUtil: Google Play services out of date.  Requires 11200000 but found 8186448
	05-02 23:45:23.439  8567  8567 I cr_SnippetsLauncher: Disabling SnippetsLauncher because Play Services is not up to date.
	05-02 23:45:23.443   907  3532 W BackupManagerService: dataChanged but no participant pkg='org.chromium.chrome' uid=10082
	05-02 23:45:23.467   907  3358 D ConnectivityService: listenForNetwork for Listen from uid/pid:10082/8567 for NetworkRequest [ id=26, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED] ]
	05-02 23:45:23.470  8567  8567 W GooglePlayServicesUtil: Google Play services out of date.  Requires 11200000 but found 8186448
	05-02 23:45:23.470  8567  8567 I cr_BgSyncLauncher: Disabling Background Sync because Play Services is not up to date.
	05-02 23:45:23.471   907  3534 W BackupManagerService: dataChanged but no participant pkg='org.chromium.chrome' uid=10082
	05-02 23:45:23.478   907   918 D ConnectivityService: listenForNetwork for Listen from uid/pid:10082/8567 for NetworkRequest [ id=27, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED] ]
	05-02 23:45:23.484  8628  8628 I cr_ChildProcessService: Creating new ChildProcessService pid=8628
	05-02 23:45:23.497  8567  8567 I cr_ContextualSearch: Tap suppression enabled: true
	05-02 23:45:23.498  8567  8567 I cr_ContextualSearch: Consructing ContextualSearchRankerLoggerImpl, enabled: true
	05-02 23:45:23.519  8628  8640 I cr_LibraryLoader: Using linker: LegacyLinker
	05-02 23:45:23.549  8609  8609 W Binder_1: type=1400 audit(0.0:67): avc: denied { ioctl } for path="socket:[55081]" dev="sockfs" ino=55081 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:r:untrusted_app:s0:c512,c768 tclass=unix_stream_socket permissive=0
	05-02 23:45:23.549  8609  8609 W Binder_1: type=1400 audit(0.0:68): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/app_chrome/paks/en-US.pak@163233b76ce" dev="dm-2" ino=1490090 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:23.549  8609  8609 W Binder_1: type=1400 audit(0.0:69): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/cache/.com.google.Chrome.8eyXiM" dev="dm-2" ino=1490110 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:23.559  8609  8609 W Binder_1: type=1400 audit(0.0:70): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/cache/.com.google.Chrome.8eyXiM" dev="dm-2" ino=1490110 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:23.559  8609  8609 W Binder_1: type=1400 audit(0.0:71): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/app_chrome/paks/en-US.pak@163233b76ce" dev="dm-2" ino=1490090 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:23.559  8609  8609 W Binder_1: type=1400 audit(0.0:72): avc: denied { ioctl } for path="socket:[55081]" dev="sockfs" ino=55081 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:r:untrusted_app:s0:c512,c768 tclass=unix_stream_socket permissive=0
	05-02 23:45:23.566  8628  8644 I cr_LibraryLoader: Loading chrome
	05-02 23:45:23.568  8598  8609 I cr_LibraryLoader: Using linker: LegacyLinker
	05-02 23:45:23.569   907   925 D BluetoothManagerService: Added callback: android.bluetooth.IBluetoothManagerCallback$Stub$Proxy@fdeac20:true
	05-02 23:45:23.597  8598  8613 I cr_LibraryLoader: Loading chrome
	05-02 23:45:23.612  8567  8567 W GooglePlayServicesUtil: Google Play services out of date.  Requires 11200000 but found 8186448
	05-02 23:45:23.636  8628  8644 I cr_LibraryLoader: Time to load native libraries: 70 ms (timestamps 2279-2349)
	05-02 23:45:23.637  8628  8644 I chromium: [INFO:library_loader_hooks.cc(36)] Chromium logging enabled: level = 0, default verbosity = 0
	05-02 23:45:23.637  8628  8644 I cr_LibraryLoader: Expected native library version number "68.0.3418.0", actual native library version number "68.0.3418.0"
	05-02 23:45:23.640  3299  3299 I Keyboard.Facilitator: onFinishInput()
	05-02 23:45:23.651  8628  8644 E libEGL  : validate_display:255 error 3008 (EGL_BAD_DISPLAY)
	05-02 23:45:23.652  8628  8644 I Adreno  : QUALCOMM build                   : 63c06b2, I8366cd0437
	05-02 23:45:23.652  8628  8644 I Adreno  : Build Date                       : 10/21/15
	05-02 23:45:23.652  8628  8644 I Adreno  : OpenGL ES Shader Compiler Version: XE031.05.13.02
	05-02 23:45:23.652  8628  8644 I Adreno  : Local Branch                     : 
	05-02 23:45:23.652  8628  8644 I Adreno  : Remote Branch                    : quic/LA.BF64.1.2.9_v2
	05-02 23:45:23.652  8628  8644 I Adreno  : Remote Branch                    : NONE
	05-02 23:45:23.652  8628  8644 I Adreno  : Reconstruct Branch               : NOTHING
	05-02 23:45:23.665  8598  8613 I cr_LibraryLoader: Time to load native libraries: 69 ms (timestamps 2310-2379)
	05-02 23:45:23.666  8598  8613 I chromium: [INFO:library_loader_hooks.cc(36)] Chromium logging enabled: level = 0, default verbosity = 0
	05-02 23:45:23.666  8598  8613 I cr_LibraryLoader: Expected native library version number "68.0.3418.0", actual native library version number "68.0.3418.0"
	05-02 23:45:23.697  8628  8644 W VideoCapabilities: Unrecognized profile 2130706433 for video/avc
	05-02 23:45:23.715  8628  8644 I VideoCapabilities: Unsupported profile 4 for video/mp4v-es
	05-02 23:45:23.816   907  3073 W BackupManagerService: dataChanged but no participant pkg='org.chromium.chrome' uid=10082
	05-02 23:45:23.841  8567  8597 I cr_BindingManager: Moderate binding enabled: maxSize=20
	05-02 23:45:23.871  8567  8567 W GooglePlayServicesUtil: Google Play services out of date.  Requires 11200000 but found 8186448
	05-02 23:45:23.873  8567  8567 W GooglePlayServicesUtil: Google Play services out of date.  Requires 11200000 but found 8186448
	05-02 23:45:23.873  8567  8669 E chromium: [ERROR:devtools_http_handler.cc(292)] Cannot start http server for devtools. Stop devtools.
	05-02 23:45:23.896  8668  8668 I dex2oat : /system/bin/dex2oat -j2 --dex-file=/data/user/0/org.chromium.chrome/app_dex/webapk5.dex --oat-file=/data/user/0/org.chromium.chrome/app_dex/oat/arm/webapk5.dex
	05-02 23:45:23.926  8668  8668 I dex2oat : dex2oat took 30.912ms (threads: 2) arena alloc=217KB java alloc=58KB native alloc=1098KB free=1205KB
	05-02 23:45:25.965  8567  8621 E cr_ApiBridge: Failed to init handler: Attempt to invoke virtual method 'java.lang.reflect.Constructor java.lang.Class.getDeclaredConstructor(java.lang.Class[])' on a null object reference
	05-02 23:45:26.588  8567  8567 I chromium: [INFO:CONSOLE(0)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/CrYjSnGjrRCn0pd9VQsnFOvvDin1pK8aKteLpeZ5c0A.woff", source: https://accounts.google.com/ServiceLogin?continue=https%3A%2F%2Faccounts.google.com%2FManageAccount (0)
	05-02 23:45:26.592  8567  8567 I chromium: [INFO:CONSOLE(0)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/RxZJdnzeo3R5zSexge8UUbO3LdcAZYWl9Si6vvxL-qU.woff", source: https://accounts.google.com/ServiceLogin?continue=https%3A%2F%2Faccounts.google.com%2FManageAccount (0)
	05-02 23:45:26.688  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream1
	05-02 23:45:26.688  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream1
	05-02 23:45:26.981  8567  8567 I chromium: [INFO:CONSOLE(0)] "Access to Font at 'https://fonts.gstatic.com/s/roboto/v16/RxZJdnzeo3R5zSexge8UUbO3LdcAZYWl9Si6vvxL-qU.woff' from origin 'https://accounts.google.com' has been blocked by CORS policy: No 'Access-Control-Allow-Origin' header is present on the requested resource. Origin 'https://accounts.google.com' is therefore not allowed access. The response had HTTP status code 404.", source: https://accounts.google.com/signin/v2/identifier?continue=https%3A%2F%2Faccounts.google.com%2FManageAccount&flowName=GlifWebSignIn&flowEntry=ServiceLogin (0)
	05-02 23:45:27.005  6387  6389 E chromium: [0502/234527.005424:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:27.203  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream3
	05-02 23:45:27.203  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream3
	05-02 23:45:27.203  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream3
	05-02 23:45:27.470  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream5
	05-02 23:45:27.470  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream5
	05-02 23:45:27.470  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream5
	05-02 23:45:27.638  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream3
	05-02 23:45:27.638  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream3
	05-02 23:45:28.216  8567  8567 I chromium: [INFO:CONSOLE(597)] "%c%s", source: /_/mss/boq-identity/_/js/k=boq-identity.AccountSettingsUi.en.QNnRQ3gW0-w.O/m=_b,_tp,_dt,_stam/am=gDZxNr___e-8IQ3ABw/rt=j/d=1/excm=welcomeview,_b,_tp,_dt,_stam/ed=1/rs=AOaEmlFRF4QwHBuC9rdwebcjjG7xh96pLw (597)
	05-02 23:45:28.216  8567  8567 I chromium: [INFO:CONSOLE(597)] "%c%s", source: /_/mss/boq-identity/_/js/k=boq-identity.AccountSettingsUi.en.QNnRQ3gW0-w.O/m=_b,_tp,_dt,_stam/am=gDZxNr___e-8IQ3ABw/rt=j/d=1/excm=welcomeview,_b,_tp,_dt,_stam/ed=1/rs=AOaEmlFRF4QwHBuC9rdwebcjjG7xh96pLw (597)
	05-02 23:45:28.332  8567  8567 I chromium: [INFO:CONSOLE(1006)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/CrYjSnGjrRCn0pd9VQsnFOvvDin1pK8aKteLpeZ5c0A.woff", source: https://myaccount.google.com/?pli=1 (1006)
	05-02 23:45:28.333  8567  8567 I chromium: [INFO:CONSOLE(1006)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/d-6IYplOFocCacKzxwXSOLO3LdcAZYWl9Si6vvxL-qU.woff", source: https://myaccount.google.com/?pli=1 (1006)
	05-02 23:45:28.362  8567  8567 I chromium: [INFO:CONSOLE(72)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/RxZJdnzeo3R5zSexge8UUbO3LdcAZYWl9Si6vvxL-qU.woff", source: https://myaccount.google.com/?pli=1 (72)
	05-02 23:45:28.469   907  3534 I ActivityManager: Start proc 8707:org.chromium.chrome:sandboxed_process1/u0i8 for service org.chromium.chrome/org.chromium.content.app.SandboxedProcessService1
	05-02 23:45:28.486  8567  8567 I chromium: [INFO:CONSOLE(0)] "Access to Font at 'https://fonts.gstatic.com/s/roboto/v16/RxZJdnzeo3R5zSexge8UUbO3LdcAZYWl9Si6vvxL-qU.woff' from origin 'https://myaccount.google.com' has been blocked by CORS policy: No 'Access-Control-Allow-Origin' header is present on the requested resource. Origin 'https://myaccount.google.com' is therefore not allowed access. The response had HTTP status code 404.", source: https://myaccount.google.com/?pli=1 (0)
	05-02 23:45:28.499  8718  8718 W Binder_1: type=1400 audit(0.0:73): avc: denied { ioctl } for path="socket:[61890]" dev="sockfs" ino=61890 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:r:untrusted_app:s0:c512,c768 tclass=unix_stream_socket permissive=0
	05-02 23:45:28.499  8718  8718 W Binder_1: type=1400 audit(0.0:74): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/app_chrome/paks/en-US.pak@163233b76ce" dev="dm-2" ino=1490090 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.499  8718  8718 W Binder_1: type=1400 audit(0.0:75): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/cache/.com.google.Chrome.QT9uJJ" dev="dm-2" ino=1490122 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.509  8718  8718 W Binder_1: type=1400 audit(0.0:76): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/cache/.com.google.Chrome.QT9uJJ" dev="dm-2" ino=1490122 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.509  8718  8718 W Binder_1: type=1400 audit(0.0:77): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/app_chrome/paks/en-US.pak@163233b76ce" dev="dm-2" ino=1490090 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.509  8718  8718 W Binder_1: type=1400 audit(0.0:78): avc: denied { ioctl } for path="socket:[61890]" dev="sockfs" ino=61890 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:r:untrusted_app:s0:c512,c768 tclass=unix_stream_socket permissive=0
	05-02 23:45:28.509  8707  8707 I cr_ChildProcessService: Creating new ChildProcessService pid=8707
	05-02 23:45:28.517  8707  8718 I cr_LibraryLoader: Using linker: LegacyLinker
	05-02 23:45:28.550  8707  8720 I cr_LibraryLoader: Loading chrome
	05-02 23:45:28.623  8707  8720 I cr_LibraryLoader: Time to load native libraries: 73 ms (timestamps 7263-7336)
	05-02 23:45:28.625  8707  8720 I chromium: [INFO:library_loader_hooks.cc(36)] Chromium logging enabled: level = 0, default verbosity = 0
	05-02 23:45:28.625  8707  8720 I cr_LibraryLoader: Expected native library version number "68.0.3418.0", actual native library version number "68.0.3418.0"
	05-02 23:45:28.632   907  3358 I ActivityManager: Start proc 8722:org.chromium.chrome:sandboxed_process2/u0i9 for service org.chromium.chrome/org.chromium.content.app.SandboxedProcessService2
	05-02 23:45:28.669  8733  8733 W Binder_1: type=1400 audit(0.0:79): avc: denied { ioctl } for path="socket:[61925]" dev="sockfs" ino=61925 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:r:untrusted_app:s0:c512,c768 tclass=unix_stream_socket permissive=0
	05-02 23:45:28.669  8733  8733 W Binder_1: type=1400 audit(0.0:80): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/app_chrome/paks/en-US.pak@163233b76ce" dev="dm-2" ino=1490090 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.669  8733  8733 W Binder_1: type=1400 audit(0.0:81): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/cache/.com.google.Chrome.YZ3t0q" dev="dm-2" ino=1490124 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.669  8733  8733 W Binder_1: type=1400 audit(0.0:82): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/cache/.com.google.Chrome.YZ3t0q" dev="dm-2" ino=1490124 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.669  8733  8733 W Binder_1: type=1400 audit(0.0:83): avc: denied { ioctl } for path="/data/data/org.chromium.chrome/app_chrome/paks/en-US.pak@163233b76ce" dev="dm-2" ino=1490090 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=file permissive=0
	05-02 23:45:28.669  8733  8733 W Binder_1: type=1400 audit(0.0:84): avc: denied { ioctl } for path="socket:[61925]" dev="sockfs" ino=61925 ioctlcmd=7704 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:r:untrusted_app:s0:c512,c768 tclass=unix_stream_socket permissive=0
	05-02 23:45:28.673  8722  8722 I cr_ChildProcessService: Creating new ChildProcessService pid=8722
	05-02 23:45:28.684  8722  8733 I cr_LibraryLoader: Using linker: LegacyLinker
	05-02 23:45:28.693   907  3535 D ConnectivityService: listenForNetwork for Listen from uid/pid:10082/8567 for NetworkRequest [ id=28, legacyType=-1, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED] ]
	05-02 23:45:28.719  8722  8742 I cr_LibraryLoader: Loading chrome
	05-02 23:45:28.767   907  3528 W libprocessgroup: failed to open /acct/uid_10082/pid_8707/cgroup.procs: No such file or directory
	05-02 23:45:28.767   907  3528 I ActivityManager: Process org.chromium.chrome:sandboxed_process1 (pid 8707) has died
	05-02 23:45:28.768  8567  8597 W cr_ChildProcessConn: onServiceDisconnected (crash or killed by oom): pid=8707
	05-02 23:45:28.775   907  3528 W ActivityManager: Scheduling restart of crashed service org.chromium.chrome/org.chromium.content.app.SandboxedProcessService1 in 1000ms
	05-02 23:45:28.783  8722  8742 I cr_LibraryLoader: Time to load native libraries: 63 ms (timestamps 7433-7496)
	05-02 23:45:28.784  8722  8742 I chromium: [INFO:library_loader_hooks.cc(36)] Chromium logging enabled: level = 0, default verbosity = 0
	05-02 23:45:28.784  8722  8742 I cr_LibraryLoader: Expected native library version number "68.0.3418.0", actual native library version number "68.0.3418.0"
	05-02 23:45:28.908  8567  8567 I chromium: [INFO:CONSOLE(71)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/CrYjSnGjrRCn0pd9VQsnFOvvDin1pK8aKteLpeZ5c0A.woff", source: https://www.youtube.com/ (71)
	05-02 23:45:28.908  8567  8567 I chromium: [INFO:CONSOLE(71)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/RxZJdnzeo3R5zSexge8UUbO3LdcAZYWl9Si6vvxL-qU.woff", source: https://www.youtube.com/ (71)
	05-02 23:45:28.981  6387  6389 E chromium: [0502/234528.980972:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:29.023  8567  8567 I chromium: [INFO:CONSOLE(0)] "Access to Font at 'https://fonts.gstatic.com/s/roboto/v16/RxZJdnzeo3R5zSexge8UUbO3LdcAZYWl9Si6vvxL-qU.woff' from origin 'https://www.youtube.com' has been blocked by CORS policy: No 'Access-Control-Allow-Origin' header is present on the requested resource. Origin 'https://www.youtube.com' is therefore not allowed access. The response had HTTP status code 404.", source: https://www.youtube.com/ (0)
	05-02 23:45:29.267  8567  8567 I chromium: [INFO:CONSOLE(0)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/oHi30kwQWvpCWqAhzHcCSPFC_w5J6O5PEm5icuu-cHc.woff", source: https://www.youtube.com/ (0)
	05-02 23:45:29.270  8567  8567 I chromium: [INFO:CONSOLE(0)] "Slow network is detected. See https://www.chromestatus.com/feature/5636954674692096 for more details. Fallback font will be used while loading: https://fonts.gstatic.com/s/roboto/v16/mx9Uck6uB63VIKFYnEMXrfFC_w5J6O5PEm5icuu-cHc.woff", source: https://www.youtube.com/ (0)
	05-02 23:45:29.421  8567  8567 I chromium: [INFO:CONSOLE(0)] "Uncaught (in promise) NetworkError: A network error occurred.", source: https://www.youtube.com/ (0)
	05-02 23:45:29.474  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream1
	05-02 23:45:29.474  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream1
	05-02 23:45:29.478  8567  8567 I chromium: [INFO:CONSOLE(0)] "Access to Font at 'https://fonts.gstatic.com/s/roboto/v16/oHi30kwQWvpCWqAhzHcCSPFC_w5J6O5PEm5icuu-cHc.woff' from origin 'https://www.youtube.com' has been blocked by CORS policy: No 'Access-Control-Allow-Origin' header is present on the requested resource. Origin 'https://www.youtube.com' is therefore not allowed access. The response had HTTP status code 404.", source: https://www.youtube.com/ (0)
	05-02 23:45:29.480  8567  8567 I chromium: [INFO:CONSOLE(0)] "Access to Font at 'https://fonts.gstatic.com/s/roboto/v16/mx9Uck6uB63VIKFYnEMXrfFC_w5J6O5PEm5icuu-cHc.woff' from origin 'https://www.youtube.com' has been blocked by CORS policy: No 'Access-Control-Allow-Origin' header is present on the requested resource. Origin 'https://www.youtube.com' is therefore not allowed access. The response had HTTP status code 404.", source: https://www.youtube.com/ (0)
	05-02 23:45:29.568   907  3458 W libprocessgroup: failed to open /acct/uid_10082/pid_8598/cgroup.procs: No such file or directory
	05-02 23:45:29.568   907  3458 I ActivityManager: Process org.chromium.chrome:sandboxed_process0 (pid 8598) has died
	05-02 23:45:29.568   907  3458 W ActivityManager: Scheduling restart of crashed service org.chromium.chrome/org.chromium.content.app.SandboxedProcessService0 in 1000ms
	05-02 23:45:29.570  8567  8597 W cr_ChildProcessConn: onServiceDisconnected (crash or killed by oom): pid=8598
	05-02 23:45:29.701  3299  3299 I Keyboard.Facilitator: onFinishInput()
	05-02 23:45:30.120  6387  6389 E chromium: [0502/234530.120591:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:30.445  6387  6389 E chromium: [0502/234530.445419:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:30.488  6387  6389 E chromium: [0502/234530.488115:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:30.521  8567  8567 I chromium: [INFO:CONSOLE(414)] "chrome.loadTimes() is deprecated, instead use standardized API: Navigation Timing 2. https://www.chromestatus.com/features/5637885046816768.", source: https://www.youtube.com/yts/jsbin/www-en_US-vflXH_pYv/base.js (414)
	05-02 23:45:30.522  8567  8567 I chromium: [INFO:CONSOLE(414)] "chrome.loadTimes() is deprecated, instead use standardized API: Navigation Timing 2. https://www.chromestatus.com/features/5637885046816768.", source: https://www.youtube.com/yts/jsbin/www-en_US-vflXH_pYv/base.js (414)
	05-02 23:45:30.522  8567  8567 I chromium: [INFO:CONSOLE(414)] "chrome.loadTimes() is deprecated, instead use standardized API: Paint Timing. https://www.chromestatus.com/features/5637885046816768.", source: https://www.youtube.com/yts/jsbin/www-en_US-vflXH_pYv/base.js (414)
	05-02 23:45:30.557  6387  6389 E chromium: [0502/234530.557886:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:30.599  8567  8567 I chromium: [INFO:CONSOLE(1)] "Manifest: Line: 1, column: 1, Unexpected token.", source: https://www.youtube.com/manifest.json (1)
	05-02 23:45:31.077  6387  6389 E chromium: [0502/234531.077607:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:31.287  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream51
	05-02 23:45:31.287  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream51
	05-02 23:45:31.342  6387  6389 E chromium: [0502/234531.342672:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:32.035  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream53
	05-02 23:45:32.035  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream53
	05-02 23:45:32.476   907  3528 I sensors : batch
	05-02 23:45:32.476   907  3528 I sensors : activate
	05-02 23:45:32.482   907  2649 I hubconnection: sensorhub said: 'batch 20 flags:0, sampling_rate_Hz:60.00, max_report_latency_us:0'
	05-02 23:45:32.484   907  2649 I hubconnection: sensorhub said: 'activate 20 enable:1'
	05-02 23:45:32.622  6387  6389 E chromium: [0502/234532.622039:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:32.725   907   918 I sensors : activate
	05-02 23:45:32.727   907  2649 I hubconnection: sensorhub said: 'activate 20 enable:0'
	05-02 23:45:32.876  8567  8567 I chromium: [INFO:CONSOLE(0)] "The deviceorientation events are blocked by feature policy. See https://github.com/WICG/feature-policy/blob/gh-pages/features.md#sensor-features", source:  (0)
	05-02 23:45:32.937  6387  6389 E chromium: [0502/234532.937086:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:32.954  8567  8567 I MediaRouter: Found default route: MediaRouter.RouteInfo{ uniqueId=android/ru:DEFAULT_ROUTE, name=Phone, description=null, iconUri=null, enabled=true, connecting=false, connectionState=0, canDisconnect=false, playbackType=0, playbackStream=3, deviceType=0, volumeHandling=1, volume=11, volumeMax=15, presentationDisplayId=-1, extras=null, settingsIntent=null, providerPackageName=android }
	05-02 23:45:32.954  8567  8567 I MediaRouter: Unselecting the current route because it is no longer selectable: null
	05-02 23:45:32.974   907  3073 W ActivityManager: Permission Denial: Accessing service ComponentInfo{com.google.android.music/com.google.android.music.dial.DialMediaRouteProviderService} from pid=8567, uid=10082 that is not exported from uid 10062
	05-02 23:45:32.980  8567  8591 E cr_MediaFling: Failed to fetch the final url
	05-02 23:45:33.090  6387  6389 E chromium: [0502/234533.090526:ERROR:socket.cc(344)] read: Connection reset by peer
	05-02 23:45:33.577  8567  8567 I chromium: [INFO:CONSOLE(209)] "The deviceorientation events are blocked by feature policy. See https://github.com/WICG/feature-policy/blob/gh-pages/features.md#sensor-features", source: https://www.youtube.com/yts/jsbin/player-vflUnLBiU/en_US/base.js (209)
	05-02 23:45:33.676  8567  8567 E cr_BkgrdTaskJS: Tried finishing non-current BackgroundTask.
	05-02 23:45:42.731  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream67
	05-02 23:45:42.731  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream67
	05-02 23:45:42.732  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream67
	05-02 23:45:42.732  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream67
	05-02 23:45:43.694  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream69
	05-02 23:45:43.695  8567  8611 W chromium: [WARNING:spdy_session.cc(2800)] Received RST for invalid stream69
	05-02 23:46:17.869   533   533 E QCALOG  : [MessageQ] ProcessNewMessage: [LOWI-SERVER] unknown deliver target [OS-Agent]
	05-02 23:46:29.087  8934  8934 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 0 <<<<<<
	05-02 23:46:29.093  8934  8934 D AndroidRuntime: CheckJNI is OFF
	05-02 23:46:29.134  8934  8934 D ICU     : No timezone override file found: /data/misc/zoneinfo/current/icu/icu_tzdata.dat
	05-02 23:46:29.215  8934  8934 I Radio-JNI: register_android_hardware_Radio DONE
	05-02 23:46:29.233  8934  8934 D AndroidRuntime: Calling main entry com.android.commands.uiautomator.Launcher
	05-02 23:46:29.738  3299  3390 I Keyboard.Facilitator.LanguageModelFlusher: run()
	05-02 23:46:29.738  3299  3390 I Keyboard.Facilitator: flushDynamicLanguageModels()
	05-02 23:46:29.753  3536  3536 I ConfigService: onCreate
	05-02 23:46:30.417  8934  8934 I AccessibilityNodeInfoDumper: Skipping invisible child: android.view.accessibility.AccessibilityNodeInfo@8000ca97; boundsInParent: Rect(0, 0 - 1, 5); boundsInScreen: Rect(1, 205 - 1, 210); packageName: org.chromium.chrome; className: android.widget.ImageView; text: null; error: null; maxTextLength: -1; contentDescription: null; viewIdResName: null; checkable: false; checked: false; focusable: false; focused: false; selected: false; clickable: false; longClickable: false; contextClickable: false; enabled: true; password: false; scrollable: false; actions: [AccessibilityAction: ACTION_SELECT - null, AccessibilityAction: ACTION_CLEAR_SELECTION - null, AccessibilityAction: ACTION_ACCESSIBILITY_FOCUS - null, AccessibilityAction: ACTION_UNKNOWN - null]
	05-02 23:46:30.419  8934  8934 I AccessibilityNodeInfoDumper: Skipping invisible child: android.view.accessibility.AccessibilityNodeInfo@8000b7d2; boundsInParent: Rect(0, 0 - 0, 0); boundsInScreen: Rect(0, 1794 - 0, 1794); packageName: org.chromium.chrome; className: android.view.View; text: null; error: null; maxTextLength: -1; contentDescription: null; viewIdResName: org.chromium.chrome:id/menu_anchor_stub; checkable: false; checked: false; focusable: false; focused: false; selected: false; clickable: false; longClickable: false; contextClickable: false; enabled: true; password: false; scrollable: false; actions: [AccessibilityAction: ACTION_SELECT - null, AccessibilityAction: ACTION_CLEAR_SELECTION - null, AccessibilityAction: ACTION_ACCESSIBILITY_FOCUS - null, AccessibilityAction: ACTION_UNKNOWN - null]
	05-02 23:46:30.420  8934  8934 W AccessibilityNodeInfoDumper: Fetch time: 32ms
	05-02 23:46:30.421   907  3528 W WindowManager: Attempted to remove non-existing token: android.os.Binder@7a15f5b
	05-02 23:46:30.428  8934  8934 D AndroidRuntime: Shutting down VM
	********************************************************************************
	Stack from Logcat
	Reading native crash info from stdin
	Unknown Android release, consider passing --packed-lib.
	Reading Android symbols from: /b/swarming/w/ir
	Searching for Chrome symbols from within: /b/swarming/w/ir/out/Release/lib.unstripped:/b/swarming/w/ir/out/Release
	
	********************************************************************************

Comment 1 by sullivan@google.com, May 3 2018

Owner: sadrul@chromium.org
Actually, looking at flakiness dashboard, this page has been flaky for the entire history of the test: https://test-results.appspot.com/dashboards/flakiness_dashboard.html#testType=rendering.mobile&showAllRuns=true&builder=chromium.perf%3AAndroid%20Nexus5X%20Perf

Assigning to test owner for triage.

Comment 2 by sadrul@chromium.org, May 7 2018

Labels: OS-Android
Status: Started (was: Available)
This line from the log seems relevant:

  05-02 23:45:23.873  8567  8669 E chromium: [ERROR:devtools_http_handler.cc(292)] Cannot start http server for devtools. Stop devtools.

If the devtools server cannot start, then telemetry tests are going to have a bad time.

Looking into it more.

Comment 3 by sadrul@chromium.org, May 7 2018 

Is there a way to get the browser logs from a successful run?

Comment 4 by sadrul@chromium.org, May 7 2018 

Also:

 (WARNING) 2018-05-02 23:46:34,109 artifact_results.AddArtifact:128  Moving artifact file '/b/swarming/w/itKsizVd/tmpd8GzUU.png' to '/b/swarming/w/itKsizVd/tmphQsBoctelemetry/artifacts'

Is there a way to get these screenshot files from a failed/passed run? :)

Comment 5 by sullivan@google.com, May 7 2018 

Tl;dr here is one: https://logs.chromium.org/v/?s=chrome%2Fbb%2Fchromium.perf%2FAndroid_Nexus5X_Perf%2F1699%2F%2B%2Frecipes%2Fsteps%2Frendering.mobile_on_Android%2F0%2Fstdout

Here are the steps:
1) Go to the buildbot status page linked in the description:
 https://ci.chromium.org/buildbot/chromium.perf/Android%20Nexus5X%20Perf
2) Ctrl+F for "rendering.mobile", click on a build where it's NOT listed as a failure (I picked https://ci.chromium.org/buildbot/chromium.perf/Android%20Nexus5X%20Perf/1699)
3) Ctrl+F for "rendering.mobile" again, eventually you get to a step "rendering.mobile on Android rendering.mobile on Android" (step 404 in that build)
4) Click the "stdout" link in that step: https://logs.chromium.org/v/?s=chrome%2Fbb%2Fchromium.perf%2FAndroid_Nexus5X_Perf%2F1699%2F%2B%2Frecipes%2Fsteps%2Frendering.mobile_on_Android%2F0%2Fstdout

Comment 6 by sullivan@google.com, May 7 2018 

Re #4: If you search for the name of that png, you'll see a line farther down:

(INFO) 2018-05-03 00:39:20,311 cloud_storage.Insert:378  Uploading /b/swarming/w/itKsizVd/tmphQsBoctelemetry/artifacts/tmpd8GzUU.png to gs://chrome-telemetry-output/6afac348-4e6a-11e8-a9ff-0242ac110003
Uploading screenshot of page youtube_desktop to https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/6afac348-4e6a-11e8-a9ff-0242ac110003 (1 out of 1)

Comment 7 by sadrul@chromium.org, May 7 2018 

Thank you!

re #5: I see the log of the test, but it doesn't look like it includes the logs from the browser? I was hoping to find the logs from the chrome browser (the ones that get spit out into the telemetry logs in a separate section when a test fails).

re #6: I see the screenshot! I believe that means the browser has not crashed. But the communication with telemetry broke down somehow; devtools server not starting would explain that. But I don't know why devtools would fail to start only for this test. So I suspect it's something else. I was hoping to compare the browser-logs between a successful run vs. a failed run.

Comment 8 by sullivan@google.com, May 7 2018

Cc: nednguyen@chromium.org
+Ned to help with getting answers to questions in #7, since it's getting out of perfbot sheriff realm.

Comment 9 by sadrul@chromium.org, May 7 2018 

From the instructions in c #5, I did end up getting the trace files for the runs (both successful and failed runs).

Trace for success: https://00e9e64bacbe9fd551ee587efa513df7db8711335b74d45bed-apidata.googleusercontent.com/download/storage/v1/b/chrome-telemetry-output/o/youtube_desktop_2018-05-07_03-45-40_91826.html?qk=AD5uMEuOgkCLdun3ieFQcHYCEcD4bNdisJQZAiF5Cx_RKfOyk6JaHNVBZR_J9w-AlHHg7Ipah3-o1At1670OEvrHOJh_osqYKsOAUh3TANUJgVCjnZYqWlkeRwvsTCoK-ys9XQEpRR9h47A4gBmdJqukGFcXne9ATkvFg9PpEkqXtNkXXjLN1ZjZMwY78xYwAVvYm2XmsKTrhaWarB8fLrhGl9UuIspkmtUzm_JXFUsRRrtMDlzc6wPO04a-tEQjABrd-COIVQ_EzJTRJB-0JNd47Ju468BcY1eUil3VfZ0GRhYOVvgs0NGZ30WQIDr9F2Yi80CYzkL5hDgehoQkTKPEm4g8PkvFfmynGjRTJvIkbA8_qpzC-ZEng6bvHfewJcvs1r5WHFLlEp6WYm7WHJQvkd6gyhpUwreZ7gIlQZdSXFquaQkYYezo2W8-KoF7ftrx0mgx5ejZKoJYsJpBFOKoOKingWHxCG56f5S_ZqdG9D2-W-eUNL28FDorqjE3Q7brRFz8s9vp24syqJtmUL6B8udL-eXTuquEmbq3cs6Aro7uVKi45VgSUG5ElvSgKurpUpuNHRMNW8v7BK6gPcMqmWl0F7B0RIVLeXNuevCKzB5-wLrLFeVO9ckM6no0vezr2lTXh-93K5KnaA2bi9FSl4ZEkZg59Uaz_HtXL51NVQcg4HWvlQ3GQNEX7jMqiQQOLIg_n8u7lpC8MpcdRJ1u_ZdfcDpOBX6WSvt7Z28ldVXJGKQ4GLoXjNwINZC92zCQxnBQZXJ1vt9pXi7dsNq_WaXhKE-l1duF8IS9U_5Rihdrr70bRDU

Trace for failure: https://00e9e64baca0df6d5c77d66ca0626f7aaa7286f9bffd5dc1db-apidata.googleusercontent.com/download/storage/v1/b/chrome-telemetry-output/o/youtube_desktop_2018-05-07_07-26-08_70402.html?qk=AD5uMEtysVdJ69FVJ-O-lupYTsHM-WJU3N5dJ37LLivNaIdJL3BzvR1tKYzaSj_0VV7DhygISvNtLjUe10SuZyfN24F9eX4sNGGO7R37D77t7atG13hT80EGFPkeKfg-8NOL4mkT2p4KC4K-0FlqH51IvEpNFd51GHbQS1nVmgRscZQgmEa-rNNgwO2qaWBf1wA4Up5q95-C9JACVvobJVJ-YUBoLi_RZPn1iQAT5uhdGDypt3abltmZi2z3b_TdSFHMJctgadMHEezw_fnfv2gihGRns-SxYt2LlfLnPxBetrrQMGnRiDyw6st_GiHj1jSdONn4Xl-6tIL3vcEtjkdbtiHtK5rTiaId5Hrdx_mneQqwazUv1b10_MQRKcA6UkhmOTrHfz7TNgm1Lb3hwKTtOBEX6hVOHsTncrGz4DyIUc3c6c__xGpXtUUrYBCqQr6OsPa01HN7H2WFqiVRhtH6Fbu-U9XutVYMzWp0_dh33-ARCTcPQBBLwTLv4k5o6o7us38Qf1OMPzdkJ0eXtP6HeBWYlgmkp7Jh9SlgS-5RQSJR2vtgnyKQRQHQBCOpH9HbYrrO1D85TiOXNmxhQiMXbwWEtge8whlIBZNxJzRAlmqIDfI37QyvF0yT62HYaR0aVym4n0nKnHdza7E-_20GWmtyr7rZ1I9pukq_Mc9D1J_3vWSmunnKmv1acp0qSJXi3TS31KbctOZAzbNapW-a-3L36t6-mzC88xAz6a6NjfS68bwLcRbSFVM5bHat2Ry1DD7V-lFhfXp7oy9UEXkwYgLPnoLHEYnDybMdZAWrbeB7lNug5bk

In the success case, the 'Process' (at the bottom) has a 'Rendering.RunNavigateSteps' step (~7 seconds), followed by some ActionRunner steps (e.g. ScrollPage).

In the failure case, the 'Process' has a long running 'Rendering.RunNavigateSteps' step (~66 seconds), followed by a 'dump state on failure'. So it looks like navigation is failing in the failure case for some reason. Looking into the traces more.

Comment 10 by sadrul@chromium.org, May 7 2018

Cc: chiniforooshan@chromium.org

Comment 11 by sadrul@chromium.org, May 15 2018

Cc: sadrul@chromium.org
Owner: nednguyen@chromium.org
Status: Assigned (was: Started)
It seems fairly conclusive that the navigation to the page is failing (or getting stuck) during the test, so the rendering related parts of the test do not even kick in.

nednguyen@: Who would be a good person to look into navigation related issues? Can you triage please?

Comment 12 by nednguyen@chromium.org, May 15 2018

Summary: Flaky crash due to navigation being stuck on rendering.mobile/youtube_desktop on chromium.perf/Android Nexus5X Perf (was: Flaky crash on rendering.mobile/youtube_desktop on chromium.perf/Android Nexus5X Perf)

Comment 13 by nednguyen@chromium.org, May 15 2018

Owner: kouhei@chromium.org
Status: Available (was: Assigned)
Kouhei@: can someone from loading team helps with debugging navigation failure in this rendering benchmark?

Comment 14 by sadrul@chromium.org, May 15 2018

Cc: dgozman@chromium.org
I should give some more details: it looks like the page did navigate and load correctly (see screenshot in [1]), but the trace [2] clearly shows that the Navigate() step did not complete. So the link between navigation and telemetry/catapult detecting that is somehow broken.

/cc+ dgozman@ from devtools, in case they are aware of related issues.

[1] https://00e9e64bac182029ca5046eda6c5a1e67a7510b981e56e4c98-apidata.googleusercontent.com/download/storage/v1/b/chrome-telemetry-output/o/d9a6740a-582d-11e8-846e-0242ac110003?qk=AD5uMEvcx6qxoy3YsJpWtETJONFbqurPGBqZMrCcN4fAtyoZ8aahHALOEzT8eg02_Oex6mFxhwmsRHqn1RvH8S5Ln4wNKV38eLJ_r4u0iU75Ah4BTDHrCHac6-hCMPPhJO4tSmJS6ZRBXcO5yErNlV6M81NKiLcq9YY1Z9ZU5sLxynX4NAHscgC7GLHqm2gR6RB8rJ8D9zNh1H1x2Ghs_X9qDMbU5XaLer-d7p1TkDkaIXmdqvifrZ3gOrMWON5A9sd9kp7k-RszLo-D-B6jlTc7DuHN5IiErpVDTqfS6Ra8YBejJb2y1cQIcnGg0HLnisAhlYkSoMq4FF_4XKX-65tHxqz6aylFEvd_f0axXLRzXRCBldh3Aki8wUNmKlQ8p6BJ-WMi2_VolSSzsU13DEAcFjTcOyimJ5ufu2uLYvM0DTk-EPfmsnYoHEEXU6Qe4h6ECM-p7cMUt17x6S8hT4fDy5bG12ZF-ripeqHxZF33GiLG70Q3iYIx2rBLhJH7xO9pUBj6fMS6dD5AbH1w4hAuUkdW2xzcsaXdzg9gP44zzrU7jXrI8CMqd2EqO1jtuw5sDx2PGL4IMksNP7TMAvFPqEUrA1gVi92XiacyLhud-_BCWhOqIjyMrYU_Smvih5_nNLTnYsrj3ppFV7lK0nmp5A0IdBodYT88-On9KBNk2Ph4h6TrJWjM3Yv16q7AQdXYAptNwacuMLpVYL9shYqx1M4CyxB7z-b5j0lLsOLvV-CuCQqyAxCft6hOQjldaDVWYN4Klgf8TWof3ki1VkurFZKMPtcNaQ

[2] https://00e9e64baceddf9838e7306db536e65c59f989545442fe894d-apidata.googleusercontent.com/download/storage/v1/b/chrome-telemetry-output/o/youtube_desktop_2018-05-15_09-57-00_98589.html?qk=AD5uMEtS1-ozF0B2uQpCsj7bMeKgTr1PvqU6-t1WwcoZ2XobgMy_6EMzVjRrAXNRGF4chuUTP_uy78aV308LHoDzIGVAwbyvAdKMutn_ABfXi56nYt6HOJCJjV8QsiGvpQldN2mpQ_Vzn3ptw10uWrYUFLAK60crPtPojKVzisBD0_Qe16KzqQv-tA-YAht0aiQDl1cgssarTkseA22DqRlHCWZUzesqgRxUz719SnAgIR1aS75mdOSl-528-arubRba81FFl7qu0J-GL3eqXQ1jGjIFgo4v2hf1yw8j45NWy5hUUc5IUtHQx07AKa6TjJAMbMPzgX3oDLCvOMccfwNdbRgz9tO5c-EJ-OBEq27QpT4vCE-8J9ZEr6nPftdlodCSCRkSbeQZVFnXEzSyAbIXW80tf6yrk61aJUCD5w7-k8zwpATWbYr07vRITM5-qVn8jjZT3x-ZoUfjSphmexDfmT3dhnA3IofFa6g0HcVe3WQCTXjvCQw2n4NF9YqdLAvnTEn8aNfWrHzKN66ST2gLodvSXLJ_XifloF5BvkDNpPPNkayqSO_RJih08HxMDs-HSiNBHyndhty6OTDV869nrjADHi_GslkbeVxKUD7po97PcygLx7oYx9ZfQZ2xj6LsSnPxNLvjPgmkGArdV8GTQlCi_R_-tp2ORpzCDpjpvC_RLXTuiUQK16ECXQrBzhsjDTJmmj8Jiw7OlSHgYIXgsrqwWq7qczDTHlCa5ek_iXRBIho6qcEMLtV3HVxCF527L-yQhen-0QyXjmIQREe49sPnyBBPe9VHSU1emoDO6i4-9ptZmso

Comment 15 by sadrul@chromium.org, May 15 2018 

kouhei@: are there navigation-related traces that I should look for to understand better where the failure is happening?

Comment 16 by kouhei@chromium.org, May 16 2018 

It looks like the traces URL has expired? Is it easy to repro on local Android device?

Comment 17 by sullivan@chromium.org, May 16 2018 

Re #16: I'm really sorry about this, what happens is that for security reasons the google cloud URL redirects to a temporary one when you click the link. So when you copy and paste the url for a trace or screenshot, you need to copy it directly from the logs or the dashboard, not the urlbar. Sadrul, would you mind updating the links? I'd do it for you, but I worry about confusing the discussion if I accidentally pull from the wrong log file.

Comment 19 by sadrul@chromium.org, May 24 2018 

ping: any ideas regarding navigation-related trace-events to look for?

Comment 20 by benhenry@chromium.org, Aug 2

Status: Assigned (was: Available)
►

Sign in to add a comment