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

Issue 655627 link

Starred by 3 users

Issue metadata

Status: Archived
Owner:
Last visit > 30 days ago
Closed: Aug 2017
Cc:
EstimatedDays: ----
NextAction: ----
OS: Android
Pri: 1
Type: Bug-Regression

Blocking:
issue 664590



Sign in to add a comment

system_health.memory_mobile.reference failed blink_style.key_mobile_sites.reference failed memory.blink_memory_mobile failed memory.blink_memory_mobile.reference failed smoothness.tough_webgl_ad_cases failure on chromium.perf on Android Nexus5 Perf (1)

Project Member Reported by eyaich@chromium.org, Oct 13 2016

Issue description

Looks like this test has been failing pretty consistently since the 10th:

Builder Page: 
https://uberchromegw.corp.google.com/i/chromium.perf/builders/Android%20Nexus5%20Perf%20%281%29?numbuilds=200

Link to failing step log:
https://uberchromegw.corp.google.com/i/chromium.perf/builders/Android%20Nexus5%20Perf%20%281%29/builds/4423/steps/system_health.memory_mobile.reference/logs/stdio


Exception: 

Traceback (most recent call last):
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/story_runner.py", line 86, in _RunStoryAndProcessErrorIfNeeded
    state.RunStory(results)
  File "/b/rr/tmpKmNEJq/w/src/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/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/page/shared_page_state.py", line 310, in RunStory
    self._current_page.Run(self)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/page/__init__.py", line 109, in Run
    self.RunPageInteractions(action_runner)
  File "/b/rr/tmpKmNEJq/w/src/tools/perf/page_sets/system_health/system_health_story.py", line 112, in RunPageInteractions
    self._DidLoadDocument(action_runner)
  File "/b/rr/tmpKmNEJq/w/src/tools/perf/page_sets/system_health/browsing_stories.py", line 243, in _DidLoadDocument
    self._NavigateToItem(action_runner, index)
  File "/b/rr/tmpKmNEJq/w/src/tools/perf/page_sets/system_health/browsing_stories.py", line 32, in _NavigateToItem
    self._ClickLink(action_runner, item_selector)
  File "/b/rr/tmpKmNEJq/w/src/tools/perf/page_sets/system_health/browsing_stories.py", line 35, in _ClickLink
    action_runner.WaitForElement(element_function=element_function)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/actions/action_runner.py", line 239, in WaitForElement
    timeout_in_seconds=timeout_in_seconds))
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/actions/action_runner.py", line 48, in _RunAction
    action.RunAction(self._tab)
  File "/b/rr/tmpKmNEJq/w/src/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/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/actions/wait.py", line 22, in RunAction
    wait=True, timeout_in_seconds=self.timeout_in_seconds)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/actions/page_action.py", line 118, in EvaluateCallbackWithElement
    tab.WaitForJavaScriptExpression(code, timeout_in_seconds)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 123, in WaitForJavaScriptExpression
    util.WaitFor(IsJavaScriptExpressionTrue, timeout)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/core/util.py", line 86, in WaitFor
    res = condition()
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 116, in IsJavaScriptExpressionTrue
    return bool(self.EvaluateJavaScript(expr))
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 187, in EvaluateJavaScript
    expr, context_id=None, timeout=timeout)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 215, in EvaluateJavaScriptInContext
    expr, context_id=context_id, timeout=timeout)
  File "/b/rr/tmpKmNEJq/w/src/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/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 37, in inner
    inspector_backend._ConvertExceptionFromInspectorWebsocket(e)
  File "/b/rr/tmpKmNEJq/w/src/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/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 34, in inner
    return func(inspector_backend, *args, **kwargs)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_backend.py", line 208, in EvaluateJavaScript
    return self._runtime.Evaluate(expr, context_id, timeout)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_runtime.py", line 45, in Evaluate
    res = self._inspector_websocket.SyncRequest(request, timeout)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 110, in SyncRequest
    res = self._Receive(timeout)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome_inspector/inspector_websocket.py", line 149, in _Receive
    data = self._socket.recv()
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 596, in recv
    opcode, data = self.recv_data()
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 606, in recv_data
    frame = self.recv_frame()
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 637, in recv_frame
    self._frame_header = self._recv_strict(2)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 746, in _recv_strict
    bytes = self._recv(shortage)
  File "/b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/third_party/websocket-client/websocket.py", line 739, in _recv
    raise WebSocketConnectionClosedException()
DevtoolsTargetCrashException: Devtools target crashed


If the test is disabled, please downgrade to Pri-2.

 

Comment 1 by eyaich@chromium.org, Oct 13 2016

Cc: nednguyen@chromium.org
Owner: jbudorick@chromium.org
Summary: system_health.memory_mobile.reference failed blink_style.key_mobile_sites.reference failed memory.blink_memory_mobile failed memory.blink_memory_mobile.reference failed smoothness.tough_webgl_ad_cases failure on chromium.perf on Android Nexus5 Perf (1) (was: system_health.memory_mobile.reference failure on chromium.perf on Android Nexus5 Perf (1))
John can you take a quick look or reassign as necessary since this seems to be an issue across several different tests and is chrome crashing on android.

This exception is actually happening on 5 separate tests and has been consistently.  The exception above is just telling us it couldn't take to the tab, and the relevant chrome crash is here: 

