2026-09-03 00:58:25.206516 | Job console starting 2026-09-03 00:58:25.219988 | Updating git repos 2026-09-03 00:58:25.268599 | Cloning repos into workspace 2026-09-03 00:58:25.323509 | Restoring repo states 2026-09-03 00:58:25.334366 | Merging changes 2026-09-03 00:58:26.071629 | Checking out repos 2026-09-03 00:58:26.167005 | Preparing playbooks 2026-09-03 00:58:27.882684 | Running Ansible setup 2026-09-03 00:58:33.057350 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-03 00:58:34.708971 | 2026-09-03 00:58:34.709080 | PLAY [Base pre] 2026-09-03 00:58:34.741468 | 2026-09-03 00:58:34.741602 | TASK [Setup log path fact] 2026-09-03 00:58:34.772287 | debian-bookworm | ok 2026-09-03 00:58:34.794232 | 2026-09-03 00:58:34.794343 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-03 00:58:34.861066 | debian-bookworm | ok 2026-09-03 00:58:34.876976 | 2026-09-03 00:58:34.877068 | TASK [emit-job-header : Print job information] 2026-09-03 00:58:34.975186 | # Job Information 2026-09-03 00:58:34.975362 | Ansible Version: 2.16.14 2026-09-03 00:58:34.975392 | Job: cfg-cookiecutter-tox-2025.2 2026-09-03 00:58:34.975419 | Pipeline: check 2026-09-03 00:58:34.975438 | Executor: 521e9411259a 2026-09-03 00:58:34.975456 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/864 2026-09-03 00:58:34.975475 | Event ID: 8b305540-a732-11f1-80da-4a00b4164387 2026-09-03 00:58:34.979932 | 2026-09-03 00:58:34.980015 | LOOP [emit-job-header : Print node information] 2026-09-03 00:58:35.193459 | debian-bookworm | ok: 2026-09-03 00:58:35.193636 | debian-bookworm | # Node Information 2026-09-03 00:58:35.193709 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-03 00:58:35.193736 | debian-bookworm | Hostname: debian 2026-09-03 00:58:35.193755 | debian-bookworm | Username: zuul 2026-09-03 00:58:35.193773 | debian-bookworm | Distro: Debian 12.15 2026-09-03 00:58:35.193792 | debian-bookworm | Provider: regiocloud-a 2026-09-03 00:58:35.193810 | debian-bookworm | Region: 2026-09-03 00:58:35.193827 | debian-bookworm | Label: debian-bookworm 2026-09-03 00:58:35.193843 | debian-bookworm | Product Name: OpenStack Nova 2026-09-03 00:58:35.193859 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2b:c658 2026-09-03 00:58:35.211752 | 2026-09-03 00:58:35.211860 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-03 00:58:36.052022 | debian-bookworm -> localhost | changed 2026-09-03 00:58:36.058372 | 2026-09-03 00:58:36.058462 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-03 00:58:38.179494 | debian-bookworm -> localhost | changed 2026-09-03 00:58:38.189830 | 2026-09-03 00:58:38.189922 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-03 00:58:38.617639 | debian-bookworm -> localhost | ok 2026-09-03 00:58:38.622421 | 2026-09-03 00:58:38.622596 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-03 00:58:38.654107 | debian-bookworm | ok 2026-09-03 00:58:38.688715 | debian-bookworm | included: /var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-03 00:58:38.693594 | 2026-09-03 00:58:38.693666 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-03 00:58:41.539951 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-03 00:58:41.540104 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/work/bae5507b7e03426facdde12e52832a3d_id_rsa 2026-09-03 00:58:41.540135 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/work/bae5507b7e03426facdde12e52832a3d_id_rsa.pub 2026-09-03 00:58:41.540157 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-03 00:58:41.540177 | debian-bookworm -> localhost | SHA256:Mf6sDDP9zn0aM0+RgW5D4u4OXJTFic2IhF4y4rVRYhI zuul-build-sshkey 2026-09-03 00:58:41.540206 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-03 00:58:41.540225 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-03 00:58:41.540242 | debian-bookworm -> localhost | | E.o+o. *.. | 2026-09-03 00:58:41.540261 | debian-bookworm -> localhost | | .o*.o oo=. | 2026-09-03 00:58:41.540279 | debian-bookworm -> localhost | | . + *o + o . | 2026-09-03 00:58:41.540296 | debian-bookworm -> localhost | | . o. = + o | 2026-09-03 00:58:41.540313 | debian-bookworm -> localhost | | S o + o | 2026-09-03 00:58:41.540333 | debian-bookworm -> localhost | | o = . . . | 2026-09-03 00:58:41.540350 | debian-bookworm -> localhost | | + + + + . | 2026-09-03 00:58:41.540366 | debian-bookworm -> localhost | | = * . *. | 2026-09-03 00:58:41.540383 | debian-bookworm -> localhost | | oo* oo. | 2026-09-03 00:58:41.540400 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-03 00:58:41.540439 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.829469 2026-09-03 00:58:41.545460 | 2026-09-03 00:58:41.545537 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-03 00:58:41.576880 | debian-bookworm | ok 2026-09-03 00:58:41.586796 | debian-bookworm | included: /var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-03 00:58:41.595067 | 2026-09-03 00:58:41.595156 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-03 00:58:41.608689 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:58:41.614461 | 2026-09-03 00:58:41.614553 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-03 00:58:42.277618 | debian-bookworm | changed 2026-09-03 00:58:42.283060 | 2026-09-03 00:58:42.283143 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-03 00:58:42.539032 | debian-bookworm | ok 2026-09-03 00:58:42.547218 | 2026-09-03 00:58:42.547314 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-03 00:58:43.286810 | debian-bookworm | changed 2026-09-03 00:58:43.298797 | 2026-09-03 00:58:43.298905 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-03 00:58:44.038381 | debian-bookworm | changed 2026-09-03 00:58:44.043363 | 2026-09-03 00:58:44.043439 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-03 00:58:44.089290 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:58:44.094290 | 2026-09-03 00:58:44.094371 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-03 00:58:45.090617 | debian-bookworm -> localhost | changed 2026-09-03 00:58:45.099242 | 2026-09-03 00:58:45.099331 | TASK [add-build-sshkey : Add back temp key] 2026-09-03 00:58:45.841675 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/work/bae5507b7e03426facdde12e52832a3d_id_rsa (zuul-build-sshkey) 2026-09-03 00:58:45.841841 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025436 2026-09-03 00:58:45.847993 | 2026-09-03 00:58:45.848069 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-03 00:58:46.253958 | debian-bookworm | ok 2026-09-03 00:58:46.258107 | 2026-09-03 00:58:46.258184 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-03 00:58:46.314645 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:58:46.323072 | 2026-09-03 00:58:46.323155 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-03 00:58:46.890436 | debian-bookworm | ok 2026-09-03 00:58:46.899345 | 2026-09-03 00:58:46.899436 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-03 00:58:46.958873 | debian-bookworm | ok 2026-09-03 00:58:46.965245 | 2026-09-03 00:58:46.965340 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-03 00:58:47.688082 | debian-bookworm -> localhost | ok 2026-09-03 00:58:47.693365 | 2026-09-03 00:58:47.693446 | TASK [validate-host : Collect information about the host] 2026-09-03 00:58:48.826415 | debian-bookworm | ok 2026-09-03 00:58:48.836314 | 2026-09-03 00:58:48.836392 | TASK [validate-host : Sanitize hostname] 2026-09-03 00:58:49.047866 | debian-bookworm | ok 2026-09-03 00:58:49.062541 | 2026-09-03 00:58:49.062639 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-03 00:58:50.225840 | debian-bookworm -> localhost | changed 2026-09-03 00:58:50.231180 | 2026-09-03 00:58:50.231267 | TASK [validate-host : Collect information about zuul worker] 2026-09-03 00:58:50.803624 | debian-bookworm | ok 2026-09-03 00:58:50.808033 | 2026-09-03 00:58:50.808123 | TASK [validate-host : Write out all zuul information for each host] 2026-09-03 00:58:51.745441 | debian-bookworm -> localhost | changed 2026-09-03 00:58:51.754656 | 2026-09-03 00:58:51.754750 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-03 00:58:52.059938 | debian-bookworm | ok 2026-09-03 00:58:52.065086 | 2026-09-03 00:58:52.065171 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-03 00:58:52.841128 | debian-bookworm | changed: 2026-09-03 00:58:52.841259 | debian-bookworm | cd+++++++++ src/ 2026-09-03 00:58:52.841287 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-03 00:58:52.841307 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-03 00:58:52.841324 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-09-03 00:58:52.841341 | debian-bookworm | Containerfile 2026-09-03 00:58:52.841449 | debian-bookworm | =7.0.3 2026-09-03 00:59:30.238005 | debian-bookworm | Downloading cachetools-7.1.8-py3-none-any.whl (16 kB) 2026-09-03 00:59:30.295652 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-03 00:59:30.307698 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-03 00:59:30.380847 | debian-bookworm | Collecting filelock>=3.25 2026-09-03 00:59:30.392394 | debian-bookworm | Downloading filelock-3.32.5-py3-none-any.whl (100 kB) 2026-09-03 00:59:30.404988 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.0/100.0 kB 10.7 MB/s eta 0:00:00 2026-09-03 00:59:30.466618 | debian-bookworm | Collecting packaging>=26 2026-09-03 00:59:30.478148 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-03 00:59:30.493199 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 10.3 MB/s eta 0:00:00 2026-09-03 00:59:30.550300 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-03 00:59:30.562267 | debian-bookworm | Downloading platformdirs-4.11.7-py3-none-any.whl (23 kB) 2026-09-03 00:59:30.604595 | debian-bookworm | Collecting pluggy>=1.6 2026-09-03 00:59:30.617051 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-03 00:59:30.676276 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-03 00:59:30.688038 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-09-03 00:59:30.733490 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-03 00:59:30.746396 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-09-03 00:59:30.797806 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-03 00:59:30.810723 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-03 00:59:30.891945 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-03 00:59:30.904901 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-03 00:59:30.917129 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 5.4 MB/s eta 0:00:00 2026-09-03 00:59:31.088237 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-03 00:59:31.099653 | debian-bookworm | Downloading virtualenv-21.7.8-py3-none-any.whl (5.3 MB) 2026-09-03 00:59:31.348603 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 21.9 MB/s eta 0:00:00 2026-09-03 00:59:31.449876 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-03 00:59:31.462074 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-03 00:59:31.489453 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 22.8 MB/s eta 0:00:00 2026-09-03 00:59:31.632998 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-03 00:59:32.576811 | debian-bookworm | Successfully installed cachetools-7.1.8 colorama-0.4.6 distlib-0.4.3 filelock-3.32.5 packaging-26.3 platformdirs-4.11.7 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.6.0 tomli-w-1.2.0 tox-4.61.2 typing-extensions-4.16.0 virtualenv-21.7.8 2026-09-03 00:59:33.089161 | debian-bookworm | ok: Runtime: 0:00:03.376701 2026-09-03 00:59:33.095530 | 2026-09-03 00:59:33.095756 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-03 00:59:33.128112 | debian-bookworm | ok 2026-09-03 00:59:33.133064 | 2026-09-03 00:59:33.133168 | TASK [ensure-tox : Output tox version] 2026-09-03 00:59:33.716982 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-03 00:59:33.737363 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-03 00:59:34.163304 | debian-bookworm | ok: Runtime: 0:00:00.380772 2026-09-03 00:59:34.178728 | 2026-09-03 00:59:34.178907 | TASK [ensure-tox : Make global symlink] 2026-09-03 00:59:34.204084 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:34.223326 | 2026-09-03 00:59:34.223450 | PLAY RECAP 2026-09-03 00:59:34.223547 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-03 00:59:34.223593 | 2026-09-03 00:59:34.352478 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-03 00:59:34.353605 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-03 00:59:35.132403 | 2026-09-03 00:59:35.132554 | PLAY [all] 2026-09-03 00:59:35.147876 | 2026-09-03 00:59:35.148004 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-03 00:59:35.609707 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-03 00:59:35.682755 | debian-bookworm | ok: Runtime: 0:00:00.031680 2026-09-03 00:59:35.690179 | 2026-09-03 00:59:35.690316 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-03 00:59:36.218978 | debian-bookworm | ok: Runtime: 0:00:00.007765 2026-09-03 00:59:36.225244 | 2026-09-03 00:59:36.234952 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-03 00:59:36.595119 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-03 00:59:36.596750 | debian-bookworm | sudo: a password is required 2026-09-03 00:59:36.799254 | debian-bookworm | ok: Runtime: 0:00:00.014886 2026-09-03 00:59:36.819362 | 2026-09-03 00:59:36.819491 | TASK [tox : Check to see if the constraints file exists] 2026-09-03 00:59:36.844770 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:36.851032 | 2026-09-03 00:59:36.851161 | TASK [tox : Fail if constraints file is missing] 2026-09-03 00:59:36.877151 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:36.884334 | 2026-09-03 00:59:36.884556 | TASK [tox : Record file location] 2026-09-03 00:59:36.910768 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:36.917133 | 2026-09-03 00:59:36.917245 | TASK [tox : Install tox siblings] 2026-09-03 00:59:36.947583 | debian-bookworm | ok 2026-09-03 00:59:36.957791 | debian-bookworm | included: /var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-03 00:59:36.963618 | 2026-09-03 00:59:36.963738 | TASK [tox : Run tox without tests] 2026-09-03 00:59:37.720375 | debian-bookworm | ROOT: 393 D setup logging to DEBUG on pid 1604 [tox/report.py:242] 2026-09-03 00:59:37.755349 | debian-bookworm | cookiecutter: 428 D created app data folder /home/zuul/.cache/virtualenv [virtualenv/app_data/__init__.py:65] 2026-09-03 00:59:37.758797 | debian-bookworm | cookiecutter: 432 I find interpreter for spec PythonSpec(path=/home/zuul/.local/tox/bin/python3) [python_discovery/_discovery.py:131] 2026-09-03 00:59:37.759343 | debian-bookworm | cookiecutter: 433 D filesystem is case-sensitive [python_discovery/_compat.py:18] 2026-09-03 00:59:37.764242 | debian-bookworm | cookiecutter: 437 D get interpreter info via cmd: /usr/bin/python3.11 /home/zuul/.local/tox/lib/python3.11/site-packages/python_discovery/_py_info_collect.py 839969cc6da7cbf58dae5afddceef232 3d12beed5f8053492062dce96395a309 [python_discovery/_cached_py_info.py:223] 2026-09-03 00:59:37.832915 | debian-bookworm | cookiecutter: 506 D wrote python info of /usr/bin/python3.11 at /home/zuul/.cache/virtualenv/py_info/5/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:159] 2026-09-03 00:59:37.833058 | debian-bookworm | cookiecutter: 506 I proposed PythonInfo(spec=CPython3.11.2.final.0-64-x86_64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [python_discovery/_discovery.py:140] 2026-09-03 00:59:37.833184 | debian-bookworm | cookiecutter: 506 D accepted PythonInfo(spec=CPython3.11.2.final.0-64-x86_64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [python_discovery/_discovery.py:144] 2026-09-03 00:59:37.835935 | debian-bookworm | cookiecutter: 509 D symlink on filesystem does work [virtualenv/info.py:51] 2026-09-03 00:59:37.836108 | debian-bookworm | cookiecutter: 509 D filesystem is case-sensitive [virtualenv/info.py:28] 2026-09-03 00:59:37.885066 | debian-bookworm | cookiecutter: 558 I create virtual environment via CPython3Posix(dest=/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter, clear=False, no_vcs_ignore=False, global=False) [virtualenv/run/session.py:77] 2026-09-03 00:59:37.885705 | debian-bookworm | cookiecutter: 559 D create folder /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/bin [virtualenv/util/path/_sync.py:22] 2026-09-03 00:59:37.885872 | debian-bookworm | cookiecutter: 559 D create folder /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/include [virtualenv/util/path/_sync.py:22] 2026-09-03 00:59:37.885985 | debian-bookworm | cookiecutter: 559 D create folder /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages [virtualenv/util/path/_sync.py:22] 2026-09-03 00:59:37.886295 | debian-bookworm | cookiecutter: 560 D write /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:41] 2026-09-03 00:59:37.886422 | debian-bookworm | cookiecutter: 560 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.886474 | debian-bookworm | cookiecutter: 560 D implementation = CPython [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.886540 | debian-bookworm | cookiecutter: 560 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.886585 | debian-bookworm | cookiecutter: 560 D version = 3.11.2 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.886797 | debian-bookworm | cookiecutter: 560 D executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.886845 | debian-bookworm | cookiecutter: 560 D command = /home/zuul/.local/tox/bin/python3 -m virtualenv /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.886896 | debian-bookworm | cookiecutter: 560 D virtualenv = 21.7.8 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.886950 | debian-bookworm | cookiecutter: 560 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.887003 | debian-bookworm | cookiecutter: 560 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.887051 | debian-bookworm | cookiecutter: 560 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.887100 | debian-bookworm | cookiecutter: 560 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:37.887597 | debian-bookworm | cookiecutter: 561 D symlink /usr/bin/python3.11 to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/bin/python [virtualenv/util/path/_sync.py:42] 2026-09-03 00:59:37.888525 | debian-bookworm | cookiecutter: 562 D ============================== target debug ============================== [virtualenv/run/session.py:79] 2026-09-03 00:59:37.888795 | debian-bookworm | cookiecutter: 562 D debug via /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/bin/python /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/create/debug.py [virtualenv/create/creator.py:265] 2026-09-03 00:59:37.931988 | debian-bookworm | cookiecutter: 562 D { 2026-09-03 00:59:37.932042 | debian-bookworm | "sys": { 2026-09-03 00:59:37.932049 | debian-bookworm | "executable": "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/bin/python", 2026-09-03 00:59:37.932055 | debian-bookworm | "_base_executable": "/usr/bin/python3.11", 2026-09-03 00:59:37.932063 | debian-bookworm | "prefix": "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter", 2026-09-03 00:59:37.932068 | debian-bookworm | "base_prefix": "/usr", 2026-09-03 00:59:37.932073 | debian-bookworm | "exec_prefix": "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter", 2026-09-03 00:59:37.932077 | debian-bookworm | "base_exec_prefix": "/usr", 2026-09-03 00:59:37.932081 | debian-bookworm | "path": [ 2026-09-03 00:59:37.932086 | debian-bookworm | "/usr/lib/python311.zip", 2026-09-03 00:59:37.932090 | debian-bookworm | "/usr/lib/python3.11", 2026-09-03 00:59:37.932094 | debian-bookworm | "/usr/lib/python3.11/lib-dynload", 2026-09-03 00:59:37.932098 | debian-bookworm | "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages" 2026-09-03 00:59:37.932103 | debian-bookworm | ], 2026-09-03 00:59:37.932107 | debian-bookworm | "meta_path": [ 2026-09-03 00:59:37.932111 | debian-bookworm | "", 2026-09-03 00:59:37.932116 | debian-bookworm | "", 2026-09-03 00:59:37.932120 | debian-bookworm | "" 2026-09-03 00:59:37.932124 | debian-bookworm | ], 2026-09-03 00:59:37.932128 | debian-bookworm | "fs_encoding": "utf-8", 2026-09-03 00:59:37.932132 | debian-bookworm | "io_encoding": "utf-8" 2026-09-03 00:59:37.932136 | debian-bookworm | }, 2026-09-03 00:59:37.932141 | debian-bookworm | "version": "3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]", 2026-09-03 00:59:37.932145 | debian-bookworm | "makefile_filename": "/usr/lib/python3.11/config-3.11-x86_64-linux-gnu/Makefile", 2026-09-03 00:59:37.932149 | debian-bookworm | "os": "", 2026-09-03 00:59:37.932153 | debian-bookworm | "site": "", 2026-09-03 00:59:37.932157 | debian-bookworm | "datetime": "", 2026-09-03 00:59:37.932161 | debian-bookworm | "math": "", 2026-09-03 00:59:37.932171 | debian-bookworm | "json": "" 2026-09-03 00:59:37.932176 | debian-bookworm | } [virtualenv/run/session.py:80] 2026-09-03 00:59:37.932290 | debian-bookworm | cookiecutter: 605 I add seed packages via FromAppData(download=False, pip=bundle, setuptools=bundle, via=copy, app_data_dir=/home/zuul/.cache/virtualenv) [virtualenv/run/session.py:84] 2026-09-03 00:59:37.941632 | debian-bookworm | cookiecutter: 615 D install pip from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/pip-26.2.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:65] 2026-09-03 00:59:37.942461 | debian-bookworm | cookiecutter: 615 D install setuptools from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/setuptools-84.0.0-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:65] 2026-09-03 00:59:37.943813 | debian-bookworm | cookiecutter: 617 D build install image for pip-26.2.1-py3-none-any.whl to /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:81] 2026-09-03 00:59:37.947974 | debian-bookworm | cookiecutter: 621 D build install image for setuptools-84.0.0-py3-none-any.whl to /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:81] 2026-09-03 00:59:38.306793 | debian-bookworm | cookiecutter: 980 D copy /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/setuptools-84.0.0.virtualenv to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/setuptools-84.0.0.virtualenv [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.308198 | debian-bookworm | cookiecutter: 981 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/_distutils_hack to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/_distutils_hack [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.309858 | debian-bookworm | cookiecutter: 983 D copy /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/distutils-precedence.pth to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/distutils-precedence.pth [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.310421 | debian-bookworm | cookiecutter: 984 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/setuptools to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/setuptools [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.454487 | debian-bookworm | cookiecutter: 1128 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-84.0.0-py3-none-any/setuptools-84.0.0.dist-info to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/setuptools-84.0.0.dist-info [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.459960 | debian-bookworm | cookiecutter: 1133 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:69] 2026-09-03 00:59:38.472817 | debian-bookworm | cookiecutter: 1146 D copy /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any/pip-26.2.1.virtualenv to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/pip-26.2.1.virtualenv [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.473282 | debian-bookworm | cookiecutter: 1147 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any/pip to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/pip [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.516292 | debian-bookworm | cookiecutter: 1190 D copy directory /home/zuul/.cache/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-26.2.1-py3-none-any/pip-26.2.1.dist-info to /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages/pip-26.2.1.dist-info [virtualenv/util/path/_sync.py:50] 2026-09-03 00:59:38.520877 | debian-bookworm | cookiecutter: 1194 D generated console scripts pip3.11 pip3 pip pip-3.11 [virtualenv/seed/embed/via_app_data/pip_install/base.py:69] 2026-09-03 00:59:38.521621 | debian-bookworm | cookiecutter: 1195 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python, Xonsh [virtualenv/run/session.py:90] 2026-09-03 00:59:38.533273 | debian-bookworm | cookiecutter: 1206 D write /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:41] 2026-09-03 00:59:38.533481 | debian-bookworm | cookiecutter: 1207 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.533600 | debian-bookworm | cookiecutter: 1207 D implementation = CPython [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.533751 | debian-bookworm | cookiecutter: 1207 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.533874 | debian-bookworm | cookiecutter: 1207 D version = 3.11.2 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.533972 | debian-bookworm | cookiecutter: 1207 D executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.534058 | debian-bookworm | cookiecutter: 1207 D command = /home/zuul/.local/tox/bin/python3 -m virtualenv /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.534141 | debian-bookworm | cookiecutter: 1208 D virtualenv = 21.7.8 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.534221 | debian-bookworm | cookiecutter: 1208 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.534307 | debian-bookworm | cookiecutter: 1208 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.534410 | debian-bookworm | cookiecutter: 1208 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.534497 | debian-bookworm | cookiecutter: 1208 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-09-03 00:59:38.544027 | debian-bookworm | cookiecutter: 1217 W install_deps> python -I -m pip install -r requirements.txt [tox/tox_env/api.py:485] 2026-09-03 00:59:39.824744 | debian-bookworm | Collecting PyYAML==6.0.3 (from -r requirements.txt (line 1)) 2026-09-03 00:59:39.942728 | debian-bookworm | Downloading pyyaml-6.0.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-09-03 00:59:40.036507 | debian-bookworm | ERROR: Ignored the following versions that require a different python version: 13.0.0 Requires-Python >=3.12; 13.0.0a1 Requires-Python >=3.12; 13.0.0a2 Requires-Python >=3.12; 13.0.0a3 Requires-Python >=3.12; 13.0.0a4 Requires-Python >=3.12; 13.0.0a5 Requires-Python >=3.12; 13.0.0b1 Requires-Python >=3.12; 13.0.0rc1 Requires-Python >=3.12; 13.1.0 Requires-Python >=3.12; 13.2.0 Requires-Python >=3.12; 13.3.0 Requires-Python >=3.12; 13.4.0 Requires-Python >=3.12; 13.5.0 Requires-Python >=3.12; 13.6.0 Requires-Python >=3.12; 13.7.0 Requires-Python >=3.12; 13.8.0 Requires-Python >=3.12; 14.0.0 Requires-Python >=3.12; 14.0.0a1 Requires-Python >=3.12; 14.0.0a2 Requires-Python >=3.12; 14.0.0a3 Requires-Python >=3.12; 14.0.0a4 Requires-Python >=3.12; 14.0.0b1 Requires-Python >=3.12; 14.0.0rc1 Requires-Python >=3.12; 14.1.0 Requires-Python >=3.12; 14.2.0 Requires-Python >=3.12; 14.3.0 Requires-Python >=3.12; 14.3.1 Requires-Python >=3.12 2026-09-03 00:59:40.037159 | debian-bookworm | ERROR: Could not find a version that satisfies the requirement ansible==14.3.1 (from versions: 1.0, 1.1, 1.2, 1.2.1, 1.2.2, 1.2.3, 1.3.0, 1.3.1, 1.3.2, 1.3.3, 1.3.4, 1.4, 1.4.1, 1.4.2, 1.4.3, 1.4.4, 1.4.5, 1.5, 1.5.1, 1.5.2, 1.5.3, 1.5.4, 1.5.5, 1.6, 1.6.1, 1.6.2, 1.6.3, 1.6.4, 1.6.5, 1.6.6, 1.6.7, 1.6.8, 1.6.9, 1.6.10, 1.7, 1.7.1, 1.7.2, 1.8, 1.8.1, 1.8.2, 1.8.3, 1.8.4, 1.9.0.1, 1.9.1, 1.9.2, 1.9.3, 1.9.4, 1.9.5, 1.9.6, 2.0.0.0, 2.0.0.1, 2.0.0.2, 2.0.1.0, 2.0.2.0, 2.1.0.0, 2.1.1.0, 2.1.2.0, 2.1.3.0, 2.1.4.0, 2.1.5.0, 2.1.6.0, 2.2.0.0, 2.2.1.0, 2.2.2.0, 2.2.3.0, 2.3.0.0, 2.3.1.0, 2.3.2.0, 2.3.3.0, 2.4.0.0, 2.4.1.0, 2.4.2.0, 2.4.3.0, 2.4.4.0, 2.4.5.0, 2.4.6.0, 2.5.0a1, 2.5.0b1, 2.5.0b2, 2.5.0rc1, 2.5.0rc2, 2.5.0rc3, 2.5.0, 2.5.1, 2.5.2, 2.5.3, 2.5.4, 2.5.5, 2.5.6, 2.5.7, 2.5.8, 2.5.9, 2.5.10, 2.5.11, 2.5.12, 2.5.13, 2.5.14, 2.5.15, 2.6.0a1, 2.6.0a2, 2.6.0rc1, 2.6.0rc2, 2.6.0rc3, 2.6.0rc4, 2.6.0rc5, 2.6.0, 2.6.1, 2.6.2, 2.6.3, 2.6.4, 2.6.5, 2.6.6, 2.6.7, 2.6.8, 2.6.9, 2.6.10, 2.6.11, 2.6.12, 2.6.13, 2.6.14, 2.6.15, 2.6.16, 2.6.17, 2.6.18, 2.6.19, 2.6.20, 2.7.0.dev0, 2.7.0a1, 2.7.0b1, 2.7.0rc1, 2.7.0rc2, 2.7.0rc3, 2.7.0rc4, 2.7.0, 2.7.1, 2.7.2, 2.7.3, 2.7.4, 2.7.5, 2.7.6, 2.7.7, 2.7.8, 2.7.9, 2.7.10, 2.7.11, 2.7.12, 2.7.13, 2.7.14, 2.7.15, 2.7.16, 2.7.17, 2.7.18, 2.8.0a1, 2.8.0b1, 2.8.0rc1, 2.8.0rc2, 2.8.0rc3, 2.8.0, 2.8.1, 2.8.2, 2.8.3, 2.8.4, 2.8.5, 2.8.6, 2.8.7, 2.8.8, 2.8.9, 2.8.10, 2.8.11, 2.8.12, 2.8.13, 2.8.14, 2.8.15, 2.8.16rc1, 2.8.16, 2.8.17rc1, 2.8.17, 2.8.18rc1, 2.8.18, 2.8.19rc1, 2.8.19, 2.8.20rc1, 2.8.20, 2.9.0b1, 2.9.0rc1, 2.9.0rc2, 2.9.0rc3, 2.9.0rc4, 2.9.0rc5, 2.9.0, 2.9.1, 2.9.2, 2.9.3, 2.9.4, 2.9.5, 2.9.6, 2.9.7, 2.9.8, 2.9.9, 2.9.10, 2.9.11, 2.9.12, 2.9.13, 2.9.14rc1, 2.9.14, 2.9.15rc1, 2.9.15, 2.9.16rc1, 2.9.16, 2.9.17rc1, 2.9.17, 2.9.18rc1, 2.9.18, 2.9.19rc1, 2.9.19, 2.9.20rc1, 2.9.20, 2.9.21rc1, 2.9.21, 2.9.22rc1, 2.9.22, 2.9.23rc1, 2.9.23, 2.9.24rc1, 2.9.24, 2.9.25rc1, 2.9.25, 2.9.26rc1, 2.9.26, 2.9.27rc1, 2.9.27, 2.10.0a1, 2.10.0a2, 2.10.0a3, 2.10.0a4, 2.10.0a5, 2.10.0a6, 2.10.0a7, 2.10.0a8, 2.10.0a9, 2.10.0b1, 2.10.0b2, 2.10.0rc1, 2.10.0, 2.10.1, 2.10.2, 2.10.3, 2.10.4, 2.10.5, 2.10.6, 2.10.7, 3.0.0b1, 3.0.0rc1, 3.0.0, 3.1.0, 3.2.0, 3.3.0, 3.4.0, 4.0.0a1, 4.0.0a2, 4.0.0a3, 4.0.0a4, 4.0.0b1, 4.0.0b2, 4.0.0rc1, 4.0.0, 4.1.0, 4.2.0, 4.3.0, 4.4.0, 4.5.0, 4.6.0, 4.7.0, 4.8.0, 4.9.0, 4.10.0, 5.0.0a1, 5.0.0a2, 5.0.0a3, 5.0.0b1, 5.0.0b2, 5.0.0rc1, 5.0.1, 5.1.0, 5.2.0, 5.3.0, 5.4.0, 5.5.0, 5.6.0, 5.7.0, 5.7.1, 5.8.0, 5.9.0, 5.10.0, 6.0.0a1, 6.0.0a2, 6.0.0a3, 6.0.0b1, 6.0.0b2, 6.0.0rc1, 6.0.0, 6.1.0, 6.2.0, 6.3.0, 6.4.0, 6.5.0, 6.6.0, 6.7.0, 7.0.0a1, 7.0.0a2, 7.0.0b1, 7.0.0rc1, 7.0.0, 7.1.0, 7.2.0, 7.3.0, 7.4.0, 7.5.0, 7.6.0, 7.7.0, 8.0.0a1, 8.0.0a2, 8.0.0a3, 8.0.0b1, 8.0.0rc1, 8.0.0, 8.1.0, 8.2.0, 8.3.0, 8.4.0, 8.5.0, 8.6.0, 8.6.1, 8.7.0, 9.0.0a1, 9.0.0a2, 9.0.0a3, 9.0.0b1, 9.0.0rc1, 9.0.1, 9.1.0, 9.2.0, 9.3.0, 9.4.0, 9.5.1, 9.6.1, 9.7.0, 9.8.0, 9.9.0, 9.10.0, 9.11.0, 9.12.0, 9.13.0, 10.0.0a1, 10.0.0a2, 10.0.0a3, 10.0.0b1, 10.0.0rc1, 10.0.1, 10.1.0, 10.2.0, 10.3.0, 10.4.0, 10.5.0, 10.6.0, 10.7.0, 11.0.0a1, 11.0.0a2, 11.0.0b1, 11.0.0b2, 11.0.0rc1, 11.0.0, 11.1.0, 11.2.0, 11.3.0, 11.4.0, 11.5.0, 11.6.0, 11.7.0, 11.8.0, 11.9.0, 11.10.0, 11.11.0, 11.12.0, 11.13.0, 12.0.0a1, 12.0.0a2, 12.0.0a3, 12.0.0a4, 12.0.0a5, 12.0.0a6, 12.0.0a7, 12.0.0a8, 12.0.0a9, 12.0.0b1, 12.0.0b2, 12.0.0b3, 12.0.0b4, 12.0.0b5, 12.0.0rc1, 12.0.0, 12.1.0, 12.2.0, 12.3.0) 2026-09-03 00:59:40.037199 | debian-bookworm | ERROR: No matching distribution found for ansible==14.3.1 2026-09-03 00:59:40.124854 | debian-bookworm | cookiecutter: 2798 C exit 1 (1.58 seconds) /home/zuul/src/github.com/osism/cfg-cookiecutter> python -I -m pip install -r requirements.txt pid=1617 [tox/execute/api.py:309] 2026-09-03 00:59:40.126546 | debian-bookworm | cookiecutter: FAIL code 1 (2.38 seconds) 2026-09-03 00:59:40.126569 | debian-bookworm | evaluation failed :( (2.41 seconds) 2026-09-03 00:59:40.497691 | debian-bookworm | ERROR 2026-09-03 00:59:40.497895 | debian-bookworm | { 2026-09-03 00:59:40.497932 | debian-bookworm | "delta": "0:00:02.923046", 2026-09-03 00:59:40.497960 | debian-bookworm | "end": "2026-09-03 00:59:40.201144", 2026-09-03 00:59:40.497983 | debian-bookworm | "msg": "non-zero return code", 2026-09-03 00:59:40.498002 | debian-bookworm | "rc": 1, 2026-09-03 00:59:40.498022 | debian-bookworm | "start": "2026-09-03 00:59:37.278098" 2026-09-03 00:59:40.498040 | debian-bookworm | } failure 2026-09-03 00:59:40.500827 | 2026-09-03 00:59:40.500903 | PLAY RECAP 2026-09-03 00:59:40.500953 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 3 rescued: 0 ignored: 0 2026-09-03 00:59:40.500977 | 2026-09-03 00:59:40.682182 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-03 00:59:40.683272 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-03 00:59:41.492938 | 2026-09-03 00:59:41.493091 | PLAY [all] 2026-09-03 00:59:41.520102 | 2026-09-03 00:59:41.520280 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-03 00:59:41.606011 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:41.614233 | 2026-09-03 00:59:41.614398 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-03 00:59:41.670433 | debian-bookworm | ok 2026-09-03 00:59:41.678548 | 2026-09-03 00:59:41.678743 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-03 00:59:42.383062 | debian-bookworm -> localhost | changed 2026-09-03 00:59:42.393932 | 2026-09-03 00:59:42.394063 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-03 00:59:42.449604 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:42.465258 | 2026-09-03 00:59:42.465505 | TASK [fetch-tox-output : Set envlist fact] 2026-09-03 00:59:42.513175 | debian-bookworm | ok 2026-09-03 00:59:42.519311 | 2026-09-03 00:59:42.519420 | TASK [fetch-tox-output : Get tox version] 2026-09-03 00:59:43.307931 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-03 00:59:43.329017 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-03 00:59:43.550363 | debian-bookworm | ok: Runtime: 0:00:00.391864 2026-09-03 00:59:43.556021 | 2026-09-03 00:59:43.556130 | TASK [fetch-tox-output : Find all default environments] 2026-09-03 00:59:44.087835 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:44.093997 | 2026-09-03 00:59:44.094126 | TASK [fetch-tox-output : Set envlist fact] 2026-09-03 00:59:44.128186 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:44.134024 | 2026-09-03 00:59:44.134142 | TASK [fetch-tox-output : Find all default environments] 2026-09-03 00:59:44.688188 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:44.694284 | 2026-09-03 00:59:44.694409 | TASK [fetch-tox-output : Set envlist fact] 2026-09-03 00:59:44.718357 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:44.724784 | 2026-09-03 00:59:44.724897 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-03 00:59:44.769286 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:44.787024 | 2026-09-03 00:59:44.787169 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-03 00:59:45.652418 | debian-bookworm | changed: 2026-09-03 00:59:45.652614 | debian-bookworm | created directory /var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/work/logs/tox/cookiecutter 2026-09-03 00:59:45.652662 | debian-bookworm | cd+++++++++ ./ 2026-09-03 00:59:45.652688 | debian-bookworm | >f+++++++++ 1-install_deps.log 2026-09-03 00:59:45.652717 | 2026-09-03 00:59:45.670864 | 2026-09-03 00:59:45.670983 | PLAY RECAP 2026-09-03 00:59:45.671055 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-03 00:59:45.671090 | 2026-09-03 00:59:45.846672 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-03 00:59:45.847717 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-03 00:59:46.766770 | 2026-09-03 00:59:46.766907 | PLAY [all] 2026-09-03 00:59:46.781375 | 2026-09-03 00:59:46.781492 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-03 00:59:47.015214 | debian-bookworm | changed: non-zero return code 2026-09-03 00:59:47.023865 | 2026-09-03 00:59:47.024048 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-03 00:59:47.048336 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:47.055020 | 2026-09-03 00:59:47.055211 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-03 00:59:47.087549 | 2026-09-03 00:59:47.087719 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-03 00:59:47.119568 | 2026-09-03 00:59:47.119765 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-03 00:59:47.143318 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:47.149870 | 2026-09-03 00:59:47.149967 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-03 00:59:47.168872 | 2026-09-03 00:59:47.169043 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-03 00:59:47.191955 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:47.200493 | 2026-09-03 00:59:47.200611 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-03 00:59:47.214098 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:47.220482 | 2026-09-03 00:59:47.220618 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-03 00:59:47.232995 | debian-bookworm | skipping: Conditional result was False 2026-09-03 00:59:47.238782 | 2026-09-03 00:59:47.238870 | PLAY RECAP 2026-09-03 00:59:47.238920 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-03 00:59:47.238944 | 2026-09-03 00:59:47.326212 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-03 00:59:47.327088 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-03 00:59:47.940258 | 2026-09-03 00:59:47.940367 | PLAY [Base post-fetch] 2026-09-03 00:59:47.950778 | 2026-09-03 00:59:47.950914 | TASK [diagnose-network : Ensure log directory exists] 2026-09-03 00:59:48.476580 | debian-bookworm | ok 2026-09-03 00:59:48.484252 | 2026-09-03 00:59:48.484394 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-03 01:00:00.092503 | debian-bookworm | ok: Runtime: 0:00:05.988014 2026-09-03 01:00:00.102689 | 2026-09-03 01:00:00.102811 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-03 01:00:06.138386 | debian-bookworm | ok: Runtime: 0:00:00.007024 2026-09-03 01:00:06.147352 | 2026-09-03 01:00:06.147508 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-03 01:00:12.191888 | debian-bookworm | ok: Runtime: 0:00:00.008811 2026-09-03 01:00:12.211831 | 2026-09-03 01:00:12.211970 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-03 01:00:12.287151 | debian-bookworm | skipping: Conditional result was False 2026-09-03 01:00:12.292804 | 2026-09-03 01:00:12.292913 | TASK [fetch-output : Set log path for single node] 2026-09-03 01:00:12.343925 | debian-bookworm | ok 2026-09-03 01:00:12.359543 | 2026-09-03 01:00:12.359811 | LOOP [fetch-output : Ensure local output dirs] 2026-09-03 01:00:12.704311 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/work/logs" 2026-09-03 01:00:12.975023 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/work/artifacts" 2026-09-03 01:00:13.257288 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bae5507b7e03426facdde12e52832a3d/work/docs" 2026-09-03 01:00:13.268201 | 2026-09-03 01:00:13.268343 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-03 01:00:14.068064 | debian-bookworm | changed: 2026-09-03 01:00:14.068352 | debian-bookworm | .d..t...... ./ 2026-09-03 01:00:14.068410 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-03 01:00:14.068451 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-03 01:00:14.068488 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-03 01:00:14.068523 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-03 01:00:14.068555 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-03 01:00:14.068622 | debian-bookworm | changed: All items complete 2026-09-03 01:00:14.068661 | 2026-09-03 01:00:14.776845 | debian-bookworm | changed: .d..t...... ./ 2026-09-03 01:00:15.397152 | debian-bookworm | changed: .d..t...... ./ 2026-09-03 01:00:15.437263 | 2026-09-03 01:00:15.437466 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-03 01:00:15.859592 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.005557 2026-09-03 01:00:16.435080 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.036354 2026-09-03 01:00:16.451007 | 2026-09-03 01:00:16.451117 | PLAY RECAP 2026-09-03 01:00:16.451170 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-03 01:00:16.451195 | 2026-09-03 01:00:16.608320 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-03 01:00:16.609485 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-03 01:00:17.504927 | 2026-09-03 01:00:17.505042 | PLAY [Base post] 2026-09-03 01:00:17.516900 | 2026-09-03 01:00:17.517013 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-03 01:00:18.075563 | debian-bookworm | changed 2026-09-03 01:00:18.081307 | 2026-09-03 01:00:18.081386 | PLAY RECAP 2026-09-03 01:00:18.081445 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-03 01:00:18.081506 | 2026-09-03 01:00:18.175144 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-03 01:00:18.176108 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-03 01:00:18.958399 | 2026-09-03 01:00:18.958520 | PLAY [Base post-logs] 2026-09-03 01:00:18.975285 | 2026-09-03 01:00:18.975400 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-03 01:00:19.585752 | localhost | changed 2026-09-03 01:00:19.594579 | 2026-09-03 01:00:19.594725 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-03 01:00:19.642813 | localhost | ok 2026-09-03 01:00:19.645913 | 2026-09-03 01:00:19.645993 | TASK [Set zuul-log-path fact] 2026-09-03 01:00:19.660121 | localhost | ok 2026-09-03 01:00:19.667319 | 2026-09-03 01:00:19.667409 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-03 01:00:19.706370 | localhost | ok 2026-09-03 01:00:19.709071 | 2026-09-03 01:00:19.709154 | TASK [upload-logs : Create log directories] 2026-09-03 01:00:20.261629 | localhost | changed 2026-09-03 01:00:20.263701 | 2026-09-03 01:00:20.263770 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-03 01:00:20.779245 | localhost -> localhost | ok: Runtime: 0:00:00.003582 2026-09-03 01:00:20.784423 | 2026-09-03 01:00:20.784515 | TASK [upload-logs : Upload logs to log server] 2026-09-03 01:00:21.270408 | localhost | Output suppressed because no_log was given 2026-09-03 01:00:21.271777 | 2026-09-03 01:00:21.271851 | LOOP [upload-logs : Compress console log and json output] 2026-09-03 01:00:21.316451 | localhost | skipping: Conditional result was False 2026-09-03 01:00:21.328132 | localhost | skipping: Conditional result was False 2026-09-03 01:00:21.336368 | 2026-09-03 01:00:21.336448 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-03 01:00:21.367968 | localhost | skipping: Conditional result was False 2026-09-03 01:00:21.368181 | 2026-09-03 01:00:21.371764 | localhost | skipping: Conditional result was False 2026-09-03 01:00:21.380887 | 2026-09-03 01:00:21.380963 | LOOP [upload-logs : Upload console log and json output]