[taskcluster 2024-06-27 21:40:12.287Z] Task ID: KZPCqQZtSPe9WrfcJeQQzw
[taskcluster 2024-06-27 21:40:12.287Z] Worker ID: 2997842439329309969
[taskcluster 2024-06-27 21:40:12.287Z] Worker Group: us-central1-a
[taskcluster 2024-06-27 21:40:12.287Z] Worker Node Type: projects/887720501152/machineTypes/n2-standard-2
[taskcluster 2024-06-27 21:40:12.287Z] Worker Type: t-linux-large-gcp
[taskcluster 2024-06-27 21:40:12.287Z] Public IP: 35.223.245.106
[taskcluster 2024-06-27 21:40:12.287Z] Hostname: mozilla-t-t-linux-large-gcp-voit6alyqfy5tixjrejwqq
[taskcluster 2024-06-27 21:40:12.287Z] using cache "mozilla-level-1-checkouts-v3-7afeb851dd97df8f3607-V3_Xfx6cRZCZWKtNhXSf0A" -> /builds/worker/checkouts

[taskcluster 2024-06-27 21:40:13.299Z] Downloading artifact "public/image.tar.zst" from task ID: V3_Xfx6cRZCZWKtNhXSf0A.
[taskcluster 2024-06-27 21:40:16.432Z] Downloaded artifact successfully.
[taskcluster 2024-06-27 21:40:16.433Z] Downloaded 190.217 mb
[taskcluster 2024-06-27 21:40:16.434Z] Decompressing downloaded image
[taskcluster 2024-06-27 21:40:17.858Z] Loading docker image from downloaded archive.
[taskcluster 2024-06-27 21:40:28.367Z] Image 'public/image.tar.zst' from task 'V3_Xfx6cRZCZWKtNhXSf0A' loaded.  Using image ID sha256:56982df8b97e0078c4115ff2e0fca2f16dd6a795631aef46f9c146c4475c7525.
[taskcluster 2024-06-27 21:40:28.512Z] === Task Starting ===
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[setup 2024-06-27T21:40:28.988Z] run-task started in /
[setup 2024-06-27T21:40:28.989Z] Invoked by command: --redo-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce tox -e py312
[setup 2024-06-27T21:40:28.989Z] Python version: 3.12.4
[cache 2024-06-27T21:40:28.991Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
/usr/local/bin/run-task:328: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[volume 2024-06-27T21:40:28.992Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2024-06-27T21:40:28.992Z] volume /builds/worker/checkouts is a cache
[setup 2024-06-27T21:40:28.992Z] running as worker:worker
[vcs 2024-06-27T21:40:28.992Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[vcs 2024-06-27T21:40:28.998Z] executing ['git', 'clone', 'https://github.com/mozilla-releng/redo', '/builds/worker/checkouts/vcs']
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[vcs 2024-06-27T21:40:29.001Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2024-06-27T21:40:29.285Z] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/redo', 'hneiva/version-bump-3.0.0']
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[vcs 2024-06-27T21:40:29.470Z] From https://github.com/mozilla-releng/redo
[vcs 2024-06-27T21:40:29.470Z]  * branch            hneiva/version-bump-3.0.0 -> FETCH_HEAD
[vcs 2024-06-27T21:40:29.476Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla-releng/redo', 'hneiva/version-bump-3.0.0']
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[vcs 2024-06-27T21:40:29.678Z] From https://github.com/mozilla-releng/redo
[vcs 2024-06-27T21:40:29.678Z]  * branch            hneiva/version-bump-3.0.0 -> FETCH_HEAD
[vcs 2024-06-27T21:40:29.684Z] executing ['git', 'checkout', '-f', '-B', 'hneiva/version-bump-3.0.0', '7086bafca50c7bba172435886f0ef3aaad631d6a']
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[vcs 2024-06-27T21:40:29.688Z] Switched to a new branch 'hneiva/version-bump-3.0.0'
[vcs 2024-06-27T21:40:29.689Z] cleaning git checkout...
[vcs 2024-06-27T21:40:29.689Z] executing ['git', 'clean', '-nxdff']
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[vcs 2024-06-27T21:40:29.691Z] removing []
[vcs 2024-06-27T21:40:29.691Z] successfully cleaned git checkout!
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[vcs 2024-06-27T21:40:29.693Z] TinderboxPrint:<a href='https://github.com/mozilla-releng/redo/commit/7086bafca50c7bba172435886f0ef3aaad631d6a' title='Built from redo commit 7086bafca50c7bba172435886f0ef3aaad631d6a'>7086bafca50c7bba172435886f0ef3aaad631d6a</a>
[task 2024-06-27T21:40:29.693Z] executing ['sh', '-lxce', 'tox -e py312']
/usr/local/bin/run-task:118: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC).
  now = datetime.datetime.utcnow().isoformat().encode("utf-8")
[task 2024-06-27T21:40:29.695Z] + id -u
[task 2024-06-27T21:40:29.696Z] + [ 1000 -eq 0 ]
[task 2024-06-27T21:40:29.696Z] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2024-06-27T21:40:29.696Z] + export PATH
[task 2024-06-27T21:40:29.697Z] + [ $  ]
[task 2024-06-27T21:40:29.697Z] + [  ]
[task 2024-06-27T21:40:29.698Z] + id -u
[task 2024-06-27T21:40:29.699Z] + [ 1000 -eq 0 ]
[task 2024-06-27T21:40:29.699Z] + PS1=$ 
[task 2024-06-27T21:40:29.699Z] + [ -d /etc/profile.d ]
[task 2024-06-27T21:40:29.700Z] + [ -r /etc/profile.d/*.sh ]
[task 2024-06-27T21:40:29.700Z] + unset i
[task 2024-06-27T21:40:29.700Z] + [ -n  ]
[task 2024-06-27T21:40:29.700Z] + [ -d /builds/worker/bin ]
[task 2024-06-27T21:40:29.700Z] + [ -d /builds/worker/.local/bin ]
[task 2024-06-27T21:40:29.700Z] + tox -e py312
[task 2024-06-27T21:40:30.347Z] py312: install_deps> python -I -m pip install mock pytest pytest-cov
[task 2024-06-27T21:40:33.498Z] .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' wheel
[task 2024-06-27T21:40:35.940Z] .pkg: _optional_hooks> python /usr/local/lib/python3.12/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-06-27T21:40:36.146Z] .pkg: get_requires_for_build_editable> python /usr/local/lib/python3.12/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-06-27T21:40:36.385Z] .pkg: build_editable> python /usr/local/lib/python3.12/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-06-27T21:40:36.656Z] py312: 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-06-27T21:40:37.132Z] py312: commands[0]> py.test --cov-config=tox.ini --cov-append --cov=redo --cov-report term-missing tests
[task 2024-06-27T21:40:37.744Z] ============================= test session starts ==============================
[task 2024-06-27T21:40:37.745Z] platform linux -- Python 3.12.4, pytest-8.2.2, pluggy-1.5.0
[task 2024-06-27T21:40:37.745Z] cachedir: .tox/py312/.pytest_cache
[task 2024-06-27T21:40:37.746Z] rootdir: /builds/worker/checkouts/vcs
[task 2024-06-27T21:40:37.746Z] configfile: pyproject.toml
[task 2024-06-27T21:40:37.746Z] plugins: cov-5.0.0
[task 2024-06-27T21:40:37.746Z] collected 39 items
[task 2024-06-27T21:40:37.747Z] 
[task 2024-06-27T21:40:37.758Z] tests/test_cmd.py .                                                      [  2%]
[task 2024-06-27T21:40:37.875Z] tests/test_retry.py .............................                        [ 76%]
[task 2024-06-27T21:40:37.931Z] tests/test_retry_async.py ssss.....                                      [100%]
[task 2024-06-27T21:40:37.931Z] 
[task 2024-06-27T21:40:37.931Z] =============================== warnings summary ===============================
[task 2024-06-27T21:40:37.931Z] tests/test_retry_async.py:28
[task 2024-06-27T21:40:37.931Z]   /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-06-27T21:40:37.931Z]     @pytest.mark.asyncio
[task 2024-06-27T21:40:37.931Z] 
[task 2024-06-27T21:40:37.931Z] tests/test_retry_async.py:37
[task 2024-06-27T21:40:37.931Z]   /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-06-27T21:40:37.931Z]     @pytest.mark.asyncio
[task 2024-06-27T21:40:37.932Z] 
[task 2024-06-27T21:40:37.932Z] tests/test_retry_async.py:48
[task 2024-06-27T21:40:37.932Z]   /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-06-27T21:40:37.932Z]     @pytest.mark.asyncio
[task 2024-06-27T21:40:37.932Z] 
[task 2024-06-27T21:40:37.932Z] tests/test_retry_async.py:62
[task 2024-06-27T21:40:37.932Z]   /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-06-27T21:40:37.932Z]     @pytest.mark.asyncio
[task 2024-06-27T21:40:37.932Z] 
[task 2024-06-27T21:40:37.933Z] tests/test_retry_async.py::test_retry_async_fail_first
[task 2024-06-27T21:40:37.933Z] tests/test_retry_async.py::test_retry_async_always_fail
[task 2024-06-27T21:40:37.933Z] tests/test_retry_async.py::test_retriable_async_fail_first
[task 2024-06-27T21:40:37.934Z] tests/test_retry_async.py::test_retriable_async_always_fail_async
[task 2024-06-27T21:40:37.934Z]   /builds/worker/checkouts/vcs/.tox/py312/lib/python3.12/site-packages/_pytest/python.py:151: PytestUnhandledCoroutineWarning: async def functions are not natively supported and have been skipped.
[task 2024-06-27T21:40:37.934Z]   You need to install a suitable plugin for your async framework, for example:
[task 2024-06-27T21:40:37.934Z]     - anyio
[task 2024-06-27T21:40:37.934Z]     - pytest-asyncio
[task 2024-06-27T21:40:37.934Z]     - pytest-tornasync
[task 2024-06-27T21:40:37.934Z]     - pytest-trio
[task 2024-06-27T21:40:37.934Z]     - pytest-twisted
[task 2024-06-27T21:40:37.934Z]     warnings.warn(PytestUnhandledCoroutineWarning(msg.format(nodeid)))
[task 2024-06-27T21:40:37.934Z] 
[task 2024-06-27T21:40:37.934Z] -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
[task 2024-06-27T21:40:37.934Z] 
[task 2024-06-27T21:40:37.934Z] ---------- coverage: platform linux, python 3.12.4-final-0 -----------
[task 2024-06-27T21:40:37.934Z] Name                   Stmts   Miss Branch BrPart  Cover   Missing
[task 2024-06-27T21:40:37.934Z] ------------------------------------------------------------------
[task 2024-06-27T21:40:37.934Z] src/redo/__init__.py      93     23     32      1    78%   164, 312-320, 324-326, 336-339, 357-370
[task 2024-06-27T21:40:37.934Z] src/redo/cmd.py           32     10     10      3    64%   19, 35-36, 50-55, 59
[task 2024-06-27T21:40:37.934Z] ------------------------------------------------------------------
[task 2024-06-27T21:40:37.934Z] TOTAL                    125     33     42      4    74%
[task 2024-06-27T21:40:37.934Z] 
[task 2024-06-27T21:40:37.934Z] ================== 35 passed, 4 skipped, 8 warnings in 0.33s ===================
[task 2024-06-27T21:40:38.002Z]   py312: OK (7.78=setup[6.91]+cmd[0.87] seconds)
[task 2024-06-27T21:40:38.002Z]   congratulations :) (7.92 seconds)
[taskcluster 2024-06-27 21:40:38.313Z] === Task Finished ===
[taskcluster 2024-06-27 21:40:38.314Z] Successful task run with exit code: 0 completed in 26.027 seconds