Found Minidump: True
Stack Trace:
********************************************************************************
	UI dump
	- (no package):
	  - (no id)
	- org.chromium.chrome:
	  - (no id)
	  - android:id/content
	  - org.chromium.chrome:id/action_bar_root
	  - org.chromium.chrome:id/compositor_view_holder
	  - org.chromium.chrome:id/control_container
	  - org.chromium.chrome:id/location_bar
	  - org.chromium.chrome:id/menu_button
	  - org.chromium.chrome:id/menu_button_wrapper
	  - 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['localhost:8000/CICAgMDOrcnRGRB4GNgEKAEyCP_ZBSfwUFsj.swf.webglbeta.html']
	********************************************************************************
	Logcat
	--------- beginning of /dev/log/main
	D/AndroidRuntime(16410): 
	D/AndroidRuntime(16410): >>>>>> AndroidRuntime START com.android.internal.os.RuntimeInit <<<<<<
	D/AndroidRuntime(16410): CheckJNI is OFF
	D/dalvikvm(16410): Trying to load lib libjavacore.so 0x0
	D/dalvikvm(16410): Added shared lib libjavacore.so 0x0
	D/dalvikvm(16410): Trying to load lib libnativehelper.so 0x0
	D/dalvikvm(16410): Added shared lib libnativehelper.so 0x0
	D/dalvikvm(16410): No JNI_OnLoad found in libnativehelper.so 0x0, skipping init
	D/dalvikvm(16410): Note: class Landroid/app/ActivityManagerNative; has 179 unimplemented (abstract) methods
	D/AndroidRuntime(16410): Calling main entry com.android.commands.am.Am
	--------- beginning of /dev/log/system
	I/ActivityManager(  783): START u0 {dat=about:blank flg=0x10000000 cmp=org.chromium.chrome/com.google.android.apps.chrome.Main} from pid 16410
	D/dalvikvm(  783): GC_FOR_ALLOC freed 1424K, 10% free 22404K/24872K, paused 45ms, total 45ms
	I/ActivityManager(  783): Start proc org.chromium.chrome for activity org.chromium.chrome/com.google.android.apps.chrome.Main: pid=16421 uid=10074 gids={50074, 3002, 3001, 3003, 1028, 1015}
	I/SearchController( 1247): #onHotwordDetectorStopped
	I/MicrophoneInputStream( 1247): mic_close
	V/SearchControllerCache( 1247): disposing SearchController
	D/dalvikvm( 1247): threadid=30: thread exiting, not yet detached (count=0)
	D/dalvikvm( 1247): threadid=32: thread exiting, not yet detached (count=0)
	I/dalvikvm(16421): Could not find method android.app.ActivityManager.getAppTasks, referenced from method org.chromium.chrome.browser.multiwindow.MultiWindowUtils.isActivityTaskInRecents
	W/dalvikvm(16421): VFY: unable to resolve virtual method 154: Landroid/app/ActivityManager;.getAppTasks ()Ljava/util/List;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0009
	D/dalvikvm(16421): DexOpt: couldn't find field Landroid/app/ActivityManager$RecentTaskInfo;.baseActivity
	I/dalvikvm(16421): DexOpt: unable to optimize instance field ref 0x0008 at 0x27 in Lorg/chromium/chrome/browser/multiwindow/MultiWindowUtils;.isActivityTaskInRecents
	D/dalvikvm(16421): DexOpt: couldn't find field Landroid/app/ActivityManager$RecentTaskInfo;.baseActivity
	I/dalvikvm(16421): DexOpt: unable to optimize instance field ref 0x0008 at 0x2f in Lorg/chromium/chrome/browser/multiwindow/MultiWindowUtils;.isActivityTaskInRecents
	I/cr_InstantAppsHandler(16421): Not handling with Instant Apps
	I/ActivityManager(  783): START u0 {dat=about:blank flg=0x14000000 cmp=org.chromium.chrome/.browser.ChromeTabbedActivity (has extras)} from pid 16421
	I/dalvikvm(16421): Could not find method org.chromium.chrome.browser.ChromeTabbedActivity.getSystemService, referenced from method org.chromium.chrome.browser.ChromeTabbedActivity.reportNewTabShortcutUsed
	W/dalvikvm(16421): VFY: unable to resolve virtual method 22247: Lorg/chromium/chrome/browser/ChromeTabbedActivity;.getSystemService (Ljava/lang/Class;)Ljava/lang/Object;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x001d
	I/dalvikvm(16421): Could not find method android.app.ActivityManager.getAppTasks, referenced from method org.chromium.chrome.browser.ChromeTabbedActivity.onResumeWithNative
	W/dalvikvm(16421): VFY: unable to resolve virtual method 154: Landroid/app/ActivityManager;.getAppTasks ()Ljava/util/List;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0044
	D/dalvikvm(16421): DexOpt: couldn't find field Landroid/app/ActivityManager$RecentTaskInfo;.baseActivity
	I/dalvikvm(16421): DexOpt: unable to optimize instance field ref 0x0008 at 0x62 in Lorg/chromium/chrome/browser/ChromeTabbedActivity;.onResumeWithNative
	D/dalvikvm(16421): DexOpt: couldn't find field Landroid/app/ActivityManager$RecentTaskInfo;.baseActivity
	I/dalvikvm(16421): DexOpt: unable to optimize instance field ref 0x0008 at 0x6a in Lorg/chromium/chrome/browser/ChromeTabbedActivity;.onResumeWithNative
	D/dalvikvm(16421): GC_CONCURRENT freed 263K, 2% free 16865K/17160K, paused 5ms+2ms, total 29ms
	I/dalvikvm(16421): Could not find method android.view.Display.getPresentationDeadlineNanos, referenced from method org.chromium.chrome.browser.ChromeActivity.initializeCompositor
	W/dalvikvm(16421): VFY: unable to resolve virtual method 12481: Landroid/view/Display;.getPresentationDeadlineNanos ()J
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x00a1
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/app/assist/AssistContent;)
	I/dalvikvm(16421): Could not find method android.app.assist.AssistContent.setWebUri, referenced from method org.chromium.chrome.browser.ChromeActivity.onProvideAssistContent
	W/dalvikvm(16421): VFY: unable to resolve virtual method 435: Landroid/app/assist/AssistContent;.setWebUri (Landroid/net/Uri;)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0021
	I/dalvikvm(16421): Could not find method android.app.ActivityManager.getAppTasks, referenced from method org.chromium.chrome.browser.tabmodel.DocumentModeAssassin.switchToTabbedMode
	W/dalvikvm(16421): VFY: unable to resolve virtual method 154: Landroid/app/ActivityManager;.getAppTasks ()Ljava/util/List;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x002a
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/view/SearchEvent;)
	I/dalvikvm(16421): Could not find method android.view.Window$Callback.onSearchRequested, referenced from method android.support.v7.view.n.onSearchRequested
	W/dalvikvm(16421): VFY: unable to resolve interface method 13150: Landroid/view/Window$Callback;.onSearchRequested (Landroid/view/SearchEvent;)Z
	D/dalvikvm(16421): VFY: replacing opcode 0x72 at 0x0002
	I/dalvikvm(16421): Could not find method android.view.Window$Callback.onWindowStartingActionMode, referenced from method android.support.v7.view.n.onWindowStartingActionMode
	W/dalvikvm(16421): VFY: unable to resolve interface method 13154: Landroid/view/Window$Callback;.onWindowStartingActionMode (Landroid/view/ActionMode$Callback;I)Landroid/view/ActionMode;
	D/dalvikvm(16421): VFY: replacing opcode 0x72 at 0x0002
	I/dalvikvm(16421): Could not find method android.support.graphics.drawable.AnimatedVectorDrawableCompat.createFromXmlInner, referenced from method android.support.v7.widget.P.a
	W/dalvikvm(16421): VFY: unable to resolve static method 3329: Landroid/support/graphics/drawable/AnimatedVectorDrawableCompat;.createFromXmlInner (Landroid/content/Context;Landroid/content/res/Resources;Lorg/xmlpull/v1/XmlPullParser;Landroid/util/AttributeSet;Landroid/content/res/Resources$Theme;)Landroid/support/graphics/drawable/AnimatedVectorDrawableCompat;
	D/dalvikvm(16421): VFY: replacing opcode 0x71 at 0x0004
	I/dalvikvm(16421): Could not find method android.content.res.Resources.getDrawable, referenced from method android.support.b.a.g.a
	W/dalvikvm(16421): VFY: unable to resolve virtual method 806: Landroid/content/res/Resources;.getDrawable (ILandroid/content/res/Resources$Theme;)Landroid/graphics/drawable/Drawable;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0012
	I/dalvikvm(16421): Could not find method android.content.Context.getDrawable, referenced from method android.support.v4.b.a.a
	W/dalvikvm(16421): VFY: unable to resolve virtual method 572: Landroid/content/Context;.getDrawable (I)Landroid/graphics/drawable/Drawable;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	I/dalvikvm(16421): Could not find method android.content.Context.getNoBackupFilesDir, referenced from method android.support.v4.b.a.a
	W/dalvikvm(16421): VFY: unable to resolve virtual method 578: Landroid/content/Context;.getNoBackupFilesDir ()Ljava/io/File;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	I/dalvikvm(16421): Could not find method android.content.Context.getColorStateList, referenced from method android.support.v4.b.a.b
	W/dalvikvm(16421): VFY: unable to resolve virtual method 568: Landroid/content/Context;.getColorStateList (I)Landroid/content/res/ColorStateList;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	I/dalvikvm(16421): Could not find method android.content.Context.getColor, referenced from method android.support.v4.b.a.c
	W/dalvikvm(16421): VFY: unable to resolve virtual method 567: Landroid/content/Context;.getColor (I)I
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	D/dalvikvm(16421): GC_CONCURRENT freed 142K, 2% free 17135K/17388K, paused 3ms+2ms, total 19ms
	I/dalvikvm(16421): Could not find method android.app.Activity.finishAndRemoveTask, referenced from method org.chromium.base.ApiCompatibilityUtils.finishAndRemoveTask
	W/dalvikvm(16421): VFY: unable to resolve virtual method 96: Landroid/app/Activity;.finishAndRemoveTask ()V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	I/dalvikvm(16421): Could not find method android.content.res.Resources.getColor, referenced from method org.chromium.base.ApiCompatibilityUtils.getColor
	W/dalvikvm(16421): VFY: unable to resolve virtual method 797: Landroid/content/res/Resources;.getColor (ILandroid/content/res/Resources$Theme;)I
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0007
	I/dalvikvm(16421): Could not find method android.content.res.Resources.getColorStateList, referenced from method org.chromium.base.ApiCompatibilityUtils.getColorStateList
	W/dalvikvm(16421): VFY: unable to resolve virtual method 799: Landroid/content/res/Resources;.getColorStateList (ILandroid/content/res/Resources$Theme;)Landroid/content/res/ColorStateList;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0007
	I/dalvikvm(16421): Could not find method android.content.res.Resources.getDrawable, referenced from method org.chromium.base.ApiCompatibilityUtils.getDrawable
	W/dalvikvm(16421): VFY: unable to resolve virtual method 806: Landroid/content/res/Resources;.getDrawable (ILandroid/content/res/Resources$Theme;)Landroid/graphics/drawable/Drawable;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0007
	I/dalvikvm(16421): Could not find method android.content.res.Resources.getDrawableForDensity, referenced from method org.chromium.base.ApiCompatibilityUtils.getDrawableForDensity
	W/dalvikvm(16421): VFY: unable to resolve virtual method 808: Landroid/content/res/Resources;.getDrawableForDensity (IILandroid/content/res/Resources$Theme;)Landroid/graphics/drawable/Drawable;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0007
	I/dalvikvm(16421): Could not find method android.content.pm.PackageManager.getUserBadgedDrawableForDensity, referenced from method org.chromium.base.ApiCompatibilityUtils.getUserBadgedDrawableForDensity
	W/dalvikvm(16421): VFY: unable to resolve virtual method 753: Landroid/content/pm/PackageManager;.getUserBadgedDrawableForDensity (Landroid/graphics/drawable/Drawable;Landroid/os/UserHandle;Landroid/graphics/Rect;I)Landroid/graphics/drawable/Drawable;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x000e
	I/dalvikvm(16421): Could not find method android.os.PowerManager.isInteractive, referenced from method org.chromium.base.ApiCompatibilityUtils.isInteractive
	W/dalvikvm(16421): VFY: unable to resolve virtual method 2082: Landroid/os/PowerManager;.isInteractive ()Z
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x000f
	I/dalvikvm(16421): Could not find method android.view.Window.getNavigationBarColor, referenced from method org.chromium.base.ApiCompatibilityUtils.setStatusBarColor
	W/dalvikvm(16421): VFY: unable to resolve virtual method 13162: Landroid/view/Window;.getNavigationBarColor ()I
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x000c
	I/dalvikvm(16421): Could not find method android.view.Window.setStatusBarColor, referenced from method org.chromium.base.ApiCompatibilityUtils.setStatusBarColor
	W/dalvikvm(16421): VFY: unable to resolve virtual method 13175: Landroid/view/Window;.setStatusBarColor (I)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0015
	E/dalvikvm(16421): Could not find class 'android.app.ActivityManager$TaskDescription', referenced from method org.chromium.base.ApiCompatibilityUtils.setTaskDescription
	W/dalvikvm(16421): VFY: unable to resolve new-instance 36 (Landroid/app/ActivityManager$TaskDescription;) in Lorg/chromium/base/ApiCompatibilityUtils;
	D/dalvikvm(16421): VFY: replacing opcode 0x22 at 0x0018
	I/dalvikvm(16421): Could not find method android.widget.TextView.setTextAppearance, referenced from method org.chromium.base.ApiCompatibilityUtils.setTextAppearance
	W/dalvikvm(16421): VFY: unable to resolve virtual method 14299: Landroid/widget/TextView;.setTextAppearance (I)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	D/dalvikvm(16421): DexOpt: unable to opt direct call 0x0098 at 0x1a in Lorg/chromium/base/ApiCompatibilityUtils;.setTaskDescription
	I/dalvikvm(16421): Could not find method android.app.Activity.shouldShowRequestPermissionRationale, referenced from method org.chromium.ui.base.ActivityWindowAndroid$ActivityAndroidPermissionDelegate.canRequestPermission
	W/dalvikvm(16421): VFY: unable to resolve virtual method 141: Landroid/app/Activity;.shouldShowRequestPermissionRationale (Ljava/lang/String;)Z
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0022
	I/dalvikvm(16421): Could not find method android.content.pm.PackageManager.isPermissionRevokedByPolicy, referenced from method org.chromium.ui.base.ActivityWindowAndroid$ActivityAndroidPermissionDelegate.isPermissionRevokedByPolicy
	W/dalvikvm(16421): VFY: unable to resolve virtual method 755: Landroid/content/pm/PackageManager;.isPermissionRevokedByPolicy (Ljava/lang/String;Ljava/lang/String;)Z
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0021
	I/dalvikvm(16421): Could not find method android.app.Activity.requestPermissions, referenced from method org.chromium.ui.base.ActivityWindowAndroid$ActivityAndroidPermissionDelegate.requestPermissions
	W/dalvikvm(16421): VFY: unable to resolve virtual method 133: Landroid/app/Activity;.requestPermissions ([Ljava/lang/String;I)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x002a
	I/dalvikvm(16421): Could not find method android.content.res.Resources.getDrawable, referenced from method android.support.v7.widget.bX.getDrawable
	W/dalvikvm(16421): VFY: unable to resolve virtual method 806: Landroid/content/res/Resources;.getDrawable (ILandroid/content/res/Resources$Theme;)Landroid/graphics/drawable/Drawable;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0002
	I/dalvikvm(16421): Could not find method android.content.res.Resources.getDrawableForDensity, referenced from method android.support.v7.widget.bX.getDrawableForDensity
	W/dalvikvm(16421): VFY: unable to resolve virtual method 808: Landroid/content/res/Resources;.getDrawableForDensity (IILandroid/content/res/Resources$Theme;)Landroid/graphics/drawable/Drawable;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0002
	D/dalvikvm(16421): GC_CONCURRENT freed 168K, 2% free 17435K/17640K, paused 2ms+2ms, total 20ms
	E/dalvikvm(16421): Could not find class 'android.graphics.drawable.RippleDrawable', referenced from method android.support.v7.widget.V.a
	W/dalvikvm(16421): VFY: unable to resolve instanceof 235 (Landroid/graphics/drawable/RippleDrawable;) in Landroid/support/v7/widget/V;
	D/dalvikvm(16421): VFY: replacing opcode 0x20 at 0x000c
	I/dalvikvm(16421): Could not find method android.content.Context.getColorStateList, referenced from method android.support.v7.b.a.b.a
	W/dalvikvm(16421): VFY: unable to resolve virtual method 568: Landroid/content/Context;.getColorStateList (I)Landroid/content/res/ColorStateList;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	I/cr_LibraryLoader(16421): Using linker: org.chromium.base.library_loader.LegacyLinker
	D/dalvikvm(16421): Trying to load lib /data/app-lib/org.chromium.chrome-1/libchromium_android_linker.so 0x425d6278
	D/dalvikvm(16421): Added shared lib /data/app-lib/org.chromium.chrome-1/libchromium_android_linker.so 0x425d6278
	W/cr_tabmodel(16421): Starting to perform legacy migration.
	W/cr_tabmodel(16421): Finished performing legacy migration.
	W/cr_tabmodel(16421): Starting to perform multi-instance migration.
	W/cr_tabmodel(16421): Finished performing multi-instance migration.
	I/cr_LibraryLoader(16421): Loading chrome
	W/cr_tabmodel(16421): Starting to fetch tab list.
	E/cr_tabmodel(16421): State file does not exist.
	I/cr_base (16421): Extracting resource /data/data/org.chromium.chrome/app_chrome/paks/en-GB.pak
	D/dalvikvm(16421): GC_CONCURRENT freed 420K, 3% free 17427K/17880K, paused 4ms+3ms, total 26ms
	I/cr_base (16421): Extracting resource /data/data/org.chromium.chrome/app_chrome/paks/en-US.pak
	I/Adreno-EGL(16421): <qeglDrvAPI_eglInitialize:320>: EGL 1.4 QUALCOMM Build: I0404c4692afb8623f95c43aeb6d5e13ed4b30ddbDate: 11/06/13
	D/OpenGLRenderer(16421): Enabling debug mode 0
	I/cr_LibraryLoader(16421): Time to load native libraries: 166 ms (timestamps 1061-1227)
	I/cr_LibraryLoader(16421): Expected native library version number "56.0.2890.0", actual native library version number "56.0.2890.0"
	I/chromium(16421): [INFO:library_loader_hooks.cc(163)] Chromium logging enabled: level = 0, default verbosity = 0
	W/cr_ChildProcLauncher(16421): Create a new ChildConnectionAllocator with package name = org.chromium.chrome, inSandbox = true
	I/ActivityManager(  783): Start proc org.chromium.chrome:sandboxed_process0 for service org.chromium.chrome/org.chromium.content.app.SandboxedProcessService0: pid=16449 uid=99028 gids={}
	I/cr_ChildProcessService(16449): Creating new ChildProcessService pid=16449
	I/ActivityManager(  783): Displayed org.chromium.chrome/.browser.ChromeTabbedActivity: +587ms (total +687ms)
	D/AndroidRuntime(16410): Shutting down VM
	D/dalvikvm(16410): GC_CONCURRENT freed 97K, 15% free 590K/692K, paused 1ms+0ms, total 3ms
	I/cr_BrowserStartup(16421): Initializing chromium process, singleProcess=false
	W/chromium(16421): [WARNING:dns_config_service_posix.cc(316)] Failed to read DnsConfig.
	I/ActivityManager(  783): Start proc org.chromium.chrome:privileged_process0 for service org.chromium.chrome/org.chromium.content.app.PrivilegedProcessService0: pid=16479 uid=10074 gids={50074, 3002, 3001, 3003, 1028, 1015}
	I/dalvikvm(16421): Could not find method android.content.pm.PackageManager.getPackageInstaller, referenced from method com.google.android.gms.common.s.a
	W/dalvikvm(16421): VFY: unable to resolve virtual method 746: Landroid/content/pm/PackageManager;.getPackageInstaller ()Landroid/content/pm/PackageInstaller;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x000e
	W/GooglePlayServicesUtil(16421): Google Play services out of date.  Requires 8487000 but found 4033538
	I/cr_ChildProcessService(16479): Creating new ChildProcessService pid=16479
	I/cr_LibraryLoader(16479): Using linker: org.chromium.base.library_loader.LegacyLinker
	D/dalvikvm(16479): Trying to load lib /data/app-lib/org.chromium.chrome-1/libchromium_android_linker.so 0x425d6280
	D/dalvikvm(16479): Added shared lib /data/app-lib/org.chromium.chrome-1/libchromium_android_linker.so 0x425d6280
	W/GooglePlayServicesUtil(16421): Google Play services out of date.  Requires 8487000 but found 4033538
	I/cr_SnippetsLauncher(16421): Disabling SnippetsLauncher because Play Services is not up to date.
	I/cr_LibraryLoader(16479): Loading chrome
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	E/dalvikvm(16421): Could not find class 'org.chromium.net.NetworkChangeNotifierAutoDetect$MyNetworkCallback', referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect.<init>
	W/dalvikvm(16421): VFY: unable to resolve new-instance 6822 (Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;) in Lorg/chromium/net/NetworkChangeNotifierAutoDetect;
	D/dalvikvm(16421): VFY: replacing opcode 0x22 at 0x0024
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature ([Landroid/net/Network;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature ([Landroid/net/Network;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	I/dalvikvm(16421): Could not find method android.net.ConnectivityManager.getAllNetworks, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect.getAllNetworksFiltered
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1767: Landroid/net/ConnectivityManager;.getAllNetworks ()[Landroid/net/Network;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0003
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	I/dalvikvm(16421): Could not find method android.net.Network.getNetworkHandle, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect.networkToNetId
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1778: Landroid/net/Network;.getNetworkHandle ()J
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0006
	I/dalvikvm(16421): Could not find method android.net.Network.toString, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect.networkToNetId
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1780: Landroid/net/Network;.toString ()Ljava/lang/String;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x000b
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	I/dalvikvm(16421): Could not find method org.chromium.net.NetworkChangeNotifierAutoDetect$MyNetworkCallback.initializeVpnInPlace, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect.register
	W/dalvikvm(16421): VFY: unable to resolve virtual method 42759: Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;.initializeVpnInPlace ()V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x002d
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	I/dalvikvm(16421): Could not find method android.net.ConnectivityManager.unregisterNetworkCallback, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect.unregister
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1773: Landroid/net/ConnectivityManager;.unregisterNetworkCallback (Landroid/net/ConnectivityManager$NetworkCallback;)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0017
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback; (372)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/net/NetworkChangeNotifierAutoDetect$MyNetworkCallback;' failed
	D/dalvikvm(16421): DexOpt: unable to opt direct call 0xa704 at 0x26 in Lorg/chromium/net/NetworkChangeNotifierAutoDetect;.<init>
	D/dalvikvm(16421): GC_CONCURRENT freed 448K, 3% free 17491K/17972K, paused 3ms+8ms, total 29ms
	D/dalvikvm(16421): WAIT_FOR_CONCURRENT_GC blocked 10ms
	D/dalvikvm(16421): DexOpt: unable to opt direct call 0x06fe at 0x2d in Lorg/chromium/net/NetworkChangeNotifierAutoDetect;.<init>
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	I/dalvikvm(16421): Could not find method android.net.Network.getSocketFactory, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect$ConnectivityManagerDelegate.vpnAccessible
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1779: Landroid/net/Network;.getSocketFactory ()Ljavax/net/SocketFactory;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0000
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	I/dalvikvm(16421): Could not find method android.net.ConnectivityManager.getNetworkCapabilities, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect$ConnectivityManagerDelegate.getNetworkCapabilities
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1768: Landroid/net/ConnectivityManager;.getNetworkCapabilities (Landroid/net/Network;)Landroid/net/NetworkCapabilities;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0002
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/net/Network;)
	I/dalvikvm(16421): Could not find method android.net.ConnectivityManager.getNetworkInfo, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect$ConnectivityManagerDelegate.getNetworkInfo
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1770: Landroid/net/ConnectivityManager;.getNetworkInfo (Landroid/net/Network;)Landroid/net/NetworkInfo;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0003
	I/dalvikvm(16421): Could not find method android.net.ConnectivityManager.getNetworkInfo, referenced from method org.chromium.net.NetworkChangeNotifierAutoDetect$ConnectivityManagerDelegate.getNetworkInfo
	W/dalvikvm(16421): VFY: unable to resolve virtual method 1770: Landroid/net/ConnectivityManager;.getNetworkInfo (Landroid/net/Network;)Landroid/net/NetworkInfo;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0018
	W/GooglePlayServicesUtil(16421): Google Play services out of date.  Requires 8487000 but found 4033538
	I/cr_BgSyncLauncher(16421): Disabling Background Sync because Play Services is not up to date.
	I/cr_LibraryLoader(16479): Time to load native libraries: 126 ms (timestamps 1642-1768)
	I/cr_LibraryLoader(16479): Expected native library version number "56.0.2890.0", actual native library version number "56.0.2890.0"
	I/chromium(16479): [INFO:library_loader_hooks.cc(163)] Chromium logging enabled: level = 0, default verbosity = 0
	E/libEGL  (16479): validate_display:254 error 3008 (EGL_BAD_DISPLAY)
	I/Adreno-EGL(16479): <qeglDrvAPI_eglInitialize:320>: EGL 1.4 QUALCOMM Build: I0404c4692afb8623f95c43aeb6d5e13ed4b30ddbDate: 11/06/13
	D/dalvikvm(16421): GC_CONCURRENT freed 328K, 3% free 17605K/17992K, paused 3ms+6ms, total 29ms
	I/dalvikvm(16479): Could not find method android.media.MediaCodecList.<init>, referenced from method org.chromium.media.MediaCodecUtil$MediaCodecListHelper.<init>
	W/dalvikvm(16479): VFY: unable to resolve direct method 1539: Landroid/media/MediaCodecList;.<init> (I)V
	D/dalvikvm(16479): VFY: replacing opcode 0x70 at 0x000c
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/view/ViewStructure;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/view/ViewStructure;)
	W/dalvikvm(16421): VFY: unable to find class referenced in signature (Landroid/view/ViewStructure;)
	I/dalvikvm(16421): Could not find method android.view.ViewStructure.setClassName, referenced from method org.chromium.content.browser.ContentViewCore.createVirtualStructure
	W/dalvikvm(16421): VFY: unable to resolve virtual method 13112: Landroid/view/ViewStructure;.setClassName (Ljava/lang/String;)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0004
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/content/browser/FloatingWebActionModeCallback; (1946)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/content/browser/FloatingWebActionModeCallback;' failed
	E/dalvikvm(16421): Could not find class 'org.chromium.content.browser.FloatingWebActionModeCallback', referenced from method org.chromium.content.browser.ContentViewCore.showSelectActionMode
	W/dalvikvm(16421): VFY: unable to resolve new-instance 6333 (Lorg/chromium/content/browser/FloatingWebActionModeCallback;) in Lorg/chromium/content/browser/ContentViewCore;
	D/dalvikvm(16421): VFY: replacing opcode 0x22 at 0x0043
	E/dalvikvm(16421): Could not find class 'android.view.ActionMode$Callback2', referenced from method org.chromium.content.browser.ContentViewCore.showSelectActionMode
	W/dalvikvm(16421): VFY: unable to resolve check-cast 1946 (Landroid/view/ActionMode$Callback2;) in Lorg/chromium/content/browser/ContentViewCore;
	D/dalvikvm(16421): VFY: replacing opcode 0x1f at 0x0062
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/content/browser/FloatingWebActionModeCallback; (1946)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/content/browser/FloatingWebActionModeCallback;' failed
	D/dalvikvm(16421): DexOpt: unable to opt direct call 0x9bcb at 0x45 in Lorg/chromium/content/browser/ContentViewCore;.showSelectActionMode
	W/dalvikvm(16421): Unable to resolve superclass of Lorg/chromium/content/browser/input/LGEmailActionModeWorkaround$1; (1946)
	W/dalvikvm(16421): Link of class 'Lorg/chromium/content/browser/input/LGEmailActionModeWorkaround$1;' failed
	D/dalvikvm(16421): DexOpt: unable to opt direct call 0x9e33 at 0x69 in Lorg/chromium/content/browser/ContentViewCore;.showSelectActionMode
	I/dalvikvm(16421): Could not find method android.view.accessibility.CaptioningManager$CaptionStyle.hasBackgroundColor, referenced from method org.chromium.content.browser.accessibility.captioning.KitKatCaptioningBridge.getCaptioningStyleFrom
	W/dalvikvm(16421): VFY: unable to resolve virtual method 13318: Landroid/view/accessibility/CaptioningManager$CaptionStyle;.hasBackgroundColor ()Z
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0013
	D/dalvikvm(16421): DexOpt: couldn't find field Landroid/view/accessibility/CaptioningManager$CaptionStyle;.windowColor
	I/dalvikvm(16421): DexOpt: unable to optimize instance field ref 0x1265 at 0x49 in Lorg/chromium/content/browser/accessibility/captioning/KitKatCaptioningBridge;.getCaptioningStyleFrom
	I/dalvikvm(16421): Could not find method android.view.ActionMode.onWindowFocusChanged, referenced from method org.chromium.content.browser.ContentView.onWindowFocusChanged
	W/dalvikvm(16421): VFY: unable to resolve virtual method 12452: Landroid/view/ActionMode;.onWindowFocusChanged (Z)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x002d
	I/dalvikvm(16421): Could not find method org.chromium.third_party.android.swiperefresh.CircleImageView.setElevation, referenced from method org.chromium.third_party.android.swiperefresh.CircleImageView.<init>
	W/dalvikvm(16421): VFY: unable to resolve virtual method 43336: Lorg/chromium/third_party/android/swiperefresh/CircleImageView;.setElevation (F)V
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x002a
	D/BluetoothManagerService(  783): Message: 20
	D/BluetoothManagerService(  783): Added callback: android.bluetooth.IBluetoothManagerCallback$Stub$Proxy@42a2fd68:true
	D/BluetoothAdapter(16421): 1114704616: getState() :  mService = null. Returning STATE_OFF
	I/cr_LibraryLoader(16449): Using linker: org.chromium.base.library_loader.LegacyLinker
	D/dalvikvm(16449): Trying to load lib /data/app-lib/org.chromium.chrome-1/libchromium_android_linker.so 0x425d4a00
	D/dalvikvm(16449): Added shared lib /data/app-lib/org.chromium.chrome-1/libchromium_android_linker.so 0x425d4a00
	I/cr_LibraryLoader(16449): Loading chrome
	D/dalvikvm(16421): GC_CONCURRENT freed 413K, 3% free 17671K/18120K, paused 3ms+7ms, total 35ms
	I/cr_LibraryLoader(16449): Time to load native libraries: 105 ms (timestamps 2016-2121)
	I/cr_LibraryLoader(16449): Expected native library version number "56.0.2890.0", actual native library version number "56.0.2890.0"
	I/chromium(16449): [INFO:library_loader_hooks.cc(163)] Chromium logging enabled: level = 0, default verbosity = 0
	I/dalvikvm(16421): Could not find method android.app.ActivityManager.getAppTasks, referenced from method org.chromium.chrome.browser.download.DownloadNotificationService.onTaskRemoved
	W/dalvikvm(16421): VFY: unable to resolve virtual method 154: Landroid/app/ActivityManager;.getAppTasks ()Ljava/util/List;
	D/dalvikvm(16421): VFY: replacing opcode 0x6e at 0x0011
	D/dalvikvm(16421): GC_FOR_ALLOC freed 263K, 3% free 17752K/18204K, paused 16ms, total 16ms
	D/dalvikvm(16421): GC_FOR_ALLOC freed 59K, 3% free 17750K/18204K, paused 17ms, total 17ms
	I/dalvikvm-heap(16421): Grow heap (frag case) to 18.180MB for 855376-byte allocation
	D/dalvikvm(16421): GC_FOR_ALLOC freed <1K, 3% free 18585K/19040K, paused 17ms, total 17ms
	D/dalvikvm(16421): GC_CONCURRENT freed 12K, 3% free 18588K/19040K, paused 3ms+3ms, total 22ms
	I/cr_Ime  (16421): ImeThread is enabled.
	I/cr_MinidmpUploadService(16421): Attempting to upload accumulated crash dumps.
	I/cr_BindingManager(16421): Moderate binding enabled: maxSize=20
	D/dalvikvm(  783): GC_CONCURRENT freed 212K, 6% free 23523K/24872K, paused 5ms+4ms, total 55ms
	W/GooglePlayServicesUtil(16421): Google Play services out of date.  Requires 8487000 but found 4033538
	I/cr_PhysicalWeb(16421): Nearby connection failed: ConnectionResult{statusCode=SERVICE_VERSION_UPDATE_REQUIRED, resolution=null, message=null}
	D/dalvikvm(16421): GC_CONCURRENT freed 539K, 3% free 18643K/19216K, paused 2ms+3ms, total 25ms
	W/GooglePlayServicesUtil(16421): Google Play services out of date.  Requires 8487000 but found 4033538
	W/GooglePlayServicesUtil(16421): Google Play services out of date.  Requires 8487000 but found 4033538
	E/cr_SafeBrowsingApi(16421): Failed to init handler: null
	D/dalvikvm(16421): GC_CONCURRENT freed 525K, 3% free 18654K/19216K, paused 2ms+1ms, total 21ms
	I/chromium(16449): [INFO:SkFontMgr_android_parser.cpp(608)] [SkFontMgr Android Parser] '/system/etc/fonts.xml' could not be opened
	I/chromium(16449): 
	I/chromium(16449): [INFO:SkFontMgr_android_parser.cpp(608)] [SkFontMgr Android Parser] '/vendor/etc/fallback_fonts.xml' could not be opened
	I/chromium(16449): 
	D/dalvikvm(16421): GC_CONCURRENT freed 535K, 3% free 18655K/19224K, paused 2ms+2ms, total 19ms
	D/dalvikvm(16421): GC_CONCURRENT freed 539K, 3% free 18652K/19228K, paused 3ms+2ms, total 23ms
	D/dalvikvm(16421): GC_CONCURRENT freed 532K, 3% free 18655K/19228K, paused 1ms+2ms, total 19ms
	D/dalvikvm(16421): GC_CONCURRENT freed 553K, 4% free 18654K/19244K, paused 1ms+2ms, total 32ms
	D/dalvikvm(16421): GC_CONCURRENT freed 535K, 4% free 18653K/19244K, paused 3ms+2ms, total 22ms
	D/dalvikvm(16421): GC_CONCURRENT freed 541K, 4% free 18656K/19244K, paused 2ms+2ms, total 33ms
	D/dalvikvm(16421): GC_CONCURRENT freed 537K, 4% free 18658K/19244K, paused 3ms+2ms, total 21ms
	D/dalvikvm(16421): GC_CONCURRENT freed 541K, 4% free 18656K/19244K, paused 1ms+1ms, total 19ms
	D/AndroidRuntime(16581): 
	D/AndroidRuntime(16581): >>>>>> AndroidRuntime START com.android.internal.os.RuntimeInit <<<<<<
	D/AndroidRuntime(16581): CheckJNI is OFF
	D/dalvikvm(16581): Trying to load lib libjavacore.so 0x0
	D/dalvikvm(16581): Added shared lib libjavacore.so 0x0
	D/dalvikvm(16581): Trying to load lib libnativehelper.so 0x0
	D/dalvikvm(16581): Added shared lib libnativehelper.so 0x0
	D/dalvikvm(16581): No JNI_OnLoad found in libnativehelper.so 0x0, skipping init
	D/dalvikvm(16581): Note: class Landroid/app/ActivityManagerNative; has 179 unimplemented (abstract) methods
	D/AndroidRuntime(16581): Calling main entry com.android.commands.uiautomator.Launcher
	D/dalvikvm(16421): GC_CONCURRENT freed 536K, 4% free 18657K/19244K, paused 2ms+2ms, total 19ms
	I/AccessibilityNodeInfoDumper(16581): Skipping invisible child: android.view.accessibility.AccessibilityNodeInfo@a14d; boundsInParent: Rect(0, 0 - 1, 6); boundsInScreen: Rect(1, 237 - 1, 243); packageName: org.chromium.chrome; className: android.widget.ImageView; text: null; contentDescription: null; viewIdResName: null; checkable: false; checked: false; focusable: false; focused: false; selected: false; clickable: false; longClickable: false; enabled: true; password: false; scrollable: false; [ACTION_SELECT, ACTION_CLEAR_SELECTION, ACTION_ACCESSIBILITY_FOCUS]
	I/AccessibilityNodeInfoDumper(16581): Skipping invisible child: android.view.accessibility.AccessibilityNodeInfo@960a; boundsInParent: Rect(0, 0 - 0, 0); boundsInScreen: Rect(0, 1776 - 0, 1776); packageName: org.chromium.chrome; className: android.view.View; text: null; contentDescription: null; viewIdResName: org.chromium.chrome:id/menu_anchor_stub; checkable: false; checked: false; focusable: false; focused: false; selected: false; clickable: false; longClickable: false; enabled: true; password: false; scrollable: false; [ACTION_SELECT, ACTION_CLEAR_SELECTION, ACTION_ACCESSIBILITY_FOCUS]
	W/AccessibilityNodeInfoDumper(16581): Fetch time: 73ms
	D/AndroidRuntime(16581): Shutting down VM
	D/dalvikvm(16581): GC_CONCURRENT freed 254K, 29% free 646K/904K, paused 0ms+0ms, total 4ms
	********************************************************************************
	Stack from Logcat
	Reading native crash info from stdin
	Unknown Android release, consider --[no-]packed-relocation-adjustments options
	Reading Android symbols from: /b/rr/tmpKmNEJq/w/src
	Searching for Chrome symbols from within: /b/rr/tmpKmNEJq/w/src/out/Release/lib.unstripped:/b/rr/tmpKmNEJq/w/src/out/Release/lib:/b/rr/tmpKmNEJq/w/src/out/Release
	
	********************************************************************************
	Tombstones
	I    0.039s TimeoutThread-1-for-MainThread  [host]> /b/rr/tmpKmNEJq/w/src/third_party/android_tools/sdk/platform-tools/adb -s 0d890b5f25995524 shell '( ( c=/data/local/tmp/cache_token;echo $EXTERNAL_STORAGE;cat $c 2>/dev/null||echo;echo "a521c0dc-912c-11e6-a461-d4ae52d2e91c">$c &&getprop )>/data/local/tmp/temp_file-4d42dc508faf6 );echo %$?'
	I    0.136s TimeoutThread-1-for-MainThread  [host]> /b/rr/tmpKmNEJq/w/src/third_party/android_tools/sdk/platform-tools/adb -s 0d890b5f25995524 pull /data/local/tmp/temp_file-4d42dc508faf6 /tmp/tmpN6LoCD/tmp_ReadFileWithPull
	I    0.297s TimeoutThread-1-for-MainThread  [host]> /b/rr/tmpKmNEJq/w/src/third_party/android_tools/sdk/platform-tools/adb -s 0d890b5f25995524 shell '( su -c ls /root && ! ls /root );echo %$?'
	I    0.298s TimeoutThread-1-for-delete_temporary_file(0d890b5f25995524)  [host]> /b/rr/tmpKmNEJq/w/src/third_party/android_tools/sdk/platform-tools/adb -s 0d890b5f25995524 shell 'rm -f /data/local/tmp/temp_file-4d42dc508faf6'
	I    0.391s TimeoutThread-1-for-MainThread  [host]> /b/rr/tmpKmNEJq/w/src/third_party/android_tools/sdk/platform-tools/adb -s 0d890b5f25995524 shell '( test -e /data/tombstones );echo %$?'
	I    0.504s TimeoutThread-1-for-MainThread  [host]> /b/rr/tmpKmNEJq/w/src/third_party/android_tools/sdk/platform-tools/adb -s 0d890b5f25995524 shell '( TZ=utc ls -a -l /data/tombstones/ );echo %$?'
	W    0.604s Main  No tombstones.
	W    0.604s Main  No tombstones to resolve.
	
	********************************************************************************
********************************************************************************
Standard output:
********************************************************************************
	Cannot get standard output on Android
********************************************************************************
System log:
(Not implemented)

INFO:root:Chrome version: None
INFO:root:Try printing formatted exception: None None None

Exception raised when cleaning story run: 

Traceback (most recent call last):
  _RunStoryAndProcessErrorIfNeeded at /b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/story_runner.py:110
    test.DidRunPage(state.platform)
  traced_function at /b/rr/tmpKmNEJq/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:75
    return func(*args, **kwargs)
  DidRunPage at /b/rr/tmpKmNEJq/w/src/tools/perf/measurements/smoothness.py:65
    self._tbm.DidRunStory(platform)
  DidRunStory at /b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/web_perf/timeline_based_measurement.py:309
    platform.tracing_controller.StopTracing()
  StopTracing at /b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/core/tracing_controller.py:47
    return self._tracing_controller_backend.StopTracing()
  StopTracing at /b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/platform/tracing_controller_backend.py:108
    self._IssueClockSyncMarker()
  _IssueClockSyncMarker at /b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/platform/tracing_controller_backend.py:203
    self._RecordIssuerClockSyncMarker)
  RecordClockSyncMarker at /b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/platform/tracing_agent/chrome_tracing_agent.py:193
    sync_id, record_controller_clock_sync_marker_callback)
  _RecordClockSyncMarkerAsyncEvent at /b/rr/tmpKmNEJq/w/src/third_party/catapult/telemetry/telemetry/internal/platform/tracing_agent/chrome_tracing_agent.py:170
    'Failed to issue clock sync to devtools client')
ChromeClockSyncError: Failed to issue clock sync to devtools client

Locals:

Comment 3 by eyaich@chromium.org, Oct 13 2016

Seems to be a similar set failing on Android Nexus7v2 Perf (1) all with chrome crashing


1) system_health.memory_mobile.reference 
2) blink_style.key_mobile_sites.reference 
3) v8.browsing_mobile_ignition 
4) memory.blink_memory_mobile 
5) thread_times.tough_scrolling_cases


