Closed
Bug 1909634
Opened 1 year ago
Closed 1 year ago
Intermittent [tier 2] raptor-browsertime Critical: [chrome] Couldn't execute script named CUSTOM error:JavascriptError: javascript error: Reduce of empty array with no initial value
Categories
(Testing :: Raptor, defect, P5)
Testing
Raptor
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=467697215&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XBz157sLRiO5u1RqxvWkaQ/runs/0/artifacts/public/logs/live_backing.log
[task 2024-07-24T11:24:11.689Z] 11:24:11 INFO - git switch -
[task 2024-07-24T11:24:11.689Z] 11:24:11 INFO - Turn off this advice by setting config variable advice.detachedHead to false
[task 2024-07-24T11:24:11.689Z] 11:24:11 INFO - HEAD is now at 5d9c881 Add more changes to the 1.3 change list in the about page
[task 2024-07-24T11:24:11.689Z] 11:24:11 INFO - raptor-benchmark Info: bench_dir: /opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark
[task 2024-07-24T11:24:11.689Z] 11:24:11 INFO - raptor-benchmark Info: bench_dir contains:
[task 2024-07-24T11:24:11.690Z] 11:24:11 INFO - raptor-benchmark Info: [PosixPath('/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark/index.html'), PosixPath('/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark/LICENSE'), PosixPath('/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark/Governance.md'), PosixPath('/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark/README.md'), PosixPath('/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark/.gitignore'), PosixPath('/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark/MotionMark'), PosixPath('/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark/.git')]
[task 2024-07-24T11:24:11.690Z] 11:24:11 INFO - raptor-benchmark Info: starting webserver on '127.0.0.1:49232'
[task 2024-07-24T11:24:11.690Z] 11:24:11 INFO - raptor-benchmark Info: serving benchmarks from here: /opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/webkit/PerformanceTests/motionmark-1-3/motionmark
[task 2024-07-24T11:24:11.691Z] 11:24:11 INFO - raptor-browsertime Info: test: {'here': '/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/benchmarks', 'alert_threshold': '2.0', 'apps': 'firefox, chrome, safari, custom-car', 'gecko_profile_interval': '1', 'gecko_profile_features': 'stackwalk,js,cpu,java,processcpu,memory', 'gecko_profile_threads': 'GeckoMain,Compositor,Renderer,SwComposite,RenderBackend,SceneBuilder,WrWorker,CanvasWorkers,TextureUpdate', 'expose_browser_profiler': True, 'expose_chrome_trace': 'true', 'lower_is_better': False, 'page_cycles': 1, 'page_timeout': 900000, 'support_class': <motionmark-1-3.py.MotionMarkSupport object at 0x106dcae50>, 'test_script': 'motionmark-1-3.js', 'owner': 'Graphics Team', 'type': 'benchmark', 'unit': 'score', 'repository': 'https://github.com/webkit/motionmark', 'repository_revision': '5d9c88136d59c11daf78d539c73e4e3e88c091ab', 'test_url': 'http://127.0.0.1:49232/MotionMark/developer.html?warmup-length=2000&warmup-frame-count=30&first-frame-minimum-length=0&test-interval=30&display=minimal&tiles=big&controller=ramp&system-frame-rate=60&frame-rate=60&time-measurement=performance', 'cold': True, 'browser_cycles': '1', 'host_from_parent': False, 'suite_name': 'MotionMark', 'name': 'motionmark-1-3', 'manifest': '/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/benchmarks/motionmark-1-3-desktop.toml', 'manifest_relpath': 'tests/benchmarks/motionmark-1-3-desktop.toml', 'path': '/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/tests/benchmarks/motionmark-1-3', 'relpath': 'tests/benchmarks/motionmark-1-3', 'ancestor_manifest': 'raptor.toml', 'expected': 'pass', 'extra_profiler_run': True, 'extra_profiler_run_browser_cycles': 1, 'extra_profiler_run_page_cycles': 1, 'expected_browser_cycles': 1, 'browser_cycle': 1}
[task 2024-07-24T11:24:11.691Z] 11:24:11 INFO - raptor-browsertime-desktop Info: binary_path: /Applications/Google Chrome.app/Contents/MacOS/Google Chrome
[task 2024-07-24T11:24:11.691Z] 11:24:11 INFO - raptor-browsertime Info: Test support class is modifying the command...
[task 2024-07-24T11:24:11.691Z] 11:24:11 INFO - raptor-browsertime Info: timeout (s): 900000
[task 2024-07-24T11:24:11.691Z] 11:24:11 INFO - raptor-browsertime Info: browsertime cwd: /opt/worker/tasks/task_172182021706760/build
[task 2024-07-24T11:24:11.692Z] 11:24:11 INFO - raptor-browsertime Info: browsertime cmd: /opt/worker/tasks/task_172182021706760/fetches/node/bin/node /opt/worker/tasks/task_172182021706760/fetches/browsertime/node_modules/browsertime/bin/browsertime.js --firefox.geckodriverPath /opt/worker/tasks/task_172182021706760/fetches/geckodriver --chrome.chromedriverPath /opt/worker/tasks/task_172182021706760/fetches/126chromedriver-mac-arm64/chromedriver /opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/browsertime/../../browsertime/motionmark-1-3.js --firefox.noDefaultPrefs --browsertime.page_cycle_delay 1000 --skipHar --pageLoadStrategy none --webdriverPageload true --firefox.disableBrowsertimeExtension true --pageCompleteCheckStartWait 5000 --pageCompleteCheckPollTimeout 1000 --beforePageCompleteWaitTime 2000 --timeouts.pageLoad 900000 --timeouts.script 900000 --browsertime.page_cycles 1 --pageCompleteWaitTime 5000 --browsertime.url http://127.0.0.1:49232/MotionMark/developer.html?warmup-length=2000&warmup-frame-count=30&first-frame-minimum-length=0&test-interval=30&display=minimal&tiles=big&controller=ramp&system-frame-rate=60&frame-rate=60&time-measurement=performance --browsertime.post_startup_delay 30000 --iterations 1 --browsertime.chimera false --browsertime.test_bytecode_cache false --firefox.perfStats false --browsertime.moz_fetch_dir /opt/worker/tasks/task_172182021706760/fetches --browsertime.expose_profiler false --browsertime.commands --viewPort 1280x1024 --browser chrome --chrome.binaryPath /Applications/Google Chrome.app/Contents/MacOS/Google Chrome --chrome.args=--use-mock-keychain --chrome.args=--no-default-browser-check --chrome.args=--no-first-run --chrome.args=--no-experiments --chrome.args=--disable-site-isolation-trials --chrome.args=--enable-benchmarking --resultDir /opt/worker/tasks/task_172182021706760/build/blobber_upload_dir/browsertime-results/motionmark-1-3 --video false --visualMetrics false --browsertime.suite_name MotionMark
[task 2024-07-24T11:24:11.692Z] 11:24:11 INFO - raptor-browsertime Info: PATH: /opt/worker/tasks/task_172182021706760/build/venv/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin
[task 2024-07-24T11:24:11.692Z] 11:24:11 INFO - raptor-browsertime Info: Calling browsertime with proc_timeout=950, and output_timeout=None
[task 2024-07-24T11:24:12.319Z] 11:24:12 INFO - raptor-browsertime Info: Running tests using Chrome - 1 iteration(s)
[task 2024-07-24T11:24:14.307Z] 11:24:14 INFO - raptor-browsertime Info: Starting MotionMark 1.3 test
[task 2024-07-24T11:24:14.308Z] 11:24:14 INFO - raptor-browsertime Info: Waiting for 30000 ms (post_startup_delay)
[task 2024-07-24T11:24:44.310Z] 11:24:44 INFO - raptor-browsertime Info: Navigating to about:blank
[task 2024-07-24T11:24:44.311Z] 11:24:44 INFO - raptor-browsertime Info: Navigating to url about:blank iteration 1
[task 2024-07-24T11:24:49.362Z] 11:24:49 INFO - raptor-browsertime Info: Cycle 0, waiting for 1000 ms
[task 2024-07-24T11:24:50.363Z] 11:24:50 INFO - raptor-browsertime Info: Cycle 0, starting the measure
[task 2024-07-24T11:24:50.365Z] 11:24:50 INFO - raptor-browsertime Info: Testing url http://127.0.0.1:49232/MotionMark/developer.html?warmup-length=2000&warmup-frame-count=30&first-frame-minimum-length=0&test-interval=30&display=minimal&tiles=big&controller=ramp&system-frame-rate=60&frame-rate=60&time-measurement=performance iteration 1
[task 2024-07-24T11:24:58.694Z] 11:24:58 INFO - raptor-browsertime Info: Waiting 3000 ms for data from MotionMark...
[task 2024-07-24T11:25:01.703Z] 11:25:01 INFO - raptor-browsertime Info: Waiting 3000 ms for data from MotionMark...
[task 2024-07-24T11:25:04.822Z] 11:25:04 INFO - raptor-browsertime Info: Waiting 3000 ms for data from MotionMark...
<...>
[task 2024-07-24T11:39:50.955Z] 11:39:50 INFO - raptor-browsertime Info: Waiting 3000 ms for data from MotionMark...
[task 2024-07-24T11:39:53.965Z] 11:39:53 INFO - raptor-browsertime Info: Waiting 3000 ms for data from MotionMark...
[task 2024-07-24T11:39:56.975Z] 11:39:56 INFO - raptor-browsertime Info: Waiting 3000 ms for data from MotionMark...
[task 2024-07-24T11:39:59.992Z] 11:39:59 ERROR - raptor-browsertime Error: [chrome] Browsertime failed to run
[task 2024-07-24T11:39:59.992Z] 11:39:59 INFO - raptor-browsertime Info: (Session info: chrome=126.0.6478.183)
[task 2024-07-24T11:40:00.143Z] 11:40:00 INFO - TEST-INFO | screencapture: exit 0
[task 2024-07-24T11:40:00.143Z] 11:40:00 CRITICAL - raptor-browsertime Critical: [chrome] Couldn't execute script named CUSTOM error:JavascriptError: javascript error: Reduce of empty array with no initial value
[task 2024-07-24T11:40:00.193Z] 11:40:00 ERROR - Traceback (most recent call last):
[task 2024-07-24T11:40:00.193Z] 11:40:00 INFO - File "/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/raptor.py", line 189, in <module>
[task 2024-07-24T11:40:00.193Z] 11:40:00 INFO - main()
[task 2024-07-24T11:40:00.194Z] 11:40:00 INFO - File "/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/raptor.py", line 138, in main
[task 2024-07-24T11:40:00.194Z] 11:40:00 INFO - success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2024-07-24T11:40:00.194Z] 11:40:00 INFO - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-07-24T11:40:00.194Z] 11:40:00 INFO - File "/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/perftest.py", line 501, in run_tests
[task 2024-07-24T11:40:00.194Z] 11:40:00 INFO - self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2024-07-24T11:40:00.194Z] 11:40:00 INFO - File "/opt/worker/tasks/task_172182021706760/build/tests/raptor/raptor/browsertime/base.py", line 1070, in run_test
[task 2024-07-24T11:40:00.194Z] 11:40:00 INFO - raise Exception(self.browsertime_failure)
[task 2024-07-24T11:40:00.195Z] 11:40:00 INFO - Exception: Couldn't execute script named CUSTOM error:JavascriptError: javascript error: Reduce of empty array with no initial value
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Return code: 1
[task 2024-07-24T11:40:00.221Z] 11:40:00 WARNING - setting return code to 1
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Copying Raptor results to upload dir:
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - /opt/worker/tasks/task_172182021706760/build/blobber_upload_dir/perfherder-data.json
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Copying raptor results from /opt/worker/tasks/task_172182021706760/build/raptor.json to /opt/worker/tasks/task_172182021706760/build/blobber_upload_dir/perfherder-data.json
[task 2024-07-24T11:40:00.221Z] 11:40:00 CRITICAL - Error copying results /opt/worker/tasks/task_172182021706760/build/raptor.json to upload dir /opt/worker/tasks/task_172182021706760/build/blobber_upload_dir/perfherder-data.json
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - [Errno 2] No such file or directory: '/opt/worker/tasks/task_172182021706760/build/raptor.json'
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Running post-action listener: _package_coverage_data
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Running post-action listener: _resource_record_post_action
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Running post-action listener: process_java_coverage_data
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Running post-action listener: stop_device
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - [mozharness: 2024-07-24 11:40:00.221757Z] Finished run-tests step (success)
[task 2024-07-24T11:40:00.221Z] 11:40:00 INFO - Running post-run listener: _resource_record_post_run
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - Total resource usage - Wall time: 950s; CPU: 8%; Read bytes: 769441792; Write bytes: 141647872; Read time: 13317; Write time: 599
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - TinderboxPrint: CPU usage<br/>8.3%
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - TinderboxPrint: I/O read bytes / time<br/>769,441,792 / 13,317
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - TinderboxPrint: I/O write bytes / time<br/>141,647,872 / 599
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - TinderboxPrint: CPU idle<br/>6,940.5 (91.6%)
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - TinderboxPrint: CPU system<br/>129.6 (1.7%)
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - TinderboxPrint: CPU user<br/>506.1 (6.7%)
[task 2024-07-24T11:40:00.408Z] 11:40:00 INFO - TinderboxPrint: Swap in / out<br/>768,688,128 / 5,750,784
[task 2024-07-24T11:40:00.409Z] 11:40:00 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 2024-07-24T11:40:00.410Z] 11:40:00 INFO - install-safari-technology-preview - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-07-24T11:40:00.430Z] 11:40:00 INFO - run-tests - Wall time: 950s; CPU: 8%; Read bytes: 769441792; Write bytes: 141647872; Read time: 13317; Write time: 599
[task 2024-07-24T11:40:01.134Z] 11:40:01 WARNING - returning nonzero exit status 1
[taskcluster 2024-07-24T11:40:01.179Z] Exit Code: 1
[taskcluster 2024-07-24T11:40:01.179Z] User Time: 23.60708s
[taskcluster 2024-07-24T11:40:01.179Z] Kernel Time: 12.024722s
[taskcluster 2024-07-24T11:40:01.179Z] Wall Time: 16m23.296831s
[taskcluster 2024-07-24T11:40:01.179Z] Result: FAILED
[taskcluster 2024-07-24T11:40:01.179Z] === Task Finished ===
[taskcluster 2024-07-24T11:40:01.179Z] Task Duration: 16m23.29936s
[taskcluster 2024-07-24T11:40:01.331Z] Uploading artifact public/logs/localconfig.json from file /opt/worker/tasks/task_172182021706760/logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2024-10-22T10:33:39.674Z
[taskcluster 2024-07-24T11:40:01.664Z] Uploading artifact public/test_info/mozilla-test-fail-screenshot_7aelhffd.png from file /opt/worker/tasks/task_172182021706760/build/blobber_upload_dir/mozilla-test-fail-screenshot_7aelhffd.png with content encoding "identity", mime type "image/png" and expiry 2024-10-22T10:33:39.674Z
[taskcluster 2024-07-24T11:40:02.041Z] Uploading artifact public/test_info/profile_resource-usage.json from file /opt/worker/tasks/task_172182021706760/build/blobber_upload_dir/profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2024-10-22T10:33:39.674Z
[taskcluster 2024-07-24T11:40:02.335Z] Uploading artifact public/test_info/resource-usage.json from file /opt/worker/tasks/task_172182021706760/build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2024-10-22T10:33:39.674Z
[taskcluster 2024-07-24T11:40:02.765Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2024-10-22T10:33:39.674Z
[taskcluster:error] exit status 1
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•