[taskcluster 2024-12-16 03:26:26.401Z] Task ID: XZ5jI4AtRW6kg41dQ-_bEg
[taskcluster 2024-12-16 03:26:26.401Z] Worker ID: 5593912993096278931
[taskcluster 2024-12-16 03:26:26.401Z] Worker Group: us-west1-a
[taskcluster 2024-12-16 03:26:26.401Z] Worker Node Type: projects/887720501152/machineTypes/c2-standard-4
[taskcluster 2024-12-16 03:26:26.401Z] Worker Pool: releng-t/linux-gcp
[taskcluster 2024-12-16 03:26:26.401Z] Worker Version: 38.0.5
[taskcluster 2024-12-16 03:26:26.401Z] Public IP: 34.127.104.28
[taskcluster 2024-12-16 03:26:26.401Z] Hostname: releng-t-linux-gcp-craq4gxoqqshwljx-daiaq
[taskcluster 2024-12-16 03:26:26.401Z] using cache "releng-level-1-checkouts-v3-492fcd7a01ebf3b75635-Mc-5T-zhTP-O4oFfWxOjaw" -> /builds/worker/checkouts

[taskcluster 2024-12-16 03:26:32.202Z] Downloading artifact "public/image.tar.zst" from task ID: Mc-5T-zhTP-O4oFfWxOjaw.
[taskcluster 2024-12-16 03:26:36.665Z] Downloaded artifact successfully.
[taskcluster 2024-12-16 03:26:36.666Z] Downloaded 285.824 mb
[taskcluster 2024-12-16 03:26:36.666Z] Decompressing downloaded image
[taskcluster 2024-12-16 03:26:38.704Z] Loading docker image from downloaded archive.
[taskcluster 2024-12-16 03:26:56.228Z] Image 'public/image.tar.zst' from task 'Mc-5T-zhTP-O4oFfWxOjaw' loaded.  Using image ID sha256:e823e2625a2cc1fad7d684044e8543df2d773be02e6cae0ea4acc1f4bd4b46e0.
[taskcluster 2024-12-16 03:26:56.344Z] === Task Starting ===
[setup 2024-12-16T03:26:59.005Z] run-task started in /
[setup 2024-12-16T03:26:59.005Z] Invoked by command: --balrog-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs/agent -- tox
[setup 2024-12-16T03:26:59.005Z] Python version: 3.11.11
[cache 2024-12-16T03:26:59.006Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2024-12-16T03:26:59.006Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2024-12-16T03:26:59.006Z] volume /builds/worker/checkouts is a cache
[setup 2024-12-16T03:26:59.006Z] running as worker:worker
[vcs 2024-12-16T03:26:59.006Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2024-12-16T03:26:59.009Z] executing ['git', 'clone', 'https://github.com/mozilla-releng/balrog', '/builds/worker/checkouts/vcs']
[vcs 2024-12-16T03:26:59.010Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2024-12-16T03:27:01.978Z] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/balrog', 'dependabot/pip/requirements/pytest-asyncio-0.25.0']
[vcs 2024-12-16T03:27:02.222Z] From https://github.com/mozilla-releng/balrog
[vcs 2024-12-16T03:27:02.223Z]  * branch              dependabot/pip/requirements/pytest-asyncio-0.25.0 -> FETCH_HEAD
[vcs 2024-12-16T03:27:02.231Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla-releng/balrog', 'dependabot/pip/requirements/pytest-asyncio-0.25.0']
[vcs 2024-12-16T03:27:02.554Z] From https://github.com/mozilla-releng/balrog
[vcs 2024-12-16T03:27:02.554Z]  * branch              dependabot/pip/requirements/pytest-asyncio-0.25.0 -> FETCH_HEAD
[vcs 2024-12-16T03:27:02.557Z] executing ['git', 'checkout', '-f', '-B', 'dependabot/pip/requirements/pytest-asyncio-0.25.0', 'd79f09b07cdb42ab19a8069f0b5bdb4c9d29b7e2']
[vcs 2024-12-16T03:27:02.677Z] Switched to a new branch 'dependabot/pip/requirements/pytest-asyncio-0.25.0'
[vcs 2024-12-16T03:27:02.678Z] cleaning git checkout...
[vcs 2024-12-16T03:27:02.678Z] executing ['git', 'clean', '-nxdff']
[vcs 2024-12-16T03:27:02.685Z] removing []
[vcs 2024-12-16T03:27:02.685Z] successfully cleaned git checkout!
[vcs 2024-12-16T03:27:02.686Z] TinderboxPrint:<a href='https://github.com/mozilla-releng/balrog/commit/d79f09b07cdb42ab19a8069f0b5bdb4c9d29b7e2' title='Built from balrog commit d79f09b07cdb42ab19a8069f0b5bdb4c9d29b7e2'>d79f09b07cdb42ab19a8069f0b5bdb4c9d29b7e2</a>
[task 2024-12-16T03:27:02.686Z] executing ['tox']
[task 2024-12-16T03:27:03.297Z] check: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2024-12-16T03:27:09.048Z] check: commands[0]> black --diff --check /builds/worker/checkouts/vcs/agent
[task 2024-12-16T03:27:09.434Z] All done! ✨ 🍰 ✨
[task 2024-12-16T03:27:09.434Z] 11 files would be left unchanged.
[task 2024-12-16T03:27:09.462Z] check: commands[1]> isort --check --diff /builds/worker/checkouts/vcs/agent
[task 2024-12-16T03:27:09.578Z] Skipped 1 files
[task 2024-12-16T03:27:09.591Z] check: commands[2]> flake8 /builds/worker/checkouts/vcs/agent
[task 2024-12-16T03:27:09.890Z] check: commands[3]> pip-compile-multi verify
[task 2024-12-16T03:27:09.945Z] OK - requirements/base.txt was generated from requirements/base.in.
[task 2024-12-16T03:27:09.946Z] OK - requirements/test.txt was generated from requirements/test.in.
[task 2024-12-16T03:27:09.946Z] OK - requirements/local.txt was generated from requirements/local.in.
[task 2024-12-16T03:27:10.149Z] check: OK ✔ in 7.02 seconds
[task 2024-12-16T03:27:10.149Z] py311: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2024-12-16T03:27:15.662Z] .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' wheel
[task 2024-12-16T03:27:16.493Z] .pkg: _optional_hooks> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-16T03:27:16.779Z] .pkg: get_requires_for_build_editable> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-16T03:27:17.002Z] .pkg: build_editable> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-16T03:27:17.259Z] py311: install_package_deps> python -I -m pip install aiohappyeyeballs==2.4.4 aiohttp==3.11.10 aiosignal==1.3.1 attrs==24.2.0 certifi==2024.8.30 frozenlist==1.5.0 idna==3.10 multidict==6.1.0 propcache==0.2.1 sentry-sdk==2.19.2 urllib3==2.2.3 yarl==1.18.3
[task 2024-12-16T03:27:17.720Z] py311: install_package> python -I -m pip install --force-reinstall --no-deps /builds/worker/checkouts/vcs/agent/.tox/.tmp/package/1/balrogagent-0.1-0.editable-py3-none-any.whl
[task 2024-12-16T03:27:18.163Z] py311: commands[0]> py.test -n auto --cov-config=tox.ini --cov-append --cov=balrogagent --cov-report term-missing tests
[task 2024-12-16T03:27:18.504Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:208: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2024-12-16T03:27:18.504Z] The event loop scope for asynchronous fixtures will default to the fixture caching scope. Future versions of pytest-asyncio will default the loop scope for asynchronous fixtures to function scope. Set the default fixture loop scope explicitly in order to avoid unexpected behavior in the future. Valid fixture loop scopes are: "function", "class", "module", "package", "session"
[task 2024-12-16T03:27:18.504Z] 
[task 2024-12-16T03:27:18.504Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-12-16T03:27:19.098Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:208: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2024-12-16T03:27:19.098Z] The event loop scope for asynchronous fixtures will default to the fixture caching scope. Future versions of pytest-asyncio will default the loop scope for asynchronous fixtures to function scope. Set the default fixture loop scope explicitly in order to avoid unexpected behavior in the future. Valid fixture loop scopes are: "function", "class", "module", "package", "session"
[task 2024-12-16T03:27:19.098Z] 
[task 2024-12-16T03:27:19.098Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-12-16T03:27:19.224Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:208: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2024-12-16T03:27:19.224Z] The event loop scope for asynchronous fixtures will default to the fixture caching scope. Future versions of pytest-asyncio will default the loop scope for asynchronous fixtures to function scope. Set the default fixture loop scope explicitly in order to avoid unexpected behavior in the future. Valid fixture loop scopes are: "function", "class", "module", "package", "session"
[task 2024-12-16T03:27:19.224Z] 
[task 2024-12-16T03:27:19.224Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-12-16T03:27:19.247Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:208: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2024-12-16T03:27:19.247Z] The event loop scope for asynchronous fixtures will default to the fixture caching scope. Future versions of pytest-asyncio will default the loop scope for asynchronous fixtures to function scope. Set the default fixture loop scope explicitly in order to avoid unexpected behavior in the future. Valid fixture loop scopes are: "function", "class", "module", "package", "session"
[task 2024-12-16T03:27:19.247Z] 
[task 2024-12-16T03:27:19.247Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-12-16T03:27:19.316Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:208: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2024-12-16T03:27:19.316Z] The event loop scope for asynchronous fixtures will default to the fixture caching scope. Future versions of pytest-asyncio will default the loop scope for asynchronous fixtures to function scope. Set the default fixture loop scope explicitly in order to avoid unexpected behavior in the future. Valid fixture loop scopes are: "function", "class", "module", "package", "session"
[task 2024-12-16T03:27:19.316Z] 
[task 2024-12-16T03:27:19.316Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-12-16T03:27:19.816Z] ============================= test session starts ==============================
[task 2024-12-16T03:27:19.817Z] platform linux -- Python 3.11.11, pytest-8.3.4, pluggy-1.5.0
[task 2024-12-16T03:27:19.818Z] cachedir: .tox/py311/.pytest_cache
[task 2024-12-16T03:27:19.818Z] rootdir: /builds/worker/checkouts/vcs/agent
[task 2024-12-16T03:27:19.818Z] configfile: tox.ini
[task 2024-12-16T03:27:19.818Z] plugins: xdist-3.6.1, cov-6.0.0, asyncio-0.24.0
[task 2024-12-16T03:27:19.818Z] asyncio: mode=Mode.STRICT, default_loop_scope=None
[task 2024-12-16T03:27:19.818Z] created: 4/4 workers
[task 2024-12-16T03:27:19.818Z] 4 workers [31 items]
[task 2024-12-16T03:27:19.818Z] 
[task 2024-12-16T03:27:20.064Z] ...............................                                          [100%]
[task 2024-12-16T03:27:20.064Z] 
[task 2024-12-16T03:27:20.064Z] ---------- coverage: platform linux, python 3.11.11-final-0 ----------
[task 2024-12-16T03:27:20.064Z] Name                          Stmts   Miss Branch BrPart  Cover   Missing
[task 2024-12-16T03:27:20.064Z] -------------------------------------------------------------------------
[task 2024-12-16T03:27:20.064Z] src/balrogagent/__init__.py       0      0      0      0   100%
[task 2024-12-16T03:27:20.064Z] src/balrogagent/changes.py       14      1      4      0    94%   6
[task 2024-12-16T03:27:20.064Z] src/balrogagent/client.py        37     27      4      0    24%   18-38, 42, 46-56
[task 2024-12-16T03:27:20.064Z] src/balrogagent/cmd.py           80     17     38      4    77%   47, 83-86, 89, 91->78, 106-122, 126
[task 2024-12-16T03:27:20.064Z] src/balrogagent/log.py           46     29     12      0    29%   63-65, 74-105, 116-120, 124-130
[task 2024-12-16T03:27:20.064Z] -------------------------------------------------------------------------
[task 2024-12-16T03:27:20.064Z] TOTAL                           177     74     58      4    57%
[task 2024-12-16T03:27:20.064Z] 
[task 2024-12-16T03:27:20.064Z] ============================== 31 passed in 1.44s ==============================
[task 2024-12-16T03:27:20.106Z]   check: OK (7.02=setup[6.11]+cmd[0.41,0.13,0.30,0.07] seconds)
[task 2024-12-16T03:27:20.106Z]   py311: OK (10.15=setup[8.21]+cmd[1.94] seconds)
[task 2024-12-16T03:27:20.106Z]   congratulations :) (17.27 seconds)
[taskcluster 2024-12-16 03:27:20.720Z] === Task Finished ===
[taskcluster 2024-12-16 03:27:20.720Z] Successful task run with exit code: 0 completed in 54.32 seconds