Link to builder status page: 
https://uberchromegw.corp.google.com/i/chromium.perf/builders/Android%20Nexus7v2%20Perf%20%281%29?numbuilds=200\

Comment 4 Deleted

Comment 5 by eyaich@chromium.org, Oct 13 2016

Android Nexus7v2 (2) also looks like similar failures.

All but one android bot is failing on the perf waterfall today.  Not sure if they are all related but we are seeing a couple of errors consistently pop up.

Comment 6 by eyaich@chromium.org, Oct 14 2016

Cc: rnep...@chromium.org
Owner: rnep...@chromium.org
Project Member

Comment 8 by 42576172...@developer.gserviceaccount.com, Oct 15 2016

Mergedinto: 655260
Status: Duplicate (was: Assigned)

===== BISECT JOB RESULTS =====
Status: completed


===== SUSPECTED CL(s) =====
Subject : Wait a frame before finishing navigation
Author  : bokan
Commit description:
  
** PERF SHERIFF: this CL may cause a small regression to
telemetry benchmark but it's expected ***

When running a telemetry benchmark, we don't want to start
interaction gestures until the page is fully interactive. Currently,
the NavigationAction waits until document.readyState is at least
interactive. This isn't enough, since it only indicates that Blink
is ready; the page's layers may not yet have made it through the
compositor's commit cycle yet. We need to wait until the compositor
is ready since the gestures may well be handled on the compositor
thread.

