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

Issue 593152 link

Starred by 3 users

Issue metadata

Status: Fixed
Owner:
Closed: Mar 2016
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Android
Pri: 1
Type: Bug-Regression



Sign in to add a comment

Several reference tests failing on Nexus 5x due to CertInstallError

Project Member Reported by simonhatch@chromium.org, Mar 8 2016

Issue description

v8.top_25_smooth.reference
page_cycler.typical_25.reference
blink_style.key_mobile_sites.reference
smoothness.scrolling_tough_ad_cases.reference
power.typical_10_mobile.reference

These have failed since the bot was introduced on Feb 29.


Dave pointed out that they consistently have this in the logs:

(ERROR) 2016-03-08 10:04:15,817 network_controller_backend._InstallTestCa:130  Failed to install test certificate authority on target platform. Browsers may fall back to ignoring certificate errors.
Traceback (most recent call last):
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/internal/platform/network_controller_backend.py", line 126, in _InstallTestCa
    self._platform_backend.InstallTestCa(self._wpr_ca_cert_path)
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/internal/platform/android_platform_backend.py", line 548, in InstallTestCa
    self._device_cert_util.install_cert(overwrite_cert=True)
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/third_party/webpagereplay/adb_install_cert.py", line 163, in install_cert
    raise CertInstallError('Cert Install Failed')
CertInstallError: Cert Install Failed




Digging around a bit more, there's also this error:

Traceback (most recent call last):
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/internal/story_runner.py", line 84, in _RunStoryAndProcessErrorIfNeeded
    state.RunStory(results)
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/page/shared_page_state.py", line 323, in RunStory
    self._current_page.Run(self)
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/page/__init__.py", line 88, in Run
    self.RunPageInteractions(action_runner)
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/tools/perf/page_sets/top_25_smooth.py", line 69, in RunPageInteractions
    'window.__scrollableElementForTelemetry != null')
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/page/action_runner.py", line 186, in WaitForJavaScriptCondition
    self._tab.WaitForJavaScriptExpression(condition, timeout_in_seconds)
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/internal/browser/web_contents.py", line 103, in WaitForJavaScriptExpression
    self._inspector_backend.GetCurrentConsoleOutputBuffer())
TimeoutException: Timed out while waiting 60s for IsJavaScriptExpressionTrue.
Console output:
(error) https://ssl.gstatic.com/gb/images/v2_0bc49e87.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/zippy/arrow_down_red_2x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/zippy/arrow_down_2x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://lh4.googleusercontent.com/-AoKvIOmAX0Q/AAAAAAAAAAI/AAAAAAAAAAA/AMW9Igc2540kM4tNT2LEu2_C_PIptZDXjg/s64-c-mo/photo.jpg:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/icons/mail/tabs/tabicons_black_2x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/icons/mail/tabs/plus_black_2x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/star/star4_2x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/icons/mail/sprite_importance_2x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/mail/sprites/general_2x_black-da66a5f662fe63f97c99b3160bab1d74.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/star/star-lit4_2x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/icons/mail/wbt/contacts_icon42.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/mail/sprites/hangouts_black_2x-5efc6d78b1e4ab9e8819827d0321e637.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/icons/mail/wbt/hangouts_icon42.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://apis.google.com/_/scs/abc-static/_/js/k=gapi.gapi.en.73CODytQm18.O/m=iframes_styles_hover/exm=gapi_iframes,googleapis_client,iframes_styles_slide_menu,plusone/rt=j/sv=1/d=1/ed=1/am=AAQ/rs=AHpOoo_oUuymWPUqneZS_mOrlV9L5nz06Q/cb=gapi.loaded_1:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/s2/oz/images/notifications/spinner_64_b6a3129c3429eba076483f2c93ba38f6.gif:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://apis.google.com/_/scs/abc-static/_/js/k=gapi.gapi.en.73CODytQm18.O/m=cloudsearch/exm=gapi_iframes,googleapis_client,iframes_styles_hover,iframes_styles_slide_menu,plusone/rt=j/sv=1/d=1/ed=1/am=AAQ/rs=AHpOoo_oUuymWPUqneZS_mOrlV9L5nz06Q/cb=gapi.loaded_2:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://mail.google.com/_/scs/mail-static/_/js/k=gmail.main.en.VSGyprhoceY.DO/m=sy431,sy434,sy438,gm/am=PiPeSsD83_uDuM4QQLv0kQrz3n9-d5HiZ889_j9vAqjUrwD-b_b_AP4P3ru2UA/rt=j/t=zcms/rs=AHGWq9Cn3yZFG2d_wpZx2qJip6zRJBgkqg:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://mail.google.com/_/scs/mail-static/_/js/k=gmail.main.en.VSGyprhoceY.DO/m=sy431,sy434,sy438,gm/am=PiPeSsD83_uDuM4QQLv0kQrz3n9-d5HiZ889_j9vAqjUrwD-b_b_AP4P3ru2UA/rt=j/t=zcms/rs=AHGWq9Cn3yZFG2d_wpZx2qJip6zRJBgkqg:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://clients5.google.com/ads/measurement/t?jog=1&hd=mail.google.com&di=%5B%2220141020-01%22%2C%2C0%5D:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://mail.google.com/_/scs/mail-static/_/js/k=gmail.main.en.VSGyprhoceY.DO/m=sy431,sy434,sy438,gm/am=PiPeSsD83_uDuM4QQLv0kQrz3n9-d5HiZ889_j9vAqjUrwD-b_b_AP4P3ru2UA/rt=j/t=zcms/rs=AHGWq9Cn3yZFG2d_wpZx2qJip6zRJBgkqg:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/ui/v1/dialog/close-x.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)
(error) https://ssl.gstatic.com/mail/sprites/hangouts_black_2x-5efc6d78b1e4ab9e8819827d0321e637.png:0: Failed to load resource: the server responded with a status of 404 (Not Found)

 

