2025-05-26 19:07:20.302294 | Job console starting 2025-05-26 19:07:20.339431 | Updating git repos 2025-05-26 19:07:20.426176 | Cloning repos into workspace 2025-05-26 19:07:20.504813 | Restoring repo states 2025-05-26 19:07:20.517977 | Merging changes 2025-05-26 19:07:21.167185 | Checking out repos 2025-05-26 19:07:21.254503 | Preparing playbooks 2025-05-26 19:07:22.680102 | Running Ansible setup 2025-05-26 19:07:30.299287 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-05-26 19:07:32.151430 | 2025-05-26 19:07:32.151609 | PLAY [Base pre] 2025-05-26 19:07:32.168764 | 2025-05-26 19:07:32.168973 | TASK [Setup log path fact] 2025-05-26 19:07:32.196031 | debian-bookworm | ok 2025-05-26 19:07:32.224716 | 2025-05-26 19:07:32.224905 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-26 19:07:32.266048 | debian-bookworm | ok 2025-05-26 19:07:32.276538 | 2025-05-26 19:07:32.276685 | TASK [emit-job-header : Print job information] 2025-05-26 19:07:32.351094 | # Job Information 2025-05-26 19:07:32.351298 | Ansible Version: 2.16.14 2025-05-26 19:07:32.351334 | Job: tox 2025-05-26 19:07:32.351367 | Pipeline: check 2025-05-26 19:07:32.351391 | Executor: 521e9411259a 2025-05-26 19:07:32.351413 | Triggered by: https://github.com/osism/openstack-image-manager/pull/933 2025-05-26 19:07:32.351435 | Event ID: a02427b0-3a64-11f0-80c6-12b06dc22d7d 2025-05-26 19:07:32.372039 | 2025-05-26 19:07:32.372201 | LOOP [emit-job-header : Print node information] 2025-05-26 19:07:32.599028 | debian-bookworm | ok: 2025-05-26 19:07:32.599396 | debian-bookworm | # Node Information 2025-05-26 19:07:32.599439 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-05-26 19:07:32.599467 | debian-bookworm | Hostname: debian 2025-05-26 19:07:32.599490 | debian-bookworm | Username: zuul 2025-05-26 19:07:32.599511 | debian-bookworm | Distro: Debian 12.11 2025-05-26 19:07:32.599541 | debian-bookworm | Provider: regiocloud-a 2025-05-26 19:07:32.599567 | debian-bookworm | Region: 2025-05-26 19:07:32.599589 | debian-bookworm | Label: debian-bookworm 2025-05-26 19:07:32.599609 | debian-bookworm | Product Name: OpenStack Nova 2025-05-26 19:07:32.599628 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe26:e5aa 2025-05-26 19:07:32.620605 | 2025-05-26 19:07:32.620753 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-05-26 19:07:33.668412 | debian-bookworm -> localhost | changed 2025-05-26 19:07:33.676260 | 2025-05-26 19:07:33.676398 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-05-26 19:07:35.481051 | debian-bookworm -> localhost | changed 2025-05-26 19:07:35.498744 | 2025-05-26 19:07:35.507036 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-05-26 19:07:36.017307 | debian-bookworm -> localhost | ok 2025-05-26 19:07:36.024075 | 2025-05-26 19:07:36.024195 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-05-26 19:07:36.055164 | debian-bookworm | ok 2025-05-26 19:07:36.102734 | debian-bookworm | included: /var/lib/zuul/builds/2c8609ef52ce4ae08f4afea6fdff9699/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-05-26 19:07:36.119628 | 2025-05-26 19:07:36.119775 | TASK [add-build-sshkey : Create Temp SSH key] 2025-05-26 19:07:37.713972 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-05-26 19:07:37.714269 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2c8609ef52ce4ae08f4afea6fdff9699/work/2c8609ef52ce4ae08f4afea6fdff9699_id_rsa 2025-05-26 19:07:37.714313 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2c8609ef52ce4ae08f4afea6fdff9699/work/2c8609ef52ce4ae08f4afea6fdff9699_id_rsa.pub 2025-05-26 19:07:37.714340 | debian-bookworm -> localhost | The key fingerprint is: 2025-05-26 19:07:37.714368 | debian-bookworm -> localhost | SHA256:tBWYLqx5ixPLC1xhve+TIgpV9v7C8SZG0H0/JTCxooo zuul-build-sshkey 2025-05-26 19:07:37.714392 | debian-bookworm -> localhost | The key's randomart image is: 2025-05-26 19:07:37.714425 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-05-26 19:07:37.714448 | debian-bookworm -> localhost | | oo. | 2025-05-26 19:07:37.714470 | debian-bookworm -> localhost | | . o oo | 2025-05-26 19:07:37.714491 | debian-bookworm -> localhost | | =o.oo oo | 2025-05-26 19:07:37.714512 | debian-bookworm -> localhost | | +.o++o+. . . | 2025-05-26 19:07:37.714533 | debian-bookworm -> localhost | | . .++.S. . o | 2025-05-26 19:07:37.714564 | debian-bookworm -> localhost | | o o+o+. o | 2025-05-26 19:07:37.714585 | debian-bookworm -> localhost | |. E..B.+.. . | 2025-05-26 19:07:37.714606 | debian-bookworm -> localhost | | . .* Bo= | 2025-05-26 19:07:37.714629 | debian-bookworm -> localhost | | ...= =o. | 2025-05-26 19:07:37.714651 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-05-26 19:07:37.714711 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.924376 2025-05-26 19:07:37.721601 | 2025-05-26 19:07:37.721723 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-05-26 19:07:37.752228 | debian-bookworm | ok 2025-05-26 19:07:37.763505 | debian-bookworm | included: /var/lib/zuul/builds/2c8609ef52ce4ae08f4afea6fdff9699/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-05-26 19:07:37.773441 | 2025-05-26 19:07:37.773578 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-05-26 19:07:37.808641 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:07:37.815606 | 2025-05-26 19:07:37.815763 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-05-26 19:07:38.540268 | debian-bookworm | changed 2025-05-26 19:07:38.550422 | 2025-05-26 19:07:38.550558 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-05-26 19:07:38.820252 | debian-bookworm | ok 2025-05-26 19:07:38.833486 | 2025-05-26 19:07:38.833748 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-05-26 19:07:39.570276 | debian-bookworm | changed 2025-05-26 19:07:39.586448 | 2025-05-26 19:07:39.586594 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-05-26 19:07:40.242725 | debian-bookworm | changed 2025-05-26 19:07:40.254190 | 2025-05-26 19:07:40.254343 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-05-26 19:07:40.278750 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:07:40.285563 | 2025-05-26 19:07:40.285700 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-05-26 19:07:40.876873 | debian-bookworm -> localhost | changed 2025-05-26 19:07:40.889024 | 2025-05-26 19:07:40.889174 | TASK [add-build-sshkey : Add back temp key] 2025-05-26 19:07:41.391330 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2c8609ef52ce4ae08f4afea6fdff9699/work/2c8609ef52ce4ae08f4afea6fdff9699_id_rsa (zuul-build-sshkey) 2025-05-26 19:07:41.391609 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013847 2025-05-26 19:07:41.400409 | 2025-05-26 19:07:41.400556 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-05-26 19:07:42.031491 | debian-bookworm | ok 2025-05-26 19:07:42.037040 | 2025-05-26 19:07:42.037166 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-05-26 19:07:42.071804 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:07:42.093670 | 2025-05-26 19:07:42.093860 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-05-26 19:07:42.572551 | debian-bookworm | ok 2025-05-26 19:07:42.584982 | 2025-05-26 19:07:42.585116 | TASK [validate-host : Define zuul_info_dir fact] 2025-05-26 19:07:42.620470 | debian-bookworm | ok 2025-05-26 19:07:42.629873 | 2025-05-26 19:07:42.630024 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-05-26 19:07:43.207228 | debian-bookworm -> localhost | ok 2025-05-26 19:07:43.214523 | 2025-05-26 19:07:43.214655 | TASK [validate-host : Collect information about the host] 2025-05-26 19:07:44.375022 | debian-bookworm | ok 2025-05-26 19:07:44.408231 | 2025-05-26 19:07:44.408380 | TASK [validate-host : Sanitize hostname] 2025-05-26 19:07:44.547473 | debian-bookworm | ok 2025-05-26 19:07:44.639006 | 2025-05-26 19:07:44.639163 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-05-26 19:07:45.465648 | debian-bookworm -> localhost | changed 2025-05-26 19:07:45.472289 | 2025-05-26 19:07:45.472429 | TASK [validate-host : Collect information about zuul worker] 2025-05-26 19:07:46.057408 | debian-bookworm | ok 2025-05-26 19:07:46.062501 | 2025-05-26 19:07:46.062628 | TASK [validate-host : Write out all zuul information for each host] 2025-05-26 19:07:46.760162 | debian-bookworm -> localhost | changed 2025-05-26 19:07:46.788468 | 2025-05-26 19:07:46.788589 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-05-26 19:07:47.091326 | debian-bookworm | ok 2025-05-26 19:07:47.122124 | 2025-05-26 19:07:47.124374 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-05-26 19:07:48.335989 | debian-bookworm | changed: 2025-05-26 19:07:48.336165 | debian-bookworm | cd+++++++++ src/ 2025-05-26 19:07:48.336200 | debian-bookworm | cd+++++++++ src/github.com/ 2025-05-26 19:07:48.336224 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-05-26 19:07:48.336245 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-05-26 19:07:48.336265 | debian-bookworm | =5.5.1 2025-05-26 19:08:09.927687 | debian-bookworm | Downloading cachetools-6.0.0-py3-none-any.whl (10 kB) 2025-05-26 19:08:09.967988 | debian-bookworm | Collecting chardet>=5.2 2025-05-26 19:08:09.978046 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-05-26 19:08:09.993659 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 19.7 MB/s eta 0:00:00 2025-05-26 19:08:10.035299 | debian-bookworm | Collecting colorama>=0.4.6 2025-05-26 19:08:10.044986 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-05-26 19:08:10.092936 | debian-bookworm | Collecting filelock>=3.16.1 2025-05-26 19:08:10.101433 | debian-bookworm | Downloading filelock-3.18.0-py3-none-any.whl (16 kB) 2025-05-26 19:08:10.145985 | debian-bookworm | Collecting packaging>=24.2 2025-05-26 19:08:10.153685 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-05-26 19:08:10.163894 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.1 MB/s eta 0:00:00 2025-05-26 19:08:10.204302 | debian-bookworm | Collecting platformdirs>=4.3.6 2025-05-26 19:08:10.212248 | debian-bookworm | Downloading platformdirs-4.3.8-py3-none-any.whl (18 kB) 2025-05-26 19:08:10.253851 | debian-bookworm | Collecting pluggy>=1.5 2025-05-26 19:08:10.261790 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-05-26 19:08:10.301090 | debian-bookworm | Collecting pyproject-api>=1.8 2025-05-26 19:08:10.308773 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-05-26 19:08:10.426914 | debian-bookworm | Collecting virtualenv>=20.31 2025-05-26 19:08:10.435534 | debian-bookworm | Downloading virtualenv-20.31.2-py3-none-any.whl (6.1 MB) 2025-05-26 19:08:10.760881 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.1/6.1 MB 19.0 MB/s eta 0:00:00 2025-05-26 19:08:10.874413 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-05-26 19:08:10.883949 | debian-bookworm | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-05-26 19:08:10.917919 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 17.2 MB/s eta 0:00:00 2025-05-26 19:08:11.088055 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-05-26 19:08:11.891093 | 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-05-26 19:08:12.398270 | debian-bookworm | ok: Runtime: 0:00:02.850744 2025-05-26 19:08:12.414675 | 2025-05-26 19:08:12.415127 | TASK [ensure-tox : Export installed tox_executable path] 2025-05-26 19:08:12.457379 | debian-bookworm | ok 2025-05-26 19:08:12.473165 | 2025-05-26 19:08:12.473328 | TASK [ensure-tox : Output tox version] 2025-05-26 19:08:12.997575 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-26 19:08:13.015763 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-05-26 19:08:13.507860 | debian-bookworm | ok: Runtime: 0:00:00.308191 2025-05-26 19:08:13.524360 | 2025-05-26 19:08:13.524520 | TASK [ensure-tox : Make global symlink] 2025-05-26 19:08:13.549230 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:13.556255 | 2025-05-26 19:08:13.556349 | PLAY RECAP 2025-05-26 19:08:13.556404 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-05-26 19:08:13.556430 | 2025-05-26 19:08:13.722413 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-05-26 19:08:13.723682 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-26 19:08:14.747163 | 2025-05-26 19:08:14.747360 | PLAY [all] 2025-05-26 19:08:14.764818 | 2025-05-26 19:08:14.765070 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-05-26 19:08:15.298917 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-05-26 19:08:15.798363 | debian-bookworm | ok: Runtime: 0:00:00.027034 2025-05-26 19:08:15.809684 | 2025-05-26 19:08:15.810246 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-05-26 19:08:16.364070 | debian-bookworm | ok: Runtime: 0:00:00.006019 2025-05-26 19:08:16.382937 | 2025-05-26 19:08:16.383149 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-05-26 19:08:16.656854 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-05-26 19:08:16.658165 | debian-bookworm | sudo: a password is required 2025-05-26 19:08:16.918973 | debian-bookworm | ok: Runtime: 0:00:00.020477 2025-05-26 19:08:16.935915 | 2025-05-26 19:08:16.936102 | TASK [tox : Check to see if the constraints file exists] 2025-05-26 19:08:16.981716 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:16.991633 | 2025-05-26 19:08:16.991827 | TASK [tox : Fail if constraints file is missing] 2025-05-26 19:08:17.037215 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:17.046568 | 2025-05-26 19:08:17.046748 | TASK [tox : Record file location] 2025-05-26 19:08:17.092780 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:17.100928 | 2025-05-26 19:08:17.101141 | TASK [tox : Install tox siblings] 2025-05-26 19:08:17.147649 | debian-bookworm | ok 2025-05-26 19:08:17.158078 | debian-bookworm | included: /var/lib/zuul/builds/2c8609ef52ce4ae08f4afea6fdff9699/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-05-26 19:08:17.164052 | 2025-05-26 19:08:17.164173 | TASK [tox : Run tox without tests] 2025-05-26 19:08:18.232001 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-05-26 19:08:32.188543 | debian-bookworm | test: OK (14.47 seconds) 2025-05-26 19:08:32.188595 | debian-bookworm | congratulations :) (14.54 seconds) 2025-05-26 19:08:32.701011 | debian-bookworm | ok: Runtime: 0:00:14.778413 2025-05-26 19:08:32.712830 | 2025-05-26 19:08:32.713061 | TASK [tox : Create a tempfile to save tox showconfig] 2025-05-26 19:08:33.159927 | debian-bookworm | changed 2025-05-26 19:08:33.165645 | 2025-05-26 19:08:33.165789 | TASK [tox : Get tox envlist config] 2025-05-26 19:08:34.199515 | debian-bookworm | ok: Runtime: 0:00:00.323707 2025-05-26 19:08:34.208049 | 2025-05-26 19:08:34.208186 | TASK [tox : Install any sibling python packages] 2025-05-26 19:08:34.693918 | debian-bookworm | ok: No setup.cfg, no action needed 2025-05-26 19:08:34.702380 | 2025-05-26 19:08:34.702522 | TASK [tox : Remove tempfile] 2025-05-26 19:08:35.292873 | debian-bookworm | changed 2025-05-26 19:08:35.303042 | 2025-05-26 19:08:35.303200 | TASK [tox : Emit tox command] 2025-05-26 19:08:35.363540 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-05-26 19:08:35.374280 | 2025-05-26 19:08:35.374561 | TASK [tox : Run tox] 2025-05-26 19:08:36.006607 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-05-26 19:08:36.788019 | debian-bookworm | ............... 2025-05-26 19:08:36.788065 | debian-bookworm | ---------------------------------------------------------------------- 2025-05-26 19:08:36.788081 | debian-bookworm | Ran 15 tests in 0.174s 2025-05-26 19:08:36.788086 | debian-bookworm | 2025-05-26 19:08:36.788093 | debian-bookworm | OK 2025-05-26 19:08:36.892731 | debian-bookworm | test: OK (0.92=setup[0.04]+cmd[0.88] seconds) 2025-05-26 19:08:36.892908 | debian-bookworm | congratulations :) (0.99 seconds) 2025-05-26 19:08:36.982378 | debian-bookworm | ok: Runtime: 0:00:01.214021 2025-05-26 19:08:36.991635 | 2025-05-26 19:08:36.991896 | TASK [tox : Look for output] 2025-05-26 19:08:37.435876 | debian-bookworm | ok 2025-05-26 19:08:37.458633 | 2025-05-26 19:08:37.458936 | TASK [tox : Return file comments to Zuul] 2025-05-26 19:08:37.506269 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:37.513768 | 2025-05-26 19:08:37.513888 | PLAY RECAP 2025-05-26 19:08:37.513944 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-05-26 19:08:37.513985 | 2025-05-26 19:08:37.678996 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-26 19:08:37.680143 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-26 19:08:38.484577 | 2025-05-26 19:08:38.484714 | PLAY [all] 2025-05-26 19:08:38.498647 | 2025-05-26 19:08:38.498781 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-05-26 19:08:38.553780 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:38.569640 | 2025-05-26 19:08:38.569869 | TASK [fetch-tox-output : Set tox log path for single node] 2025-05-26 19:08:38.617725 | debian-bookworm | ok 2025-05-26 19:08:38.625929 | 2025-05-26 19:08:38.626096 | TASK [fetch-tox-output : Ensure local tox dir] 2025-05-26 19:08:39.200350 | debian-bookworm -> localhost | changed 2025-05-26 19:08:39.211810 | 2025-05-26 19:08:39.212031 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-05-26 19:08:39.260655 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:39.267755 | 2025-05-26 19:08:39.267918 | TASK [fetch-tox-output : Set envlist fact] 2025-05-26 19:08:39.311044 | debian-bookworm | ok 2025-05-26 19:08:39.319361 | 2025-05-26 19:08:39.319529 | TASK [fetch-tox-output : Get tox version] 2025-05-26 19:08:40.028160 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-26 19:08:40.046236 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-05-26 19:08:40.351524 | debian-bookworm | ok: Runtime: 0:00:00.286757 2025-05-26 19:08:40.361462 | 2025-05-26 19:08:40.361589 | TASK [fetch-tox-output : Find all default environments] 2025-05-26 19:08:40.900208 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:40.906001 | 2025-05-26 19:08:40.906110 | TASK [fetch-tox-output : Set envlist fact] 2025-05-26 19:08:40.940090 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:40.949804 | 2025-05-26 19:08:40.949943 | TASK [fetch-tox-output : Find all default environments] 2025-05-26 19:08:41.493586 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:41.507380 | 2025-05-26 19:08:41.507581 | TASK [fetch-tox-output : Set envlist fact] 2025-05-26 19:08:41.543667 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:41.560305 | 2025-05-26 19:08:41.560500 | LOOP [fetch-tox-output : Copy tox logs] 2025-05-26 19:08:41.621160 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:41.640574 | 2025-05-26 19:08:41.640874 | LOOP [fetch-tox-output : Collect tox logs] 2025-05-26 19:08:42.595352 | debian-bookworm | changed: 2025-05-26 19:08:42.596554 | debian-bookworm | created directory /var/lib/zuul/builds/2c8609ef52ce4ae08f4afea6fdff9699/work/logs/tox/test 2025-05-26 19:08:42.597341 | debian-bookworm | cd+++++++++ ./ 2025-05-26 19:08:42.597735 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-05-26 19:08:42.598242 | 2025-05-26 19:08:42.622286 | 2025-05-26 19:08:42.622398 | PLAY RECAP 2025-05-26 19:08:42.622464 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-05-26 19:08:42.622495 | 2025-05-26 19:08:42.819697 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-26 19:08:42.820809 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-26 19:08:43.746223 | 2025-05-26 19:08:43.746439 | PLAY [all] 2025-05-26 19:08:43.764853 | 2025-05-26 19:08:43.765093 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-05-26 19:08:44.008698 | debian-bookworm | changed: non-zero return code 2025-05-26 19:08:44.017921 | 2025-05-26 19:08:44.018267 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-05-26 19:08:44.056288 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:44.075472 | 2025-05-26 19:08:44.075836 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-05-26 19:08:44.114739 | 2025-05-26 19:08:44.115182 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-05-26 19:08:44.163289 | 2025-05-26 19:08:44.164432 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-05-26 19:08:44.192703 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:44.204762 | 2025-05-26 19:08:44.204978 | LOOP [fetch-subunit-output : Generate subunit file] 2025-05-26 19:08:44.251014 | 2025-05-26 19:08:44.251381 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-05-26 19:08:44.288201 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:44.298085 | 2025-05-26 19:08:44.298248 | TASK [fetch-subunit-output : Remove the temporary file] 2025-05-26 19:08:44.324317 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:44.332265 | 2025-05-26 19:08:44.332533 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-05-26 19:08:44.359046 | debian-bookworm | skipping: Conditional result was False 2025-05-26 19:08:44.374396 | 2025-05-26 19:08:44.374637 | PLAY RECAP 2025-05-26 19:08:44.374712 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-05-26 19:08:44.374883 | 2025-05-26 19:08:44.599154 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-26 19:08:44.600376 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-26 19:08:45.457490 | 2025-05-26 19:08:45.457695 | PLAY [Base post] 2025-05-26 19:08:45.474805 | 2025-05-26 19:08:45.475045 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-05-26 19:08:46.066373 | debian-bookworm | changed 2025-05-26 19:08:46.073908 | 2025-05-26 19:08:46.074069 | PLAY RECAP 2025-05-26 19:08:46.074136 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-05-26 19:08:46.074199 | 2025-05-26 19:08:46.259815 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-26 19:08:46.263113 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-05-26 19:08:47.165457 | 2025-05-26 19:08:47.165649 | PLAY [Base post-logs] 2025-05-26 19:08:47.177204 | 2025-05-26 19:08:47.177359 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-05-26 19:08:47.722094 | localhost | changed 2025-05-26 19:08:47.733614 | 2025-05-26 19:08:47.733785 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-05-26 19:08:47.770520 | localhost | ok 2025-05-26 19:08:47.776856 | 2025-05-26 19:08:47.777334 | TASK [Set zuul-log-path fact] 2025-05-26 19:08:47.797552 | localhost | ok 2025-05-26 19:08:47.806804 | 2025-05-26 19:08:47.806947 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-26 19:08:47.832929 | localhost | ok 2025-05-26 19:08:47.836899 | 2025-05-26 19:08:47.837053 | TASK [upload-logs : Create log directories] 2025-05-26 19:08:48.368947 | localhost | changed 2025-05-26 19:08:48.376766 | 2025-05-26 19:08:48.376986 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-05-26 19:08:48.916464 | localhost -> localhost | ok: Runtime: 0:00:00.008087 2025-05-26 19:08:48.925352 | 2025-05-26 19:08:48.925536 | TASK [upload-logs : Upload logs to log server] 2025-05-26 19:08:49.549645 | localhost | Output suppressed because no_log was given 2025-05-26 19:08:49.554505 | 2025-05-26 19:08:49.554745 | LOOP [upload-logs : Compress console log and json output] 2025-05-26 19:08:49.619930 | localhost | skipping: Conditional result was False 2025-05-26 19:08:49.625348 | localhost | skipping: Conditional result was False 2025-05-26 19:08:49.638008 | 2025-05-26 19:08:49.638219 | LOOP [upload-logs : Upload compressed console log and json output] 2025-05-26 19:08:49.689573 | localhost | skipping: Conditional result was False 2025-05-26 19:08:49.690285 | 2025-05-26 19:08:49.693821 | localhost | skipping: Conditional result was False 2025-05-26 19:08:49.700922 | 2025-05-26 19:08:49.701172 | LOOP [upload-logs : Upload console log and json output]