[taskcluster 2023-10-24 08:59:49.352Z] Task ID: Sb8DJMOkQbOtvg_9t8UQuQ
[taskcluster 2023-10-24 08:59:49.353Z] Worker ID: 1834409294370060761
[taskcluster 2023-10-24 08:59:49.353Z] Worker Group: us-west1
[taskcluster 2023-10-24 08:59:49.353Z] Worker Node Type: projects/887720501152/machineTypes/n2-standard-4
[taskcluster 2023-10-24 08:59:49.353Z] Worker Pool: releng-t/linux-gcp
[taskcluster 2023-10-24 08:59:49.353Z] Worker Version: 38.0.5
[taskcluster 2023-10-24 08:59:49.353Z] Public IP: 35.230.23.211
[taskcluster 2023-10-24 08:59:49.353Z] Hostname: releng-t-linux-gcp-jyml1rmwqhu46dxejdxh4w
[taskcluster 2023-10-24 08:59:49.353Z] using cache "releng-level-1-checkouts-hg58-v3-cd3f3781615a074a8159" -> /builds/worker/checkouts

[taskcluster 2023-10-24 08:59:52.945Z] Downloading artifact "public/image.tar.zst" from task ID: D3vY2eSDRS6NGgisD1K9Gw.
[taskcluster 2023-10-24 08:59:57.949Z] Download Progress: 77.24%
[taskcluster 2023-10-24 08:59:59.361Z] Downloaded artifact successfully.
[taskcluster 2023-10-24 08:59:59.361Z] Downloaded 269.285 mb
[taskcluster 2023-10-24 08:59:59.362Z] Decompressing downloaded image
[taskcluster 2023-10-24 09:00:01.596Z] Loading docker image from downloaded archive.
[taskcluster 2023-10-24 09:00:21.292Z] Image 'public/image.tar.zst' from task 'D3vY2eSDRS6NGgisD1K9Gw' loaded.  Using image ID sha256:94da4131d290ee5e303719054265df8904bc8cf578d0949eda4e8ae50f732489.
[taskcluster 2023-10-24 09:00:21.412Z] === Task Starting ===
[setup 2023-10-24T09:00:23.996Z] run-task started in /
[cache 2023-10-24T09:00:23.998Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2023-10-24T09:00:23.999Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2023-10-24T09:00:23.999Z] volume /builds/worker/checkouts is a cache
[setup 2023-10-24T09:00:23.999Z] running as worker:worker
[vcs 2023-10-24T09:00:23.999Z] executing ['git', 'clone', 'https://github.com/mozilla-releng/balrog', '/builds/worker/checkouts/vcs']
[vcs 2023-10-24T09:00:24.002Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2023-10-24T09:00:28.877Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/michellemounde/balrog', 'duplicate-alias-scheduled-rule']
[vcs 2023-10-24T09:00:29.323Z] From https://github.com/michellemounde/balrog
[vcs 2023-10-24T09:00:29.323Z]  * branch              duplicate-alias-scheduled-rule -> FETCH_HEAD
[vcs 2023-10-24T09:00:29.325Z] executing ['git', 'checkout', '-f', '-B', 'duplicate-alias-scheduled-rule', '333df81763c9f133ef41839cc9d2a299ea23288e']
[vcs 2023-10-24T09:00:29.459Z] Switched to a new branch 'duplicate-alias-scheduled-rule'
[vcs 2023-10-24T09:00:29.460Z] cleaning git checkout...
[vcs 2023-10-24T09:00:29.460Z] executing ['git', 'clean', '-nxdff']
[vcs 2023-10-24T09:00:29.468Z] removing []
[vcs 2023-10-24T09:00:29.468Z] successfully cleaned git checkout!
[vcs 2023-10-24T09:00:29.471Z] TinderboxPrint:<a href='https://github.com/michellemounde/balrog/commit/333df81763c9f133ef41839cc9d2a299ea23288e' title='Built from balrog commit 333df81763c9f133ef41839cc9d2a299ea23288e'>333df81763c9f133ef41839cc9d2a299ea23288e</a>
[task 2023-10-24T09:00:29.471Z] executing ['tox']
[task 2023-10-24T09:00:29.712Z] check create: /builds/worker/checkouts/vcs/agent/.tox/check
[task 2023-10-24T09:00:30.346Z] check installdeps: -r/builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2023-10-24T09:00:42.898Z] check installed: aiohttp==3.8.6,aiosignal==1.3.1,async-timeout==4.0.3,attrs==23.1.0,black==23.9.1,build==1.0.3,certifi==2023.7.22,charset-normalizer==3.3.0,check-manifest==0.49,click==8.1.7,coverage==7.3.2,exceptiongroup==1.1.3,execnet==2.0.2,flake8==6.1.0,frozenlist==1.4.0,idna==3.4,importlib-metadata==6.8.0,iniconfig==2.0.0,isort==5.12.0,mccabe==0.7.0,multidict==6.0.4,mypy-extensions==1.0.0,packaging==23.2,pathspec==0.11.2,pip-compile-multi==2.6.3,pip-tools==7.3.0,platformdirs==3.11.0,pluggy==1.3.0,pycodestyle==2.11.0,pyflakes==3.1.0,pyproject_hooks==1.0.0,pytest==7.4.2,pytest-asyncio==0.21.1,pytest-cov==4.1.0,pytest-xdist==3.3.1,sentry-sdk==1.31.0,tomli==2.0.1,toposort==1.10,typing_extensions==4.8.0,urllib3==2.0.6,yarl==1.9.2,zipp==3.17.0
[task 2023-10-24T09:00:42.898Z] check run-test-pre: PYTHONHASHSEED='1846158746'
[task 2023-10-24T09:00:42.899Z] check run-test: commands[0] | black --diff --check /builds/worker/checkouts/vcs/agent
[task 2023-10-24T09:00:43.322Z] All done! ✨ 🍰 ✨
[task 2023-10-24T09:00:43.322Z] 11 files would be left unchanged.
[task 2023-10-24T09:00:43.346Z] check run-test: commands[1] | isort --check --diff /builds/worker/checkouts/vcs/agent
[task 2023-10-24T09:00:43.485Z] Skipped 1 files
[task 2023-10-24T09:00:43.496Z] check run-test: commands[2] | flake8 /builds/worker/checkouts/vcs/agent
[task 2023-10-24T09:00:43.897Z] check run-test: commands[3] | pip-compile-multi verify
[task 2023-10-24T09:00:43.965Z] OK - requirements/base.txt was generated from requirements/base.in.
[task 2023-10-24T09:00:43.965Z] OK - requirements/test.txt was generated from requirements/test.in.
[task 2023-10-24T09:00:43.965Z] OK - requirements/local.txt was generated from requirements/local.in.
[task 2023-10-24T09:00:43.975Z] py39 create: /builds/worker/checkouts/vcs/agent/.tox/py39
[task 2023-10-24T09:00:44.269Z] py39 installdeps: -r/builds/worker/checkouts/vcs/agent/requirements/test.txt
[task 2023-10-24T09:00:54.902Z] py39 develop-inst: /builds/worker/checkouts/vcs/agent
[task 2023-10-24T09:00:59.469Z] py39 installed: aiohttp==3.8.6,aiosignal==1.3.1,async-timeout==4.0.3,attrs==23.1.0,-e git+https://github.com/mozilla-releng/balrog@333df81763c9f133ef41839cc9d2a299ea23288e#egg=balrogagent&subdirectory=agent,black==23.9.1,build==1.0.3,certifi==2023.7.22,charset-normalizer==3.3.0,check-manifest==0.49,click==8.1.7,coverage==7.3.2,exceptiongroup==1.1.3,execnet==2.0.2,flake8==6.1.0,frozenlist==1.4.0,idna==3.4,importlib-metadata==6.8.0,iniconfig==2.0.0,isort==5.12.0,mccabe==0.7.0,multidict==6.0.4,mypy-extensions==1.0.0,packaging==23.2,pathspec==0.11.2,pip-compile-multi==2.6.3,pip-tools==7.3.0,platformdirs==3.11.0,pluggy==1.3.0,pycodestyle==2.11.0,pyflakes==3.1.0,pyproject_hooks==1.0.0,pytest==7.4.2,pytest-asyncio==0.21.1,pytest-cov==4.1.0,pytest-xdist==3.3.1,sentry-sdk==1.31.0,tomli==2.0.1,toposort==1.10,typing_extensions==4.8.0,urllib3==2.0.6,yarl==1.9.2,zipp==3.17.0
[task 2023-10-24T09:00:59.469Z] py39 run-test-pre: PYTHONHASHSEED='1846158746'
[task 2023-10-24T09:00:59.470Z] py39 run-test: commands[0] | py.test -n auto --cov-config=tox.ini --cov-append --cov=balrogagent --cov-report term-missing tests
[task 2023-10-24T09:01:01.687Z] ============================= test session starts ==============================
[task 2023-10-24T09:01:01.687Z] platform linux -- Python 3.9.15, pytest-7.4.2, pluggy-1.3.0
[task 2023-10-24T09:01:01.687Z] cachedir: .tox/py39/.pytest_cache
[task 2023-10-24T09:01:01.687Z] rootdir: /builds/worker/checkouts/vcs/agent
[task 2023-10-24T09:01:01.687Z] configfile: tox.ini
[task 2023-10-24T09:01:01.687Z] plugins: cov-4.1.0, xdist-3.3.1, asyncio-0.21.1
[task 2023-10-24T09:01:01.687Z] asyncio: mode=strict
[task 2023-10-24T09:01:01.687Z] created: 4/4 workers
[task 2023-10-24T09:01:01.687Z] 4 workers [31 items]
[task 2023-10-24T09:01:01.687Z] 
[task 2023-10-24T09:01:02.060Z] ...............................                                          [100%]
[task 2023-10-24T09:01:02.060Z] =============================== warnings summary ===============================
[task 2023-10-24T09:01:02.060Z] .tox/py39/lib/python3.9/site-packages/xdist/plugin.py:252
[task 2023-10-24T09:01:02.060Z] .tox/py39/lib/python3.9/site-packages/xdist/plugin.py:252
[task 2023-10-24T09:01:02.060Z] .tox/py39/lib/python3.9/site-packages/xdist/plugin.py:252
[task 2023-10-24T09:01:02.060Z] .tox/py39/lib/python3.9/site-packages/xdist/plugin.py:252
[task 2023-10-24T09:01:02.060Z] .tox/py39/lib/python3.9/site-packages/xdist/plugin.py:252
[task 2023-10-24T09:01:02.060Z]   /builds/worker/checkouts/vcs/agent/.tox/py39/lib/python3.9/site-packages/xdist/plugin.py:252: DeprecationWarning: The --rsyncdir command line argument and rsyncdirs config variable are deprecated.
[task 2023-10-24T09:01:02.060Z]   The rsync feature will be removed in pytest-xdist 4.0.
[task 2023-10-24T09:01:02.060Z]     config.issue_config_time_warning(warning, 2)
[task 2023-10-24T09:01:02.060Z] 
[task 2023-10-24T09:01:02.060Z] -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html
[task 2023-10-24T09:01:02.060Z] 
[task 2023-10-24T09:01:02.060Z] ---------- coverage: platform linux, python 3.9.15-final-0 -----------
[task 2023-10-24T09:01:02.060Z] Name                          Stmts   Miss Branch BrPart  Cover   Missing
[task 2023-10-24T09:01:02.060Z] -------------------------------------------------------------------------
[task 2023-10-24T09:01:02.060Z] src/balrogagent/__init__.py       0      0      0      0   100%
[task 2023-10-24T09:01:02.060Z] src/balrogagent/changes.py       14      1      4      0    94%   6
[task 2023-10-24T09:01:02.060Z] src/balrogagent/client.py        37     27      4      0    24%   18-38, 42, 46-56
[task 2023-10-24T09:01:02.060Z] src/balrogagent/cmd.py           79     17     45      5    76%   47, 83-86, 88->exit, 89, 91->79, 106-122, 126
[task 2023-10-24T09:01:02.060Z] src/balrogagent/log.py           46     29     12      0    29%   63-65, 74-105, 116-120, 124-130
[task 2023-10-24T09:01:02.060Z] -------------------------------------------------------------------------
[task 2023-10-24T09:01:02.060Z] TOTAL                           176     74     65      5    57%
[task 2023-10-24T09:01:02.060Z] 
[task 2023-10-24T09:01:02.060Z] ======================== 31 passed, 5 warnings in 2.08s ========================
[task 2023-10-24T09:01:02.101Z] ___________________________________ summary ____________________________________
[task 2023-10-24T09:01:02.101Z]   check: commands succeeded
[task 2023-10-24T09:01:02.101Z]   py39: commands succeeded
[task 2023-10-24T09:01:02.101Z]   congratulations :)
[taskcluster 2023-10-24 09:01:02.334Z] === Task Finished ===
[taskcluster 2023-10-24 09:01:02.335Z] Successful task run with exit code: 0 completed in 72.985 seconds