Comment 1 by dtu@chromium.org, Mar 8 2016

Cc: eakuefner@chromium.org
Owner: eakuefner@chromium.org
Status: Assigned (was: Untriaged)
Temporarily assign to Ethan to disable these test on nexus-5x. I will investigate the adb cert failure later.
 Issue 593960  has been merged into this issue.

Comment 5 by picksi@chromium.org, Mar 11 2016

Lots of tests still failing:

power.tough_ad_cases.reference
page_cycler.intl_ko_th_vi.reference
smoothness.top_25_smooth.reference
oilpan_gc_times.sync_scroll.key_mobile_sites_smooth.reference
smoothness.fling.simple_mobile_sites
smoothness.fling.simple_mobile_sites.reference
smoothness.gpu_rasterization.top_25_smooth smoothness.gpu_rasterization.top_25_smooth.reference smoothness.tough_texture_upload_cases smoothness.tough_texture_upload_cases.reference failed thread_times.key_noop_cases
thread_times.key_noop_cases.reference
thread_times.polymer
thread_times.polymer.reference

This is so systemic that it must be an 5X issue rather than a Clank issue? Disabling all these tests feels too extreme.

Comment 6 by picksi@chromium.org, Mar 11 2016

Both Perf (2) and Perf (3) are failing in the same way. Perf (1) is green.
Ethan, are you working on the cert issue?
Summary: Several reference tests failing on Nexus 5x due to CertInstallError (was: Several reference tests failing on Nexus 5x)
There are two set of failures here: 
1) Failure due to CertInstallError('Cert Install Failed')
2) Failure due to test timeout.

Retitle this bug to be about CertInstallError & file bug 594136 about test timeout.
Owner: nedngu...@google.com
I will look at the CertInstallError. 
Hopefully https://codereview.chromium.org/1790563002/ will fix it.
Though I am still a little bit unsure why we don't see this problem with other M devices on the waterfall.
The CertInstallError fix in #10 probably work since the nexus5 log no longer contains such error, but look like the cert removal step fails:

(ERROR) 2016-03-11 11:21:40,442 network_controller_backend._RemoveTestCa:142  Error trying to remove certificate authority from target platform.
Traceback (most recent call last):
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/internal/platform/network_controller_backend.py", line 138, in _RemoveTestCa
    self._platform_backend.RemoveTestCa()
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/telemetry/internal/platform/android_platform_backend.py", line 558, in RemoveTestCa
    self._device_cert_util.remove_cert()
  File "/b/build/slave/Android_Nexus5X_Perf__1_/build/src/third_party/catapult/telemetry/third_party/webpagereplay/adb_install_cert.py", line 151, in remove_cert
    raise CertRemovalError('Cert Removal Failed')
CertRemovalError: Cert Removal Failed
[  PASSED  ] 30 tests.


