Open Bug 1988298 Opened 4 months ago Updated 14 hours ago

Intermittent Btime [tier 2] subprocess.CalledProcessError: Command '['git', 'clone', '--depth', '1', '--filter', 'blob:none', '--sparse', 'https://github.com/mozilla/perf-automation '<...> returned non-zero exit status 128.

Categories

(Testing :: Raptor, defect, P5)

Product:
Testing
Component:
Raptor
Type:
defect

Tracking

(Not tracked)

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=526741965&repo=autoland&task=IfhdBqtxQyaHyUAQPPyUcw.0
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IfhdBqtxQyaHyUAQPPyUcw/runs/0/artifacts/public/logs/live_backing.log

[task 2025-09-11T22:57:56.409+00:00] 22:57:56     INFO -  raptor-benchmark Info: Performing a sparse clone...
[task 2025-09-11T22:57:56.425+00:00] 22:57:56     INFO -  Cloning into 'C:\Users\task_175762173249070\AppData\Local\Temp\tmpu5f4rhpa\performance-tests\perf-automation'...
[task 2025-09-11T22:58:17.579+00:00] 22:58:17     INFO -  fatal: unable to access 'https://github.com/mozilla/perf-automation/': Failed to connect to github.com port 443 after 21109 ms: Could not connect to server
[task 2025-09-11T22:58:17.585+00:00] 22:58:17     INFO -  raptor-perftest Info: Removing temporary directory: C:\Users\task_175762173249070\AppData\Local\Temp\tmpc6jfxuiu
[task 2025-09-11T22:58:17.589+00:00] 22:58:17    ERROR -  Traceback (most recent call last):
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\raptor.py", line 189, in <module>
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -      main()
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\raptor.py", line 138, in main
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -      success = raptor.run_tests(raptor_test_list, raptor_test_names)
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\perftest.py", line 494, in run_tests
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -      self.run_test(test, timeout=int(test.get("page_timeout")))
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\browsertime\base.py", line 954, in run_test
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -      self.run_test_setup(test)
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\browsertime\base.py", line 164, in run_test_setup
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -      self.benchmark = Benchmark(self.config, test, debug_mode=self.debug_mode)
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -                       ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2025-09-11T22:58:17.590+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\benchmark.py", line 42, in __init__
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -      self.setup_benchmarks(
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\benchmark.py", line 367, in setup_benchmarks
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -      bench_dir = self._setup_git_benchmarks(
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\benchmark.py", line 283, in _setup_git_benchmarks
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -      self._sparse_clone(benchmark_repository, external_repo_path)
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -    File "C:\task_175762173249070\build\tests\raptor\raptor\benchmark.py", line 134, in _sparse_clone
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -      subprocess.check_call(
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -    File "c:\mozilla-build\python3\Lib\subprocess.py", line 413, in check_call
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -      raise CalledProcessError(retcode, cmd)
[task 2025-09-11T22:58:17.591+00:00] 22:58:17     INFO -  subprocess.CalledProcessError: Command '['git', 'clone', '--depth', '1', '--filter', 'blob:none', '--sparse', 'https://github.com/mozilla/perf-automation', 'C:\\Users\\task_175762173249070\\AppData\\Local\\Temp\\tmpu5f4rhpa\\performance-tests\\perf-automation']' returned non-zero exit status 128.
[task 2025-09-11T22:58:17.625+00:00] 22:58:17     INFO - Return code: 1
[task 2025-09-11T22:58:17.625+00:00] 22:58:17  WARNING - setting return code to 1
[task 2025-09-11T22:58:17.625+00:00] 22:58:17     INFO - Copying Raptor results to upload dir:
[task 2025-09-11T22:58:17.636+00:00] 22:58:17     INFO - C:\task_175762173249070\build\blobber_upload_dir\perfherder-data.json
[task 2025-09-11T22:58:17.636+00:00] 22:58:17     INFO - Copying raptor results from C:\task_175762173249070\build\raptor.json to C:\task_175762173249070\build\blobber_upload_dir\perfherder-data.json
[task 2025-09-11T22:58:17.636+00:00] 22:58:17 CRITICAL - Error copying results C:\task_175762173249070\build\raptor.json to upload dir C:\task_175762173249070\build\blobber_upload_dir\perfherder-data.json
[task 2025-09-11T22:58:17.636+00:00] 22:58:17     INFO - [Errno 2] No such file or directory: 'C:\\task_175762173249070\\build\\raptor.json'
[task 2025-09-11T22:58:17.636+00:00] 22:58:17     INFO - Running post-action listener: _package_coverage_data
[task 2025-09-11T22:58:17.637+00:00] 22:58:17     INFO - Running post-action listener: _resource_record_post_action
[task 2025-09-11T22:58:17.637+00:00] 22:58:17     INFO - Running post-action listener: process_java_coverage_data
[task 2025-09-11T22:58:17.637+00:00] 22:58:17     INFO - Running post-action listener: stop_device
[task 2025-09-11T22:58:17.637+00:00] 22:58:17     INFO - [mozharness: 2025-09-11 22:58:17.637309Z] Finished run-tests step (success)
[task 2025-09-11T22:58:17.637+00:00] 22:58:17     INFO - Running post-run listener: _resource_record_post_run
[task 2025-09-11T22:58:17.815+00:00] 22:58:17     INFO - Total resource usage - Wall time: 24s; CPU: 1%; Read bytes: 11276800; Write bytes: 318201856; Read time: 0; Write time: 6
[task 2025-09-11T22:58:17.815+00:00] 22:58:17     INFO - TinderboxPrint: CPU usage<br/>1.1%
[task 2025-09-11T22:58:17.815+00:00] 22:58:17     INFO - TinderboxPrint: I/O read bytes / time<br/>11,276,800 / 0
[task 2025-09-11T22:58:17.815+00:00] 22:58:17     INFO - TinderboxPrint: I/O write bytes / time<br/>318,201,856 / 6
[task 2025-09-11T22:58:17.815+00:00] 22:58:17     INFO - TinderboxPrint: CPU idle<br/>377.3 (98.8%)
[task 2025-09-11T22:58:17.815+00:00] 22:58:17     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 2025-09-11T22:58:17.816+00:00] 22:58:17     INFO - install - Wall time: 2s; CPU: 7%; Read bytes: 3250176; Write bytes: 58630144; Read time: 0; Write time: 0
[task 2025-09-11T22:58:17.818+00:00] 22:58:17     INFO - run-tests - Wall time: 22s; CPU: 1%; Read bytes: 8026624; Write bytes: 259571712; Read time: 0; Write time: 6
[task 2025-09-11T22:58:17.863+00:00] 22:58:17  WARNING - returning nonzero exit status 1
[taskcluster 2025-09-11T22:58:17.904Z]                        Exit Code: 1
[taskcluster 2025-09-11T22:58:17.904Z]                        User Time: 31.25ms
[taskcluster 2025-09-11T22:58:17.904Z]                      Kernel Time: 0s
[taskcluster 2025-09-11T22:58:17.904Z]                        Wall Time: 1m34.1519614s
[taskcluster 2025-09-11T22:58:17.904Z]  Average Available System Memory: 4.14 GiB
[taskcluster 2025-09-11T22:58:17.904Z]       Average System Memory Used: 3.42 GiB
[taskcluster 2025-09-11T22:58:17.904Z]          Peak System Memory Used: 3.64 GiB
[taskcluster 2025-09-11T22:58:17.904Z]              Total System Memory: 7.56 GiB
[taskcluster 2025-09-11T22:58:17.904Z]                           Result: FAILED
[taskcluster 2025-09-11T22:58:17.904Z] === Task Finished ===
[taskcluster 2025-09-11T22:58:17.904Z] Task Duration: 1m34.1524876s
[taskcluster 2025-09-11T23:06:05.092Z] Uploading artifact public/test_info/resource-usage.json from file C:\task_175762173249070\build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2026-09-11T21:23:56.945Z
[taskcluster 2025-09-11T23:06:05.100Z] Uploading artifact public/logs/localconfig.json from file C:\task_175762173249070\logs\localconfig.json with content encoding "gzip", mime type "application/json" and expiry 2026-09-11T21:23:56.945Z
[taskcluster 2025-09-11T23:06:36.805Z] Uploading artifact public/test_info/profile_resource-usage.json from file C:\task_175762173249070\build\blobber_upload_dir\profile_resource-usage.json with content encoding "gzip", mime type "application/json" and expiry 2026-09-11T21:23:56.945Z
[taskcluster 2025-09-11T23:06:37.001Z] [mounts] Preserving cache: Moving "C:\\task_175762173249070\\.task-cache\\pip" to "C:\\cache\\S8Kv6xEvTduilZY2-U3jtw"
[taskcluster 2025-09-11T23:06:37.002Z] [mounts] Preserving cache: Moving "C:\\task_175762173249070\\.task-cache\\uv" to "C:\\cache\\OBKrzkgZTk6yFA153h0Hzg"
[taskcluster 2025-09-11T23:06:37.080Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2026-09-11T21:23:56.945Z
[taskcluster:error] exit status 1
You need to log in before you can comment on or make changes to this bug.