[taskcluster 2024-12-24 00:30:40.305Z] Task ID: XYymvJPcRra9E8XhqZUeQA
[taskcluster 2024-12-24 00:30:40.305Z] Worker ID: 4475333136452402825
[taskcluster 2024-12-24 00:30:40.305Z] Worker Group: us-central1-a
[taskcluster 2024-12-24 00:30:40.305Z] Worker Node Type: projects/887720501152/machineTypes/c2-standard-4
[taskcluster 2024-12-24 00:30:40.305Z] Worker Type: t-linux-large-gcp
[taskcluster 2024-12-24 00:30:40.305Z] Public IP: 34.173.165.213
[taskcluster 2024-12-24 00:30:40.305Z] Hostname: mozilla-t-t-linux-large-gcp-efw6ccidqfglybybh0h7oq
[taskcluster 2024-12-24 00:30:40.305Z] using cache "mozilla-level-1-checkouts-v3-7afeb851dd97df8f3607-V2p_Hr59TIyUUH0ny92XKw" -> /builds/worker/checkouts

[taskcluster 2024-12-24 00:30:41.500Z] Downloading artifact "public/image.tar.zst" from task ID: V2p_Hr59TIyUUH0ny92XKw.
[taskcluster 2024-12-24 00:30:43.523Z] Downloaded artifact successfully.
[taskcluster 2024-12-24 00:30:43.524Z] Downloaded 188.451 mb
[taskcluster 2024-12-24 00:30:43.525Z] Decompressing downloaded image
[taskcluster 2024-12-24 00:30:44.804Z] Loading docker image from downloaded archive.
[taskcluster 2024-12-24 00:30:52.071Z] Image 'public/image.tar.zst' from task 'V2p_Hr59TIyUUH0ny92XKw' loaded.  Using image ID sha256:f76ec7f9ed56f3d1f067a0778aed0fed9d1c6d19c2334672e19ed7652ab21552.
[taskcluster 2024-12-24 00:30:52.219Z] === Task Starting ===
[setup 2024-12-24T00:30:52.610Z] run-task started in /
[setup 2024-12-24T00:30:52.610Z] Invoked by command: --redo-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce tox -e py39
[setup 2024-12-24T00:30:52.610Z] Python version: 3.9.19
[cache 2024-12-24T00:30:52.611Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2024-12-24T00:30:52.611Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2024-12-24T00:30:52.612Z] volume /builds/worker/checkouts is a cache
[setup 2024-12-24T00:30:52.612Z] running as worker:worker
[vcs 2024-12-24T00:30:52.612Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2024-12-24T00:30:52.614Z] executing ['git', 'clone', 'https://github.com/mozilla-releng/redo', '/builds/worker/checkouts/vcs']
[vcs 2024-12-24T00:30:52.616Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2024-12-24T00:30:52.959Z] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/redo', 'dependabot/pip/taskcluster/jinja2-3.1.5']
[vcs 2024-12-24T00:30:53.128Z] From https://github.com/mozilla-releng/redo
[vcs 2024-12-24T00:30:53.128Z]  * branch            dependabot/pip/taskcluster/jinja2-3.1.5 -> FETCH_HEAD
[vcs 2024-12-24T00:30:53.132Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla-releng/redo', 'dependabot/pip/taskcluster/jinja2-3.1.5']
[vcs 2024-12-24T00:30:53.341Z] From https://github.com/mozilla-releng/redo
[vcs 2024-12-24T00:30:53.341Z]  * branch            dependabot/pip/taskcluster/jinja2-3.1.5 -> FETCH_HEAD
[vcs 2024-12-24T00:30:53.345Z] executing ['git', 'checkout', '-f', '-B', 'dependabot/pip/taskcluster/jinja2-3.1.5', '35c888296c72fe6541b68f0d8b80c72d77c32bc1']
[vcs 2024-12-24T00:30:53.349Z] Switched to a new branch 'dependabot/pip/taskcluster/jinja2-3.1.5'
[vcs 2024-12-24T00:30:53.349Z] cleaning git checkout...
[vcs 2024-12-24T00:30:53.349Z] executing ['git', 'clean', '-nxdff']
[vcs 2024-12-24T00:30:53.351Z] removing []
[vcs 2024-12-24T00:30:53.352Z] successfully cleaned git checkout!
[vcs 2024-12-24T00:30:53.353Z] TinderboxPrint:<a href='https://github.com/mozilla-releng/redo/commit/35c888296c72fe6541b68f0d8b80c72d77c32bc1' title='Built from redo commit 35c888296c72fe6541b68f0d8b80c72d77c32bc1'>35c888296c72fe6541b68f0d8b80c72d77c32bc1</a>
[task 2024-12-24T00:30:53.353Z] executing ['sh', '-lxce', 'tox -e py39']
[task 2024-12-24T00:30:53.355Z] + id -u
[task 2024-12-24T00:30:53.356Z] + [ 1000 -eq 0 ]
[task 2024-12-24T00:30:53.356Z] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2024-12-24T00:30:53.356Z] + export PATH
[task 2024-12-24T00:30:53.356Z] + [ $  ]
[task 2024-12-24T00:30:53.356Z] + [  ]
[task 2024-12-24T00:30:53.356Z] + id -u
[task 2024-12-24T00:30:53.357Z] + [ 1000 -eq 0 ]
[task 2024-12-24T00:30:53.357Z] + PS1=$ 
[task 2024-12-24T00:30:53.357Z] + [ -d /etc/profile.d ]
[task 2024-12-24T00:30:53.357Z] + [ -r /etc/profile.d/*.sh ]
[task 2024-12-24T00:30:53.357Z] + unset i
[task 2024-12-24T00:30:53.357Z] + [ -n  ]
[task 2024-12-24T00:30:53.357Z] + [ -d /builds/worker/bin ]
[task 2024-12-24T00:30:53.357Z] + [ -d /builds/worker/.local/bin ]
[task 2024-12-24T00:30:53.357Z] + tox -e py39
[task 2024-12-24T00:30:53.904Z] py39: install_deps> python -I -m pip install mock pytest pytest-cov
[task 2024-12-24T00:30:56.750Z] .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' wheel
[task 2024-12-24T00:30:57.534Z] .pkg: _optional_hooks> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-24T00:30:57.821Z] .pkg: get_requires_for_build_editable> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-24T00:30:58.042Z] .pkg: build_editable> python /usr/local/lib/python3.9/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-24T00:30:58.286Z] py39: install_package> python -I -m pip install --force-reinstall --no-deps /builds/worker/checkouts/vcs/.tox/.tmp/package/1/redo-3.0.0-0.editable-py2.py3-none-any.whl
[task 2024-12-24T00:30:58.667Z] py39: commands[0]> py.test --cov-config=tox.ini --cov-append --cov=redo --cov-report term-missing tests
[task 2024-12-24T00:30:59.039Z] ============================= test session starts ==============================
[task 2024-12-24T00:30:59.039Z] platform linux -- Python 3.9.19, pytest-8.3.4, pluggy-1.5.0
[task 2024-12-24T00:30:59.040Z] cachedir: .tox/py39/.pytest_cache
[task 2024-12-24T00:30:59.040Z] rootdir: /builds/worker/checkouts/vcs
[task 2024-12-24T00:30:59.040Z] configfile: pyproject.toml
[task 2024-12-24T00:30:59.040Z] plugins: cov-6.0.0
[task 2024-12-24T00:30:59.040Z] collected 39 items
[task 2024-12-24T00:30:59.040Z] 
[task 2024-12-24T00:30:59.045Z] tests/test_cmd.py .                                                      [  2%]
[task 2024-12-24T00:30:59.122Z] tests/test_retry.py .............................                        [ 76%]
[task 2024-12-24T00:30:59.162Z] tests/test_retry_async.py ssss.....                                      [100%]
[task 2024-12-24T00:30:59.162Z] 
[task 2024-12-24T00:30:59.162Z] =============================== warnings summary ===============================
[task 2024-12-24T00:30:59.162Z] tests/test_retry_async.py:28
[task 2024-12-24T00:30:59.162Z]   /builds/worker/checkouts/vcs/tests/test_retry_async.py:28: PytestUnknownMarkWarning: Unknown pytest.mark.asyncio - is this a typo?  You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html
[task 2024-12-24T00:30:59.162Z]     @pytest.mark.asyncio
[task 2024-12-24T00:30:59.162Z] 
[task 2024-12-24T00:30:59.162Z] tests/test_retry_async.py:37
[task 2024-12-24T00:30:59.162Z]   /builds/worker/checkouts/vcs/tests/test_retry_async.py:37: PytestUnknownMarkWarning: Unknown pytest.mark.asyncio - is this a typo?  You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html
[task 2024-12-24T00:30:59.162Z]     @pytest.mark.asyncio
[task 2024-12-24T00:30:59.162Z] 
[task 2024-12-24T00:30:59.162Z] tests/test_retry_async.py:48
[task 2024-12-24T00:30:59.162Z]   /builds/worker/checkouts/vcs/tests/test_retry_async.py:48: PytestUnknownMarkWarning: Unknown pytest.mark.asyncio - is this a typo?  You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html
[task 2024-12-24T00:30:59.162Z]     @pytest.mark.asyncio
[task 2024-12-24T00:30:59.162Z] 
[task 2024-12-24T00:30:59.163Z] tests/test_retry_async.py:62
[task 2024-12-24T00:30:59.163Z]   /builds/worker/checkouts/vcs/tests/test_retry_async.py:62: PytestUnknownMarkWarning: Unknown pytest.mark.asyncio - is this a typo?  You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html
[task 2024-12-24T00:30:59.163Z]     @pytest.mark.asyncio
[task 2024-12-24T00:30:59.163Z] 
[task 2024-12-24T00:30:59.163Z] tests/test_retry_async.py::test_retry_async_fail_first
[task 2024-12-24T00:30:59.163Z] tests/test_retry_async.py::test_retry_async_always_fail
[task 2024-12-24T00:30:59.163Z] tests/test_retry_async.py::test_retriable_async_fail_first
[task 2024-12-24T00:30:59.163Z] tests/test_retry_async.py::test_retriable_async_always_fail_async
[task 2024-12-24T00:30:59.163Z]   /builds/worker/checkouts/vcs/.tox/py39/lib/python3.9/site-packages/_pytest/python.py:148: PytestUnhandledCoroutineWarning: async def functions are not natively supported and have been skipped.
[task 2024-12-24T00:30:59.164Z]   You need to install a suitable plugin for your async framework, for example:
[task 2024-12-24T00:30:59.164Z]     - anyio
[task 2024-12-24T00:30:59.164Z]     - pytest-asyncio
[task 2024-12-24T00:30:59.164Z]     - pytest-tornasync
[task 2024-12-24T00:30:59.164Z]     - pytest-trio
[task 2024-12-24T00:30:59.164Z]     - pytest-twisted
[task 2024-12-24T00:30:59.164Z]     warnings.warn(PytestUnhandledCoroutineWarning(msg.format(nodeid)))
[task 2024-12-24T00:30:59.164Z] 
[task 2024-12-24T00:30:59.164Z] -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
[task 2024-12-24T00:30:59.164Z] 
[task 2024-12-24T00:30:59.164Z] ---------- coverage: platform linux, python 3.9.19-final-0 -----------
[task 2024-12-24T00:30:59.164Z] Name                   Stmts   Miss Branch BrPart  Cover   Missing
[task 2024-12-24T00:30:59.164Z] ------------------------------------------------------------------
[task 2024-12-24T00:30:59.164Z] src/redo/__init__.py      92     22     24      1    78%   164, 312-320, 324-326, 336-339, 357-370
[task 2024-12-24T00:30:59.164Z] src/redo/cmd.py           32     10      6      3    66%   19, 35-36, 50-55, 59
[task 2024-12-24T00:30:59.164Z] ------------------------------------------------------------------
[task 2024-12-24T00:30:59.164Z] TOTAL                    124     32     30      4    75%
[task 2024-12-24T00:30:59.165Z] 
[task 2024-12-24T00:30:59.165Z] ================== 35 passed, 4 skipped, 8 warnings in 0.20s ===================
[task 2024-12-24T00:30:59.196Z]   py39: OK (5.52=setup[4.99]+cmd[0.53] seconds)
[task 2024-12-24T00:30:59.196Z]   congratulations :) (5.66 seconds)
[taskcluster 2024-12-24 00:30:59.489Z] === Task Finished ===
[taskcluster 2024-12-24 00:30:59.489Z] Successful task run with exit code: 0 completed in 19.185 seconds