For some reason, the benchmark that run right after this timed out. So I think it's probably the certificate removal error that causes all the subsequent runs to fail.
Please let me know if anyone needs an N5X for local testing to make this process go easier. (There are also N5X trybots)
Ok, the problem was that during the step of removing certificate, we run the removing command with:

> adb shell su 0 rm /system/etc/security/cacerts/d07fb5db.0 
rm ro /system/etc/security/cacerts/d07fb5db.0 (y/N) # the command ask for 'Y' to confirm

Hence Telemetry hang here, killing all the subsequent runs. The immediate fix here is to replace the "adb shell su 0 rm" command with "adb shell su 0 rm -f".
Good catch. Do you know why this wasn't impacting other bots?
Likely this is just an L->M thing as in https://github.com/chromium/web-page-replay/issues/60
*Note that this problems does not happen on other bots because "adb shell su 0 rm" inherently only requires confirmation prompt since Marshmallow.
The certificate removal is still failing. Running this locally shows:
"
$ adb shell su 0 rm -f /system/etc/security/cacerts/d07fb5db.0
rm: /system/etc/security/cacerts/d07fb5db.0: Read-only file system
"
Maybe I need extra step of changing the permission of certificate file for android M, will investigate more.


The good news here is although the benchmarks are failing at this final step, all the benchmark values produced from earlier steps should still be sent to the dashboard, hence there is no discontinuity of sending data to the dashboard.
Issue 595703 has been merged into this issue.
It looks to me that starting from Android M, the certificate injection by webpagereplay no longer works, probably due to a change in security restriction.

From the log:

https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/profiler-file-id_0-2016-03-17_07-49-2165733.png


03-17 14:40:46.885 24217 24264 I X509Util: Failed to validate the certificate chain, error: java.security.cert.CertPathValidatorException: Trust anchor for certification path not found.
	03-17 14:40:46.927   916  3623 I ActivityManager: Start proc 24342:com.google.android.apps.chrome:sandboxed_process1/u0i109 for service com.google.android.apps.chrome/org.chromium.content.app.SandboxedProcessService1
	03-17 14:40:46.959 24355 24355 W com.google.android.apps.chrome:sandboxed_process1: type=1400 audit(0.0:313): avc: denied { search } for comm=4173796E635461736B202331 name="com.google.android.apps.chrome" dev="dm-2" ino=376494 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=dir permissive=0
	03-17 14:40:46.959 24355 24355 W com.google.android.apps.chrome:sandboxed_process1: type=1400 audit(0.0:314): avc: denied { search } for comm=4173796E635461736B202331 name="com.google.android.apps.chrome" dev="dm-2" ino=376494 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=dir permissive=0
	03-17 14:40:46.959 24355 24355 W com.google.android.apps.chrome:sandboxed_process1: type=1400 audit(0.0:315): avc: denied { search } for comm=4173796E635461736B202331 name="com.google.android.apps.chrome" dev="dm-2" ino=376494 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=dir permissive=0
	03-17 14:40:46.959 24355 24355 W com.google.android.apps.chrome:sandboxed_process1: type=1400 audit(0.0:316): avc: denied { search } for comm=4173796E635461736B202331 name="com.google.android.apps.chrome" dev="dm-2" ino=376494 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=dir permissive=0
	03-17 14:40:46.959 24355 24355 W com.google.android.apps.chrome:sandboxed_process1: type=1400 audit(0.0:317): avc: denied { search } for comm=4173796E635461736B202331 name="com.google.android.apps.chrome" dev="dm-2" ino=376494 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=dir permissive=0
	03-17 14:40:46.959 24355 24355 W com.google.android.apps.chrome:sandboxed_process1: type=1400 audit(0.0:318): avc: denied { search } for comm=4173796E635461736B202331 name="com.google.android.apps.chrome" dev="dm-2" ino=376494 scontext=u:r:isolated_app:s0:c512,c768 tcontext=u:object_r:app_data_file:s0:c512,c768 tclass=dir permissive=0
	03-17 14:40:46.964 24342 24355 W FileUtils: Failed to chmod(/data/user/0/com.google.android.apps.chrome/app_chrome): android.system.ErrnoException: chmod failed: EACCES (Permission denied)
	03-17 14:40:46.964 24342 24355 W ContextImpl: Unable to create files subdir /data/user/0/com.google.android.apps.chrome/cache
	03-17 14:40:46.967 24342 24342 I cr_ChildProcessService: Creating new ChildProcessService pid=24342
	03-17 14:40:46.971 24342 24356 I cr_LibraryLoader: Using linker: org.chromium.base.library_loader.ModernLinker
	03-17 14:40:46.986 24342 24356 I cr_LibraryLoader: Loading chrome from within /data/app/com.google.android.apps.chrome-1/base.apk
	03-17 14:40:47.019 24342 24356 I cr_LibraryLoader: Time to load native libraries: 48 ms (timestamps 5510-5558)
	03-17 14:40:47.020 24342 24356 I cr_LibraryLoader: Expected native library version number "48.0.2564.95", actual native library version number "48.0.2564.95"
	03-17 14:40:47.020 24342 24356 I chromium: [INFO:library_loader_hooks.cc(130)] Chromium logging enabled: level = 0, default verbosity = 0
	03-17 14:40:48.261   607   607 E QCALOG  : [MessageQ] ProcessNewMessage: [LOWI-SERVER] unknown deliver target [OS-Agent]
	03-17 14:40:55.391   916  3623 I AccountManagerService: getTypesVisibleToCaller: isPermitted? true
	03-17 14:40:55.396 24217 24388 E cr_omaha: Failed to contact server: 
	03-17 14:41:45.370  3227  3458 I Keyboard.Facilitator.LanguageModelFlusher: run()
	03-17 14:41:45.371  3227  3458 I Keyboard.Facilitator: flushDynamicLanguageModels()
	03-17 14:41:45.386  3469  3469 I ConfigService: onCreate

