[STABILITY] Chrome ANR: Timeout executing service:org.chromium.chrome.browser.customtabs.CustomTabsConnectionService
Reported by
symbolze...@gmail.com,
Oct 4
|
|||
Issue description
Steps to reproduce the problem:
Its an ANR in org.chromium.chrome.browser.customtabs.CustomTabsConnectionService.
Bug-report and Logs attached. Refer to ANR bugreport.txt & ANR logcat.txt for more info.
PFA.
What is the expected behavior?
ANR shouldn't happen at any point of time of execution of chrome services in app.
What went wrong?
There is a timeout executing service in org.chromium.chrome.browser.customtabs.CustomTabsConnectionService.
Find the log snippet below:
09-30 07:36:47.702 W/ActivityManager( 1295): Timeout executing service: ServiceRecord{a159473 u0 com.android.chrome/org.chromium.chrome.browser.customtabs.CustomTabsConnectionService}
And ANR occurred for the same.
Find the ANR logs below:
09-30 07:36:48.189 E/ActivityManager( 1295): ANR in com.android.chrome
09-30 07:36:48.189 E/ActivityManager( 1295): PID: 27913
09-30 07:36:48.189 E/ActivityManager( 1295): Reason: executing service com.android.chrome/org.chromium.chrome.browser.customtabs.CustomTabsConnectionService
-----
Device Model : TC70x Zebra
Android OS Version : 8.1.0
Android SDK Version : 27
Bug-report and Logs attached. Refer to ANR bugreport.txt & ANR logcat.txt for more info.
PFA.
Crashed report ID:
How much crashed? Whole browser
Is it a problem with a plugin? N/A
Did this work before? N/A
Chrome version: 69.0.3497.100 Channel: stable
OS Version: Oreo
Flash Version:
,
Oct 5
@symbolzebra001: Is this issue seen on doing any particular steps, if so please provide steps to reproduce this issue. This would help in further triaging. Adding UI>Browser>Mobile>CustomTabs and cc'ing peconn@ and mvanouwerkerk@ for further inputs on this issue. Thanks!
,
Oct 8
Hi Chelamcherla, This chrome issues has been seen intermittently in all the Android GMS version we tested from M, N and O. In this particular case Chrome was likely running in the background, since the test case when failure occurred was 'Send an email with no attachment using WLAN'. Device was in the email app composing and sending email with no attachment. Please let Me know if you need more info on the same.
,
Oct 8
Thank you for providing more feedback. Adding the requester to the cc list. For more details visit https://www.chromium.org/issue-tracking/autotriage - Your friendly Sheriffbot
,
Oct 8
This issue occurred in 2 more devices. Find the attached BR and ANR Logs.
Root cause is the same.
Find the log snippets below
Device4
10-06 11:14:13.609 W/ActivityManager( 1312): Timeout executing service: ServiceRecord{34464f1 u0 com.android.chrome/org.chromium.chrome.browser.customtabs.CustomTabsConnectionService}
Device8
10-04 17:29:20.849 W/ActivityManager( 1316): Timeout executing service: ServiceRecord{b4bcc4d u0 com.android.chrome/org.chromium.chrome.browser.customtabs.CustomTabsConnectionService}
,
Oct 11
Any update on this issue? Revert ASAP.
,
Oct 11
For Device 4 the logcat shows these (note the "bg anr" for chrome) but I'm not sure why:
10-06 11:14:13.920 W/PackageManager( 1312): Caller hasn't been triaged for missing apps; they asked about com.android.chrome with flags 0x0
10-06 11:14:13.920 W/PackageManager( 1312): java.lang.Throwable
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.pm.PackageManagerService.updateFlagsForPackage(PackageManagerService.java:4992)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.pm.PackageManagerService.getPackageInfoInternal(PackageManagerService.java:4166)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.pm.PackageManagerService.getPackageInfo(PackageManagerService.java:4146)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.am.ActivityManagerService.appendDropBoxProcessHeaders(ActivityManagerService.java:14971)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.am.ActivityManagerService.addErrorToDropBox(ActivityManagerService.java:15040)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.am.AppErrors.appNotResponding(AppErrors.java:948)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.am.ActiveServices.serviceTimeout(ActiveServices.java:3436)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.am.ActivityManagerService$MainHandler.handleMessage(ActivityManagerService.java:2064)
10-06 11:14:13.920 W/PackageManager( 1312): at android.os.Handler.dispatchMessage(Handler.java:106)
10-06 11:14:13.920 W/PackageManager( 1312): at android.os.Looper.loop(Looper.java:164)
10-06 11:14:13.920 W/PackageManager( 1312): at android.os.HandlerThread.run(HandlerThread.java:65)
10-06 11:14:13.920 W/PackageManager( 1312): at com.android.server.ServiceThread.run(ServiceThread.java:46)
10-06 11:14:13.921 V/PackageManager( 1312): getPackageInfo com.android.chrome: Package{c31007d com.android.chrome}
10-06 11:14:13.922 W/ContextImpl( 1312): Calling a method in the system process without a qualified user: android.app.ContextImpl.sendBroadcast:966 com.android.server.am.ActivityManagerService.killedApplications:6573 com.android.server.am.ProcessRecord.kill:643 com.android.server.am.AppErrors.appNotResponding:976 com.android.server.am.ActiveServices.serviceTimeout:3436
10-06 11:14:13.922 D/ActivityManager( 1312): sendBroadcast
10-06 11:14:13.922 I/ActivityManager( 1312): Killing 15321:com.android.chrome/u0a120 (adj 0): bg anr
10-06 11:14:13.923 W/zygote64( 1312): kill(-15321, 9) failed: No such process
10-06 11:14:13.944 W/PackageManager( 1312): Caller hasn't been triaged for missing apps; they asked about com.android.launcher3 with flags 0x0
10-06 11:14:13.944 W/PackageManager( 1312): java.lang.Throwable
10-06 11:14:13.944 W/PackageManager( 1312): at com.android.server.pm.PackageManagerService.updateFlagsForPackage(PackageManagerService.java:4992)
10-06 11:14:13.944 W/PackageManager( 1312): at com.android.server.pm.PackageManagerService.getPackageUid(PackageManagerService.java:4428)
10-06 11:14:13.944 W/PackageManager( 1312): at android.app.ApplicationPackageManager.getPackageUidAsUser(ApplicationPackageManager.java:274)
10-06 11:14:13.944 W/PackageManager( 1312): at android.app.ApplicationPackageManager.getPackageUid(ApplicationPackageManager.java:262)
10-06 11:14:13.944 W/PackageManager( 1312): at com.android.server.accessibility.AccessibilityManagerService$SecurityPolicy.isValidPackageForUid(AccessibilityManagerService.java:4579)
10-06 11:14:13.944 W/PackageManager( 1312): at com.android.server.accessibility.AccessibilityManagerService$SecurityPolicy.resolveValidReportedPackageLocked(AccessibilityManagerService.java:4600)
10-06 11:14:13.944 W/PackageManager( 1312): at com.android.server.accessibility.AccessibilityManagerService.sendAccessibilityEvent(AccessibilityManagerService.java:513)
10-06 11:14:13.944 W/PackageManager( 1312): at android.view.accessibility.IAccessibilityManager$Stub.onTransact(IAccessibilityManager.java:71)
10-06 11:14:13.944 W/PackageManager( 1312): at android.os.Binder.execTransact(Binder.java:697)
,
Oct 23
The following is not the root-cause of ANR. ANR happened before that warning got logged.
10-06 11:14:13.920 W/PackageManager( 1312): Caller hasn't been triaged for missing apps;
As you can see from the below log snippet, there is a time-out on executing CustomTabsConnectionService.
0-06 11:14:13.609 W/ActivityManager( 1312): Timeout executing service: ServiceRecord{34464f1 u0 com.android.chrome/org.chromium.chrome.browser.customtabs.CustomTabsConnectionService}
Please check on why this time-out happened.
,
Oct 23
Hi, Thanks for the report, and sorry for the long delay to get a reply. From the logs, I doubt the actual issue is linked to Chrome. The log line you're referring to above happens when a service fails to start, which AFAICT from a cursory look at AOSP source code means that it failed to reach the Java entry point in the Service class. If that's the case, then it's likely that something is blocking it in the framework. In this instance, there is a lot of exceptions on device 5. It's possible that it held a lock in PackageManager the prevented the device from starting. Is this a device with significant Android framework changes?
,
Oct 25
Hi, I already checked the ANR logs. There no locks or deadlocks holding our chrome process. And there is no much changes in framework as well. Revert to me if you need more info on the same. Thanks for your reply
,
Oct 25
This issue got reproduced again.
SERVICE TIME-OUT
10-17 18:39:48.028 W/ActivityManager( 1324): Timeout executing service: ServiceRecord{da9901a u0 com.android.chrome/org.chromium.chrome.browser.customtabs.CustomTabsConnectionService}
ANR
10-17 18:39:48.300 E/ActivityManager( 1324): ANR in com.android.chrome
10-17 18:39:48.300 E/ActivityManager( 1324): PID: 18684
10-17 18:39:48.300 E/ActivityManager( 1324): Reason: executing service com.android.chrome/org.chromium.chrome.browser.customtabs.CustomTabsConnectionService
Please check why CustomTabsConnectionService is getting called again and again while composing an email on GMail app.
Thanks for your time.
|
|||
►
Sign in to add a comment |
|||
Comment 1 by chelamcherla@chromium.org
, Oct 5