[taskcluster 2024-10-17 15:52:58.539Z] Task ID: K36lDZf4QOiiBUNMF3e0-w
[taskcluster 2024-10-17 15:52:58.539Z] Worker ID: 6947172684580818790
[taskcluster 2024-10-17 15:52:58.539Z] Worker Group: us-central1-c
[taskcluster 2024-10-17 15:52:58.539Z] Worker Node Type: projects/887720501152/machineTypes/c2-standard-4
[taskcluster 2024-10-17 15:52:58.539Z] Worker Pool: releng-t/linux-gcp
[taskcluster 2024-10-17 15:52:58.539Z] Worker Version: 38.0.5
[taskcluster 2024-10-17 15:52:58.539Z] Public IP: 34.45.14.42
[taskcluster 2024-10-17 15:52:58.539Z] Hostname: releng-t-linux-gcp-brps5t40qm6sqccvazvr3a
[taskcluster 2024-10-17 15:52:58.539Z] using cache "releng-level-1-checkouts-v3-7f8630edfaa3588fb9ab-FIFCGTSoSM-Mzg7crJjYdQ" -> /builds/worker/checkouts

[taskcluster 2024-10-17 15:52:58.943Z] Image 'public/image.tar.zst' from task 'FIFCGTSoSM-Mzg7crJjYdQ' loaded.  Using image ID sha256:8374b430ab4c25cb16315120d3fc8d67b12552344b42d094cfea0131b37c0dee.
[taskcluster 2024-10-17 15:52:58.996Z] === Task Starting ===
[setup 2024-10-17T15:52:59.378Z] run-task started in /
[setup 2024-10-17T15:52:59.378Z] Invoked by command: --balrog-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs/agent -- tox
[setup 2024-10-17T15:52:59.378Z] Python version: 3.11.2
[cache 2024-10-17T15:52:59.380Z] cache /builds/worker/checkouts exists; requirements: gid=1000 uid=1000 version=1
[volume 2024-10-17T15:52:59.380Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2024-10-17T15:52:59.380Z] volume /builds/worker/checkouts is a cache
[setup 2024-10-17T15:52:59.380Z] running as worker:worker
[vcs 2024-10-17T15:52:59.380Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2024-10-17T15:52:59.382Z] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/balrog', 'dependabot/pip/requirements/sphinx-7.3.7']
[vcs 2024-10-17T15:52:59.582Z] From https://github.com/mozilla-releng/balrog
[vcs 2024-10-17T15:52:59.582Z]  * branch              dependabot/pip/requirements/sphinx-7.3.7 -> FETCH_HEAD
[vcs 2024-10-17T15:52:59.591Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla-releng/balrog', 'dependabot/pip/requirements/sphinx-7.3.7']
[vcs 2024-10-17T15:52:59.766Z] From https://github.com/mozilla-releng/balrog
[vcs 2024-10-17T15:52:59.767Z]  * branch              dependabot/pip/requirements/sphinx-7.3.7 -> FETCH_HEAD
[vcs 2024-10-17T15:52:59.771Z] executing ['git', 'checkout', '-f', '-B', 'dependabot/pip/requirements/sphinx-7.3.7', '3d0c28c4b7296d8e2f2b180fcfd6d2fc7371317a']
[vcs 2024-10-17T15:52:59.778Z] Reset branch 'dependabot/pip/requirements/sphinx-7.3.7'
[vcs 2024-10-17T15:52:59.779Z] cleaning git checkout...
[vcs 2024-10-17T15:52:59.779Z] executing ['git', 'clean', '-nxdff']
[vcs 2024-10-17T15:52:59.809Z] removing ['/builds/worker/checkouts/vcs/client/.coverage', '/builds/worker/checkouts/vcs/client/.tox/', '/builds/worker/checkouts/vcs/client/src/balrogclient.egg-info/']
[vcs 2024-10-17T15:52:59.912Z] successfully cleaned git checkout!
[vcs 2024-10-17T15:52:59.914Z] TinderboxPrint:<a href='https://github.com/mozilla-releng/balrog/commit/3d0c28c4b7296d8e2f2b180fcfd6d2fc7371317a' title='Built from balrog commit 3d0c28c4b7296d8e2f2b180fcfd6d2fc7371317a'>3d0c28c4b7296d8e2f2b180fcfd6d2fc7371317a</a>
[task 2024-10-17T15:52:59.914Z] executing ['tox']
[task 2024-10-17T15:53:00.707Z] check: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2024-10-17T15:53:09.834Z] check: commands[0]> black --diff --check /builds/worker/checkouts/vcs/agent
[task 2024-10-17T15:53:10.270Z] All done! ✨ 🍰 ✨
[task 2024-10-17T15:53:10.270Z] 11 files would be left unchanged.
[task 2024-10-17T15:53:10.309Z] check: commands[1]> isort --check --diff /builds/worker/checkouts/vcs/agent
[task 2024-10-17T15:53:10.445Z] Skipped 1 files
[task 2024-10-17T15:53:10.464Z] check: commands[2]> flake8 /builds/worker/checkouts/vcs/agent
[task 2024-10-17T15:53:10.816Z] check: commands[3]> pip-compile-multi verify
[task 2024-10-17T15:53:10.882Z] OK - requirements/base.txt was generated from requirements/base.in.
[task 2024-10-17T15:53:10.883Z] OK - requirements/test.txt was generated from requirements/test.in.
[task 2024-10-17T15:53:10.883Z] OK - requirements/local.txt was generated from requirements/local.in.
[task 2024-10-17T15:53:11.068Z] check: OK ✔ in 10.49 seconds
[task 2024-10-17T15:53:11.068Z] py311: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2024-10-17T15:53:19.966Z] .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' wheel
[task 2024-10-17T15:53:21.181Z] .pkg: _optional_hooks> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-10-17T15:53:21.428Z] .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-10-17T15:53:21.479Z] .pkg: install_requires_for_build_editable> python -I -m pip install wheel
[task 2024-10-17T15:53:21.989Z] .pkg: build_editable> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-10-17T15:53:22.099Z] py311: install_package_deps> python -I -m pip install aiohappyeyeballs==2.4.3 aiohttp==3.10.10 aiosignal==1.3.1 attrs==24.2.0 certifi==2024.8.30 frozenlist==1.4.1 idna==3.10 multidict==6.1.0 propcache==0.2.0 sentry-sdk==2.17.0 urllib3==2.2.3 yarl==1.15.4
[task 2024-10-17T15:53:22.656Z] 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-10-17T15:53:23.116Z] py311: commands[0]> py.test -n auto --cov-config=tox.ini --cov-append --cov=balrogagent --cov-report term-missing tests
[task 2024-10-17T15:53:23.475Z] /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-10-17T15:53:23.475Z] 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-10-17T15:53:23.475Z] 
[task 2024-10-17T15:53:23.475Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-10-17T15:53:24.155Z] /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-10-17T15:53:24.155Z] 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-10-17T15:53:24.155Z] 
[task 2024-10-17T15:53:24.155Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-10-17T15:53:24.230Z] /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-10-17T15:53:24.232Z] 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-10-17T15:53:24.233Z] 
[task 2024-10-17T15:53:24.233Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-10-17T15:53:24.275Z] /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-10-17T15:53:24.276Z] 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-10-17T15:53:24.277Z] 
[task 2024-10-17T15:53:24.278Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-10-17T15:53:24.356Z] /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-10-17T15:53:24.358Z] 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-10-17T15:53:24.358Z] 
[task 2024-10-17T15:53:24.359Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-10-17T15:53:24.927Z] ============================= test session starts ==============================
[task 2024-10-17T15:53:24.927Z] platform linux -- Python 3.11.7, pytest-8.3.3, pluggy-1.5.0
[task 2024-10-17T15:53:24.927Z] cachedir: .tox/py311/.pytest_cache
[task 2024-10-17T15:53:24.927Z] rootdir: /builds/worker/checkouts/vcs/agent
[task 2024-10-17T15:53:24.927Z] configfile: tox.ini
[task 2024-10-17T15:53:24.927Z] plugins: cov-5.0.0, asyncio-0.24.0, xdist-3.6.1
[task 2024-10-17T15:53:24.927Z] asyncio: mode=Mode.STRICT, default_loop_scope=None
[task 2024-10-17T15:53:24.927Z] created: 4/4 workers
[task 2024-10-17T15:53:24.927Z] 4 workers [31 items]
[task 2024-10-17T15:53:24.927Z] 
[task 2024-10-17T15:53:25.246Z] ...............................                                          [100%]
[task 2024-10-17T15:53:25.246Z] 
[task 2024-10-17T15:53:25.246Z] ---------- coverage: platform linux, python 3.11.7-final-0 -----------
[task 2024-10-17T15:53:25.246Z] Name                          Stmts   Miss Branch BrPart  Cover   Missing
[task 2024-10-17T15:53:25.246Z] -------------------------------------------------------------------------
[task 2024-10-17T15:53:25.246Z] src/balrogagent/__init__.py       0      0      0      0   100%
[task 2024-10-17T15:53:25.246Z] src/balrogagent/changes.py       14      1      4      0    94%   6
[task 2024-10-17T15:53:25.246Z] src/balrogagent/client.py        37     27      4      0    24%   18-38, 42, 46-56
[task 2024-10-17T15:53:25.247Z] src/balrogagent/cmd.py           80     17     38      4    77%   47, 83-86, 89, 91->78, 106-122, 126
[task 2024-10-17T15:53:25.247Z] src/balrogagent/log.py           46     29     12      0    29%   63-65, 74-105, 116-120, 124-130
[task 2024-10-17T15:53:25.247Z] -------------------------------------------------------------------------
[task 2024-10-17T15:53:25.247Z] TOTAL                           177     74     58      4    57%
[task 2024-10-17T15:53:25.247Z] 
[task 2024-10-17T15:53:25.247Z] ============================== 31 passed in 1.64s ==============================
[task 2024-10-17T15:53:25.308Z] .pkg: _exit> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-10-17T15:53:25.351Z]   check: OK (10.49=setup[9.43]+cmd[0.47,0.15,0.35,0.08] seconds)
[task 2024-10-17T15:53:25.352Z]   py311: OK (14.45=setup[12.26]+cmd[2.19] seconds)
[task 2024-10-17T15:53:25.352Z]   congratulations :) (25.11 seconds)
[taskcluster 2024-10-17 15:53:25.620Z] === Task Finished ===
[taskcluster 2024-10-17 15:53:25.620Z] Successful task run with exit code: 0 completed in 27.081 seconds