BUG= chromium:652905 

Review-Url: https://codereview.chromium.org/2400123002
Commit  : 584a67ecbf203fd00b785dc76fcfeb964c09c664
Date    : Tue Oct 11 15:41:55 2016


===== TESTED REVISIONS =====
Revision                             Exit Code  Std Dev  N  Good?
chromium@423027                      0          N/A      2  good
chromium@423785                      0          N/A      2  good
chromium@424164                      0          N/A      2  good
chromium@424354                      0          N/A      2  good
chromium@424449                      0          N/A      2  good
chromium@424461                      0          N/A      2  good
chromium@424462                      0          N/A      2  good
chromium@424462,catapult@584a67ecbf  1          N/A      2  bad    <--
chromium@424462,catapult@840e04295a  1          N/A      2  bad
chromium@424463                      1          N/A      2  bad
chromium@424464                      1          N/A      2  bad
chromium@424467                      1          N/A      2  bad
chromium@424473                      1          N/A      2  bad
chromium@424496                      1          N/A      2  bad
chromium@424543                      1          N/A      2  bad

Bisect job ran on: android_nexus6_perf_bisect
Bug ID: 655627

Test Command: src/tools/perf/run_benchmark -v --browser=android-chromium --output-format=chartjson --upload-results --pageset-repeat=1 --also-run-disabled-tests memory.blink_memory_mobile
Test Metric: memory:chrome:renderer_processes:process_count_avg/memory:chrome:renderer_processes:process_count_avg
Relative Change: Zero to non-zero
Score: 99.9

