[taskcluster 2025-01-27 03:25:23.670Z] Task ID: JbqCj5BYQC2xT9TwrpNJkw
[taskcluster 2025-01-27 03:25:23.670Z] Worker ID: 875900532348895885
[taskcluster 2025-01-27 03:25:23.670Z] Worker Group: us-west1-b
[taskcluster 2025-01-27 03:25:23.670Z] Worker Node Type: projects/887720501152/machineTypes/c2-standard-4
[taskcluster 2025-01-27 03:25:23.670Z] Worker Pool: releng-t/linux-gcp
[taskcluster 2025-01-27 03:25:23.670Z] Worker Version: 38.0.5
[taskcluster 2025-01-27 03:25:23.670Z] Public IP: 35.233.248.16
[taskcluster 2025-01-27 03:25:23.670Z] Hostname: releng-t-linux-gcp-f6juqy86qaqkdvh7w-cl3w
[taskcluster 2025-01-27 03:25:23.670Z] using cache "releng-level-1-checkouts-v3-492fcd7a01ebf3b75635-Mc-5T-zhTP-O4oFfWxOjaw" -> /builds/worker/checkouts

[taskcluster 2025-01-27 03:25:27.250Z] Downloading artifact "public/image.tar.zst" from task ID: Mc-5T-zhTP-O4oFfWxOjaw.
[taskcluster 2025-01-27 03:25:30.038Z] Downloaded artifact successfully.
[taskcluster 2025-01-27 03:25:30.039Z] Downloaded 285.824 mb
[taskcluster 2025-01-27 03:25:30.040Z] Decompressing downloaded image
[taskcluster 2025-01-27 03:25:32.182Z] Loading docker image from downloaded archive.
[taskcluster 2025-01-27 03:25:49.675Z] Image 'public/image.tar.zst' from task 'Mc-5T-zhTP-O4oFfWxOjaw' loaded.  Using image ID sha256:e823e2625a2cc1fad7d684044e8543df2d773be02e6cae0ea4acc1f4bd4b46e0.
[taskcluster 2025-01-27 03:25:49.796Z] === Task Starting ===
[setup 2025-01-27T03:25:52.579Z] run-task started in /
[setup 2025-01-27T03:25:52.580Z] Invoked by command: --balrog-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs/agent -- tox
[setup 2025-01-27T03:25:52.580Z] Python version: 3.11.11
[cache 2025-01-27T03:25:52.581Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2025-01-27T03:25:52.581Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2025-01-27T03:25:52.581Z] volume /builds/worker/checkouts is a cache
[setup 2025-01-27T03:25:52.581Z] running as worker:worker
[vcs 2025-01-27T03:25:52.581Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2025-01-27T03:25:52.584Z] executing ['git', 'clone', 'https://github.com/mozilla-releng/balrog', '/builds/worker/checkouts/vcs']
[vcs 2025-01-27T03:25:52.585Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2025-01-27T03:25:55.920Z] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/balrog', 'dependabot/npm_and_yarn/ui/react-router-dom-6.28.2']
[vcs 2025-01-27T03:25:56.171Z] From https://github.com/mozilla-releng/balrog
[vcs 2025-01-27T03:25:56.171Z]  * branch              dependabot/npm_and_yarn/ui/react-router-dom-6.28.2 -> FETCH_HEAD
[vcs 2025-01-27T03:25:56.180Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla-releng/balrog', 'dependabot/npm_and_yarn/ui/react-router-dom-6.28.2']
[vcs 2025-01-27T03:25:56.409Z] From https://github.com/mozilla-releng/balrog
[vcs 2025-01-27T03:25:56.409Z]  * branch              dependabot/npm_and_yarn/ui/react-router-dom-6.28.2 -> FETCH_HEAD
[vcs 2025-01-27T03:25:56.413Z] executing ['git', 'checkout', '-f', '-B', 'dependabot/npm_and_yarn/ui/react-router-dom-6.28.2', 'c16a3ce3f9179a074bcfbb66392d026843c949f2']
[vcs 2025-01-27T03:25:56.531Z] Switched to a new branch 'dependabot/npm_and_yarn/ui/react-router-dom-6.28.2'
[vcs 2025-01-27T03:25:56.532Z] cleaning git checkout...
[vcs 2025-01-27T03:25:56.532Z] executing ['git', 'clean', '-nxdff']
[vcs 2025-01-27T03:25:56.539Z] removing []
[vcs 2025-01-27T03:25:56.539Z] successfully cleaned git checkout!
[vcs 2025-01-27T03:25:56.540Z] TinderboxPrint:<a href='https://github.com/mozilla-releng/balrog/commit/c16a3ce3f9179a074bcfbb66392d026843c949f2' title='Built from balrog commit c16a3ce3f9179a074bcfbb66392d026843c949f2'>c16a3ce3f9179a074bcfbb66392d026843c949f2</a>
[task 2025-01-27T03:25:56.540Z] executing ['tox']
[task 2025-01-27T03:25:57.171Z] check: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2025-01-27T03:26:04.165Z] check: commands[0]> black --diff --check /builds/worker/checkouts/vcs/agent
[task 2025-01-27T03:26:04.562Z] All done! ✨ 🍰 ✨
[task 2025-01-27T03:26:04.562Z] 11 files would be left unchanged.
[task 2025-01-27T03:26:04.589Z] check: commands[1]> isort --check --diff /builds/worker/checkouts/vcs/agent
[task 2025-01-27T03:26:04.706Z] Skipped 1 files
[task 2025-01-27T03:26:04.720Z] check: commands[2]> flake8 /builds/worker/checkouts/vcs/agent
[task 2025-01-27T03:26:05.024Z] check: commands[3]> pip-compile-multi verify
[task 2025-01-27T03:26:05.079Z] OK - requirements/base.txt was generated from requirements/base.in.
[task 2025-01-27T03:26:05.079Z] OK - requirements/test.txt was generated from requirements/test.in.
[task 2025-01-27T03:26:05.080Z] OK - requirements/local.txt was generated from requirements/local.in.
[task 2025-01-27T03:26:05.282Z] check: OK ✔ in 8.3 seconds
[task 2025-01-27T03:26:05.282Z] py311: install_deps> python -I -m pip install -r /builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2025-01-27T03:26:11.487Z] .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' wheel
[task 2025-01-27T03:26:12.347Z] .pkg: _optional_hooks> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2025-01-27T03:26:12.622Z] .pkg: get_requires_for_build_editable> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2025-01-27T03:26:12.858Z] .pkg: build_editable> python /usr/local/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2025-01-27T03:26:13.128Z] py311: install_package_deps> python -I -m pip install aiohappyeyeballs==2.4.4 aiohttp==3.11.11 aiosignal==1.3.2 attrs==24.3.0 certifi==2024.12.14 frozenlist==1.5.0 idna==3.10 multidict==6.1.0 propcache==0.2.1 sentry-sdk==2.20.0 urllib3==2.3.0 yarl==1.18.3
[task 2025-01-27T03:26:13.597Z] 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 2025-01-27T03:26:14.047Z] py311: commands[0]> py.test -n auto --cov-config=tox.ini --cov-append --cov=balrogagent --cov-report term-missing tests
[task 2025-01-27T03:26:14.379Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2025-01-27T03:26:14.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 2025-01-27T03:26:14.379Z] 
[task 2025-01-27T03:26:14.380Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2025-01-27T03:26:14.988Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2025-01-27T03:26:14.988Z] 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 2025-01-27T03:26:14.988Z] 
[task 2025-01-27T03:26:14.988Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2025-01-27T03:26:15.067Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2025-01-27T03:26:15.067Z] 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 2025-01-27T03:26:15.068Z] 
[task 2025-01-27T03:26:15.068Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2025-01-27T03:26:15.167Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2025-01-27T03:26:15.167Z] 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 2025-01-27T03:26:15.167Z] 
[task 2025-01-27T03:26:15.167Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2025-01-27T03:26:15.196Z] /builds/worker/checkouts/vcs/agent/.tox/py311/lib/python3.11/site-packages/pytest_asyncio/plugin.py:207: PytestDeprecationWarning: The configuration option "asyncio_default_fixture_loop_scope" is unset.
[task 2025-01-27T03:26:15.196Z] 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 2025-01-27T03:26:15.196Z] 
[task 2025-01-27T03:26:15.196Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2025-01-27T03:26:15.713Z] ============================= test session starts ==============================
[task 2025-01-27T03:26:15.713Z] platform linux -- Python 3.11.11, pytest-8.3.4, pluggy-1.5.0
[task 2025-01-27T03:26:15.713Z] cachedir: .tox/py311/.pytest_cache
[task 2025-01-27T03:26:15.713Z] rootdir: /builds/worker/checkouts/vcs/agent
[task 2025-01-27T03:26:15.713Z] configfile: tox.ini
[task 2025-01-27T03:26:15.713Z] plugins: cov-6.0.0, asyncio-0.25.2, xdist-3.6.1
[task 2025-01-27T03:26:15.713Z] asyncio: mode=Mode.STRICT, asyncio_default_fixture_loop_scope=None
[task 2025-01-27T03:26:15.713Z] created: 4/4 workers
[task 2025-01-27T03:26:15.713Z] 4 workers [31 items]
[task 2025-01-27T03:26:15.714Z] 
[task 2025-01-27T03:26:15.967Z] ...............................                                          [100%]
[task 2025-01-27T03:26:15.967Z] 
[task 2025-01-27T03:26:15.967Z] ---------- coverage: platform linux, python 3.11.11-final-0 ----------
[task 2025-01-27T03:26:15.967Z] Name                          Stmts   Miss Branch BrPart  Cover   Missing
[task 2025-01-27T03:26:15.967Z] -------------------------------------------------------------------------
[task 2025-01-27T03:26:15.967Z] src/balrogagent/__init__.py       0      0      0      0   100%
[task 2025-01-27T03:26:15.967Z] src/balrogagent/changes.py       14      1      4      0    94%   6
[task 2025-01-27T03:26:15.967Z] src/balrogagent/client.py        37     27      4      0    24%   18-38, 42, 46-56
[task 2025-01-27T03:26:15.967Z] src/balrogagent/cmd.py           80     17     38      4    77%   47, 83-86, 89, 91->78, 106-122, 126
[task 2025-01-27T03:26:15.968Z] src/balrogagent/log.py           46     29     12      0    29%   63-65, 74-105, 116-120, 124-130
[task 2025-01-27T03:26:15.968Z] -------------------------------------------------------------------------
[task 2025-01-27T03:26:15.968Z] TOTAL                           177     74     58      4    57%
[task 2025-01-27T03:26:15.968Z] 
[task 2025-01-27T03:26:15.968Z] ============================== 31 passed in 1.47s ==============================
[task 2025-01-27T03:26:16.013Z]   check: OK (8.30=setup[7.38]+cmd[0.42,0.13,0.30,0.07] seconds)
[task 2025-01-27T03:26:16.013Z]   py311: OK (10.92=setup[8.96]+cmd[1.97] seconds)
[task 2025-01-27T03:26:16.013Z]   congratulations :) (19.32 seconds)
[taskcluster 2025-01-27 03:26:16.647Z] === Task Finished ===
[taskcluster 2025-01-27 03:26:16.647Z] Successful task run with exit code: 0 completed in 52.978 seconds