1705713 - Intermittent tier 2 raptor-browsertime Critical: [chrome] Exception: Cannot find the chromedriver for the chrome version being tested

Status: RESOLVED FIXED

(Testing :: Raptor, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=336771242&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fBzb6bd8Q9KmsZ8mo9QcRw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-04-16T11:26:44.270Z] 11:26:44     INFO -  raptor-main Info: webaudio
[task 2021-04-16T11:26:44.270Z] 11:26:44     INFO -  raptor-perftest Info: Using an empty profile.
[task 2021-04-16T11:26:44.271Z] 11:26:44     INFO -  raptor-perftest Warning: [chrome] Failed to get browser meta data through mozversion: LocalAppNotFoundError-Application not found at: /opt/google/chrome
[task 2021-04-16T11:26:44.271Z] 11:26:44     INFO -  raptor-perftest Info: Attempting to get version through fallback method...
[task 2021-04-16T11:26:44.329Z] 11:26:44     INFO -  Google Chrome 90.0.4430.72
[task 2021-04-16T11:26:44.350Z] 11:26:44     INFO -  raptor-perftest Info: Browser name: chrome
[task 2021-04-16T11:26:44.350Z] 11:26:44     INFO -  raptor-perftest Info: Browser version: 90.0.4430.72
[task 2021-04-16T11:26:44.351Z] 11:26:44     INFO -  raptor-perftest Warning: [chrome] Failed to get browser meta data through mozversion: LocalAppNotFoundError-Application not found at: /opt/google/chrome
[task 2021-04-16T11:26:44.351Z] 11:26:44     INFO -  raptor-perftest Info: Attempting to get version through fallback method...
[task 2021-04-16T11:26:44.401Z] 11:26:44     INFO -  Google Chrome 90.0.4430.72
[task 2021-04-16T11:26:44.421Z] 11:26:44     INFO -  raptor-perftest Info: Browser name: chrome
[task 2021-04-16T11:26:44.422Z] 11:26:44     INFO -  raptor-perftest Info: Browser version: 90.0.4430.72
[task 2021-04-16T11:26:44.422Z] 11:26:44     INFO -  raptor-perftest Info: Post startup delay set to 30000 ms
[task 2021-04-16T11:26:44.422Z] 11:26:44     INFO -  raptor-perftest Info: main raptor init, config is: {'app': 'chrome', 'binary': '/usr/bin/google-chrome', 'platform': 'linux', 'processor': 'x86_64', 'run_local': False, 'obj_path': None, 'gecko_profile': False, 'gecko_profile_interval': None, 'gecko_profile_entries': None, 'symbols_path': 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/TV7MKpeTRxyDKjHxOyKbaA/artifacts/public/build/target.crashreporter-symbols.zip', 'host': '127.0.0.1', 'power_test': False, 'memory_test': False, 'cpu_test': False, 'cold': False, 'live_sites': False, 'is_release_build': False, 'enable_control_server_wait': False, 'e10s': True, 'enable_webrender': False, 'device_name': None, 'enable_fission': False, 'disable_perf_tuning': False, 'conditioned_profile': None, 'chimera': False, 'extra_prefs': {}, 'environment': {'MOZ_WEBRENDER': '0'}, 'project': 'mozilla-central', 'verbose': False}
[task 2021-04-16T11:26:44.423Z] 11:26:44     INFO -  raptor-perftest Info: Merging profile: /home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/profile_data/base
[task 2021-04-16T11:26:44.423Z] 11:26:44     INFO -  raptor-perftest Info: Merging profile: /home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/profile_data/common
[task 2021-04-16T11:26:44.423Z] 11:26:44     INFO -  raptor-perftest Info: Merging profile: /home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/profile_data/perf
[task 2021-04-16T11:26:44.423Z] 11:26:44     INFO -  raptor-perftest Info: Merging profile: /home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/profile_data/raptor
[task 2021-04-16T11:26:44.423Z] 11:26:44     INFO -  raptor-perftest Info: Local browser profile: /tmp/tmp6awfuk1j.mozrunner/Default
[task 2021-04-16T11:26:44.424Z] 11:26:44     INFO -  raptor-browsertime Info: Removing mozprofile delimiters from browser profile
[task 2021-04-16T11:26:44.424Z] 11:26:44 CRITICAL -  raptor-browsertime Critical: [chrome] Exception [Errno 2] No such file or directory: '/tmp/tmp6awfuk1j.mozrunner/Default/user.js' while removing mozprofile delimiters
[task 2021-04-16T11:26:44.424Z] 11:26:44     INFO -  raptor-browsertime Info: cwd: '/home/cltbld/tasks/task_161857236343392/build'
[task 2021-04-16T11:26:44.424Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_node: /home/cltbld/tasks/task_161857236343392/fetches/node/bin/node
[task 2021-04-16T11:26:44.425Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_node: os.stat_result(st_mode=33261, st_ino=10918978, st_dev=64768, st_nlink=1, st_uid=1006, st_gid=1007, st_size=41226208, st_atime=1618572365, st_mtime=1609759187, st_ctime=1618572366)
[task 2021-04-16T11:26:44.425Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_browsertimejs: /home/cltbld/tasks/task_161857236343392/fetches/browsertime/node_modules/browsertime/bin/browsertime.js
[task 2021-04-16T11:26:44.425Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_browsertimejs: os.stat_result(st_mode=33261, st_ino=5003202, st_dev=64768, st_nlink=1, st_uid=1006, st_gid=1007, st_size=4376, st_atime=1618572367, st_mtime=1610746082, st_ctime=1618572367)
[task 2021-04-16T11:26:44.425Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_geckodriver: /home/cltbld/tasks/task_161857236343392/fetches/geckodriver
[task 2021-04-16T11:26:44.426Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_geckodriver: os.stat_result(st_mode=33261, st_ino=9996759, st_dev=64768, st_nlink=1, st_uid=1006, st_gid=1007, st_size=8401744, st_atime=1618572364, st_mtime=1618286969, st_ctime=1618572364)
[task 2021-04-16T11:26:44.426Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_chromedriver: /home/cltbld/tasks/task_161857236343392/fetches/{}chromedriver
[task 2021-04-16T11:26:44.426Z] 11:26:44     INFO -  raptor-browsertime Info: browsertime_chromedriver: [Errno 2] No such file or directory: '/home/cltbld/tasks/task_161857236343392/fetches/{}chromedriver'
[task 2021-04-16T11:26:44.426Z] 11:26:44     INFO -  raptor-perftest Info: starting test: webaudio
[task 2021-04-16T11:26:44.426Z] 11:26:44     INFO -  raptor-benchmark Info: bench_dir contains:
[task 2021-04-16T11:26:44.426Z] 11:26:44     INFO -  raptor-benchmark Info: ['webaudio', 'StyleBench', 'six-speed', 'SunSpider', 'MotionMark', 'resources', 'ARES-6', 'wasm-godot', 'Speedometer']
[task 2021-04-16T11:26:44.427Z] 11:26:44     INFO -  raptor-benchmark Info: writing wptserve headers file
[task 2021-04-16T11:26:44.427Z] 11:26:44     INFO -  raptor-benchmark Info: wrote wpt headers file: /home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/tests/webkit/PerformanceTests/__dir__.headers
[task 2021-04-16T11:26:44.427Z] 11:26:44     INFO -  raptor-benchmark Info: starting webserver on '127.0.0.1:36355'
[task 2021-04-16T11:26:44.427Z] 11:26:44     INFO -  raptor-benchmark Info: serving benchmarks from here: /home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/tests/webkit/PerformanceTests
[task 2021-04-16T11:26:44.427Z] 11:26:44    ERROR -  Traceback (most recent call last):
[task 2021-04-16T11:26:44.427Z] 11:26:44     INFO -    File "/home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/raptor.py", line 211, in <module>
[task 2021-04-16T11:26:44.427Z] 11:26:44     INFO -      main()
[task 2021-04-16T11:26:44.428Z] 11:26:44     INFO -    File "/home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/raptor.py", line 157, in main
[task 2021-04-16T11:26:44.428Z] 11:26:44     INFO -      success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2021-04-16T11:26:44.428Z] 11:26:44     INFO -    File "/home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/perftest.py", line 384, in run_tests
[task 2021-04-16T11:26:44.428Z] 11:26:44     INFO -      self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2021-04-16T11:26:44.428Z] 11:26:44     INFO -    File "/home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/browsertime/base.py", line 367, in run_test
[task 2021-04-16T11:26:44.428Z] 11:26:44     INFO -      self.run_test_setup(test)
[task 2021-04-16T11:26:44.428Z] 11:26:44     INFO -    File "/home/cltbld/tasks/task_161857236343392/build/tests/raptor/raptor/browsertime/base.py", line 152, in run_test_setup
[task 2021-04-16T11:26:44.429Z] 11:26:44     INFO -      "being tested: %s" % self.browsertime_chromedriver
[task 2021-04-16T11:26:44.429Z] 11:26:44     INFO -  Exception: Cannot find the chromedriver for the chrome version being tested: /home/cltbld/tasks/task_161857236343392/fetches/90chromedriver
[task 2021-04-16T11:26:44.469Z] 11:26:44    ERROR - Return code: 1
[task 2021-04-16T11:26:44.469Z] 11:26:44  WARNING - setting return code to 1
[task 2021-04-16T11:26:44.469Z] 11:26:44     INFO - Copying Raptor results to upload dir:
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - /home/cltbld/tasks/task_161857236343392/build/blobber_upload_dir/perfherder-data.json
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - Copying raptor results from /home/cltbld/tasks/task_161857236343392/build/raptor.json to /home/cltbld/tasks/task_161857236343392/build/blobber_upload_dir/perfherder-data.json
[task 2021-04-16T11:26:44.470Z] 11:26:44 CRITICAL - Error copying results /home/cltbld/tasks/task_161857236343392/build/raptor.json to upload dir /home/cltbld/tasks/task_161857236343392/build/blobber_upload_dir/perfherder-data.json
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - [Errno 2] No such file or directory: '/home/cltbld/tasks/task_161857236343392/build/raptor.json'
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - Running post-action listener: _package_coverage_data
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - Running post-action listener: _resource_record_post_action
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - Running post-action listener: process_java_coverage_data
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - Running post-action listener: stop_device
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - [mozharness: 2021-04-16 11:26:44.470818Z] Finished run-tests step (success)
[task 2021-04-16T11:26:44.470Z] 11:26:44     INFO - Running post-run listener: _resource_record_post_run
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - Total resource usage - Wall time: 9s; CPU: 13%; Read bytes: 90112; Write bytes: 1502392320; Read time: 64; Write time: 13652
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - TinderboxPrint: CPU usage<br/>12.6%
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - TinderboxPrint: I/O read bytes / time<br/>90,112 / 64
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - TinderboxPrint: I/O write bytes / time<br/>1,502,392,320 / 13,652
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - TinderboxPrint: CPU idle<br/>62.3 (86.6%)
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - TinderboxPrint: CPU user<br/>8.8 (12.2%)
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - install-chromium-distribution - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - install - Wall time: 9s; CPU: 13%; Read bytes: 90112; Write bytes: 1502392320; Read time: 64; Write time: 13652
[task 2021-04-16T11:26:44.510Z] 11:26:44     INFO - run-tests - Wall time: 1s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-04-16T11:26:44.513Z] 11:26:44  WARNING - returning nonzero exit status 1
[taskcluster 2021-04-16T11:26:44.535Z]    Exit Code: 1
[taskcluster 2021-04-16T11:26:44.535Z]    User Time: 40.635087s
[taskcluster 2021-04-16T11:26:44.535Z]  Kernel Time: 3.774708s
[taskcluster 2021-04-16T11:26:44.535Z]    Wall Time: 40.136151689s
[taskcluster 2021-04-16T11:26:44.535Z]       Result: FAILED
[taskcluster 2021-04-16T11:26:44.535Z] === Task Finished ===
[taskcluster 2021-04-16T11:26:44.535Z] Task Duration: 40.137229358s
[taskcluster 2021-04-16T11:26:44.575Z] Uploading artifact public/logs/localconfig.json from file logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2022-04-16T10:32:53.650Z
[taskcluster 2021-04-16T11:26:44.836Z] Uploading artifact public/test_info/resource-usage.json from file build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2022-04-16T10:32:53.650Z
[taskcluster 2021-04-16T11:26:44.968Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fBzb6bd8Q9KmsZ8mo9QcRw/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2022-04-16T10:32:53.650Z
[taskcluster:error] exit status 1```

Comment 1

[Greg Mierzwinski [:sparky]]

Attachment: Bug 1705713 - Add chromedriver for v90. r?#perftest

Comment 2

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/31b34d4ad188
Add chromedriver for v90. r=perftest-reviewers,davehunt

Comment 3

[Narcis Beleuzu [:NarcisB]]

https://hg.mozilla.org/mozilla-central/rev/31b34d4ad188

Comment 4

[Intermittent Failures Robot]

11 failures in 4255 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 11

Platform and build breakdown:

• linux1804-64-shippable: 7
  • opt: 7
• windows10-64-shippable: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1705713&startday=2021-04-12&endday=2021-04-18&tree=all