Buildbot stdio: http://build.chromium.org/p/tryserver.chromium.perf/builders/android_nexus6_perf_bisect/builds/2649
Job details: https://chromeperf.appspot.com/buildbucket_job_status/8998791434514339808


Not what you expected? We'll investigate and get back to you!
  https://chromeperf.appspot.com/bad_bisect?try_job_id=5794276317855744

| O O | Visit http://www.chromium.org/developers/speed-infra/perf-bug-faq
|  X  | for more information addressing perf regression bugs. For feedback,
| / \ | file a bug with component Tests>AutoBisect.  Thank you!
Owner: bokan@chromium.org
Status: Assigned (was: Duplicate)
David, looks like this caused failures on some benchmarks, can you take a look?

Comment 10 by bokan@chromium.org, Oct 17 2016

Will do, thanks.
#1: sorry about not getting back to you earlier, was ooo last week.

Comment 12 by bokan@chromium.org, Oct 18 2016

Status: Started (was: Assigned)

Comment 14 by bokan@chromium.org, Oct 19 2016

I have a CL up at https://codereview.chromium.org/2440503002/ for the failures in memory.blink_memory_mobile.

I couldn't reproduce any of the other failures locally except blink_style.polymer which turned out to be unrelated to my patch. I filled a bug for that in 
 issue 657424 .

