Closed Bug 1804864 Opened 3 years ago Closed 3 years ago

Intermittent [tier 2] raptor-browsertime Critical: InvalidArgumentError: Could not parse requested URL 'data:text/html;charset=utf-8,<html><body></body></html>'

Categories

(Testing :: Raptor, defect, P5)

defect

Tracking

(firefox-esr102 unaffected, firefox107 unaffected, firefox108 unaffected, firefox109 wontfix, firefox110 wontfix)

RESOLVED INCOMPLETE
Tracking Status
firefox-esr102 --- unaffected
firefox107 --- unaffected
firefox108 --- unaffected
firefox109 --- wontfix
firefox110 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

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


[task 2022-12-09T10:49:20.128Z] 10:49:20     INFO -  raptor-browsertime-desktop Info: binary_path: /Applications/Safari.app/Contents/MacOS/Safari
[task 2022-12-09T10:49:20.128Z] 10:49:20     INFO -  raptor-browsertime Info: timeout (s): 55000
[task 2022-12-09T10:49:20.129Z] 10:49:20     INFO -  raptor-browsertime Info: browsertime cwd: /opt/worker/tasks/task_167058285647151/build
[task 2022-12-09T10:49:20.130Z] 10:49:20     INFO -  raptor-browsertime Info: browsertime cmd: /opt/worker/tasks/task_167058285647151/fetches/node/bin/node /opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/browsertime/bin/browsertime.js --firefox.geckodriverPath /opt/worker/tasks/task_167058285647151/fetches/geckodriver /opt/worker/tasks/task_167058285647151/build/tests/raptor/raptor/browsertime/../../browsertime/browsertime_benchmark.js --firefox.noDefaultPrefs --browsertime.page_cycle_delay 1000 --skipHar --pageLoadStrategy none --webdriverPageload true --firefox.disableBrowsertimeExtension true --pageCompleteCheckStartWait 5000 --pageCompleteCheckPollTimeout 1000 --timeouts.pageLoad 55000 --timeouts.script 275000 --browsertime.page_cycles 5 --pageCompleteWaitTime 5000 --browsertime.url http://127.0.0.1:49264/SunSpider/sunspider-1.0.1/sunspider-1.0.1/driver.html?raptor --browsertime.post_startup_delay 1000 --iterations 1 --videoParams.androidVideoWaitTime 20000 --browsertime.chimera false --browsertime.test_bytecode_cache false --firefox.perfStats false --browsertime.commands  --viewPort 1280x1024 --browser safari --firefox.binaryPath /Applications/Safari.app/Contents/MacOS/Safari --resultDir /opt/worker/tasks/task_167058285647151/build/blobber_upload_dir/browsertime-results/sunspider --video false --visualMetrics false
[task 2022-12-09T10:49:20.130Z] 10:49:20     INFO -  raptor-browsertime Info: PATH: /opt/worker/tasks/task_167058285647151/build/venv/bin:/usr/local/bin:/usr/bin:/bin:/usr/sbin:/sbin
[task 2022-12-09T10:49:20.724Z] 10:49:20     INFO -  raptor-browsertime Info: Running tests using Safari - 1 iteration(s)
[task 2022-12-09T10:49:21.699Z] 10:49:21    ERROR -  raptor-browsertime Error: Browsertime failed to run
[task 2022-12-09T10:49:21.723Z] 10:49:21     INFO -  raptor-browsertime Info:     at Object.throwDecodedError (/opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/selenium-webdriver/lib/error.js:522:15)
[task 2022-12-09T10:49:21.724Z] 10:49:21     INFO -  raptor-browsertime Info:     at parseHttpResponse (/opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/selenium-webdriver/lib/http.js:589:13)
[task 2022-12-09T10:49:21.724Z] 10:49:21     INFO -  raptor-browsertime Info:     at Executor.execute (/opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/selenium-webdriver/lib/http.js:514:28)
[task 2022-12-09T10:49:21.724Z] 10:49:21     INFO -  raptor-browsertime Info:     at processTicksAndRejections (node:internal/process/task_queues:96:5)
[task 2022-12-09T10:49:21.725Z] 10:49:21     INFO -  raptor-browsertime Info:     at async Driver.execute (/opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/selenium-webdriver/lib/webdriver.js:740:17)
[task 2022-12-09T10:49:21.725Z] 10:49:21     INFO -  raptor-browsertime Info:     at async Iteration.run (/opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/browsertime/lib/core/engine/iteration.js:202:9)
[task 2022-12-09T10:49:21.725Z] 10:49:21     INFO -  raptor-browsertime Info:     at async Engine.runByScript (/opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/browsertime/lib/core/engine/index.js:274:20)
[task 2022-12-09T10:49:21.726Z] 10:49:21     INFO -  raptor-browsertime Info:     at async run (/opt/worker/tasks/task_167058285647151/fetches/browsertime/node_modules/browsertime/bin/browsertime.js:104:22)
[task 2022-12-09T10:49:21.726Z] 10:49:21 CRITICAL -  raptor-browsertime Critical: InvalidArgumentError: Could not parse requested URL 'data:text/html;charset=utf-8,<html><body></body></html>'
[task 2022-12-09T10:49:21.726Z] 10:49:21    ERROR -  Traceback (most recent call last):
[task 2022-12-09T10:49:21.726Z] 10:49:21     INFO -    File "/opt/worker/tasks/task_167058285647151/build/tests/raptor/raptor/raptor.py", line 206, in <module>
[task 2022-12-09T10:49:21.726Z] 10:49:21     INFO -      main()
[task 2022-12-09T10:49:21.727Z] 10:49:21     INFO -    File "/opt/worker/tasks/task_167058285647151/build/tests/raptor/raptor/raptor.py", line 152, in main
[task 2022-12-09T10:49:21.727Z] 10:49:21     INFO -      success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2022-12-09T10:49:21.727Z] 10:49:21     INFO -    File "/opt/worker/tasks/task_167058285647151/build/tests/raptor/raptor/perftest.py", line 471, in run_tests
[task 2022-12-09T10:49:21.728Z] 10:49:21     INFO -      self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2022-12-09T10:49:21.728Z] 10:49:21     INFO -    File "/opt/worker/tasks/task_167058285647151/build/tests/raptor/raptor/browsertime/base.py", line 802, in run_test
[task 2022-12-09T10:49:21.728Z] 10:49:21     INFO -      raise Exception(self.browsertime_failure)
[task 2022-12-09T10:49:21.728Z] 10:49:21     INFO -  Exception: InvalidArgumentError: Could not parse requested URL 'data:text/html;charset=utf-8,<html><body></body></html>'
[task 2022-12-09T10:49:21.795Z] 10:49:21    ERROR - Return code: 1
[task 2022-12-09T10:49:21.795Z] 10:49:21  WARNING - setting return code to 1
[task 2022-12-09T10:49:21.795Z] 10:49:21     INFO - Copying Raptor results to upload dir:
[task 2022-12-09T10:49:21.795Z] 10:49:21     INFO - /opt/worker/tasks/task_167058285647151/build/blobber_upload_dir/perfherder-data.json
[task 2022-12-09T10:49:21.795Z] 10:49:21     INFO - Copying raptor results from /opt/worker/tasks/task_167058285647151/build/raptor.json to /opt/worker/tasks/task_167058285647151/build/blobber_upload_dir/perfherder-data.json
[task 2022-12-09T10:49:21.796Z] 10:49:21 CRITICAL - Error copying results /opt/worker/tasks/task_167058285647151/build/raptor.json to upload dir /opt/worker/tasks/task_167058285647151/build/blobber_upload_dir/perfherder-data.json
[task 2022-12-09T10:49:21.796Z] 10:49:21     INFO - [Errno 2] No such file or directory: '/opt/worker/tasks/task_167058285647151/build/raptor.json'
[task 2022-12-09T10:49:21.797Z] 10:49:21     INFO - Running post-action listener: _package_coverage_data
[task 2022-12-09T10:49:21.797Z] 10:49:21     INFO - Running post-action listener: _resource_record_post_action
[task 2022-12-09T10:49:21.797Z] 10:49:21     INFO - Running post-action listener: process_java_coverage_data
[task 2022-12-09T10:49:21.797Z] 10:49:21     INFO - Running post-action listener: stop_device
[task 2022-12-09T10:49:21.797Z] 10:49:21     INFO - [mozharness: 2022-12-09 10:49:21.797289Z] Finished run-tests step (success)
[task 2022-12-09T10:49:21.797Z] 10:49:21     INFO - Running post-run listener: _resource_record_post_run
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - Total resource usage - Wall time: 36s; CPU: 13%; Read bytes: 30961664; Write bytes: 465424384; Read time: 848; Write time: 1037
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - TinderboxPrint: CPU usage<br/>13.5%
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - TinderboxPrint: I/O read bytes / time<br/>30,961,664 / 848
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - TinderboxPrint: I/O write bytes / time<br/>465,424,384 / 1,037
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - TinderboxPrint: CPU idle<br/>377.6 (86.5%)
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - TinderboxPrint: CPU system<br/>6.1 (1.4%)
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - TinderboxPrint: CPU user<br/>52.8 (12.1%)
[task 2022-12-09T10:49:21.859Z] 10:49:21     INFO - TinderboxPrint: Swap in / out<br/>491,106,304 / 0
[task 2022-12-09T10:49:21.859Z] 10:49:21     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 2022-12-09T10:49:21.860Z] 10:49:21     INFO - install - Wall time: 34s; CPU: 14%; Read bytes: 434304512; Write bytes: 432373760; Read time: 29026; Write time: 840
[task 2022-12-09T10:49:21.860Z] 10:49:21     INFO - run-tests - Wall time: 2s; CPU: 12%; Read bytes: 28340224; Write bytes: 659456; Read time: 799; Write time: 141
[task 2022-12-09T10:49:21.870Z] 10:49:21  WARNING - returning nonzero exit status 1
[taskcluster 2022-12-09T10:49:21.896Z]    Exit Code: 1
[taskcluster 2022-12-09T10:49:21.896Z]    User Time: 1m9.027161s
[taskcluster 2022-12-09T10:49:21.896Z]  Kernel Time: 15.097553s
[taskcluster 2022-12-09T10:49:21.896Z]    Wall Time: 1m39.45139s
[taskcluster 2022-12-09T10:49:21.896Z]       Result: FAILED
[taskcluster 2022-12-09T10:49:21.897Z] === Task Finished ===
[taskcluster 2022-12-09T10:49:21.897Z] Task Duration: 1m39.454903s
[taskcluster 2022-12-09T10:49:22.006Z] Uploading artifact public/logs/localconfig.json from file logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2023-03-09T10:47:33.183Z
[taskcluster 2022-12-09T10:49:22.259Z] 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 2023-03-09T10:47:33.183Z
[taskcluster 2022-12-09T10:49:22.493Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CNYuEjV9RrOYuYbgQxcyAQ/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2023-03-09T10:47:33.183Z
[taskcluster:error] exit status 1

Set release status flags based on info from the regressing bug 1803792

:kshampur, since you are the author of the regressor, bug 1803792, could you take a look?

For more information, please visit auto_nag documentation.

Flags: needinfo?(kshampur)
Keywords: regression

I see in the retriggers for the same job passed e.g.: LINK. So is this actually OK?

Flags: needinfo?(kshampur) → needinfo?(aryx.bugmail)
See Also: → 1802922

It's an intermittent failure - lets watch the weekly Intermittent Bug Report if it will be frequent.

Flags: needinfo?(aryx.bugmail)

Set release status flags based on info from the regressing bug 1803792

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.