[taskcluster 2024-09-19 15:48:45.524Z] Task ID: f1SOUjarRGefBR9MLAl_MA
[taskcluster 2024-09-19 15:48:45.525Z] Worker ID: 5159839980109035899
[taskcluster 2024-09-19 15:48:45.525Z] Worker Group: us-west1-b
[taskcluster 2024-09-19 15:48:45.525Z] Worker Node Type: projects/887720501152/machineTypes/c2-standard-4
[taskcluster 2024-09-19 15:48:45.525Z] Worker Pool: releng-t/linux-gcp
[taskcluster 2024-09-19 15:48:45.525Z] Worker Version: 38.0.5
[taskcluster 2024-09-19 15:48:45.525Z] Public IP: 34.19.58.202
[taskcluster 2024-09-19 15:48:45.525Z] Hostname: releng-t-linux-gcp-jji9y7kbqoojzbmjzn48ug
[taskcluster 2024-09-19 15:48:45.525Z] using cache "releng-level-1-checkouts-v3-7f8630edfaa3588fb9ab-FIFCGTSoSM-Mzg7crJjYdQ" -> /builds/worker/checkouts

[taskcluster 2024-09-19 15:48:49.854Z] Downloading artifact "public/image.tar.zst" from task ID: FIFCGTSoSM-Mzg7crJjYdQ.
[taskcluster 2024-09-19 15:48:52.891Z] Downloaded artifact successfully.
[taskcluster 2024-09-19 15:48:52.892Z] Downloaded 284.699 mb
[taskcluster 2024-09-19 15:48:52.893Z] Decompressing downloaded image
[taskcluster 2024-09-19 15:48:54.903Z] Loading docker image from downloaded archive.
[taskcluster 2024-09-19 15:49:13.206Z] Image 'public/image.tar.zst' from task 'FIFCGTSoSM-Mzg7crJjYdQ' loaded.  Using image ID sha256:8374b430ab4c25cb16315120d3fc8d67b12552344b42d094cfea0131b37c0dee.
[taskcluster 2024-09-19 15:49:13.323Z] === Task Starting ===
[setup 2024-09-19T15:49:15.954Z] run-task started in /
[setup 2024-09-19T15:49:15.954Z] Invoked by command: --balrog-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs/agent -- tox
[setup 2024-09-19T15:49:15.954Z] Python version: 3.11.2
[cache 2024-09-19T15:49:15.955Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2024-09-19T15:49:15.955Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2024-09-19T15:49:15.956Z] volume /builds/worker/checkouts is a cache
[setup 2024-09-19T15:49:15.956Z] running as worker:worker
[vcs 2024-09-19T15:49:15.956Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2024-09-19T15:49:15.958Z] executing ['git', 'clone', 'https://github.com/mozilla-releng/balrog', '/builds/worker/checkouts/vcs']
[vcs 2024-09-19T15:49:15.959Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2024-09-19T15:49:21.901Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/jcristau/balrog', 'deps-20240919']
[vcs 2024-09-19T15:49:22.284Z] From https://github.com/jcristau/balrog
[vcs 2024-09-19T15:49:22.284Z]  * branch              deps-20240919 -> FETCH_HEAD
[vcs 2024-09-19T15:49:22.286Z] executing ['git', 'checkout', '-f', '-B', 'deps-20240919', '7cb924ab2c3d6cd778fa148eb4feee148e15bbc2']
[vcs 2024-09-19T15:49:22.397Z] Switched to a new branch 'deps-20240919'
[vcs 2024-09-19T15:49:22.398Z] cleaning git checkout...
[vcs 2024-09-19T15:49:22.398Z] executing ['git', 'clean', '-nxdff']
[vcs 2024-09-19T15:49:22.404Z] removing []
[vcs 2024-09-19T15:49:22.404Z] successfully cleaned git checkout!
[vcs 2024-09-19T15:49:22.406Z] TinderboxPrint:<a href='https://github.com/jcristau/balrog/commit/7cb924ab2c3d6cd778fa148eb4feee148e15bbc2' title='Built from balrog commit 7cb924ab2c3d6cd778fa148eb4feee148e15bbc2'>7cb924ab2c3d6cd778fa148eb4feee148e15bbc2</a>
[task 2024-09-19T15:49:22.406Z] executing ['tox']
[task 2024-09-19T15:49:23.130Z] check: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2024-09-19T15:49:31.726Z] check: commands[0]> black --diff --check /builds/worker/checkouts/vcs/agent
[task 2024-09-19T15:49:32.129Z] All done! ✨ 🍰 ✨
[task 2024-09-19T15:49:32.129Z] 11 files would be left unchanged.
[task 2024-09-19T15:49:32.156Z] check: commands[1]> isort --check --diff /builds/worker/checkouts/vcs/agent
[task 2024-09-19T15:49:32.274Z] Skipped 1 files
[task 2024-09-19T15:49:32.289Z] check: commands[2]> flake8 /builds/worker/checkouts/vcs/agent
[task 2024-09-19T15:49:32.601Z] check: commands[3]> pip-compile-multi verify
[task 2024-09-19T15:49:32.659Z] OK - requirements/base.txt was generated from requirements/base.in.
[task 2024-09-19T15:49:32.660Z] OK - requirements/test.txt was generated from requirements/test.in.
[task 2024-09-19T15:49:32.660Z] OK - requirements/local.txt was generated from requirements/local.in.
[task 2024-09-19T15:49:32.830Z] check: OK ✔ in 9.81 seconds
[task 2024-09-19T15:49:32.830Z] py311: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2024-09-19T15:49:40.652Z] .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' wheel
[task 2024-09-19T15:49:41.650Z] .pkg: _optional_hooks> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-09-19T15:49:41.854Z] .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-09-19T15:49:41.898Z] .pkg: install_requires_for_build_editable> python -I -m pip install wheel
[task 2024-09-19T15:49:42.283Z] .pkg: build_editable> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-09-19T15:49:42.382Z] py311: install_package_deps> python -I -m pip install aiohappyeyeballs==2.4.0 aiohttp==3.10.5 aiosignal==1.3.1 attrs==24.2.0 certifi==2024.8.30 frozenlist==1.4.1 idna==3.10 multidict==6.1.0 sentry-sdk==2.14.0 urllib3==2.2.3 yarl==1.11.1
[task 2024-09-19T15:49:42.818Z] 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-09-19T15:49:43.235Z] py311: commands[0]> py.test -n auto --cov-config=tox.ini --cov-append --cov=balrogagent --cov-report term-missing tests
[task 2024-09-19T15:49:43.573Z] /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-09-19T15:49:43.573Z] 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-09-19T15:49:43.573Z] 
[task 2024-09-19T15:49:43.573Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-09-19T15:49:44.357Z] /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-09-19T15:49:44.357Z] 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-09-19T15:49:44.357Z] 
[task 2024-09-19T15:49:44.357Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-09-19T15:49:44.378Z] /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-09-19T15:49:44.379Z] 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-09-19T15:49:44.379Z] 
[task 2024-09-19T15:49:44.379Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-09-19T15:49:44.389Z] /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-09-19T15:49:44.389Z] 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-09-19T15:49:44.390Z] 
[task 2024-09-19T15:49:44.390Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-09-19T15:49:44.538Z] /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-09-19T15:49:44.539Z] 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-09-19T15:49:44.539Z] 
[task 2024-09-19T15:49:44.539Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-09-19T15:49:45.105Z] ============================= test session starts ==============================
[task 2024-09-19T15:49:45.106Z] platform linux -- Python 3.11.7, pytest-8.3.3, pluggy-1.5.0
[task 2024-09-19T15:49:45.106Z] cachedir: .tox/py311/.pytest_cache
[task 2024-09-19T15:49:45.106Z] rootdir: /builds/worker/checkouts/vcs/agent
[task 2024-09-19T15:49:45.106Z] configfile: tox.ini
[task 2024-09-19T15:49:45.106Z] plugins: asyncio-0.24.0, cov-5.0.0, xdist-3.6.1
[task 2024-09-19T15:49:45.106Z] asyncio: mode=Mode.STRICT, default_loop_scope=None
[task 2024-09-19T15:49:45.106Z] created: 4/4 workers
[task 2024-09-19T15:49:45.106Z] 4 workers [31 items]
[task 2024-09-19T15:49:45.106Z] 
[task 2024-09-19T15:49:45.424Z] ...............................                                          [100%]
[task 2024-09-19T15:49:45.424Z] 
[task 2024-09-19T15:49:45.424Z] ---------- coverage: platform linux, python 3.11.7-final-0 -----------
[task 2024-09-19T15:49:45.424Z] Name                          Stmts   Miss Branch BrPart  Cover   Missing
[task 2024-09-19T15:49:45.424Z] -------------------------------------------------------------------------
[task 2024-09-19T15:49:45.425Z] src/balrogagent/__init__.py       0      0      0      0   100%
[task 2024-09-19T15:49:45.425Z] src/balrogagent/changes.py       14      1      4      0    94%   6
[task 2024-09-19T15:49:45.425Z] src/balrogagent/client.py        37     27     12      0    20%   18-38, 42, 46-56
[task 2024-09-19T15:49:45.425Z] src/balrogagent/cmd.py           80     17     45      5    76%   47, 83-86, 88->exit, 89, 91->78, 106-122, 126
[task 2024-09-19T15:49:45.425Z] src/balrogagent/log.py           46     29     12      0    29%   63-65, 74-105, 116-120, 124-130
[task 2024-09-19T15:49:45.425Z] -------------------------------------------------------------------------
[task 2024-09-19T15:49:45.425Z] TOTAL                           177     74     73      5    56%
[task 2024-09-19T15:49:45.425Z] 
[task 2024-09-19T15:49:45.425Z] ============================== 31 passed in 1.72s ==============================
[task 2024-09-19T15:49:45.472Z] .pkg: _exit> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-09-19T15:49:45.515Z]   check: OK (9.81=setup[8.87]+cmd[0.43,0.13,0.31,0.07] seconds)
[task 2024-09-19T15:49:45.515Z]   py311: OK (12.84=setup[10.61]+cmd[2.24] seconds)
[task 2024-09-19T15:49:45.515Z]   congratulations :) (22.80 seconds)
[taskcluster 2024-09-19 15:49:46.178Z] === Task Finished ===
[taskcluster 2024-09-19 15:49:46.178Z] Successful task run with exit code: 0 completed in 60.655 seconds