2025-06-01 15:13:07.296360 | Job console starting 2025-06-01 15:13:07.325106 | Updating git repos 2025-06-01 15:13:07.387792 | Cloning repos into workspace 2025-06-01 15:13:07.434279 | Restoring repo states 2025-06-01 15:13:07.459040 | Merging changes 2025-06-01 15:13:08.014370 | Checking out repos 2025-06-01 15:13:08.138130 | Preparing playbooks 2025-06-01 15:13:09.334693 | Running Ansible setup 2025-06-01 15:13:15.783671 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-06-01 15:13:16.634875 | 2025-06-01 15:13:16.635411 | PLAY [Base pre] 2025-06-01 15:13:16.657561 | 2025-06-01 15:13:16.657751 | TASK [Setup log path fact] 2025-06-01 15:13:16.694474 | debian-bookworm | ok 2025-06-01 15:13:16.733217 | 2025-06-01 15:13:16.733364 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-01 15:13:16.762576 | debian-bookworm | ok 2025-06-01 15:13:16.795252 | 2025-06-01 15:13:16.795378 | TASK [emit-job-header : Print job information] 2025-06-01 15:13:16.835072 | # Job Information 2025-06-01 15:13:16.835242 | Ansible Version: 2.16.14 2025-06-01 15:13:16.835295 | Job: cfg-generics-tox-zuul-latest 2025-06-01 15:13:16.835335 | Pipeline: check 2025-06-01 15:13:16.835360 | Executor: 521e9411259a 2025-06-01 15:13:16.835382 | Triggered by: https://github.com/osism/cfg-generics/pull/541 2025-06-01 15:13:16.835406 | Event ID: e66e2690-3efa-11f0-8b49-f6decab53388 2025-06-01 15:13:16.840473 | 2025-06-01 15:13:16.840581 | LOOP [emit-job-header : Print node information] 2025-06-01 15:13:16.994055 | debian-bookworm | ok: 2025-06-01 15:13:16.994233 | debian-bookworm | # Node Information 2025-06-01 15:13:16.994267 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-06-01 15:13:16.994292 | debian-bookworm | Hostname: debian 2025-06-01 15:13:16.994314 | debian-bookworm | Username: zuul 2025-06-01 15:13:16.994334 | debian-bookworm | Distro: Debian 12.11 2025-06-01 15:13:16.994359 | debian-bookworm | Provider: regiocloud-a 2025-06-01 15:13:16.994380 | debian-bookworm | Region: 2025-06-01 15:13:16.994401 | debian-bookworm | Label: debian-bookworm 2025-06-01 15:13:16.994421 | debian-bookworm | Product Name: OpenStack Nova 2025-06-01 15:13:16.994440 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4f:eb1 2025-06-01 15:13:17.012480 | 2025-06-01 15:13:17.012611 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-06-01 15:13:18.108474 | debian-bookworm -> localhost | changed 2025-06-01 15:13:18.115458 | 2025-06-01 15:13:18.115567 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-06-01 15:13:20.451794 | debian-bookworm -> localhost | changed 2025-06-01 15:13:20.462387 | 2025-06-01 15:13:20.462501 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-06-01 15:13:20.990823 | debian-bookworm -> localhost | ok 2025-06-01 15:13:20.997406 | 2025-06-01 15:13:20.997522 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-06-01 15:13:21.025642 | debian-bookworm | ok 2025-06-01 15:13:21.062888 | debian-bookworm | included: /var/lib/zuul/builds/26065ef6eca0447ebe20bca76652ca94/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-06-01 15:13:21.070494 | 2025-06-01 15:13:21.070593 | TASK [add-build-sshkey : Create Temp SSH key] 2025-06-01 15:13:23.441048 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-06-01 15:13:23.441260 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/26065ef6eca0447ebe20bca76652ca94/work/26065ef6eca0447ebe20bca76652ca94_id_rsa 2025-06-01 15:13:23.441300 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/26065ef6eca0447ebe20bca76652ca94/work/26065ef6eca0447ebe20bca76652ca94_id_rsa.pub 2025-06-01 15:13:23.441326 | debian-bookworm -> localhost | The key fingerprint is: 2025-06-01 15:13:23.441353 | debian-bookworm -> localhost | SHA256:IuOrZcGBLqH5lt7jr6vdjJTnNl3KeB6TNJBZfMYgd2M zuul-build-sshkey 2025-06-01 15:13:23.441376 | debian-bookworm -> localhost | The key's randomart image is: 2025-06-01 15:13:23.441412 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-06-01 15:13:23.441434 | debian-bookworm -> localhost | | ..+oE | 2025-06-01 15:13:23.441455 | debian-bookworm -> localhost | | . *.o+. | 2025-06-01 15:13:23.441475 | debian-bookworm -> localhost | |. . . + o | 2025-06-01 15:13:23.441495 | debian-bookworm -> localhost | |.+ . . . | 2025-06-01 15:13:23.441515 | debian-bookworm -> localhost | |+ . = . S | 2025-06-01 15:13:23.441539 | debian-bookworm -> localhost | | o o = o o. | 2025-06-01 15:13:23.441559 | debian-bookworm -> localhost | | + * .++o | 2025-06-01 15:13:23.441580 | debian-bookworm -> localhost | | o *.B+ =o | 2025-06-01 15:13:23.441601 | debian-bookworm -> localhost | | ++O*=+. | 2025-06-01 15:13:23.441634 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-06-01 15:13:23.441685 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.695460 2025-06-01 15:13:23.447937 | 2025-06-01 15:13:23.448036 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-06-01 15:13:23.487694 | debian-bookworm | ok 2025-06-01 15:13:23.504749 | debian-bookworm | included: /var/lib/zuul/builds/26065ef6eca0447ebe20bca76652ca94/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-06-01 15:13:23.520801 | 2025-06-01 15:13:23.520916 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-06-01 15:13:23.554259 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:13:23.560187 | 2025-06-01 15:13:23.560286 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-06-01 15:13:24.325755 | debian-bookworm | changed 2025-06-01 15:13:24.334564 | 2025-06-01 15:13:24.334716 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-06-01 15:13:24.611282 | debian-bookworm | ok 2025-06-01 15:13:24.621275 | 2025-06-01 15:13:24.621380 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-06-01 15:13:25.319276 | debian-bookworm | changed 2025-06-01 15:13:25.329162 | 2025-06-01 15:13:25.329297 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-06-01 15:13:25.925950 | debian-bookworm | changed 2025-06-01 15:13:25.931326 | 2025-06-01 15:13:25.931417 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-06-01 15:13:25.972817 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:13:25.979226 | 2025-06-01 15:13:25.979331 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-06-01 15:13:26.523259 | debian-bookworm -> localhost | changed 2025-06-01 15:13:26.538453 | 2025-06-01 15:13:26.538568 | TASK [add-build-sshkey : Add back temp key] 2025-06-01 15:13:26.898167 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/26065ef6eca0447ebe20bca76652ca94/work/26065ef6eca0447ebe20bca76652ca94_id_rsa (zuul-build-sshkey) 2025-06-01 15:13:26.898390 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010687 2025-06-01 15:13:26.906739 | 2025-06-01 15:13:26.906850 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-06-01 15:13:27.336990 | debian-bookworm | ok 2025-06-01 15:13:27.343253 | 2025-06-01 15:13:27.343350 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-06-01 15:13:27.399943 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:13:27.423220 | 2025-06-01 15:13:27.423345 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-06-01 15:13:27.919889 | debian-bookworm | ok 2025-06-01 15:13:27.941348 | 2025-06-01 15:13:27.941474 | TASK [validate-host : Define zuul_info_dir fact] 2025-06-01 15:13:27.990712 | debian-bookworm | ok 2025-06-01 15:13:27.995594 | 2025-06-01 15:13:27.995698 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-06-01 15:13:28.634274 | debian-bookworm -> localhost | ok 2025-06-01 15:13:28.642316 | 2025-06-01 15:13:28.642415 | TASK [validate-host : Collect information about the host] 2025-06-01 15:13:29.843698 | debian-bookworm | ok 2025-06-01 15:13:29.868726 | 2025-06-01 15:13:29.868854 | TASK [validate-host : Sanitize hostname] 2025-06-01 15:13:29.981297 | debian-bookworm | ok 2025-06-01 15:13:30.105915 | 2025-06-01 15:13:30.106091 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-06-01 15:13:30.950091 | debian-bookworm -> localhost | changed 2025-06-01 15:13:30.955685 | 2025-06-01 15:13:30.955784 | TASK [validate-host : Collect information about zuul worker] 2025-06-01 15:13:31.359101 | debian-bookworm | ok 2025-06-01 15:13:31.367936 | 2025-06-01 15:13:31.368047 | TASK [validate-host : Write out all zuul information for each host] 2025-06-01 15:13:32.259148 | debian-bookworm -> localhost | changed 2025-06-01 15:13:32.267523 | 2025-06-01 15:13:32.267634 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-06-01 15:13:32.578101 | debian-bookworm | ok 2025-06-01 15:13:32.582700 | 2025-06-01 15:13:32.582782 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-06-01 15:13:33.536157 | debian-bookworm | changed: 2025-06-01 15:13:33.536344 | debian-bookworm | cd+++++++++ src/ 2025-06-01 15:13:33.536379 | debian-bookworm | cd+++++++++ src/github.com/ 2025-06-01 15:13:33.536404 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-06-01 15:13:33.536426 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-generics/ 2025-06-01 15:13:33.536447 | debian-bookworm | =5.5.1 2025-06-01 15:13:56.063890 | debian-bookworm | Downloading cachetools-6.0.0-py3-none-any.whl (10 kB) 2025-06-01 15:13:56.096293 | debian-bookworm | Collecting chardet>=5.2 2025-06-01 15:13:56.104774 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-06-01 15:13:56.120370 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 15.3 MB/s eta 0:00:00 2025-06-01 15:13:56.165920 | debian-bookworm | Collecting colorama>=0.4.6 2025-06-01 15:13:56.174540 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-06-01 15:13:56.226914 | debian-bookworm | Collecting filelock>=3.16.1 2025-06-01 15:13:56.236283 | debian-bookworm | Downloading filelock-3.18.0-py3-none-any.whl (16 kB) 2025-06-01 15:13:56.286326 | debian-bookworm | Collecting packaging>=24.2 2025-06-01 15:13:56.295305 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-06-01 15:13:56.303932 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.3 MB/s eta 0:00:00 2025-06-01 15:13:56.343994 | debian-bookworm | Collecting platformdirs>=4.3.6 2025-06-01 15:13:56.351316 | debian-bookworm | Downloading platformdirs-4.3.8-py3-none-any.whl (18 kB) 2025-06-01 15:13:56.385979 | debian-bookworm | Collecting pluggy>=1.5 2025-06-01 15:13:56.393394 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-06-01 15:13:56.426255 | debian-bookworm | Collecting pyproject-api>=1.8 2025-06-01 15:13:56.435715 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-06-01 15:13:56.551534 | debian-bookworm | Collecting virtualenv>=20.31 2025-06-01 15:13:56.559567 | debian-bookworm | Downloading virtualenv-20.31.2-py3-none-any.whl (6.1 MB) 2025-06-01 15:13:56.819224 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.1/6.1 MB 23.6 MB/s eta 0:00:00 2025-06-01 15:13:56.928404 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-06-01 15:13:56.935812 | debian-bookworm | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-06-01 15:13:56.963210 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 21.4 MB/s eta 0:00:00 2025-06-01 15:13:57.120928 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-06-01 15:13:57.896831 | debian-bookworm | Successfully installed cachetools-6.0.0 chardet-5.2.0 colorama-0.4.6 distlib-0.3.9 filelock-3.18.0 packaging-25.0 platformdirs-4.3.8 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.26.0 virtualenv-20.31.2 2025-06-01 15:13:58.090128 | debian-bookworm | ok: Runtime: 0:00:02.680140 2025-06-01 15:13:58.098683 | 2025-06-01 15:13:58.099138 | TASK [ensure-tox : Export installed tox_executable path] 2025-06-01 15:13:58.281052 | debian-bookworm | ok 2025-06-01 15:13:58.293276 | 2025-06-01 15:13:58.293447 | TASK [ensure-tox : Output tox version] 2025-06-01 15:13:58.941617 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-06-01 15:13:58.959969 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-06-01 15:13:59.377292 | debian-bookworm | ok: Runtime: 0:00:00.312083 2025-06-01 15:13:59.387170 | 2025-06-01 15:13:59.387465 | TASK [ensure-tox : Make global symlink] 2025-06-01 15:13:59.450683 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:13:59.462307 | 2025-06-01 15:13:59.462492 | PLAY RECAP 2025-06-01 15:13:59.462556 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-06-01 15:13:59.462715 | 2025-06-01 15:13:59.949080 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-06-01 15:13:59.950980 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-06-01 15:14:02.085543 | 2025-06-01 15:14:02.085720 | PLAY [all] 2025-06-01 15:14:02.106013 | 2025-06-01 15:14:02.106144 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-06-01 15:14:02.731819 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-06-01 15:14:03.134115 | debian-bookworm | ok: Runtime: 0:00:00.030166 2025-06-01 15:14:03.140239 | 2025-06-01 15:14:03.140446 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-06-01 15:14:03.676255 | debian-bookworm | ok: Runtime: 0:00:00.007608 2025-06-01 15:14:03.682043 | 2025-06-01 15:14:03.682146 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-06-01 15:14:03.980688 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-06-01 15:14:03.984843 | debian-bookworm | sudo: a password is required 2025-06-01 15:14:04.228123 | debian-bookworm | ok: Runtime: 0:00:00.025469 2025-06-01 15:14:04.242486 | 2025-06-01 15:14:04.242594 | TASK [tox : Check to see if the constraints file exists] 2025-06-01 15:14:04.271557 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:04.280177 | 2025-06-01 15:14:04.280328 | TASK [tox : Fail if constraints file is missing] 2025-06-01 15:14:04.315166 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:04.323355 | 2025-06-01 15:14:04.323457 | TASK [tox : Record file location] 2025-06-01 15:14:04.351497 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:04.357085 | 2025-06-01 15:14:04.357181 | TASK [tox : Install tox siblings] 2025-06-01 15:14:04.376633 | debian-bookworm | ok 2025-06-01 15:14:04.384588 | debian-bookworm | included: /var/lib/zuul/builds/26065ef6eca0447ebe20bca76652ca94/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-06-01 15:14:04.390171 | 2025-06-01 15:14:04.390256 | TASK [tox : Run tox without tests] 2025-06-01 15:14:04.842527 | debian-bookworm | ROOT: 147 D setup logging to DEBUG on pid 1419 [tox/report.py:222] 2025-06-01 15:14:04.922267 | debian-bookworm | zuul: 227 D created app data folder /home/zuul/.local/share/virtualenv [virtualenv/app_data/__init__.py:42] 2025-06-01 15:14:04.925268 | debian-bookworm | zuul: 230 I find interpreter for spec PythonSpec(path=/home/zuul/.local/tox/bin/python3) [virtualenv/discovery/builtin.py:76] 2025-06-01 15:14:04.925772 | debian-bookworm | zuul: 231 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-06-01 15:14:04.926657 | debian-bookworm | zuul: 232 D get interpreter info via cmd: /usr/bin/python3.11 /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/discovery/py_info.py u8EecIVanKFYfn9dbsGsdvrYaDnRg9LC 4kO7dEyX34qygQWAr5DG4TRLKCXj8ENW [virtualenv/discovery/cached_py_info.py:117] 2025-06-01 15:14:04.998072 | debian-bookworm | zuul: 303 D wrote python info of /usr/bin/python3.11 at /home/zuul/.local/share/virtualenv/py_info/2/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:152] 2025-06-01 15:14:04.998340 | debian-bookworm | zuul: 303 I proposed PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:83] 2025-06-01 15:14:04.998507 | debian-bookworm | zuul: 304 D accepted PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:85] 2025-06-01 15:14:05.030597 | debian-bookworm | zuul: 335 I create virtual environment via CPython3Posix(dest=/home/zuul/src/github.com/osism/cfg-generics/.tox/zuul, clear=False, no_vcs_ignore=False, global=False) [virtualenv/run/session.py:52] 2025-06-01 15:14:05.030943 | debian-bookworm | zuul: 336 D create folder /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/bin [virtualenv/util/path/_sync.py:14] 2025-06-01 15:14:05.031153 | debian-bookworm | zuul: 336 D create folder /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages [virtualenv/util/path/_sync.py:14] 2025-06-01 15:14:05.031492 | debian-bookworm | zuul: 336 D write /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-06-01 15:14:05.031592 | debian-bookworm | zuul: 337 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.031677 | debian-bookworm | zuul: 337 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.031760 | debian-bookworm | zuul: 337 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.031818 | debian-bookworm | zuul: 337 D virtualenv = 20.31.2 [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.031900 | debian-bookworm | zuul: 337 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.031977 | debian-bookworm | zuul: 337 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.032093 | debian-bookworm | zuul: 337 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.032204 | debian-bookworm | zuul: 337 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.032612 | debian-bookworm | zuul: 338 D symlink /usr/bin/python3.11 to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/bin/python [virtualenv/util/path/_sync.py:34] 2025-06-01 15:14:05.033026 | debian-bookworm | zuul: 338 D create virtualenv import hook file /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/_virtualenv.pth [virtualenv/create/via_global_ref/api.py:93] 2025-06-01 15:14:05.033206 | debian-bookworm | zuul: 338 D create /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/_virtualenv.py [virtualenv/create/via_global_ref/api.py:96] 2025-06-01 15:14:05.033697 | debian-bookworm | zuul: 339 D ============================== target debug ============================== [virtualenv/run/session.py:54] 2025-06-01 15:14:05.033980 | debian-bookworm | zuul: 339 D debug via /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/bin/python /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/create/debug.py [virtualenv/create/creator.py:215] 2025-06-01 15:14:05.069661 | debian-bookworm | zuul: 339 D { 2025-06-01 15:14:05.069724 | debian-bookworm | "sys": { 2025-06-01 15:14:05.069730 | debian-bookworm | "executable": "/home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/bin/python", 2025-06-01 15:14:05.069736 | debian-bookworm | "_base_executable": "/usr/bin/python3.11", 2025-06-01 15:14:05.069742 | debian-bookworm | "prefix": "/home/zuul/src/github.com/osism/cfg-generics/.tox/zuul", 2025-06-01 15:14:05.069748 | debian-bookworm | "base_prefix": "/usr", 2025-06-01 15:14:05.069754 | debian-bookworm | "real_prefix": null, 2025-06-01 15:14:05.069758 | debian-bookworm | "exec_prefix": "/home/zuul/src/github.com/osism/cfg-generics/.tox/zuul", 2025-06-01 15:14:05.069765 | debian-bookworm | "base_exec_prefix": "/usr", 2025-06-01 15:14:05.069769 | debian-bookworm | "path": [ 2025-06-01 15:14:05.069775 | debian-bookworm | "/usr/lib/python311.zip", 2025-06-01 15:14:05.069779 | debian-bookworm | "/usr/lib/python3.11", 2025-06-01 15:14:05.069783 | debian-bookworm | "/usr/lib/python3.11/lib-dynload", 2025-06-01 15:14:05.069787 | debian-bookworm | "/home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages" 2025-06-01 15:14:05.069791 | debian-bookworm | ], 2025-06-01 15:14:05.069795 | debian-bookworm | "meta_path": [ 2025-06-01 15:14:05.069800 | debian-bookworm | "", 2025-06-01 15:14:05.069804 | debian-bookworm | "", 2025-06-01 15:14:05.069809 | debian-bookworm | "", 2025-06-01 15:14:05.069814 | debian-bookworm | "" 2025-06-01 15:14:05.069817 | debian-bookworm | ], 2025-06-01 15:14:05.069821 | debian-bookworm | "fs_encoding": "utf-8", 2025-06-01 15:14:05.069825 | debian-bookworm | "io_encoding": "utf-8" 2025-06-01 15:14:05.069829 | debian-bookworm | }, 2025-06-01 15:14:05.069834 | debian-bookworm | "version": "3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]", 2025-06-01 15:14:05.069838 | debian-bookworm | "makefile_filename": "/usr/lib/python3.11/config-3.11-x86_64-linux-gnu/Makefile", 2025-06-01 15:14:05.069841 | debian-bookworm | "os": "", 2025-06-01 15:14:05.069846 | debian-bookworm | "site": "", 2025-06-01 15:14:05.069850 | debian-bookworm | "datetime": "", 2025-06-01 15:14:05.069854 | debian-bookworm | "math": "", 2025-06-01 15:14:05.069858 | debian-bookworm | "json": "" 2025-06-01 15:14:05.069862 | debian-bookworm | } [virtualenv/run/session.py:55] 2025-06-01 15:14:05.069909 | debian-bookworm | zuul: 375 I add seed packages via FromAppData(download=False, pip=bundle, setuptools=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) [virtualenv/run/session.py:59] 2025-06-01 15:14:05.071611 | debian-bookworm | zuul: 377 D install pip from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/pip-25.1.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-06-01 15:14:05.072031 | debian-bookworm | zuul: 377 D install setuptools from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/setuptools-80.3.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-06-01 15:14:05.073301 | debian-bookworm | zuul: 378 D build install image for pip-25.1.1-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-06-01 15:14:05.073559 | debian-bookworm | zuul: 379 D build install image for setuptools-80.3.1-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-06-01 15:14:05.286359 | debian-bookworm | zuul: 591 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/pkg_resources to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/pkg_resources [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.292935 | debian-bookworm | zuul: 598 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/distutils-precedence.pth to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/distutils-precedence.pth [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.293299 | debian-bookworm | zuul: 598 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/setuptools to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/setuptools [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.333046 | debian-bookworm | zuul: 638 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/pip [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.496793 | debian-bookworm | zuul: 802 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/_distutils_hack to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/_distutils_hack [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.498129 | debian-bookworm | zuul: 803 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/setuptools-80.3.1.dist-info to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/setuptools-80.3.1.dist-info [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.500356 | debian-bookworm | zuul: 805 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/setuptools-80.3.1.virtualenv to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/setuptools-80.3.1.virtualenv [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.500656 | debian-bookworm | zuul: 806 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-06-01 15:14:05.502798 | debian-bookworm | zuul: 808 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip-25.1.1.dist-info to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/pip-25.1.1.dist-info [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.503941 | debian-bookworm | zuul: 809 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip-25.1.1.virtualenv to /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/lib/python3.11/site-packages/pip-25.1.1.virtualenv [virtualenv/util/path/_sync.py:42] 2025-06-01 15:14:05.504715 | debian-bookworm | zuul: 810 D generated console scripts pip3.11 pip3 pip pip-3.11 [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-06-01 15:14:05.505150 | debian-bookworm | zuul: 810 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python [virtualenv/run/session.py:65] 2025-06-01 15:14:05.511124 | debian-bookworm | zuul: 816 D write /home/zuul/src/github.com/osism/cfg-generics/.tox/zuul/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-06-01 15:14:05.511199 | debian-bookworm | zuul: 816 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.511256 | debian-bookworm | zuul: 816 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.511298 | debian-bookworm | zuul: 816 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.511357 | debian-bookworm | zuul: 816 D virtualenv = 20.31.2 [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.511390 | debian-bookworm | zuul: 817 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.511469 | debian-bookworm | zuul: 817 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.511515 | debian-bookworm | zuul: 817 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.511584 | debian-bookworm | zuul: 817 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-06-01 15:14:05.513521 | debian-bookworm | zuul: OK (0.59 seconds) 2025-06-01 15:14:05.513542 | debian-bookworm | congratulations :) (0.67 seconds) 2025-06-01 15:14:05.920301 | debian-bookworm | ok: Runtime: 0:00:00.913219 2025-06-01 15:14:05.935872 | 2025-06-01 15:14:05.936061 | TASK [tox : Create a tempfile to save tox showconfig] 2025-06-01 15:14:06.363979 | debian-bookworm | changed 2025-06-01 15:14:06.373263 | 2025-06-01 15:14:06.373443 | TASK [tox : Get tox envlist config] 2025-06-01 15:14:07.409369 | debian-bookworm | ok: Runtime: 0:00:00.346698 2025-06-01 15:14:07.420639 | 2025-06-01 15:14:07.420814 | TASK [tox : Install any sibling python packages] 2025-06-01 15:14:07.877890 | debian-bookworm | ok: No setup.cfg, no action needed 2025-06-01 15:14:07.883946 | 2025-06-01 15:14:07.884071 | TASK [tox : Remove tempfile] 2025-06-01 15:14:08.376524 | debian-bookworm | changed 2025-06-01 15:14:08.384504 | 2025-06-01 15:14:08.384690 | TASK [tox : Emit tox command] 2025-06-01 15:14:08.442159 | /home/zuul/.local/tox/bin/tox -ezuul -vv --skip-missing-interpreters=false 2025-06-01 15:14:08.450743 | 2025-06-01 15:14:08.450977 | TASK [tox : Run tox] 2025-06-01 15:14:08.995383 | debian-bookworm | ROOT: 143 D setup logging to DEBUG on pid 1452 [tox/report.py:222] 2025-06-01 15:14:09.074249 | debian-bookworm | zuul: 222 I find interpreter for spec PythonSpec(path=/home/zuul/.local/tox/bin/python3) [virtualenv/discovery/builtin.py:76] 2025-06-01 15:14:09.074834 | debian-bookworm | zuul: 223 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-06-01 15:14:09.075732 | debian-bookworm | zuul: 224 D got python info of /usr/bin/python3.11 from /home/zuul/.local/share/virtualenv/py_info/2/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:132] 2025-06-01 15:14:09.076408 | debian-bookworm | zuul: 225 I proposed PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:83] 2025-06-01 15:14:09.076496 | debian-bookworm | zuul: 225 D accepted PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:85] 2025-06-01 15:14:09.108111 | debian-bookworm | zuul: 256 W commands[0]> python3 src/render-images.py [tox/tox_env/api.py:435] 2025-06-01 15:14:09.132501 | debian-bookworm | Traceback (most recent call last): 2025-06-01 15:14:09.132571 | debian-bookworm | File "/home/zuul/src/github.com/osism/cfg-generics/src/render-images.py", line 3, in 2025-06-01 15:14:09.132968 | debian-bookworm | import jinja2 2025-06-01 15:14:09.132984 | debian-bookworm | ModuleNotFoundError: No module named 'jinja2' 2025-06-01 15:14:09.139333 | debian-bookworm | zuul: 288 C exit 1 (0.03 seconds) /home/zuul/src/github.com/osism/cfg-generics> python3 src/render-images.py pid=1459 [tox/execute/api.py:294] 2025-06-01 15:14:09.144119 | debian-bookworm | zuul: FAIL code 1 (0.07=setup[0.04]+cmd[0.03] seconds) 2025-06-01 15:14:09.144166 | debian-bookworm | evaluation failed :( (0.15 seconds) 2025-06-01 15:14:09.538761 | debian-bookworm | ERROR 2025-06-01 15:14:09.539302 | debian-bookworm | { 2025-06-01 15:14:09.539410 | debian-bookworm | "delta": "0:00:00.396494", 2025-06-01 15:14:09.539480 | debian-bookworm | "end": "2025-06-01 15:14:09.200746", 2025-06-01 15:14:09.539537 | debian-bookworm | "msg": "non-zero return code", 2025-06-01 15:14:09.539614 | debian-bookworm | "rc": 1, 2025-06-01 15:14:09.539669 | debian-bookworm | "start": "2025-06-01 15:14:08.804252" 2025-06-01 15:14:09.539719 | debian-bookworm | } failure 2025-06-01 15:14:09.559768 | 2025-06-01 15:14:09.559956 | TASK [tox : Look for output] 2025-06-01 15:14:09.988473 | debian-bookworm | ok 2025-06-01 15:14:10.025631 | 2025-06-01 15:14:10.025955 | TASK [tox : Return file comments to Zuul] 2025-06-01 15:14:10.066543 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:10.070742 | 2025-06-01 15:14:10.070968 | PLAY RECAP 2025-06-01 15:14:10.071114 | debian-bookworm | ok: 11 changed: 7 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0 2025-06-01 15:14:10.071176 | 2025-06-01 15:14:10.248490 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-06-01 15:14:10.249696 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-06-01 15:14:11.039231 | 2025-06-01 15:14:11.039443 | PLAY [all] 2025-06-01 15:14:11.056371 | 2025-06-01 15:14:11.056546 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-06-01 15:14:11.102235 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:11.109091 | 2025-06-01 15:14:11.109300 | TASK [fetch-tox-output : Set tox log path for single node] 2025-06-01 15:14:11.149489 | debian-bookworm | ok 2025-06-01 15:14:11.154880 | 2025-06-01 15:14:11.155010 | TASK [fetch-tox-output : Ensure local tox dir] 2025-06-01 15:14:11.645724 | debian-bookworm -> localhost | changed 2025-06-01 15:14:11.653914 | 2025-06-01 15:14:11.654036 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-06-01 15:14:11.700239 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:11.714465 | 2025-06-01 15:14:11.714708 | TASK [fetch-tox-output : Set envlist fact] 2025-06-01 15:14:11.755993 | debian-bookworm | ok 2025-06-01 15:14:11.761992 | 2025-06-01 15:14:11.762106 | TASK [fetch-tox-output : Get tox version] 2025-06-01 15:14:12.494118 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-06-01 15:14:12.512678 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-06-01 15:14:12.793698 | debian-bookworm | ok: Runtime: 0:00:00.298504 2025-06-01 15:14:12.808866 | 2025-06-01 15:14:12.809089 | TASK [fetch-tox-output : Find all default environments] 2025-06-01 15:14:13.343431 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:13.356352 | 2025-06-01 15:14:13.356549 | TASK [fetch-tox-output : Set envlist fact] 2025-06-01 15:14:13.392420 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:13.405521 | 2025-06-01 15:14:13.405741 | TASK [fetch-tox-output : Find all default environments] 2025-06-01 15:14:13.940081 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:13.954866 | 2025-06-01 15:14:13.955075 | TASK [fetch-tox-output : Set envlist fact] 2025-06-01 15:14:13.991780 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:14.012014 | 2025-06-01 15:14:14.012259 | LOOP [fetch-tox-output : Copy tox logs] 2025-06-01 15:14:14.073998 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:14.092834 | 2025-06-01 15:14:14.093027 | LOOP [fetch-tox-output : Collect tox logs] 2025-06-01 15:14:14.945609 | debian-bookworm | changed: 2025-06-01 15:14:14.945962 | debian-bookworm | created directory /var/lib/zuul/builds/26065ef6eca0447ebe20bca76652ca94/work/logs/tox/zuul 2025-06-01 15:14:14.946107 | debian-bookworm | cd+++++++++ ./ 2025-06-01 15:14:14.946161 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-06-01 15:14:14.946213 | 2025-06-01 15:14:14.963527 | 2025-06-01 15:14:14.963678 | PLAY RECAP 2025-06-01 15:14:14.963737 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-06-01 15:14:14.963763 | 2025-06-01 15:14:15.126392 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-06-01 15:14:15.127798 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-06-01 15:14:15.893295 | 2025-06-01 15:14:15.893545 | PLAY [all] 2025-06-01 15:14:15.911711 | 2025-06-01 15:14:15.911875 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-06-01 15:14:16.160879 | debian-bookworm | changed: non-zero return code 2025-06-01 15:14:16.169070 | 2025-06-01 15:14:16.169274 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-06-01 15:14:16.195013 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:16.205875 | 2025-06-01 15:14:16.206063 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-06-01 15:14:16.239421 | 2025-06-01 15:14:16.239693 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-06-01 15:14:16.271143 | 2025-06-01 15:14:16.271341 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-06-01 15:14:16.295272 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:16.307238 | 2025-06-01 15:14:16.307444 | LOOP [fetch-subunit-output : Generate subunit file] 2025-06-01 15:14:16.338789 | 2025-06-01 15:14:16.339038 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-06-01 15:14:16.362863 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:16.369132 | 2025-06-01 15:14:16.369261 | TASK [fetch-subunit-output : Remove the temporary file] 2025-06-01 15:14:16.394323 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:16.408392 | 2025-06-01 15:14:16.408598 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-06-01 15:14:16.434232 | debian-bookworm | skipping: Conditional result was False 2025-06-01 15:14:16.448334 | 2025-06-01 15:14:16.448465 | PLAY RECAP 2025-06-01 15:14:16.448546 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-06-01 15:14:16.448611 | 2025-06-01 15:14:16.597219 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-06-01 15:14:16.598685 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-06-01 15:14:17.362623 | 2025-06-01 15:14:17.362824 | PLAY [Base post] 2025-06-01 15:14:17.378432 | 2025-06-01 15:14:17.378648 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-06-01 15:14:17.992145 | debian-bookworm | changed 2025-06-01 15:14:18.009246 | 2025-06-01 15:14:18.009408 | PLAY RECAP 2025-06-01 15:14:18.009491 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-06-01 15:14:18.009599 | 2025-06-01 15:14:18.161660 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-06-01 15:14:18.163095 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-06-01 15:14:19.016344 | 2025-06-01 15:14:19.016559 | PLAY [Base post-logs] 2025-06-01 15:14:19.029038 | 2025-06-01 15:14:19.029199 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-06-01 15:14:19.514646 | localhost | changed 2025-06-01 15:14:19.532427 | 2025-06-01 15:14:19.532667 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-06-01 15:14:19.561086 | localhost | ok 2025-06-01 15:14:19.569457 | 2025-06-01 15:14:19.569675 | TASK [Set zuul-log-path fact] 2025-06-01 15:14:19.587628 | localhost | ok 2025-06-01 15:14:19.599014 | 2025-06-01 15:14:19.599155 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-01 15:14:19.627044 | localhost | ok 2025-06-01 15:14:19.631501 | 2025-06-01 15:14:19.631673 | TASK [upload-logs : Create log directories] 2025-06-01 15:14:20.176438 | localhost | changed 2025-06-01 15:14:20.179909 | 2025-06-01 15:14:20.180039 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-06-01 15:14:20.706761 | localhost -> localhost | ok: Runtime: 0:00:00.005448 2025-06-01 15:14:20.711495 | 2025-06-01 15:14:20.711651 | TASK [upload-logs : Upload logs to log server] 2025-06-01 15:14:21.280371 | localhost | Output suppressed because no_log was given 2025-06-01 15:14:21.283053 | 2025-06-01 15:14:21.283173 | LOOP [upload-logs : Compress console log and json output] 2025-06-01 15:14:21.351502 | localhost | skipping: Conditional result was False 2025-06-01 15:14:21.365138 | localhost | skipping: Conditional result was False 2025-06-01 15:14:21.371138 | 2025-06-01 15:14:21.371368 | LOOP [upload-logs : Upload compressed console log and json output] 2025-06-01 15:14:21.422493 | localhost | skipping: Conditional result was False 2025-06-01 15:14:21.422808 | 2025-06-01 15:14:21.427671 | localhost | skipping: Conditional result was False 2025-06-01 15:14:21.432100 | 2025-06-01 15:14:21.432274 | LOOP [upload-logs : Upload console log and json output]