I suspect the other failures may be unrelated to my patch since they all occurred over a period of 6 days without any builds so lots of patches. I've kicked off a new bisect above.
Project Member

Comment 15 by 42576172...@developer.gserviceaccount.com, Oct 20 2016


===== BISECT JOB RESULTS =====
Status: completed


===== TESTED REVISIONS =====
Revision         Exit Code  Std Dev  N   Good?
chromium@423027  1          N/A      20  good
chromium@424542  1          N/A      20  bad

Bisect job ran on: android_nexus6_perf_bisect
Bug ID: 655627

Test Command: src/tools/perf/run_benchmark -v --browser=android-chromium --output-format=chartjson --upload-results --pageset-repeat=1 --also-run-disabled-tests memory.blink_memory_mobile
Test Metric: memory:chrome:renderer_processes:reported_by_os:peak_resident_size_avg/memory:chrome:renderer_processes:reported_by_os:peak_resident_size_avg
Relative Change: 0.00%
Score: 0

Buildbot stdio: http://build.chromium.org/p/tryserver.chromium.perf/builders/android_nexus6_perf_bisect/builds/2676
Job details: https://chromeperf.appspot.com/buildbucket_job_status/8998336938141756288


Not what you expected? We'll investigate and get back to you!
  https://chromeperf.appspot.com/bad_bisect?try_job_id=5847245746864128

