Closed Bug 1753511 Opened 4 years ago Closed 3 years ago

Intermittent [tier 2] raptor-browsertime Critical: Couldn't execute async script named toggle composition recorder error:JavascriptError: JavaScriptError: Document was unloaded | after raptor-browsertime Error: Browsertime failed to run

Categories

(Testing :: Raptor, defect, P5)

Product:
Testing
Component:
Raptor
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2022-02-03T20:13:28.365Z] 20:13:28     INFO -  raptor-browsertime Info: Cycle 1, waiting for 1000 ms
[task 2022-02-03T20:13:29.365Z] 20:13:29     INFO -  raptor-browsertime Info: Cycle 1, starting the measure
[task 2022-02-03T20:13:29.366Z] 20:13:29     INFO -  raptor-browsertime Info: Testing url http://www.espn.com/nba/story/_/page/allstarweekend25788027/the-comparison-lebron-james-michael-jordan-their-own-words iteration 21
[task 2022-02-03T20:13:29.366Z] 20:13:29     INFO -  raptor-browsertime Info: Start firefox window recorder.
[task 2022-02-03T20:13:36.382Z] 20:13:36     INFO -  raptor-browsertime Info: http://www.espn.com/nba/story/_/page/allstarweekend25788027/the-comparison-lebron-james-michael-jordan-their-own-words has been tested before within the same run, it will get an extra query parameter named browsertime_run. Make sure to use alias to keep track of the URLs
[task 2022-02-03T20:13:36.382Z] 20:13:36     INFO -  raptor-browsertime Info: Stop firefox window recorder.
[task 2022-02-03T20:13:38.440Z] 20:13:38    ERROR -  raptor-browsertime Error: Browsertime failed to run
[task 2022-02-03T20:13:38.460Z] 20:13:38 CRITICAL -  raptor-browsertime Critical: Couldn't execute async script named toggle composition recorder error:JavascriptError: JavaScriptError: Document was unloaded
[task 2022-02-03T20:13:38.461Z] 20:13:38     INFO -  raptor-mitmproxy Info: MitmproxyDesktop stop!!
[task 2022-02-03T20:13:38.461Z] 20:13:38     INFO -  raptor-mitmproxy Info: Mitmproxy stop!!
[task 2022-02-03T20:13:38.461Z] 20:13:38     INFO -  raptor-mitmproxy Info: Stopping mitmproxy playback, killing process 958
[task 2022-02-03T20:13:38.875Z] 20:13:38     INFO -  raptor-mitmproxy Info: Successfully killed the mitmproxy playback process
[task 2022-02-03T20:13:38.876Z] 20:13:38     INFO -  raptor-mitmproxy Info: Turning off the browser proxy
[task 2022-02-03T20:13:38.876Z] 20:13:38     INFO -  raptor-mitmproxy Info: writing: /opt/worker/tasks/task_164391804811782/build/application/Firefox Nightly.app/Contents/Resources/distribution/policies.json
[task 2022-02-03T20:13:38.876Z] 20:13:38     INFO -  raptor-perftest Info: Removing temporary directory: /var/folders/7d/n3tbrx9x3rz1yz0rb4m6j3mr000014/T/tmp3r6bxm1d
[task 2022-02-03T20:13:38.887Z] 20:13:38    ERROR -  Traceback (most recent call last):
[task 2022-02-03T20:13:38.888Z] 20:13:38     INFO -    File "/opt/worker/tasks/task_164391804811782/build/tests/raptor/raptor/raptor.py", line 215, in <module>
[task 2022-02-03T20:13:38.888Z] 20:13:38     INFO -      main()
[task 2022-02-03T20:13:38.888Z] 20:13:38     INFO -    File "/opt/worker/tasks/task_164391804811782/build/tests/raptor/raptor/raptor.py", line 161, in main
[task 2022-02-03T20:13:38.888Z] 20:13:38     INFO -      success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2022-02-03T20:13:38.889Z] 20:13:38     INFO -    File "/opt/worker/tasks/task_164391804811782/build/tests/raptor/raptor/perftest.py", line 450, in run_tests
[task 2022-02-03T20:13:38.889Z] 20:13:38     INFO -      self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2022-02-03T20:13:38.889Z] 20:13:38     INFO -    File "/opt/worker/tasks/task_164391804811782/build/tests/raptor/raptor/browsertime/base.py", line 555, in run_test
[task 2022-02-03T20:13:38.889Z] 20:13:38     INFO -      raise Exception(self.browsertime_failure)
[task 2022-02-03T20:13:38.890Z] 20:13:38     INFO -  Exception: Couldn't execute async script named toggle composition recorder error:JavascriptError: JavaScriptError: Document was unloaded
[task 2022-02-03T20:13:39.026Z] 20:13:39    ERROR - Return code: 1
[task 2022-02-03T20:13:39.026Z] 20:13:39  WARNING - setting return code to 1
[task 2022-02-03T20:13:39.026Z] 20:13:39     INFO - Copying Raptor results to upload dir:
[task 2022-02-03T20:13:39.027Z] 20:13:39     INFO - /opt/worker/tasks/task_164391804811782/build/blobber_upload_dir/perfherder-data.json
[task 2022-02-03T20:13:39.027Z] 20:13:39     INFO - Copying raptor results from /opt/worker/tasks/task_164391804811782/build/raptor.json to /opt/worker/tasks/task_164391804811782/build/blobber_upload_dir/perfherder-data.json
[task 2022-02-03T20:13:39.027Z] 20:13:39 CRITICAL - Error copying results /opt/worker/tasks/task_164391804811782/build/raptor.json to upload dir /opt/worker/tasks/task_164391804811782/build/blobber_upload_dir/perfherder-data.json
[task 2022-02-03T20:13:39.027Z] 20:13:39     INFO - [Errno 2] No such file or directory: '/opt/worker/tasks/task_164391804811782/build/raptor.json'
[task 2022-02-03T20:13:39.027Z] 20:13:39     INFO - Running post-action listener: _package_coverage_data
[task 2022-02-03T20:13:39.027Z] 20:13:39     INFO - Running post-action listener: _resource_record_post_action
[task 2022-02-03T20:13:39.028Z] 20:13:39     INFO - Running post-action listener: process_java_coverage_data
[task 2022-02-03T20:13:39.028Z] 20:13:39     INFO - Running post-action listener: stop_device
[task 2022-02-03T20:13:39.028Z] 20:13:39     INFO - [mozharness: 2022-02-03 20:13:39.028118Z] Finished run-tests step (success)
[task 2022-02-03T20:13:39.028Z] 20:13:39     INFO - Running post-run listener: _resource_record_post_run
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - Total resource usage - Wall time: 1112s; CPU: 7%; Read bytes: 159436800; Write bytes: 3198963712; Read time: 2123; Write time: 7682
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - TinderboxPrint: CPU usage<br/>7.1%
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - TinderboxPrint: I/O read bytes / time<br/>159,436,800 / 2,123
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - TinderboxPrint: I/O write bytes / time<br/>3,198,963,712 / 7,682
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - TinderboxPrint: CPU idle<br/>11,843.5 (88.7%)
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - TinderboxPrint: CPU system<br/>515.4 (3.9%)
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - TinderboxPrint: CPU user<br/>989.9 (7.4%)
[task 2022-02-03T20:13:39.155Z] 20:13:39     INFO - TinderboxPrint: Swap in / out<br/>717,127,680 / 0
[task 2022-02-03T20:13:39.155Z] 20:13:39     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-02-03T20:13:39.156Z] 20:13:39     INFO - install - Wall time: 33s; CPU: 14%; Read bytes: 433153536; Write bytes: 437436416; Read time: 29169; Write time: 775
[task 2022-02-03T20:13:39.170Z] 20:13:39     INFO - run-tests - Wall time: 1080s; CPU: 7%; Read bytes: 155455488; Write bytes: 2748649472; Read time: 1930; Write time: 6890
[task 2022-02-03T20:13:39.430Z] 20:13:39  WARNING - returning nonzero exit status 1
[taskcluster 2022-02-03T20:13:39.461Z]    Exit Code: 1

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=382882956&repo=mozilla-central

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

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