[taskcluster 2024-12-10 19:52:30.375Z] Task ID: CwNGkSZTSWCM4UUAMoJcnA
[taskcluster 2024-12-10 19:52:30.375Z] Worker ID: 4304468290764612947
[taskcluster 2024-12-10 19:52:30.375Z] Worker Group: us-central1-f
[taskcluster 2024-12-10 19:52:30.375Z] Worker Node Type: projects/970387039909/machineTypes/c2-standard-4
[taskcluster 2024-12-10 19:52:30.375Z] Worker Pool: scriptworker-3/b-linux-gcp
[taskcluster 2024-12-10 19:52:30.375Z] Worker Version: 38.0.5
[taskcluster 2024-12-10 19:52:30.375Z] Public IP: 34.31.4.58
[taskcluster 2024-12-10 19:52:30.375Z] Hostname: scriptworker-3-b-linux-gcp-d7mc8v-zqjk4xslrm3htcq
[taskcluster 2024-12-10 19:52:30.375Z] using cache "scriptworker-level-3-checkouts-v3-7afeb851dd97df8f3607-DBS6rkORSV2FBLVFGWbQHA" -> /builds/worker/checkouts

[taskcluster 2024-12-10 19:52:33.999Z] Downloading artifact "public/image.tar.zst" from task ID: DBS6rkORSV2FBLVFGWbQHA.
[taskcluster 2024-12-10 19:52:37.151Z] Downloaded artifact successfully.
[taskcluster 2024-12-10 19:52:37.152Z] Downloaded 285.440 mb
[taskcluster 2024-12-10 19:52:37.153Z] Decompressing downloaded image
[taskcluster 2024-12-10 19:52:39.809Z] Loading docker image from downloaded archive.
[taskcluster 2024-12-10 19:52:59.533Z] Image 'public/image.tar.zst' from task 'DBS6rkORSV2FBLVFGWbQHA' loaded.  Using image ID sha256:60321cf0c7e2778eeb1d676e892d746ab5a5a30e6c1b723690dc85fd99995106.
[taskcluster 2024-12-10 19:52:59.695Z] === Task Starting ===
[setup 2024-12-10T19:53:02.473Z] run-task started in /
[setup 2024-12-10T19:53:02.473Z] Invoked by command: --scriptworker-checkout=/builds/worker/checkouts/vcs/ --task-cwd /builds/worker/checkouts/vcs -- sh -lxce tox -e shipitscript-py311
[setup 2024-12-10T19:53:02.473Z] Python version: 3.11.9
[cache 2024-12-10T19:53:02.474Z] cache /builds/worker/checkouts is empty; writing requirements: gid=1000 uid=1000 version=1
[volume 2024-12-10T19:53:02.475Z] changing ownership of volume /builds/worker/.cache to 1000:1000
[volume 2024-12-10T19:53:02.475Z] volume /builds/worker/checkouts is a cache
[setup 2024-12-10T19:53:02.475Z] running as worker:worker
[vcs 2024-12-10T19:53:02.475Z] executing ['git', 'config', '--global', '--add', 'safe.directory', '/builds/worker/checkouts/vcs']
[vcs 2024-12-10T19:53:02.480Z] executing ['git', 'clone', 'https://github.com/mozilla-releng/scriptworker-scripts', '/builds/worker/checkouts/vcs']
[vcs 2024-12-10T19:53:02.481Z] Cloning into '/builds/worker/checkouts/vcs'...
[vcs 2024-12-10T19:53:06.558Z] executing ['git', 'fetch', '--tags', '--force', 'https://github.com/mozilla-releng/scriptworker-scripts', 'refs/heads/dev-bouncerscript']
[vcs 2024-12-10T19:53:06.823Z] From https://github.com/mozilla-releng/scriptworker-scripts
[vcs 2024-12-10T19:53:06.823Z]  * branch              dev-bouncerscript -> FETCH_HEAD
[vcs 2024-12-10T19:53:06.828Z] executing ['git', 'fetch', '--no-tags', 'https://github.com/mozilla-releng/scriptworker-scripts', 'refs/heads/dev-bouncerscript']
[vcs 2024-12-10T19:53:07.010Z] From https://github.com/mozilla-releng/scriptworker-scripts
[vcs 2024-12-10T19:53:07.010Z]  * branch              dev-bouncerscript -> FETCH_HEAD
[vcs 2024-12-10T19:53:07.015Z] executing ['git', 'checkout', '-f', '-B', 'refs/heads/dev-bouncerscript', 'd24accd1cd995325c5b1a108615e2edf3ee6f5f5']
[vcs 2024-12-10T19:53:08.150Z] Switched to a new branch 'refs/heads/dev-bouncerscript'
[vcs 2024-12-10T19:53:08.150Z] cleaning git checkout...
[vcs 2024-12-10T19:53:08.150Z] executing ['git', 'clean', '-nxdff']
[vcs 2024-12-10T19:53:08.162Z] removing []
[vcs 2024-12-10T19:53:08.162Z] successfully cleaned git checkout!
[vcs 2024-12-10T19:53:08.164Z] TinderboxPrint:<a href='https://github.com/mozilla-releng/scriptworker-scripts/commit/d24accd1cd995325c5b1a108615e2edf3ee6f5f5' title='Built from scriptworker-scripts commit d24accd1cd995325c5b1a108615e2edf3ee6f5f5'>d24accd1cd995325c5b1a108615e2edf3ee6f5f5</a>
[task 2024-12-10T19:53:08.164Z] executing ['sh', '-lxce', 'tox -e shipitscript-py311']
[task 2024-12-10T19:53:08.165Z] + id -u
[task 2024-12-10T19:53:08.166Z] + [ 1000 -eq 0 ]
[task 2024-12-10T19:53:08.166Z] + PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
[task 2024-12-10T19:53:08.167Z] + export PATH
[task 2024-12-10T19:53:08.167Z] + [ $  ]
[task 2024-12-10T19:53:08.167Z] + [  ]
[task 2024-12-10T19:53:08.167Z] + id -u
[task 2024-12-10T19:53:08.168Z] + [ 1000 -eq 0 ]
[task 2024-12-10T19:53:08.168Z] + PS1=$ 
[task 2024-12-10T19:53:08.168Z] + [ -d /etc/profile.d ]
[task 2024-12-10T19:53:08.168Z] + [ -r /etc/profile.d/*.sh ]
[task 2024-12-10T19:53:08.168Z] + unset i
[task 2024-12-10T19:53:08.168Z] + [ -n  ]
[task 2024-12-10T19:53:08.168Z] + [ -d /builds/worker/bin ]
[task 2024-12-10T19:53:08.168Z] + [ -d /builds/worker/.local/bin ]
[task 2024-12-10T19:53:08.168Z] + tox -e shipitscript-py311
[task 2024-12-10T19:53:08.744Z] shipitscript-py311: install_deps> python -I -m pip install pyparsing tox
[task 2024-12-10T19:53:12.062Z] shipitscript-py311: commands[0] /builds/worker/checkouts/vcs/shipitscript> tox -e py311
[task 2024-12-10T19:53:12.401Z] .pkg: remove tox env folder /builds/worker/checkouts/vcs/.tox/.pkg
[task 2024-12-10T19:53:12.860Z] py311: install_deps> python -I -m pip install -r requirements/test.txt
[task 2024-12-10T19:53:24.781Z] .pkg: install_requires> python -I -m pip install 'setuptools>=40.8.0' wheel
[task 2024-12-10T19:53:25.826Z] .pkg: _optional_hooks> python /builds/worker/checkouts/vcs/.tox/shipitscript-py311/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-10T19:53:26.143Z] .pkg: get_requires_for_build_editable> python /builds/worker/checkouts/vcs/.tox/shipitscript-py311/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-10T19:53:26.417Z] .pkg: build_editable> python /builds/worker/checkouts/vcs/.tox/shipitscript-py311/lib/python3.11/site-packages/pyproject_api/_backend.py True setuptools.build_meta __legacy__
[task 2024-12-10T19:53:26.732Z] py311: install_package_deps> python -I -m pip install redo scriptworker taskcluster
[task 2024-12-10T19:53:27.509Z] py311: install_package> python -I -m pip install --force-reinstall --no-deps /builds/worker/checkouts/vcs/.tox/.tmp/package/1/shipitscript-4.2.3-0.editable-py3-none-any.whl
[task 2024-12-10T19:53:28.051Z] py311: commands[0]> py.test --cov-config=tox.ini --cov-append --cov=/builds/worker/checkouts/vcs/shipitscript/src/shipitscript --cov-report term-missing tests
[task 2024-12-10T19:53:28.816Z] /builds/worker/checkouts/vcs/.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-10T19:53:28.816Z] 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-10T19:53:28.816Z] 
[task 2024-12-10T19:53:28.816Z]   warnings.warn(PytestDeprecationWarning(_DEFAULT_FIXTURE_LOOP_SCOPE_UNSET))
[task 2024-12-10T19:53:28.859Z] ============================= test session starts ==============================
[task 2024-12-10T19:53:28.859Z] platform linux -- Python 3.11.9, pytest-8.3.4, pluggy-1.5.0 -- /builds/worker/checkouts/vcs/.tox/py311/bin/python
[task 2024-12-10T19:53:28.859Z] cachedir: /builds/worker/checkouts/vcs/.tox/py311/.pytest_cache
[task 2024-12-10T19:53:28.859Z] rootdir: /builds/worker/checkouts/vcs/shipitscript
[task 2024-12-10T19:53:28.859Z] configfile: tox.ini
[task 2024-12-10T19:53:28.859Z] plugins: cov-6.0.0, asyncio-0.24.0, mock-3.14.0, requests-mock-1.12.1
[task 2024-12-10T19:53:28.859Z] asyncio: mode=Mode.STRICT, default_loop_scope=None
[task 2024-12-10T19:53:29.035Z] collecting ... collected 48 items
[task 2024-12-10T19:53:29.035Z] 
[task 2024-12-10T19:53:29.051Z] tests/integration/test_integration_script.py::test_main_mark_release_as_shipped_v2 PASSED
[task 2024-12-10T19:53:29.068Z] tests/test_config.py::test_config PASSED
[task 2024-12-10T19:53:29.074Z] tests/test_pushlog.py::test_get_shippable_revision_build[releases/mozilla-beta-38fc7c5ed55f4bdbdc837e11bb67eb6bfde947c9-2691dbbedf8783bfccbeba8dc6296bc3d7750285-ret_json0-c24dfbda267ec738479bdc63ac6cfad60212d8dc] PASSED
[task 2024-12-10T19:53:29.082Z] tests/test_pushlog.py::test_get_shippable_revision_build[releases/mozilla-beta-c24dfbda267ec738479bdc63ac6cfad60212d8dc-efdc710721d690d0629ccd17651d7a2885fd3d60-ret_json1-None] PASSED
[task 2024-12-10T19:53:29.083Z] tests/test_pushlog.py::test_get_shippable_revision_build[releases/mozilla-beta-6a9e651e08318cd1a5da7f1bc2b489e47d4acf3f-3f19dfb367d6f5418554b52997592a49a2c47f99-ret_json2-3f19dfb367d6f5418554b52997592a49a2c47f99] PASSED
[task 2024-12-10T19:53:29.086Z] tests/test_pushlog.py::test_get_shippable_revision_build[releases/mozilla-beta-fe6f34b2859f0706c88ec07199e30395041af258-6a9e651e08318cd1a5da7f1bc2b489e47d4acf3f-ret_json3-6a9e651e08318cd1a5da7f1bc2b489e47d4acf3f] PASSED
[task 2024-12-10T19:53:29.090Z] tests/test_pushlog.py::test_get_shippable_revision_build[releases/mozilla-esr68-d8e217ff942c17a15075e6cd4ec0f33b095f45fd-5b60ba8829c01de0197b4154a5bc8b89e01defbc-ret_json4-fac95027fda1540460a05a2f6b05210a14e60293] PASSED
[task 2024-12-10T19:53:29.102Z] tests/test_script.py::test_mark_as_shipped[scopes0] PASSED
[task 2024-12-10T19:53:29.113Z] tests/test_script.py::test_async_main[task0-False] PASSED
[task 2024-12-10T19:53:29.117Z] tests/test_script.py::test_async_main[task1-True] PASSED
[task 2024-12-10T19:53:29.119Z] tests/test_script.py::test_get_default_config PASSED
[task 2024-12-10T19:53:29.126Z] tests/test_script.py::test_main PASSED
[task 2024-12-10T19:53:29.126Z] tests/test_ship_actions.py::test_mark_as_shipped_v2[1-1] PASSED
[task 2024-12-10T19:53:29.132Z] tests/test_ship_actions.py::test_mark_as_shipped_v2[10-10] PASSED
[task 2024-12-10T19:53:29.138Z] tests/test_ship_actions.py::test_mark_as_shipped_v2[None-60] PASSED
[task 2024-12-10T19:53:29.147Z] tests/test_shipitapi.py::test_release_v2_class PASSED
[task 2024-12-10T19:53:29.149Z] tests/test_task.py::test_get_scope[scopes0-server-False] PASSED
[task 2024-12-10T19:53:29.151Z] tests/test_task.py::test_get_scope[scopes1-server-False] PASSED
[task 2024-12-10T19:53:29.153Z] tests/test_task.py::test_get_scope[scopes2-server-False] PASSED
[task 2024-12-10T19:53:29.155Z] tests/test_task.py::test_get_scope[scopes3-server-True] PASSED
[task 2024-12-10T19:53:29.157Z] tests/test_task.py::test_get_scope[scopes4-server-True] PASSED
[task 2024-12-10T19:53:29.159Z] tests/test_task.py::test_get_scope[scopes5-action-False] PASSED
[task 2024-12-10T19:53:29.162Z] tests/test_task.py::test_get_scope[scopes6-action-True] PASSED
[task 2024-12-10T19:53:29.166Z] tests/test_task.py::test_get_ship_it_instance_config_from_scope[https://localhost:8015-project:releng:ship-it:server:dev-False] PASSED
[task 2024-12-10T19:53:29.166Z] tests/test_task.py::test_get_ship_it_instance_config_from_scope[http://some-ship-it.url/v2-project:releng:ship-it:server:dev-False] PASSED
[task 2024-12-10T19:53:29.170Z] tests/test_task.py::test_get_ship_it_instance_config_from_scope[https://api.shipit.testing.mozilla-releng.net-project:releng:ship-it:server:staging-False] PASSED
[task 2024-12-10T19:53:29.170Z] tests/test_task.py::test_get_ship_it_instance_config_from_scope[https://api.shipit.testing.mozilla-releng.net/-project:releng:ship-it:server:staging-False] PASSED
[task 2024-12-10T19:53:29.174Z] tests/test_task.py::test_get_ship_it_instance_config_from_scope[https://shipit-api.mozilla-releng.net-project:releng:ship-it:server:production-False] PASSED
[task 2024-12-10T19:53:29.174Z] tests/test_task.py::test_get_ship_it_instance_config_from_scope[https://shipit-api.mozilla-releng.net/-project:releng:ship-it:server:production-False] PASSED
[task 2024-12-10T19:53:29.175Z] tests/test_task.py::test_fail_get_ship_it_instance_config_from_scope[some:random:scope] PASSED
[task 2024-12-10T19:53:29.177Z] tests/test_task.py::test_fail_get_ship_it_instance_config_from_scope[project:releng:ship-it:server:staging] PASSED
[task 2024-12-10T19:53:29.179Z] tests/test_task.py::test_fail_get_ship_it_instance_config_from_scope[project:releng:ship-it:server:production] PASSED
[task 2024-12-10T19:53:29.189Z] tests/test_task.py::test_validate_task[task0-False] PASSED
[task 2024-12-10T19:53:29.200Z] tests/test_task.py::test_validate_task[task1-True] PASSED
[task 2024-12-10T19:53:29.202Z] tests/test_task.py::test_validate_task[task2-True] PASSED
[task 2024-12-10T19:53:29.204Z] tests/test_task.py::test_get_task_action[project:releng:ship-it:action:mark-as-random-None-True] PASSED
[task 2024-12-10T19:53:29.206Z] tests/test_task.py::test_get_task_action[project:releng:ship-it:action:mark-as-shipped-mark-as-shipped-False] PASSED
[task 2024-12-10T19:53:29.208Z] tests/test_utils.py::test_get_auth_primitives_v2[ship_it_instance_config0-expected0] PASSED
[task 2024-12-10T19:53:29.210Z] tests/test_utils.py::test_get_auth_primitives_v2[ship_it_instance_config1-expected1] PASSED
[task 2024-12-10T19:53:29.213Z] tests/test_utils.py::test_generic_validation_v2[release_info0-values0-False] PASSED
[task 2024-12-10T19:53:29.216Z] tests/test_utils.py::test_generic_validation_v2[release_info1-values1-True] PASSED
[task 2024-12-10T19:53:29.224Z] tests/test_utils.py::test_same_timing[2018-07-02 16:51:04-2018-07-02T16:51:04+00:00-True] PASSED
[task 2024-12-10T19:53:29.226Z] tests/test_utils.py::test_same_timing[2018-07-02 16:51:04-2018-07-02T16:51:04+01:00-False] PASSED
[task 2024-12-10T19:53:29.230Z] tests/test_utils.py::test_same_timing[2018-07-02 16:51:04-2018-07-02T16:51:04+00:11-False] PASSED
[task 2024-12-10T19:53:29.232Z] tests/test_utils.py::test_same_timing[2018-07-02 16:51:04-2018-07-02T16:51:04-True] PASSED
[task 2024-12-10T19:53:29.235Z] tests/test_utils.py::test_request_headers[http://example.com-expected0] PASSED
[task 2024-12-10T19:53:29.236Z] tests/test_utils.py::test_request_headers[https://example.com-expected1] PASSED
[task 2024-12-10T19:53:29.303Z] tests/test_utils.py::test_request_headers[https://example.com:1234-expected2] PASSED
[task 2024-12-10T19:53:29.303Z] 
[task 2024-12-10T19:53:29.303Z] ---------- coverage: platform linux, python 3.11.9-final-0 -----------
[task 2024-12-10T19:53:29.303Z] Name                               Stmts   Miss Branch BrPart  Cover   Missing
[task 2024-12-10T19:53:29.303Z] ------------------------------------------------------------------------------
[task 2024-12-10T19:53:29.303Z] src/shipitscript/__init__.py           0      0      0      0   100%
[task 2024-12-10T19:53:29.303Z] src/shipitscript/pushlog_scan.py      58      3     24      5    90%   39->exit, 85, 98, 101->100, 104
[task 2024-12-10T19:53:29.303Z] src/shipitscript/script.py            59     31      6      0    43%   38-61, 66-76
[task 2024-12-10T19:53:29.303Z] src/shipitscript/ship_actions.py      72     49      8      0    29%   21, 25-41, 45-56, 60-65, 70-93, 108-111, 115-118
[task 2024-12-10T19:53:29.303Z] src/shipitscript/shipitapi.py        117     61     16      0    45%   65-78, 101-111, 115-131, 140-148, 157-163, 173-180
[task 2024-12-10T19:53:29.303Z] src/shipitscript/task.py              25      0      4      0   100%
[task 2024-12-10T19:53:29.303Z] src/shipitscript/utils.py             30      0      8      0   100%
[task 2024-12-10T19:53:29.304Z] ------------------------------------------------------------------------------
[task 2024-12-10T19:53:29.304Z] TOTAL                                361    144     66      5    59%
[task 2024-12-10T19:53:29.304Z] 
[task 2024-12-10T19:53:29.304Z] 
[task 2024-12-10T19:53:29.304Z] ============================== 48 passed in 0.45s ==============================
[task 2024-12-10T19:53:29.457Z]   py311: OK (17.06=setup[15.65]+cmd[1.40] seconds)
[task 2024-12-10T19:53:29.457Z]   congratulations :) (17.22 seconds)
[task 2024-12-10T19:53:29.518Z]   shipitscript-py311: OK (21.10=setup[3.64]+cmd[17.46] seconds)
[task 2024-12-10T19:53:29.518Z]   congratulations :) (21.18 seconds)
[taskcluster 2024-12-10 19:53:30.667Z] === Task Finished ===
[taskcluster 2024-12-10 19:53:30.668Z] Successful task run with exit code: 0 completed in 60.294 seconds