Closed Bug 1829858 Opened 2 years ago Closed 2 years ago

Intermittent subprocess.CalledProcessError: Command '['/opt/worker/tasks/task_168242546023045/checkouts/gecko/obj-x86_64-apple-darwin19.6.0/_virtualenvs/python-test/bin/python', '-m', 'pip', 'install', 'pytest==7.0.1']' returned non-zero exit status 1.

Categories

(Firefox Build System :: Task Configuration, defect, P5)

Product:
Firefox Build System
Component:
Task Configuration
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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

[vcs 2023-04-25T12:27:40.875Z] updating [================================================> ] 340362/340431 01s
[vcs 2023-04-25T12:27:45.107Z]                                                                                 
[vcs 2023-04-25T12:27:45.107Z] 340431 files updated, 0 files merged, 0 files removed, 0 files unresolved
[vcs 2023-04-25T12:27:45.491Z] updated to 72310c61f78749c737d418683f0f46fa2026f64d
[vcs 2023-04-25T12:27:45.921Z] TinderboxPrint:<a href=https://hg.mozilla.org/integration/autoland/rev/72310c61f78749c737d418683f0f46fa2026f64d title='Built from autoland revision 72310c61f78749c737d418683f0f46fa2026f64d'>72310c61f78749c737d418683f0f46fa2026f64d</a>
[setup 2023-04-25T12:27:45.921Z] GECKO_PATH is /opt/worker/tasks/task_168242546023045/checkouts/gecko
[setup 2023-04-25T12:27:45.921Z] MOZ_FETCHES_DIR is /opt/worker/tasks/task_168242546023045/fetches
[fetches 2023-04-25T12:27:45.921Z] fetching artifacts
[fetches 2023-04-25T12:27:45.925Z] executing ['/usr/local/bin/python3', '-u', '/opt/worker/tasks/task_168242546023045/checkouts/gecko/taskcluster/scripts/misc/fetch-content', 'task-artifacts']
[fetches 2023-04-25T12:27:46.142Z] attempt 1/5
[fetches 2023-04-25T12:27:46.142Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KZw6C29pQKW6QEbxy2UNIQ/artifacts/public/build/node.tar.zst to /opt/worker/tasks/task_168242546023045/fetches/node.tar.zst
[fetches 2023-04-25T12:27:46.142Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KZw6C29pQKW6QEbxy2UNIQ/artifacts/public/build/node.tar.zst
[fetches 2023-04-25T12:27:46.142Z] /opt/worker/tasks/task_168242546023045/checkouts/gecko/taskcluster/scripts/misc/fetch-content:187: DeprecationWarning: cafile, capath and cadefault are deprecated, use a custom context instead.
[fetches 2023-04-25T12:27:46.142Z]   ) if certifi else urllib.request.urlopen(url, timeout=60) as fh:
[fetches 2023-04-25T12:27:46.462Z] https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/KZw6C29pQKW6QEbxy2UNIQ/artifacts/public/build/node.tar.zst resolved to 19339910 bytes with sha256 dc7c797816d121de13432c4b455ae503c251e6c4ed84439fbbf0b260ccc5cdff in 0.320s
[fetches 2023-04-25T12:27:46.463Z] Extracting /opt/worker/tasks/task_168242546023045/fetches/node.tar.zst to /opt/worker/tasks/task_168242546023045/fetches
[fetches 2023-04-25T12:27:47.351Z] /opt/worker/tasks/task_168242546023045/fetches/node.tar.zst extracted in 0.888s
[fetches 2023-04-25T12:27:47.351Z] Removing /opt/worker/tasks/task_168242546023045/fetches/node.tar.zst
[fetches 2023-04-25T12:27:47.352Z] PERFHERDER_DATA: {"framework": {"name": "build_metrics"}, "suites": [{"name": "fetch_content", "value": 1.2204578400000001, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}]}
[fetches 2023-04-25T12:27:47.364Z] finished fetching artifacts
[task 2023-04-25T12:27:47.364Z] executing ['bash', '-cx', 'LC_ALL=en_US.UTF-8 LANG=en_US.UTF-8 /usr/local/bin/python3 ./mach python-test --subsuite mozbuild --run-slow']in checkouts/gecko
[task 2023-04-25T12:27:47.367Z] + LC_ALL=en_US.UTF-8
[task 2023-04-25T12:27:47.367Z] + LANG=en_US.UTF-8
[task 2023-04-25T12:27:47.367Z] + /usr/local/bin/python3 ./mach python-test --subsuite mozbuild --run-slow
[task 2023-04-25T12:27:50.953Z] ERROR: Could not find a version that satisfies the requirement pytest==7.0.1 (from versions: none)
[task 2023-04-25T12:27:50.953Z] ERROR: No matching distribution found for pytest==7.0.1
[task 2023-04-25T12:27:51.059Z] Site not up-to-date reason: "/opt/worker/tasks/task_168242546023045/checkouts/gecko/obj-x86_64-apple-darwin19.6.0/_virtualenvs/python-test" does not exist
[task 2023-04-25T12:27:51.059Z] Error running mach:
[task 2023-04-25T12:27:51.059Z] 
[task 2023-04-25T12:27:51.059Z]     ['python-test', '--subsuite', 'mozbuild', '--run-slow']
[task 2023-04-25T12:27:51.059Z] 
[task 2023-04-25T12:27:51.059Z] The error occurred in code that was called by the mach command. This is either
[task 2023-04-25T12:27:51.059Z] a bug in the called code itself or in the way that mach is calling it.
[task 2023-04-25T12:27:51.059Z] You can invoke ``./mach busted`` to check if this issue is already on file. If it
[task 2023-04-25T12:27:51.059Z] isn't, please use ``./mach busted file python-test`` to report it. If ``./mach busted`` is
[task 2023-04-25T12:27:51.059Z] misbehaving, you can also inspect the dependencies of bug 1543241.
[task 2023-04-25T12:27:51.059Z] 
[task 2023-04-25T12:27:51.059Z] If filing a bug, please include the full output of mach, including this error
[task 2023-04-25T12:27:51.059Z] message.
[task 2023-04-25T12:27:51.059Z] 
[task 2023-04-25T12:27:51.059Z] The details of the failure are as follows:
[task 2023-04-25T12:27:51.059Z] 
[task 2023-04-25T12:27:51.059Z] subprocess.CalledProcessError: Command '['/opt/worker/tasks/task_168242546023045/checkouts/gecko/obj-x86_64-apple-darwin19.6.0/_virtualenvs/python-test/bin/python', '-m', 'pip', 'install', 'pytest==7.0.1']' returned non-zero exit status 1.
[task 2023-04-25T12:27:51.059Z] 
[task 2023-04-25T12:27:51.059Z]   File "/opt/worker/tasks/task_168242546023045/checkouts/gecko/python/mozbuild/mozbuild/base.py", line 828, in activate_virtualenv
[task 2023-04-25T12:27:51.059Z]     self.virtualenv_manager.activate()
[task 2023-04-25T12:27:51.059Z]   File "/opt/worker/tasks/task_168242546023045/checkouts/gecko/python/mach/mach/site.py", line 603, in activate
[task 2023-04-25T12:27:51.059Z]     self.ensure()
[task 2023-04-25T12:27:51.059Z]   File "/opt/worker/tasks/task_168242546023045/checkouts/gecko/python/mach/mach/site.py", line 580, in ensure
[task 2023-04-25T12:27:51.059Z]     self._metadata,
[task 2023-04-25T12:27:51.059Z]   File "/opt/worker/tasks/task_168242546023045/checkouts/gecko/python/mach/mach/site.py", line 1324, in _create_venv_with_pthfile
[task 2023-04-25T12:27:51.059Z]     target_venv.pip_install([str(requirement.requirement)])
[task 2023-04-25T12:27:51.059Z]   File "/opt/worker/tasks/task_168242546023045/checkouts/gecko/python/mach/mach/site.py", line 914, in pip_install
[task 2023-04-25T12:27:51.059Z]     **kwargs,
[task 2023-04-25T12:27:51.059Z]   File "/Library/Frameworks/Python.framework/Versions/3.7/lib/python3.7/subprocess.py", line 512, in run
[task 2023-04-25T12:27:51.059Z]     output=stdout, stderr=stderr)
[taskcluster 2023-04-25T12:27:51.115Z]    Exit Code: 1
[taskcluster 2023-04-25T12:27:51.115Z]    User Time: 5m37.246462s
[taskcluster 2023-04-25T12:27:51.115Z]  Kernel Time: 6m54.22513s
[taskcluster 2023-04-25T12:27:51.115Z]    Wall Time: 3m16.147509s
[taskcluster 2023-04-25T12:27:51.115Z]       Result: FAILED
[taskcluster 2023-04-25T12:27:51.116Z] === Task Finished ===
[taskcluster 2023-04-25T12:27:51.116Z] Task Duration: 3m16.209409s
[taskcluster 2023-04-25T12:27:51.116Z] [mounts] Preserving cache: Moving "/opt/worker/tasks/task_168242546023045/checkouts" to "/opt/worker/cache/E2zsgwhzRR2vK9YvmY9r6g"
[taskcluster 2023-04-25T12:27:51.251Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dwvSGveBQt6Q7CrNWo9f-g/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2024-04-24T11:42:40.869Z
[taskcluster:error] exit status 1

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

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