New issue
Advanced search Search tips

Issue 778806 link

Starred by 2 users

Issue metadata

Status: Duplicate
Merged: issue 778680
Owner: ----
Closed: Oct 2017
Cc:
EstimatedDays: ----
NextAction: ----
OS: Windows
Pri: 1
Type: Bug

Blocking:
issue 744658
issue 777565
issue 777569
issue 777963
issue 777964
issue 777970



Sign in to add a comment

>100% execution time regression for telemetry_unittests on windows starting ~2017-10-24T12:00:00Z

Project Member Reported by jbudorick@chromium.org, Oct 26 2017

Issue description

^ http://shortn/_zpZTNqLhw9

nednguyen suggested that this could be related to https://chromium.googlesource.com/chromium/src/+/22525134c75ca2a005997a717f6887a23af86210. the timing doesn't quite line up, but there were other changes related to the clang switch & telemetry_unittests in https://bugs.chromium.org/p/chromium/issues/detail?id=777741

This is causing the swarming pending times alerts to fire on the main waterfall.
 
Description: Show this description
Cc: jbudorick@chromium.org
 Issue 778807  has been merged into this issue.
Note that a bunch of benchmarks' runtime duration regressed heavily as well: https://chromeperf.appspot.com/group_report?keys=agxzfmNocm9tZXBlcmZyFAsSB0Fub21hbHkYgIDgx5ilswoM
Cc: -nednguyen@chromium.org nedngu...@google.com
Components: -Tests

Comment 6 by thakis@chromium.org, Oct 26 2017

 issue 778135  tracks general perf impact of the switch.

last time we switched (in m62), we didn't see this. Did change anything?

Can anyone profile telemetry_unittests with both compilers and check where the difference is?

Comment 7 by thakis@chromium.org, Oct 26 2017

s/Did change anything/Did anything change wrt how telemetry_unittests are built/
While waiting for the bisect, here are the before & after traces:

Before: https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/browse_media_youtube_2017-10-24_06-57-54_56880.html

After: https://console.developers.google.com/m/cloudstorage/b/chrome-telemetry-output/o/browse_media_youtube_2017-10-24_13-03-24_7400.html


I notice that in before trace, DesktopBrowserBackend.GetSystemInfo was taking 4ms, while in the after trace, DesktopBrowserBackend.GetSystemInfo was taking 5000ms (1000X regression)

+kbr@ in case you notice any slow down in GPU tests

Comment 9 by thakis@chromium.org, Oct 26 2017

We have 118 hits for 'GetSystemInfo', pointing to several different symbols. Which one are you referring to? (Can't see your traces, off corp atm)
Do you know which c++ code this ends up calling?

Comment 13 by h...@chromium.org, Oct 27 2017

jbudorick, can you link to a build that shows the slow telemetry_unittests behaviour, and ideally which test it is that slowed down?


> I notice that in before trace, DesktopBrowserBackend.GetSystemInfo was taking 4ms, while in the after trace, DesktopBrowserBackend.GetSystemInfo was taking 5000ms (1000X regression)

Thanks! That suggests it's hitting the kGPUInfoWatchdogTimeoutMs timeout: https://cs.chromium.org/chromium/src/content/browser/devtools/protocol/system_info_handler.cc?rcl=f0dfc858de0d129a4e8a013ae9f870b710fc2d80&l=31
Cc: yhirano@chromium.org
Project Member

Comment 15 by 42576172...@developer.gserviceaccount.com, Oct 27 2017

Mergedinto: 778680
Status: Duplicate (was: Untriaged)

=== BISECT JOB RESULTS ===
Perf regression found with culprit

Suspected Commit
  Author : Zhenyao Mo
  Commit : 0728a85617eae06098373349af80c28ce26b60fd
  Date   : Tue Oct 24 11:16:56 2017
  Subject: One step further into moving GPU feature decisions to GPU process

Bisect Details
  Configuration: win_8_perf_bisect
  Benchmark    : smoothness.tough_scrolling_cases
  Metric       : benchmark_duration/benchmark_duration
  Change       : 45.13% | 11.9583611098 -> 17.3554694447

Revision             Result                   N
chromium@511078      11.9584 +- 2.22448       6      good
chromium@511087      11.9043 +- 1.801         6      good
chromium@511088      11.8134 +- 1.04186       6      good
chromium@511089      17.1544 +- 1.11707       6      bad       <--
chromium@511090      17.2969 +- 0.750019      6      bad
chromium@511092      17.3596 +- 1.09234       6      bad
chromium@511096      17.1842 +- 0.8886        6      bad
chromium@511114      17.4095 +- 1.22446       6      bad
chromium@511150      17.3555 +- 1.37406       6      bad

To Run This Test
  src/tools/perf/run_benchmark -v --browser=release --output-format=chartjson --upload-results --pageset-repeat=1 --also-run-disabled-tests smoothness.tough_scrolling_cases

More information on addressing performance regressions:
  http://g.co/ChromePerformanceRegressions

Debug information about this bisect:
  https://chromeperf.appspot.com/buildbucket_job_status/8964636886560192128


For feedback, file a bug with component Speed>Bisection
#13: a passing run from Monday, pre-regression, where both swarming shards complete in <6 minutes: https://build.chromium.org/p/chromium.win/builders/Win7%20Tests%20%281%29/builds/72715

a passing run from Tuesday, post-regression, where both swarming shards complete in >20 minutes: https://build.chromium.org/p/chromium.win/builders/Win7%20Tests%20%281%29/builds/72749

Haven't looked into the particulars of what slowed down.

Comment 17 by kbr@chromium.org, Oct 27 2017

Blocking: 744658
Issue 777565 has been merged into this issue.
Issue 777569 has been merged into this issue.
Issue 777963 has been merged into this issue.
Issue 777964 has been merged into this issue.
Issue 777970 has been merged into this issue.

Sign in to add a comment