1874691 - Intermittent subprocess.CalledProcessError: Command '['/Users/cltbld/.mozbuild/srcdirs/gecko-de871df74965/_virtualenvs/python-test/bin/python', '-m', 'pip', 'install', 'pytest==7.0.1']' returned non-zero exit status 1.

Status: RESOLVED DUPLICATE of bug 1875936

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

Description

[Treeherder Bug Filer]

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

---

[setup 2024-01-15T12:40:17.078Z] GECKO_PATH is /opt/worker/tasks/task_170532203919357/checkouts/gecko
[setup 2024-01-15T12:40:17.078Z] MOZ_FETCHES_DIR is /opt/worker/tasks/task_170532203919357/fetches
[setup 2024-01-15T12:40:17.078Z] MOZ_PYTHON_HOME is /opt/worker/tasks/task_170532203919357/fetches/python
[fetches 2024-01-15T12:40:17.078Z] fetching artifacts
[fetches 2024-01-15T12:40:17.081Z] executing ['/usr/local/bin/python3', '-u', '/opt/worker/tasks/task_170532203919357/checkouts/gecko/taskcluster/scripts/misc/fetch-content', 'task-artifacts']
[fetches 2024-01-15T12:40:17.301Z] attempt 1/5attempt 1/5
[fetches 2024-01-15T12:40:17.301Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aoXw0WqLSM26-DF7OeuHMA/artifacts/public/build/node.tar.zst to /opt/worker/tasks/task_170532203919357/fetches/node.tar.zst
[fetches 2024-01-15T12:40:17.301Z] 
[fetches 2024-01-15T12:40:17.301Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Y_TqMUHZSVGnOUUh8Ic0Og/artifacts/public/build/python.tar.zst to /opt/worker/tasks/task_170532203919357/fetches/python.tar.zst
[fetches 2024-01-15T12:40:17.302Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aoXw0WqLSM26-DF7OeuHMA/artifacts/public/build/node.tar.zst
[fetches 2024-01-15T12:40:17.302Z] Downloading https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Y_TqMUHZSVGnOUUh8Ic0Og/artifacts/public/build/python.tar.zst
[fetches 2024-01-15T12:40:17.302Z] /opt/worker/tasks/task_170532203919357/checkouts/gecko/taskcluster/scripts/misc/fetch-content:188: DeprecationWarning: cafile, capath and cadefault are deprecated, use a custom context instead.
[fetches 2024-01-15T12:40:17.302Z]   ) if certifi else urllib.request.urlopen(url, timeout=60) as fh:
[fetches 2024-01-15T12:40:18.297Z] https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/aoXw0WqLSM26-DF7OeuHMA/artifacts/public/build/node.tar.zst resolved to 30116323 bytes with sha256 8a33e706b10c25f5e9b642d2d61647d718a458899a2c424092cc7926db7d871b in 0.995s
[fetches 2024-01-15T12:40:18.297Z] Extracting /opt/worker/tasks/task_170532203919357/fetches/node.tar.zst to /opt/worker/tasks/task_170532203919357/fetches
[fetches 2024-01-15T12:40:18.335Z] https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Y_TqMUHZSVGnOUUh8Ic0Og/artifacts/public/build/python.tar.zst resolved to 48082240 bytes with sha256 5cbffa437bcac03b5db896aceee9c0a2e651d63e4ce95f3f6fd0e40a2cafdbba in 1.034s
[fetches 2024-01-15T12:40:18.336Z] Extracting /opt/worker/tasks/task_170532203919357/fetches/python.tar.zst to /opt/worker/tasks/task_170532203919357/fetches
[fetches 2024-01-15T12:40:19.144Z] /opt/worker/tasks/task_170532203919357/fetches/node.tar.zst extracted in 0.846s
[fetches 2024-01-15T12:40:19.144Z] Removing /opt/worker/tasks/task_170532203919357/fetches/node.tar.zst
[fetches 2024-01-15T12:40:20.189Z] /opt/worker/tasks/task_170532203919357/fetches/python.tar.zst extracted in 1.853s
[fetches 2024-01-15T12:40:20.189Z] Removing /opt/worker/tasks/task_170532203919357/fetches/python.tar.zst
[fetches 2024-01-15T12:40:20.191Z] PERFHERDER_DATA: {"framework": {"name": "build_metrics"}, "suites": [{"name": "fetch_content", "value": 2.901078976, "lowerIsBetter": true, "shouldAlert": false, "subtests": []}]}
[fetches 2024-01-15T12:40:20.206Z] finished fetching artifacts
[setup 2024-01-15T12:40:20.206Z] Setting up local python environment
[setup 2024-01-15T12:40:20.206Z] updated PATH with python artifact: /opt/worker/tasks/task_170532203919357/fetches/python/bin:/usr/local/bin:/bin:/sbin:/usr/bin:/usr/sbin
[task 2024-01-15T12:40:20.206Z] executing ['bash', '-cx', 'LC_ALL=en_US.UTF-8 LANG=en_US.UTF-8 python3 ./mach python-test --subsuite telemetry-python --run-slow']in checkouts/gecko
[task 2024-01-15T12:40:20.209Z] + LC_ALL=en_US.UTF-8
[task 2024-01-15T12:40:20.209Z] + LANG=en_US.UTF-8
[task 2024-01-15T12:40:20.210Z] + python3 ./mach python-test --subsuite telemetry-python --run-slow
[task 2024-01-15T12:40:22.426Z] Looking in links: https://pypi.pub.build.mozilla.org/pub/
[task 2024-01-15T12:40:22.871Z] ERROR: Could not find a version that satisfies the requirement pytest==7.0.1 (from versions: asyncio-0.23.3, cov-4.1.0, timeout-2.2.0, xdist-3.5.0, 2.9.1, 3.6.2, 5.0.1, 5.1.2, 7.4.4)
[task 2024-01-15T12:40:22.872Z] ERROR: No matching distribution found for pytest==7.0.1
[task 2024-01-15T12:40:22.929Z] Creating local state directory: /Users/cltbld/.mozbuild/srcdirs/gecko-de871df74965
[task 2024-01-15T12:40:22.929Z] Site not up-to-date reason: "/Users/cltbld/.mozbuild/srcdirs/gecko-de871df74965/_virtualenvs/python-test" does not exist
[task 2024-01-15T12:40:22.930Z] Traceback (most recent call last):
[task 2024-01-15T12:40:22.930Z]   File "./mach", line 199, in <module>
[task 2024-01-15T12:40:22.930Z]     main(sys.argv[1:])
[task 2024-01-15T12:40:22.930Z]   File "./mach", line 155, in main
[task 2024-01-15T12:40:22.930Z]     mach = check_and_get_mach(os.path.dirname(os.path.realpath(__file__)), args)
[task 2024-01-15T12:40:22.930Z]   File "./mach", line 39, in check_and_get_mach
[task 2024-01-15T12:40:22.930Z]     return load_mach(dir_path, mach_path, args)
[task 2024-01-15T12:40:22.930Z]   File "./mach", line 25, in load_mach
[task 2024-01-15T12:40:22.930Z]     return mach_initialize.initialize(dir_path, args)
[task 2024-01-15T12:40:22.930Z]   File "/opt/worker/tasks/task_170532203919357/checkouts/gecko/build/mach_initialize.py", line 338, in initialize
[task 2024-01-15T12:40:22.930Z]     command_site_manager.activate()
[task 2024-01-15T12:40:22.930Z]   File "/opt/worker/tasks/task_170532203919357/checkouts/gecko/python/mach/mach/site.py", line 603, in activate
[task 2024-01-15T12:40:22.930Z]     self.ensure()
[task 2024-01-15T12:40:22.930Z]   File "/opt/worker/tasks/task_170532203919357/checkouts/gecko/python/mach/mach/site.py", line 575, in ensure
[task 2024-01-15T12:40:22.930Z]     _create_venv_with_pthfile(
[task 2024-01-15T12:40:22.930Z]   File "/opt/worker/tasks/task_170532203919357/checkouts/gecko/python/mach/mach/site.py", line 1310, in _create_venv_with_pthfile
[task 2024-01-15T12:40:22.930Z]     target_venv.pip_install([str(requirement.requirement)])
[task 2024-01-15T12:40:22.930Z]   File "/opt/worker/tasks/task_170532203919357/checkouts/gecko/python/mach/mach/site.py", line 912, in pip_install
[task 2024-01-15T12:40:22.930Z]     return subprocess.run(
[task 2024-01-15T12:40:22.930Z]   File "/opt/worker/tasks/task_170532203919357/fetches/python/lib/python3.8/subprocess.py", line 516, in run
[task 2024-01-15T12:40:22.930Z]     raise CalledProcessError(retcode, process.args,
[task 2024-01-15T12:40:22.930Z] subprocess.CalledProcessError: Command '['/Users/cltbld/.mozbuild/srcdirs/gecko-de871df74965/_virtualenvs/python-test/bin/python', '-m', 'pip', 'install', 'pytest==7.0.1']' returned non-zero exit status 1.
[taskcluster 2024-01-15T12:40:22.971Z]    Exit Code: 1
[taskcluster 2024-01-15T12:40:22.971Z]    User Time: 6m26.531079s
[taskcluster 2024-01-15T12:40:22.971Z]  Kernel Time: 7m33.284841s
[taskcluster 2024-01-15T12:40:22.971Z]    Wall Time: 6m20.383347s
[taskcluster 2024-01-15T12:40:22.971Z]       Result: FAILED
[taskcluster 2024-01-15T12:40:22.972Z] === Task Finished ===
[taskcluster 2024-01-15T12:40:22.972Z] Task Duration: 6m20.409937s
[taskcluster 2024-01-15T12:40:22.972Z] [mounts] Preserving cache: Moving "/opt/worker/tasks/task_170532203919357/checkouts" to "/opt/worker/cache/WTRfd2KeRsS3l0XX4V9zYg"
[taskcluster 2024-01-15T12:40:23.139Z] Uploading link artifact public/logs/live.log to artifact public/logs/live_backing.log with expiry 2025-01-14T12:33:06.967Z
[taskcluster:error] exit status 1

Comment 1

[Intermittent Failures Robot]

7 failures in 3599 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 6
• mozilla-central: 1

Platform and build breakdown:

• macosx1015-64: 7
  • opt: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1874691&startday=2024-01-15&endday=2024-01-21&tree=all