2026-08-29 19:31:45.497100 | Job console starting 2026-08-29 19:31:45.516123 | Updating git repos 2026-08-29 19:31:45.547039 | Cloning repos into workspace 2026-08-29 19:31:45.602808 | Restoring repo states 2026-08-29 19:31:45.611395 | Merging changes 2026-08-29 19:31:46.241173 | Checking out repos 2026-08-29 19:31:46.305085 | Preparing playbooks 2026-08-29 19:31:47.819637 | Running Ansible setup 2026-08-29 19:31:54.787889 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-29 19:31:55.798990 | 2026-08-29 19:31:55.799109 | PLAY [Base pre] 2026-08-29 19:31:55.826859 | 2026-08-29 19:31:55.826993 | TASK [Setup log path fact] 2026-08-29 19:31:55.854216 | debian-bookworm | ok 2026-08-29 19:31:55.868826 | 2026-08-29 19:31:55.868947 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-29 19:31:55.897130 | debian-bookworm | ok 2026-08-29 19:31:55.905925 | 2026-08-29 19:31:55.906015 | TASK [emit-job-header : Print job information] 2026-08-29 19:31:55.981736 | # Job Information 2026-08-29 19:31:55.981888 | Ansible Version: 2.16.14 2026-08-29 19:31:55.981916 | Job: cfg-cookiecutter-tox-2025.1 2026-08-29 19:31:55.981943 | Pipeline: check 2026-08-29 19:31:55.981962 | Executor: 521e9411259a 2026-08-29 19:31:55.981980 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/864 2026-08-29 19:31:55.981999 | Event ID: 1ec2ca00-a3e0-11f1-91e7-838c9f56b669 2026-08-29 19:31:55.986577 | 2026-08-29 19:31:55.986673 | LOOP [emit-job-header : Print node information] 2026-08-29 19:31:56.165464 | debian-bookworm | ok: 2026-08-29 19:31:56.165682 | debian-bookworm | # Node Information 2026-08-29 19:31:56.165862 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-29 19:31:56.165937 | debian-bookworm | Hostname: debian 2026-08-29 19:31:56.165964 | debian-bookworm | Username: zuul 2026-08-29 19:31:56.165987 | debian-bookworm | Distro: Debian 12.15 2026-08-29 19:31:56.166012 | debian-bookworm | Provider: regiocloud-a 2026-08-29 19:31:56.166034 | debian-bookworm | Region: 2026-08-29 19:31:56.166056 | debian-bookworm | Label: debian-bookworm 2026-08-29 19:31:56.166076 | debian-bookworm | Product Name: OpenStack Nova 2026-08-29 19:31:56.166095 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe29:122d 2026-08-29 19:31:56.176088 | 2026-08-29 19:31:56.176188 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-29 19:31:57.466005 | debian-bookworm -> localhost | changed 2026-08-29 19:31:57.477838 | 2026-08-29 19:31:57.477938 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-29 19:31:59.791175 | debian-bookworm -> localhost | changed 2026-08-29 19:31:59.807972 | 2026-08-29 19:31:59.808099 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-29 19:32:00.670041 | debian-bookworm -> localhost | ok 2026-08-29 19:32:00.676065 | 2026-08-29 19:32:00.676174 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-29 19:32:00.711346 | debian-bookworm | ok 2026-08-29 19:32:00.749889 | debian-bookworm | included: /var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-29 19:32:00.758506 | 2026-08-29 19:32:00.758623 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-29 19:32:02.733973 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-29 19:32:02.735117 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/work/a8433dfe38b24fb0b774df0b657a0b4c_id_rsa 2026-08-29 19:32:02.735178 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/work/a8433dfe38b24fb0b774df0b657a0b4c_id_rsa.pub 2026-08-29 19:32:02.735205 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-29 19:32:02.735227 | debian-bookworm -> localhost | SHA256:WLCtuCBZWab6EnZdVeLoWP8/AwAr+hCSIPDLVcqAyBw zuul-build-sshkey 2026-08-29 19:32:02.735254 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-29 19:32:02.735274 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-29 19:32:02.735292 | debian-bookworm -> localhost | |*Eo o o o.. | 2026-08-29 19:32:02.735310 | debian-bookworm -> localhost | |=+ B o =+ . | 2026-08-29 19:32:02.735328 | debian-bookworm -> localhost | |o * + .+=. | 2026-08-29 19:32:02.735344 | debian-bookworm -> localhost | | B +.o==.. | 2026-08-29 19:32:02.735361 | debian-bookworm -> localhost | |=.=.+o+.S.. | 2026-08-29 19:32:02.735381 | debian-bookworm -> localhost | |.+.+ . .. | 2026-08-29 19:32:02.735398 | debian-bookworm -> localhost | |. . + .. | 2026-08-29 19:32:02.735414 | debian-bookworm -> localhost | | . . .o | 2026-08-29 19:32:02.735456 | debian-bookworm -> localhost | | .o | 2026-08-29 19:32:02.735474 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-29 19:32:02.735522 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.706262 2026-08-29 19:32:02.740712 | 2026-08-29 19:32:02.740784 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-29 19:32:02.778594 | debian-bookworm | ok 2026-08-29 19:32:02.788825 | debian-bookworm | included: /var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-29 19:32:02.804384 | 2026-08-29 19:32:02.804506 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-29 19:32:02.845570 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:32:02.851136 | 2026-08-29 19:32:02.851227 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-29 19:32:03.546846 | debian-bookworm | changed 2026-08-29 19:32:03.552224 | 2026-08-29 19:32:03.552314 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-29 19:32:03.848710 | debian-bookworm | ok 2026-08-29 19:32:03.854131 | 2026-08-29 19:32:03.854230 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-29 19:32:04.641559 | debian-bookworm | changed 2026-08-29 19:32:04.653824 | 2026-08-29 19:32:04.653935 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-29 19:32:05.488333 | debian-bookworm | changed 2026-08-29 19:32:05.493845 | 2026-08-29 19:32:05.493934 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-29 19:32:05.527235 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:32:05.533293 | 2026-08-29 19:32:05.533382 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-29 19:32:06.735310 | debian-bookworm -> localhost | changed 2026-08-29 19:32:06.746046 | 2026-08-29 19:32:06.746154 | TASK [add-build-sshkey : Add back temp key] 2026-08-29 19:32:07.718271 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/work/a8433dfe38b24fb0b774df0b657a0b4c_id_rsa (zuul-build-sshkey) 2026-08-29 19:32:07.718521 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013715 2026-08-29 19:32:07.727368 | 2026-08-29 19:32:07.727498 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-29 19:32:08.313010 | debian-bookworm | ok 2026-08-29 19:32:08.323772 | 2026-08-29 19:32:08.323900 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-29 19:32:08.393842 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:32:08.417700 | 2026-08-29 19:32:08.417823 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-29 19:32:09.020832 | debian-bookworm | ok 2026-08-29 19:32:09.032519 | 2026-08-29 19:32:09.032622 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-29 19:32:09.068400 | debian-bookworm | ok 2026-08-29 19:32:09.092682 | 2026-08-29 19:32:09.092800 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-29 19:32:09.599682 | debian-bookworm -> localhost | ok 2026-08-29 19:32:09.606328 | 2026-08-29 19:32:09.606441 | TASK [validate-host : Collect information about the host] 2026-08-29 19:32:10.992298 | debian-bookworm | ok 2026-08-29 19:32:11.014956 | 2026-08-29 19:32:11.015077 | TASK [validate-host : Sanitize hostname] 2026-08-29 19:32:11.206927 | debian-bookworm | ok 2026-08-29 19:32:11.211335 | 2026-08-29 19:32:11.211453 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-29 19:32:12.271531 | debian-bookworm -> localhost | changed 2026-08-29 19:32:12.280730 | 2026-08-29 19:32:12.280864 | TASK [validate-host : Collect information about zuul worker] 2026-08-29 19:32:12.752025 | debian-bookworm | ok 2026-08-29 19:32:12.772398 | 2026-08-29 19:32:12.772571 | TASK [validate-host : Write out all zuul information for each host] 2026-08-29 19:32:14.121371 | debian-bookworm -> localhost | changed 2026-08-29 19:32:14.131278 | 2026-08-29 19:32:14.131369 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-29 19:32:14.413442 | debian-bookworm | ok 2026-08-29 19:32:14.420778 | 2026-08-29 19:32:14.420879 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-29 19:32:15.669466 | debian-bookworm | changed: 2026-08-29 19:32:15.669627 | debian-bookworm | cd+++++++++ src/ 2026-08-29 19:32:15.669656 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-29 19:32:15.669676 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-29 19:32:15.669695 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-08-29 19:32:15.669713 | debian-bookworm | Containerfile 2026-08-29 19:32:15.669823 | debian-bookworm | =7.0.3 2026-08-29 19:32:54.765127 | debian-bookworm | Downloading cachetools-7.1.7-py3-none-any.whl (16 kB) 2026-08-29 19:32:54.805780 | debian-bookworm | Collecting colorama>=0.4.6 2026-08-29 19:32:54.817321 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-08-29 19:32:54.936727 | debian-bookworm | Collecting filelock>=3.25 2026-08-29 19:32:54.949303 | debian-bookworm | Downloading filelock-3.32.4-py3-none-any.whl (99 kB) 2026-08-29 19:32:54.960045 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 99.9/99.9 kB 13.0 MB/s eta 0:00:00 2026-08-29 19:32:55.019813 | debian-bookworm | Collecting packaging>=26 2026-08-29 19:32:55.031106 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-08-29 19:32:55.047241 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 9.2 MB/s eta 0:00:00 2026-08-29 19:32:55.101168 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-08-29 19:32:55.114044 | debian-bookworm | Downloading platformdirs-4.11.5-py3-none-any.whl (23 kB) 2026-08-29 19:32:55.169373 | debian-bookworm | Collecting pluggy>=1.6 2026-08-29 19:32:55.181227 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-08-29 19:32:55.226948 | debian-bookworm | Collecting pyproject-api>=1.10 2026-08-29 19:32:55.261263 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-08-29 19:32:55.313606 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-08-29 19:32:55.324753 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-08-29 19:32:55.360432 | debian-bookworm | Collecting tomli-w>=1.2 2026-08-29 19:32:55.374322 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-08-29 19:32:55.445127 | debian-bookworm | Collecting typing-extensions>=4.15 2026-08-29 19:32:55.456359 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-08-29 19:32:55.463854 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 7.2 MB/s eta 0:00:00 2026-08-29 19:32:55.615885 | debian-bookworm | Collecting virtualenv>=21.1 2026-08-29 19:32:55.627204 | debian-bookworm | Downloading virtualenv-21.7.7-py3-none-any.whl (5.3 MB) 2026-08-29 19:32:55.884942 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 21.1 MB/s eta 0:00:00 2026-08-29 19:32:55.970308 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-08-29 19:32:55.981359 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-08-29 19:32:56.000960 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 27.3 MB/s eta 0:00:00 2026-08-29 19:32:56.100895 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-08-29 19:32:56.949525 | debian-bookworm | Successfully installed cachetools-7.1.7 colorama-0.4.6 distlib-0.4.3 filelock-3.32.4 packaging-26.3 platformdirs-4.11.5 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.6.0 tomli-w-1.2.0 tox-4.61.1 typing-extensions-4.16.0 virtualenv-21.7.7 2026-08-29 19:32:57.080883 | debian-bookworm | ok: Runtime: 0:00:03.192881 2026-08-29 19:32:57.088879 | 2026-08-29 19:32:57.089009 | TASK [ensure-tox : Export installed tox_executable path] 2026-08-29 19:32:57.134099 | debian-bookworm | ok 2026-08-29 19:32:57.160052 | 2026-08-29 19:32:57.160235 | TASK [ensure-tox : Output tox version] 2026-08-29 19:32:57.767185 | 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-08-29 19:32:57.785427 | debian-bookworm | 4.61.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-29 19:32:58.236265 | debian-bookworm | ok: Runtime: 0:00:00.297647 2026-08-29 19:32:58.242388 | 2026-08-29 19:32:58.242511 | TASK [ensure-tox : Make global symlink] 2026-08-29 19:32:58.288740 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:32:58.295333 | 2026-08-29 19:32:58.295432 | PLAY RECAP 2026-08-29 19:32:58.295482 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-08-29 19:32:58.295503 | 2026-08-29 19:32:58.580838 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-08-29 19:32:58.581954 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-29 19:33:00.215817 | 2026-08-29 19:33:00.215977 | PLAY [all] 2026-08-29 19:33:00.289905 | 2026-08-29 19:33:00.290073 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-08-29 19:33:00.883217 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-29 19:33:01.386939 | debian-bookworm | ok: Runtime: 0:00:00.035203 2026-08-29 19:33:01.393258 | 2026-08-29 19:33:01.393374 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-08-29 19:33:01.938037 | debian-bookworm | ok: Runtime: 0:00:00.007026 2026-08-29 19:33:01.944499 | 2026-08-29 19:33:01.944603 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-08-29 19:33:02.385682 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-29 19:33:02.387237 | debian-bookworm | sudo: a password is required 2026-08-29 19:33:02.510067 | debian-bookworm | ok: Runtime: 0:00:00.018100 2026-08-29 19:33:02.534232 | 2026-08-29 19:33:02.534367 | TASK [tox : Check to see if the constraints file exists] 2026-08-29 19:33:02.698358 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:02.706115 | 2026-08-29 19:33:02.706251 | TASK [tox : Fail if constraints file is missing] 2026-08-29 19:33:02.775448 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:02.781605 | 2026-08-29 19:33:02.781709 | TASK [tox : Record file location] 2026-08-29 19:33:02.845891 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:02.852695 | 2026-08-29 19:33:02.852813 | TASK [tox : Install tox siblings] 2026-08-29 19:33:02.898745 | debian-bookworm | ok 2026-08-29 19:33:02.919539 | debian-bookworm | included: /var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-08-29 19:33:02.939822 | 2026-08-29 19:33:02.939933 | TASK [tox : Run tox without tests] 2026-08-29 19:33:03.637996 | debian-bookworm | ROOT: 235 D setup logging to DEBUG on pid 1584 [tox/report.py:242] 2026-08-29 19:33:03.674127 | debian-bookworm | cookiecutter: 271 D created app data folder /home/zuul/.cache/virtualenv [virtualenv/app_data/__init__.py:65] 2026-08-29 19:33:03.680320 | debian-bookworm | cookiecutter: 277 I find interpreter for spec PythonSpec(path=/home/zuul/.local/tox/bin/python3) [python_discovery/_discovery.py:131] 2026-08-29 19:33:03.681112 | debian-bookworm | cookiecutter: 278 D filesystem is case-sensitive [python_discovery/_compat.py:18] 2026-08-29 19:33:03.689511 | debian-bookworm | cookiecutter: 286 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 adcc0eccd9207b52283d5df07fe7883b 295d43d575c3731958225ec73ca5966d [python_discovery/_cached_py_info.py:223] 2026-08-29 19:33:03.760599 | debian-bookworm | cookiecutter: 357 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-08-29 19:33:03.761238 | debian-bookworm | cookiecutter: 358 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-08-29 19:33:03.761642 | debian-bookworm | cookiecutter: 358 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-08-29 19:33:03.765877 | debian-bookworm | cookiecutter: 363 D symlink on filesystem does work [virtualenv/info.py:51] 2026-08-29 19:33:03.766146 | debian-bookworm | cookiecutter: 363 D filesystem is case-sensitive [virtualenv/info.py:28] 2026-08-29 19:33:03.801229 | debian-bookworm | cookiecutter: 398 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-08-29 19:33:03.801698 | debian-bookworm | cookiecutter: 398 D create folder /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/bin [virtualenv/util/path/_sync.py:22] 2026-08-29 19:33:03.801795 | debian-bookworm | cookiecutter: 399 D create folder /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/include [virtualenv/util/path/_sync.py:22] 2026-08-29 19:33:03.802007 | debian-bookworm | cookiecutter: 399 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-08-29 19:33:03.802266 | debian-bookworm | cookiecutter: 399 D write /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:41] 2026-08-29 19:33:03.802448 | debian-bookworm | cookiecutter: 399 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.802560 | debian-bookworm | cookiecutter: 399 D implementation = CPython [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.802629 | debian-bookworm | cookiecutter: 400 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.802707 | debian-bookworm | cookiecutter: 400 D version = 3.11.2 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.802774 | debian-bookworm | cookiecutter: 400 D executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.802847 | debian-bookworm | cookiecutter: 400 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-08-29 19:33:03.802914 | debian-bookworm | cookiecutter: 400 D virtualenv = 21.7.7 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.802985 | debian-bookworm | cookiecutter: 400 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.803053 | debian-bookworm | cookiecutter: 400 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.803116 | debian-bookworm | cookiecutter: 400 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.803178 | debian-bookworm | cookiecutter: 400 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:03.803631 | debian-bookworm | cookiecutter: 400 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-08-29 19:33:03.804265 | debian-bookworm | cookiecutter: 401 D ============================== target debug ============================== [virtualenv/run/session.py:79] 2026-08-29 19:33:03.804653 | debian-bookworm | cookiecutter: 401 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-08-29 19:33:03.845003 | debian-bookworm | cookiecutter: 401 D { 2026-08-29 19:33:03.845059 | debian-bookworm | "sys": { 2026-08-29 19:33:03.845070 | debian-bookworm | "executable": "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/bin/python", 2026-08-29 19:33:03.845077 | debian-bookworm | "_base_executable": "/usr/bin/python3.11", 2026-08-29 19:33:03.845087 | debian-bookworm | "prefix": "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter", 2026-08-29 19:33:03.845095 | debian-bookworm | "base_prefix": "/usr", 2026-08-29 19:33:03.845102 | debian-bookworm | "exec_prefix": "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter", 2026-08-29 19:33:03.845109 | debian-bookworm | "base_exec_prefix": "/usr", 2026-08-29 19:33:03.845116 | debian-bookworm | "path": [ 2026-08-29 19:33:03.845122 | debian-bookworm | "/usr/lib/python311.zip", 2026-08-29 19:33:03.845128 | debian-bookworm | "/usr/lib/python3.11", 2026-08-29 19:33:03.845135 | debian-bookworm | "/usr/lib/python3.11/lib-dynload", 2026-08-29 19:33:03.845142 | debian-bookworm | "/home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/lib/python3.11/site-packages" 2026-08-29 19:33:03.845148 | debian-bookworm | ], 2026-08-29 19:33:03.845155 | debian-bookworm | "meta_path": [ 2026-08-29 19:33:03.845161 | debian-bookworm | "", 2026-08-29 19:33:03.845168 | debian-bookworm | "", 2026-08-29 19:33:03.845174 | debian-bookworm | "" 2026-08-29 19:33:03.845181 | debian-bookworm | ], 2026-08-29 19:33:03.845188 | debian-bookworm | "fs_encoding": "utf-8", 2026-08-29 19:33:03.845194 | debian-bookworm | "io_encoding": "utf-8" 2026-08-29 19:33:03.845201 | debian-bookworm | }, 2026-08-29 19:33:03.845208 | debian-bookworm | "version": "3.11.2 (main, May 12 2026, 05:17:27) [GCC 12.2.0]", 2026-08-29 19:33:03.845214 | debian-bookworm | "makefile_filename": "/usr/lib/python3.11/config-3.11-x86_64-linux-gnu/Makefile", 2026-08-29 19:33:03.845221 | debian-bookworm | "os": "", 2026-08-29 19:33:03.845227 | debian-bookworm | "site": "", 2026-08-29 19:33:03.845234 | debian-bookworm | "datetime": "", 2026-08-29 19:33:03.845241 | debian-bookworm | "math": "", 2026-08-29 19:33:03.845254 | debian-bookworm | "json": "" 2026-08-29 19:33:03.845262 | debian-bookworm | } [virtualenv/run/session.py:80] 2026-08-29 19:33:03.845275 | debian-bookworm | cookiecutter: 442 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-08-29 19:33:03.853100 | debian-bookworm | cookiecutter: 450 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-08-29 19:33:03.854088 | debian-bookworm | cookiecutter: 451 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-08-29 19:33:03.858797 | debian-bookworm | cookiecutter: 456 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-08-29 19:33:03.861749 | debian-bookworm | cookiecutter: 459 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-08-29 19:33:04.189921 | debian-bookworm | cookiecutter: 787 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-08-29 19:33:04.191131 | debian-bookworm | cookiecutter: 788 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-08-29 19:33:04.192281 | debian-bookworm | cookiecutter: 789 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-08-29 19:33:04.198315 | debian-bookworm | cookiecutter: 795 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-08-29 19:33:04.201004 | debian-bookworm | cookiecutter: 798 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-08-29 19:33:04.294695 | debian-bookworm | cookiecutter: 891 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-08-29 19:33:04.295390 | debian-bookworm | cookiecutter: 892 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-08-29 19:33:04.348169 | debian-bookworm | cookiecutter: 945 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:69] 2026-08-29 19:33:04.375291 | debian-bookworm | cookiecutter: 972 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-08-29 19:33:04.380231 | debian-bookworm | cookiecutter: 977 D generated console scripts pip3 pip3.11 pip pip-3.11 [virtualenv/seed/embed/via_app_data/pip_install/base.py:69] 2026-08-29 19:33:04.380900 | debian-bookworm | cookiecutter: 977 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python, Xonsh [virtualenv/run/session.py:90] 2026-08-29 19:33:04.386442 | debian-bookworm | cookiecutter: 983 D write /home/zuul/src/github.com/osism/cfg-cookiecutter/.tox/cookiecutter/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:41] 2026-08-29 19:33:04.386646 | debian-bookworm | cookiecutter: 984 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.386728 | debian-bookworm | cookiecutter: 984 D implementation = CPython [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.386797 | debian-bookworm | cookiecutter: 984 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.386860 | debian-bookworm | cookiecutter: 984 D version = 3.11.2 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.386925 | debian-bookworm | cookiecutter: 984 D executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.386990 | debian-bookworm | cookiecutter: 984 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-08-29 19:33:04.387054 | debian-bookworm | cookiecutter: 984 D virtualenv = 21.7.7 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.387126 | debian-bookworm | cookiecutter: 984 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.387191 | debian-bookworm | cookiecutter: 984 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.387258 | debian-bookworm | cookiecutter: 984 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.387322 | debian-bookworm | cookiecutter: 984 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:51] 2026-08-29 19:33:04.392514 | debian-bookworm | cookiecutter: 989 W install_deps> python -I -m pip install -r requirements.txt [tox/tox_env/api.py:485] 2026-08-29 19:33:05.746954 | debian-bookworm | Collecting PyYAML==6.0.3 (from -r requirements.txt (line 1)) 2026-08-29 19:33:05.866320 | 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-08-29 19:33:05.940747 | 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-08-29 19:33:05.941413 | 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-08-29 19:33:05.941481 | debian-bookworm | ERROR: No matching distribution found for ansible==14.3.1 2026-08-29 19:33:06.021380 | debian-bookworm | cookiecutter: 2618 C exit 1 (1.63 seconds) /home/zuul/src/github.com/osism/cfg-cookiecutter> python -I -m pip install -r requirements.txt pid=1597 [tox/execute/api.py:309] 2026-08-29 19:33:06.022993 | debian-bookworm | cookiecutter: FAIL code 1 (2.36 seconds) 2026-08-29 19:33:06.023085 | debian-bookworm | evaluation failed :( (2.39 seconds) 2026-08-29 19:33:06.527719 | debian-bookworm | ERROR 2026-08-29 19:33:06.527907 | debian-bookworm | { 2026-08-29 19:33:06.527942 | debian-bookworm | "delta": "0:00:02.722471", 2026-08-29 19:33:06.527968 | debian-bookworm | "end": "2026-08-29 19:33:06.084788", 2026-08-29 19:33:06.527990 | debian-bookworm | "msg": "non-zero return code", 2026-08-29 19:33:06.528010 | debian-bookworm | "rc": 1, 2026-08-29 19:33:06.528029 | debian-bookworm | "start": "2026-08-29 19:33:03.362317" 2026-08-29 19:33:06.528047 | debian-bookworm | } failure 2026-08-29 19:33:06.531428 | 2026-08-29 19:33:06.531498 | PLAY RECAP 2026-08-29 19:33:06.531549 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 1 skipped: 3 rescued: 0 ignored: 0 2026-08-29 19:33:06.531573 | 2026-08-29 19:33:06.796875 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-29 19:33:06.797993 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-29 19:33:08.874309 | 2026-08-29 19:33:08.874458 | PLAY [all] 2026-08-29 19:33:08.948400 | 2026-08-29 19:33:08.948563 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-08-29 19:33:09.132296 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:09.137570 | 2026-08-29 19:33:09.137698 | TASK [fetch-tox-output : Set tox log path for single node] 2026-08-29 19:33:09.225894 | debian-bookworm | ok 2026-08-29 19:33:09.230780 | 2026-08-29 19:33:09.230930 | TASK [fetch-tox-output : Ensure local tox dir] 2026-08-29 19:33:11.094768 | debian-bookworm -> localhost | changed 2026-08-29 19:33:11.102471 | 2026-08-29 19:33:11.102572 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-08-29 19:33:11.173147 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:11.178191 | 2026-08-29 19:33:11.178288 | TASK [fetch-tox-output : Set envlist fact] 2026-08-29 19:33:11.267289 | debian-bookworm | ok 2026-08-29 19:33:11.274201 | 2026-08-29 19:33:11.274295 | TASK [fetch-tox-output : Get tox version] 2026-08-29 19:33:12.193896 | 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-08-29 19:33:12.213080 | debian-bookworm | 4.61.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-29 19:33:12.332726 | debian-bookworm | ok: Runtime: 0:00:00.378145 2026-08-29 19:33:12.337882 | 2026-08-29 19:33:12.337969 | TASK [fetch-tox-output : Find all default environments] 2026-08-29 19:33:12.962473 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:12.967357 | 2026-08-29 19:33:12.967510 | TASK [fetch-tox-output : Set envlist fact] 2026-08-29 19:33:13.035435 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:13.040649 | 2026-08-29 19:33:13.040739 | TASK [fetch-tox-output : Find all default environments] 2026-08-29 19:33:13.630079 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:13.635878 | 2026-08-29 19:33:13.635982 | TASK [fetch-tox-output : Set envlist fact] 2026-08-29 19:33:13.669252 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:13.675778 | 2026-08-29 19:33:13.675877 | LOOP [fetch-tox-output : Copy tox logs] 2026-08-29 19:33:13.793188 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:13.816867 | 2026-08-29 19:33:13.816980 | LOOP [fetch-tox-output : Collect tox logs] 2026-08-29 19:33:15.550616 | debian-bookworm | changed: 2026-08-29 19:33:15.550781 | debian-bookworm | created directory /var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/work/logs/tox/cookiecutter 2026-08-29 19:33:15.550815 | debian-bookworm | cd+++++++++ ./ 2026-08-29 19:33:15.550852 | debian-bookworm | >f+++++++++ 1-install_deps.log 2026-08-29 19:33:15.550881 | 2026-08-29 19:33:15.557432 | 2026-08-29 19:33:15.557509 | PLAY RECAP 2026-08-29 19:33:15.557558 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-08-29 19:33:15.557580 | 2026-08-29 19:33:15.879732 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-29 19:33:15.880571 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-29 19:33:17.252297 | 2026-08-29 19:33:17.252450 | PLAY [all] 2026-08-29 19:33:17.295105 | 2026-08-29 19:33:17.295235 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-08-29 19:33:17.727336 | debian-bookworm | changed: non-zero return code 2026-08-29 19:33:17.743497 | 2026-08-29 19:33:17.743636 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-08-29 19:33:17.832928 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:17.838586 | 2026-08-29 19:33:17.838712 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-08-29 19:33:17.908430 | 2026-08-29 19:33:17.908644 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-08-29 19:33:17.974255 | 2026-08-29 19:33:17.974502 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-08-29 19:33:18.021922 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:18.028581 | 2026-08-29 19:33:18.028685 | LOOP [fetch-subunit-output : Generate subunit file] 2026-08-29 19:33:18.092014 | 2026-08-29 19:33:18.092273 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-08-29 19:33:18.177040 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:18.184332 | 2026-08-29 19:33:18.184502 | TASK [fetch-subunit-output : Remove the temporary file] 2026-08-29 19:33:18.233658 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:18.239296 | 2026-08-29 19:33:18.239384 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-08-29 19:33:18.283360 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:18.290246 | 2026-08-29 19:33:18.290321 | PLAY RECAP 2026-08-29 19:33:18.290475 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-08-29 19:33:18.290513 | 2026-08-29 19:33:18.919132 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-29 19:33:18.920137 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-29 19:33:20.800609 | 2026-08-29 19:33:20.800741 | PLAY [Base post-fetch] 2026-08-29 19:33:20.816893 | 2026-08-29 19:33:20.817014 | TASK [diagnose-network : Ensure log directory exists] 2026-08-29 19:33:21.382619 | debian-bookworm | ok 2026-08-29 19:33:21.395806 | 2026-08-29 19:33:21.395966 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-08-29 19:33:33.914769 | debian-bookworm | ok: Runtime: 0:00:06.046412 2026-08-29 19:33:33.925648 | 2026-08-29 19:33:33.925843 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-08-29 19:33:40.050686 | debian-bookworm | ok: Runtime: 0:00:00.008446 2026-08-29 19:33:40.060796 | 2026-08-29 19:33:40.060962 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-08-29 19:33:46.153079 | debian-bookworm | ok: Runtime: 0:00:00.008887 2026-08-29 19:33:46.175586 | 2026-08-29 19:33:46.175726 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-29 19:33:46.233946 | debian-bookworm | skipping: Conditional result was False 2026-08-29 19:33:46.240583 | 2026-08-29 19:33:46.247124 | TASK [fetch-output : Set log path for single node] 2026-08-29 19:33:46.301551 | debian-bookworm | ok 2026-08-29 19:33:46.308801 | 2026-08-29 19:33:46.308946 | LOOP [fetch-output : Ensure local output dirs] 2026-08-29 19:33:46.767954 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/work/logs" 2026-08-29 19:33:47.069749 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/work/artifacts" 2026-08-29 19:33:47.389824 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a8433dfe38b24fb0b774df0b657a0b4c/work/docs" 2026-08-29 19:33:47.407294 | 2026-08-29 19:33:47.407476 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-29 19:33:48.325561 | debian-bookworm | changed: 2026-08-29 19:33:48.325906 | debian-bookworm | .d..t...... ./ 2026-08-29 19:33:48.325951 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-29 19:33:48.325978 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-29 19:33:48.325999 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-29 19:33:48.326020 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-29 19:33:48.326042 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-29 19:33:48.326097 | debian-bookworm | changed: All items complete 2026-08-29 19:33:48.326126 | 2026-08-29 19:33:48.992170 | debian-bookworm | changed: .d..t...... ./ 2026-08-29 19:33:49.612441 | debian-bookworm | changed: .d..t...... ./ 2026-08-29 19:33:49.636672 | 2026-08-29 19:33:49.636819 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-29 19:33:50.404640 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.016413 2026-08-29 19:33:50.953924 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.014211 2026-08-29 19:33:50.969845 | 2026-08-29 19:33:50.969983 | PLAY RECAP 2026-08-29 19:33:50.970158 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-29 19:33:50.970189 | 2026-08-29 19:33:51.261700 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-29 19:33:51.262915 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-29 19:33:52.953160 | 2026-08-29 19:33:52.953369 | PLAY [Base post] 2026-08-29 19:33:52.974992 | 2026-08-29 19:33:52.975180 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-29 19:33:53.650639 | debian-bookworm | changed 2026-08-29 19:33:53.667816 | 2026-08-29 19:33:53.667982 | PLAY RECAP 2026-08-29 19:33:53.668053 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-29 19:33:53.668120 | 2026-08-29 19:33:53.993325 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-29 19:33:53.994565 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-29 19:33:55.282767 | 2026-08-29 19:33:55.283006 | PLAY [Base post-logs] 2026-08-29 19:33:55.301322 | 2026-08-29 19:33:55.301471 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-29 19:33:55.864357 | localhost | changed 2026-08-29 19:33:55.875445 | 2026-08-29 19:33:55.875583 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-29 19:33:55.925661 | localhost | ok 2026-08-29 19:33:55.928812 | 2026-08-29 19:33:55.928904 | TASK [Set zuul-log-path fact] 2026-08-29 19:33:55.951401 | localhost | ok 2026-08-29 19:33:55.959302 | 2026-08-29 19:33:55.959403 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-29 19:33:55.983807 | localhost | ok 2026-08-29 19:33:55.986260 | 2026-08-29 19:33:55.986340 | TASK [upload-logs : Create log directories] 2026-08-29 19:33:56.572369 | localhost | changed 2026-08-29 19:33:56.574570 | 2026-08-29 19:33:56.574645 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-29 19:33:57.136814 | localhost -> localhost | ok: Runtime: 0:00:00.007309 2026-08-29 19:33:57.140153 | 2026-08-29 19:33:57.140240 | TASK [upload-logs : Upload logs to log server] 2026-08-29 19:33:57.693743 | localhost | Output suppressed because no_log was given 2026-08-29 19:33:57.698394 | 2026-08-29 19:33:57.698560 | LOOP [upload-logs : Compress console log and json output] 2026-08-29 19:33:57.741659 | localhost | skipping: Conditional result was False 2026-08-29 19:33:57.747353 | localhost | skipping: Conditional result was False 2026-08-29 19:33:57.756434 | 2026-08-29 19:33:57.756536 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-29 19:33:57.801389 | localhost | skipping: Conditional result was False 2026-08-29 19:33:57.801667 | 2026-08-29 19:33:57.811155 | localhost | skipping: Conditional result was False 2026-08-29 19:33:57.814322 | 2026-08-29 19:33:57.814405 | LOOP [upload-logs : Upload console log and json output]