(https://build.chromium.org/p/chromium.perf/builders/Android%20Nexus5X%20Perf%20%283%29/builds/99/steps/tracing.tracing_with_debug_overhead.reference/logs/stdio)


So for now, I will just skip Certificate installation on Android M & switch telemetry to use --disable-certificate-error
Cc: petrcermak@chromium.org
Status: Fixed (was: Assigned)
Project Member

Comment 25 by bugdroid1@chromium.org, Mar 21 2016

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

commit da0ce8d5f44c0bee79fff8a39a500548d06df79b
Author: nednguyen <nednguyen@google.com>
Date: Mon Mar 21 17:10:30 2016

Revert of [Telemetry] Disable some tests on Nexus 5X reference (patchset #1 id:1 of https://codereview.chromium.org/1776473008/ )

Reason for revert:
CertInstallationError problem is fixed.

Original issue's description:
> [Telemetry] Disable some tests on Nexus 5X reference
>
> BUG= 593152 
> CQ_EXTRA_TRYBOTS=tryserver.chromium.perf:winx64_10_perf_cq;tryserver.chromium.perf:mac_retina_perf_cq;tryserver.chromium.perf:linux_perf_cq
>
> Committed: https://crrev.com/e89802a4b539562b96d004ae22ab0af163ba08a1
> Cr-Commit-Position: refs/heads/master@{#380412}

TBR=aiolos@chromium.org,simonhatch@chromium.org,nednguyen@chromium.org,eakuefner@chromium.org
# Not skipping CQ checks because original CL landed more than 1 days ago.
BUG= 593152 

Review URL: https://codereview.chromium.org/1824623002

Cr-Commit-Position: refs/heads/master@{#382311}

[modify] https://crrev.com/da0ce8d5f44c0bee79fff8a39a500548d06df79b/tools/perf/benchmarks/blink_style.py
[modify] https://crrev.com/da0ce8d5f44c0bee79fff8a39a500548d06df79b/tools/perf/benchmarks/page_cycler.py
[modify] https://crrev.com/da0ce8d5f44c0bee79fff8a39a500548d06df79b/tools/perf/benchmarks/power.py
[modify] https://crrev.com/da0ce8d5f44c0bee79fff8a39a500548d06df79b/tools/perf/benchmarks/smoothness.py
[modify] https://crrev.com/da0ce8d5f44c0bee79fff8a39a500548d06df79b/tools/perf/benchmarks/v8.py

Status: Assigned (was: Fixed)
Re-opening this again as aeference tests are still failing on Nexus5x.

https://build.chromium.org/p/chromium.perf/builders/Android%20Nexus5X%20Perf%20(1)
Status: Fixed (was: Assigned)
They are failing due to different reason. (see  issue 596464 )

Sign in to add a comment