Closed Bug 1928631 Opened 1 month ago Closed 16 days ago

Intermittent raptor-browsertime Critical: [chrome] Browsertime process timed out after 950 seconds

Categories

(Testing :: Raptor, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2024-11-01T11:00:49.573Z] 11:00:49     INFO -  raptor-browsertime Info: Waiting 3000 ms for data from HTML suite...
[task 2024-11-01T11:00:52.103Z] 11:00:52     INFO -  TEST-INFO | screencapture: exit 0
[task 2024-11-01T11:00:52.103Z] 11:00:52 CRITICAL -  raptor-browsertime Critical: [chrome] Browsertime process timed out after 950 seconds
[task 2024-11-01T11:00:52.111Z] 11:00:52     INFO -  raptor-browsertime Info: Test support class is cleaning up...
[task 2024-11-01T11:00:52.112Z] 11:00:52    ERROR -  Traceback (most recent call last):
[task 2024-11-01T11:00:52.112Z] 11:00:52     INFO -    File "/opt/worker/tasks/task_173045786315559/build/tests/raptor/raptor/raptor.py", line 189, in <module>
[task 2024-11-01T11:00:52.112Z] 11:00:52     INFO -      main()
[task 2024-11-01T11:00:52.112Z] 11:00:52     INFO -    File "/opt/worker/tasks/task_173045786315559/build/tests/raptor/raptor/raptor.py", line 138, in main
[task 2024-11-01T11:00:52.112Z] 11:00:52     INFO -      success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2024-11-01T11:00:52.113Z] 11:00:52     INFO -                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2024-11-01T11:00:52.113Z] 11:00:52     INFO -    File "/opt/worker/tasks/task_173045786315559/build/tests/raptor/raptor/perftest.py", line 501, in run_tests
[task 2024-11-01T11:00:52.113Z] 11:00:52     INFO -      self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2024-11-01T11:00:52.113Z] 11:00:52     INFO -    File "/opt/worker/tasks/task_173045786315559/build/tests/raptor/raptor/browsertime/base.py", line 1089, in run_test
[task 2024-11-01T11:00:52.113Z] 11:00:52     INFO -      raise Exception(
[task 2024-11-01T11:00:52.113Z] 11:00:52     INFO -  Exception: Browsertime process timed out after 950 seconds
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Return code: 1
[task 2024-11-01T11:00:52.137Z] 11:00:52  WARNING - setting return code to 1
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Copying Raptor results to upload dir:
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - /opt/worker/tasks/task_173045786315559/build/blobber_upload_dir/perfherder-data.json
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Copying raptor results from /opt/worker/tasks/task_173045786315559/build/raptor.json to /opt/worker/tasks/task_173045786315559/build/blobber_upload_dir/perfherder-data.json
[task 2024-11-01T11:00:52.137Z] 11:00:52 CRITICAL - Error copying results /opt/worker/tasks/task_173045786315559/build/raptor.json to upload dir /opt/worker/tasks/task_173045786315559/build/blobber_upload_dir/perfherder-data.json
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - [Errno 2] No such file or directory: '/opt/worker/tasks/task_173045786315559/build/raptor.json'
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Running post-action listener: _package_coverage_data
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Running post-action listener: _resource_record_post_action
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Running post-action listener: process_java_coverage_data
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Running post-action listener: stop_device
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - [mozharness: 2024-11-01 11:00:52.137692Z] Finished run-tests step (success)
[task 2024-11-01T11:00:52.137Z] 11:00:52     INFO - Running post-run listener: _resource_record_post_run
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - Total resource usage - Wall time: 951s; CPU: 8%; Read bytes: 1840676864; Write bytes: 387915776; Read time: 84534; Write time: 817
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - TinderboxPrint: CPU usage<br/>7.7%
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - TinderboxPrint: I/O read bytes / time<br/>1,840,676,864 / 84,534
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - TinderboxPrint: I/O write bytes / time<br/>387,915,776 / 817
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - TinderboxPrint: CPU idle<br/>7,002.1 (92.3%)
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - TinderboxPrint: CPU system<br/>262.9 (3.5%)
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - TinderboxPrint: CPU user<br/>324.3 (4.3%)
[task 2024-11-01T11:00:52.340Z] 11:00:52     INFO - TinderboxPrint: Swap in / out<br/>1,279,574,016 / 9,306,112
[task 2024-11-01T11:00:52.341Z] 11:00:52     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-11-01T11:00:52.342Z] 11:00:52     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-11-01T11:00:52.362Z] 11:00:52     INFO - run-tests - Wall time: 951s; CPU: 8%; Read bytes: 1840676864; Write bytes: 387915776; Read time: 84534; Write time: 817
[task 2024-11-01T11:00:53.070Z] 11:00:53  WARNING - returning nonzero exit status 1
[taskcluster 2024-11-01T11:00:53.116Z]    Exit Code: 1
[taskcluster 2024-11-01T11:00:53.116Z]    User Time: 24.965339s
[taskcluster 2024-11-01T11:00:53.116Z]  Kernel Time: 12.951439s
[taskcluster 2024-11-01T11:00:53.116Z]    Wall Time: 16m29.196503s
[taskcluster 2024-11-01T11:00:53.116Z]       Result: FAILED
[taskcluster 2024-11-01T11:00:53.116Z] === Task Finished ===
[taskcluster 2024-11-01T11:00:53.116Z] Task Duration: 16m29.207496s
[taskcluster 2024-11-01T11:00:53.317Z] Uploading artifact public/logs/localconfig.json from file /opt/worker/tasks/task_173045786315559/logs/localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2025-01-30T10:34:12.312Z
[taskcluster 2024-11-01T11:00:53.651Z] Uploading artifact public/test_info/mozilla-test-fail-screenshot_0re0zlho.png from file /opt/worker/tasks/task_173045786315559/build/blobber_upload_dir/mozilla-test-fail-screenshot_0re0zlho.png with content encoding "identity", mime type "image/png" and expiry 2025-01-30T10:34:12.312Z
[taskcluster 2024-11-01T11:00:54.009Z] Uploading artifact public/test_info/profile_resource-usage.json from file /opt/worker/tasks/task_173045786315559/build/blobber_upload_dir/profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-01-30T10:34:12.312Z
[taskcluster 2024-11-01T11:00:54.411Z] Uploading artifact public/test_info/resource-usage.json from file /opt/worker/tasks/task_173045786315559/build/blobber_upload_dir/resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2025-01-30T10:34:12.312Z
[taskcluster 2024-11-01T11:00:54.890Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-01-30T10:34:12.312Z
[taskcluster:error] exit status 1
Status: NEW → RESOLVED
Closed: 16 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.