| O O | Visit http://www.chromium.org/developers/speed-infra/perf-bug-faq
|  X  | for more information addressing perf regression bugs. For feedback,
| / \ | file a bug with component Tests>AutoBisect.  Thank you!
Project Member

Comment 16 by bugdroid1@chromium.org, Oct 20 2016

The following revision refers to this bug:
  https://chromium.googlesource.com/chromium/src.git/+/7319aef904a71aba9f710482b8372434b8227df6

commit 7319aef904a71aba9f710482b8372434b8227df6
Author: bokan <bokan@chromium.org>
Date: Thu Oct 20 15:26:04 2016

Don't WaitForNavigate in memory.blink_memory_mobile benchmark.

Since https://codereview.chromium.org/2400123002 has landed, the navigate step
waits for an additional frame before returning. I suspect the redirect
navigations happen in that frame so when the page calls WaitForNavigate it'll
timeout.

BUG= 655627 

Review-Url: https://chromiumcodereview.appspot.com/2440503002
Cr-Commit-Position: refs/heads/master@{#426480}

[modify] https://crrev.com/7319aef904a71aba9f710482b8372434b8227df6/tools/perf/page_sets/blink_memory_mobile.py

Comment 17 by bokan@chromium.org, Oct 20 2016

Cc: -nednguyen@chromium.org bokan@chromium.org
Owner: nednguyen@chromium.org
Bisect in #15 seems not to have done anything, not sure why...

I'm seeing two kinds of failures remaining. The first seems to be issues with adb, this is causing redness on:
  smoothness.tough_webgl_ad_cases
  v8.top_25_smooth

Here's a representative stack trace:

Traceback (most recent call last):
  RunBenchmark at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/story_runner.py:341
    benchmark.ShouldTearDownStateAfterEachStorySetRun())
  Run at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/story_runner.py:265
    state.TearDownState()
  traced_function at /b/rr/tmp1O95cj/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:52
    return func(*args, **kwargs)
  TearDownState at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/page/shared_page_state.py:322
    self._StopBrowser()
  traced_function at /b/rr/tmp1O95cj/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:52
    return func(*args, **kwargs)
  _StopBrowser at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/page/shared_page_state.py:328
    self._browser.Close()
  Close at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/browser/browser.py:265
    self._browser_backend.Close()
  traced_function at /b/rr/tmp1O95cj/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:52
    return func(*args, **kwargs)
  Close at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/android_browser_backend.py:225
    self._StopBrowser()
  traced_function at /b/rr/tmp1O95cj/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:52
    return func(*args, **kwargs)
  _StopBrowser at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/android_browser_backend.py:73
    self.platform_backend.StopApplication(self._backend_settings.package)
  StopApplication at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/platform/android_platform_backend.py:327
    self._device.ForceStop(application)
  timeout_retry_wrapper at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/decorators.py:57
    retry_if_func=retry_if_func)
  Run at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/utils/timeout_retry.py:159
    error_log_func=error_log_func)
  JoinAll at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/utils/reraiser_thread.py:186
    self._JoinAll(watcher, timeout)
  _JoinAll at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/utils/reraiser_thread.py:158
    thread.ReraiseIfException()
  run at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/utils/reraiser_thread.py:81
    self._ret = self._func(*self._args, **self._kwargs)
  <lambda> at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/utils/timeout_retry.py:152
    child_thread = reraiser_thread.ReraiserThread(lambda: func(*args, **kwargs),
  impl at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/decorators.py:47
    return f(*args, **kwargs)
  ForceStop at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/device_utils.py:1136
    self.RunShellCommand(cmd % package, check_return=True)
  timeout_retry_wrapper at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/decorators.py:51
    return impl()
  impl at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/decorators.py:47
    return f(*args, **kwargs)
  RunShellCommand at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/device_utils.py:911
    output = handle_large_output(cmd, large_output)
  handle_large_output at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/device_utils.py:889
    return handle_large_command(cmd)
  handle_large_command at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/device_utils.py:871
    return handle_check_return(cmd)
  handle_check_return at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/device_utils.py:862
    return run(cmd)
  run at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/device_utils.py:858
    return self.adb.Shell(cmd)
  Shell at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/sdk/adb_wrapper.py:480
    output = self._RunDeviceAdbCmd(args, timeout, retries, check_error=False)
  _RunDeviceAdbCmd at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/sdk/adb_wrapper.py:282
    check_error=check_error)
  timeout_retry_wrapper at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/decorators.py:51
    return impl()
  impl at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/decorators.py:47
    return f(*args, **kwargs)
  _RunAdbCmd at /b/rr/tmp1O95cj/w/src/third_party/catapult/devil/devil/android/sdk/adb_wrapper.py:258
    args, output, status, device_serial)
AdbCommandFailedError: (device: ZX1G22KGXG) adb shell '( p=org.chromium.chrome;if [[ "$(ps)" = *$p* ]]; then am force-stop $p; fi );echo %$?': failed with exit status 255 and output:
- error: device 'ZX1G22KGXG' not found

and this one from system_health.memory_mobile and smoothness.key_silk_cases

Traceback (most recent call last):
  RunBenchmark at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/story_runner.py:341
    benchmark.ShouldTearDownStateAfterEachStorySetRun())
  Run at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/story_runner.py:252
    state.TearDownState()
  traced_function at /b/rr/tmp1O95cj/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:52
    return func(*args, **kwargs)
  TearDownState at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/page/shared_page_state.py:322
    self._StopBrowser()
  traced_function at /b/rr/tmp1O95cj/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:52
    return func(*args, **kwargs)
  _StopBrowser at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/page/shared_page_state.py:328
    self._browser.Close()
  Close at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/browser/browser.py:265
    self._browser_backend.Close()
  traced_function at /b/rr/tmp1O95cj/w/src/third_party/catapult/common/py_trace_event/py_trace_event/trace_event_impl/decorators.py:52
    return func(*args, **kwargs)
  Close at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/backends/chrome/android_browser_backend.py:227
    self.platform_backend.StopForwardingHost(self._port)
  StopForwardingHost at /b/rr/tmp1O95cj/w/src/third_party/catapult/telemetry/telemetry/internal/platform/android_platform_backend.py:487
    if line[0] == self._device and line[1] == 'tcp:%s' % host_port:
IndexError: list index out of range

And I see this commonly, even though many/all the tests above this point passed:
C    1.330s Main  ********************************************************************************
C    1.330s Main  Summary
C    1.330s Main  ********************************************************************************
C    1.330s Main  [==========] 1 test ran.
C    1.330s Main  [  PASSED  ] 0 tests.
C    1.330s Main  [  FAILED  ] 1 test, listed below:
C    1.330s Main  [  FAILED  ] PrintStep
C    1.330s Main  
C    1.330s Main  1 FAILED TEST
C    1.330s Main  ********************************************************************************
I    1.330s Main  Writing results to /b/rr/tmp1O95cj/w/src/out/Release/test_logs/perf.log.


Assigning to nednguyen@ who owns the system_health.memory_mobile metric for further investigation.

Cc: -rnep...@chromium.org nedngu...@google.com
Owner: rnep...@chromium.org
Randy: can you look at the ADB failure?
The IndexError one should be fixed with https://codereview.chromium.org/2419023003/

Is there a current run with that issue still, or just old ones?

For the adb issue, I cant find runs with that for smoothness.tough_webgl_ad_cases or v8.top_25_smooth. Can you link me to some? Based off the device ID in the traec its on nexus 6 (1). That error usually is just device flakiness though.

Comment 20 by bokan@chromium.org, Oct 20 2016

Yeah, it was a Nexus 6 but it seemed pretty persistent. build.chromium.org seems to be down right now, I'll link to a few runs when I can reach it again.
Cc: jbudorick@chromium.org
Labels: OS-Android
Ok, I think I have figured out what is going on.

(1)A test fails. (2)After a test fails it attempts to unmap the ports which fails. (3)It then attempts to do some device recovery. During device recovery, it reboots and never comes back up.

1:
I  967.479s device_shard_helper(0)  v8.top_25_smooth : exit_code=255 in 79 secs on device ZX1G22KCC9


2:
I  967.480s device_shard_helper(0)  Unmapping device ports for ZX1G22KCC9.

HostForwarderError: `/b/rr/tmpZZlSeu/w/src/out/Release/host_forwarder --adb=/b/rr/tmpZZlSeu/w/src/third_party/catapult/devil/bin/deps/linux2/x86_64/bin/adb --serial-id=ZX1G22KCC9 --unmap-all` exited with 1


(3)
I  967.521s TimeoutThread-1-for-device_shard_helper(0)  [host]> /b/rr/tmpZZlSeu/w/src/third_party/catapult/devil/bin/deps/linux2/x86_64/bin/adb -s ZX1G22KCC9 wait-for-device

E  997.557s device_shard_helper(0)  Failure while waiting for ZX1G22KCC9. Attempting to recover.

I  997.566s TimeoutThread-1-for-device_shard_helper(0)  [host]> /b/rr/tmpZZlSeu/w/src/third_party/catapult/devil/bin/deps/linux2/x86_64/bin/adb -s ZX1G22KCC9 reboot

E  997.630s device_shard_helper(0)  Failed to reboot ZX1G22KCC9.

AdbCommandFailedError: (device: ZX1G22KCC9) adb reboot: failed with exit status 1 and output:
- error: device 'ZX1G22KCC9' not found

I  997.630s device_shard_helper(0)  Adding ZX1G22KCC9 to blacklist /b/rr/tmpZZlSeu/w/src/out/bad_devices.json for reason: reboot_failure

----
https://build.chromium.org/p/chromium.perf/builders/Android%20Nexus6%20Perf%20%281%29/builds/4306/steps/Sharded%20Perf%20Tests/logs/stdio


Adding John to see if he thinks that the host forwarder failing to unmap ports could cause the device to become unresponsive.
#21: I'd guess that you've got the cause & effect backwards.
Blocking: 664590
Status: Archived (was: Started)

Sign in to add a comment