2025-11-09 15:56:28.174793 | Job console starting 2025-11-09 15:56:28.198201 | Updating git repos 2025-11-09 15:56:28.238240 | Cloning repos into workspace 2025-11-09 15:56:28.285822 | Restoring repo states 2025-11-09 15:56:28.294095 | Merging changes 2025-11-09 15:56:28.699785 | Checking out repos 2025-11-09 15:56:28.739887 | Preparing playbooks 2025-11-09 15:56:29.475796 | Running Ansible setup 2025-11-09 15:56:34.475803 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-09 15:56:35.574646 | 2025-11-09 15:56:35.574811 | PLAY [Base pre] 2025-11-09 15:56:35.593701 | 2025-11-09 15:56:35.593861 | TASK [Setup log path fact] 2025-11-09 15:56:35.623540 | debian-bookworm | ok 2025-11-09 15:56:35.640175 | 2025-11-09 15:56:35.640327 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-09 15:56:35.669697 | debian-bookworm | ok 2025-11-09 15:56:35.690969 | 2025-11-09 15:56:35.691123 | TASK [emit-job-header : Print job information] 2025-11-09 15:56:35.761135 | # Job Information 2025-11-09 15:56:35.761313 | Ansible Version: 2.16.14 2025-11-09 15:56:35.761347 | Job: tox 2025-11-09 15:56:35.761379 | Pipeline: check 2025-11-09 15:56:35.761401 | Executor: 521e9411259a 2025-11-09 15:56:35.761422 | Triggered by: https://github.com/osism/openstack-image-manager/pull/986 2025-11-09 15:56:35.761444 | Event ID: a21763e0-bd84-11f0-9cd6-c5c70b5a7e33 2025-11-09 15:56:35.766404 | 2025-11-09 15:56:35.766521 | LOOP [emit-job-header : Print node information] 2025-11-09 15:56:35.891487 | debian-bookworm | ok: 2025-11-09 15:56:35.891776 | debian-bookworm | # Node Information 2025-11-09 15:56:35.891813 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-09 15:56:35.891838 | debian-bookworm | Hostname: debian 2025-11-09 15:56:35.891860 | debian-bookworm | Username: zuul 2025-11-09 15:56:35.891881 | debian-bookworm | Distro: Debian 12.12 2025-11-09 15:56:35.891905 | debian-bookworm | Provider: regiocloud-a 2025-11-09 15:56:35.891927 | debian-bookworm | Region: 2025-11-09 15:56:35.891949 | debian-bookworm | Label: debian-bookworm 2025-11-09 15:56:35.891969 | debian-bookworm | Product Name: OpenStack Nova 2025-11-09 15:56:35.892026 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2d:5fc7 2025-11-09 15:56:35.914453 | 2025-11-09 15:56:35.914584 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-09 15:56:36.651387 | debian-bookworm -> localhost | changed 2025-11-09 15:56:36.659818 | 2025-11-09 15:56:36.659945 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-09 15:56:38.029168 | debian-bookworm -> localhost | changed 2025-11-09 15:56:38.037568 | 2025-11-09 15:56:38.037656 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-09 15:56:38.394532 | debian-bookworm -> localhost | ok 2025-11-09 15:56:38.400017 | 2025-11-09 15:56:38.400109 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-09 15:56:38.446955 | debian-bookworm | ok 2025-11-09 15:56:38.469838 | debian-bookworm | included: /var/lib/zuul/builds/4dba2bbb623945bc85d21dcd0b3d6ece/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-09 15:56:38.486072 | 2025-11-09 15:56:38.486159 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-09 15:56:39.860558 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-09 15:56:39.860745 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4dba2bbb623945bc85d21dcd0b3d6ece/work/4dba2bbb623945bc85d21dcd0b3d6ece_id_rsa 2025-11-09 15:56:39.860778 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4dba2bbb623945bc85d21dcd0b3d6ece/work/4dba2bbb623945bc85d21dcd0b3d6ece_id_rsa.pub 2025-11-09 15:56:39.860800 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-09 15:56:39.860821 | debian-bookworm -> localhost | SHA256:IRZXTYah1CMMSSKDyXAHiUnCkDZT35r95f/q4IMURX4 zuul-build-sshkey 2025-11-09 15:56:39.860840 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-09 15:56:39.860865 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-09 15:56:39.860884 | debian-bookworm -> localhost | |XB*+o.++oo+*o | 2025-11-09 15:56:39.860902 | debian-bookworm -> localhost | |BB.+..o+o.=o. | 2025-11-09 15:56:39.860919 | debian-bookworm -> localhost | |. o .o.o..o E | 2025-11-09 15:56:39.860935 | debian-bookworm -> localhost | | .+. o . | 2025-11-09 15:56:39.860952 | debian-bookworm -> localhost | | o .S .. | 2025-11-09 15:56:39.860984 | debian-bookworm -> localhost | | ..o | 2025-11-09 15:56:39.861003 | debian-bookworm -> localhost | | ...o | 2025-11-09 15:56:39.861020 | debian-bookworm -> localhost | | ...o | 2025-11-09 15:56:39.861038 | debian-bookworm -> localhost | | .o+o. | 2025-11-09 15:56:39.861055 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-09 15:56:39.861096 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.687350 2025-11-09 15:56:39.866136 | 2025-11-09 15:56:39.866221 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-09 15:56:39.902547 | debian-bookworm | ok 2025-11-09 15:56:39.909904 | debian-bookworm | included: /var/lib/zuul/builds/4dba2bbb623945bc85d21dcd0b3d6ece/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-09 15:56:39.917075 | 2025-11-09 15:56:39.917147 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-09 15:56:39.929600 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:56:39.934055 | 2025-11-09 15:56:39.934130 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-09 15:56:40.547177 | debian-bookworm | changed 2025-11-09 15:56:40.553790 | 2025-11-09 15:56:40.553890 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-09 15:56:40.848283 | debian-bookworm | ok 2025-11-09 15:56:40.857717 | 2025-11-09 15:56:40.857847 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-09 15:56:41.542020 | debian-bookworm | changed 2025-11-09 15:56:41.551077 | 2025-11-09 15:56:41.551180 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-09 15:56:42.178473 | debian-bookworm | changed 2025-11-09 15:56:42.186403 | 2025-11-09 15:56:42.186519 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-09 15:56:42.209966 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:56:42.215544 | 2025-11-09 15:56:42.215626 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-09 15:56:42.522109 | debian-bookworm -> localhost | changed 2025-11-09 15:56:42.530703 | 2025-11-09 15:56:42.530792 | TASK [add-build-sshkey : Add back temp key] 2025-11-09 15:56:42.859610 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4dba2bbb623945bc85d21dcd0b3d6ece/work/4dba2bbb623945bc85d21dcd0b3d6ece_id_rsa (zuul-build-sshkey) 2025-11-09 15:56:42.859782 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.026910 2025-11-09 15:56:42.866062 | 2025-11-09 15:56:42.866137 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-09 15:56:43.227074 | debian-bookworm | ok 2025-11-09 15:56:43.231951 | 2025-11-09 15:56:43.232049 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-09 15:56:43.254755 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:56:43.264993 | 2025-11-09 15:56:43.265101 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-09 15:56:43.683521 | debian-bookworm | ok 2025-11-09 15:56:43.698072 | 2025-11-09 15:56:43.698170 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-09 15:56:43.735591 | debian-bookworm | ok 2025-11-09 15:56:43.740416 | 2025-11-09 15:56:43.740503 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-09 15:56:44.145755 | debian-bookworm -> localhost | ok 2025-11-09 15:56:44.151790 | 2025-11-09 15:56:44.151884 | TASK [validate-host : Collect information about the host] 2025-11-09 15:56:45.284756 | debian-bookworm | ok 2025-11-09 15:56:45.297570 | 2025-11-09 15:56:45.297667 | TASK [validate-host : Sanitize hostname] 2025-11-09 15:56:45.436573 | debian-bookworm | ok 2025-11-09 15:56:45.482872 | 2025-11-09 15:56:45.482998 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-09 15:56:46.041807 | debian-bookworm -> localhost | changed 2025-11-09 15:56:46.047173 | 2025-11-09 15:56:46.047263 | TASK [validate-host : Collect information about zuul worker] 2025-11-09 15:56:46.439007 | debian-bookworm | ok 2025-11-09 15:56:46.443398 | 2025-11-09 15:56:46.443488 | TASK [validate-host : Write out all zuul information for each host] 2025-11-09 15:56:47.049296 | debian-bookworm -> localhost | changed 2025-11-09 15:56:47.061285 | 2025-11-09 15:56:47.061390 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-09 15:56:47.338736 | debian-bookworm | ok 2025-11-09 15:56:47.344762 | 2025-11-09 15:56:47.344851 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-09 15:56:48.153289 | debian-bookworm | changed: 2025-11-09 15:56:48.153455 | debian-bookworm | cd+++++++++ src/ 2025-11-09 15:56:48.153490 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-09 15:56:48.153514 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-09 15:56:48.153536 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-11-09 15:56:48.153557 | debian-bookworm | =6.2 2025-11-09 15:57:10.052494 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-11-09 15:57:10.095983 | debian-bookworm | Collecting chardet>=5.2 2025-11-09 15:57:10.115014 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-11-09 15:57:10.127632 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 23.8 MB/s eta 0:00:00 2025-11-09 15:57:10.169200 | debian-bookworm | Collecting colorama>=0.4.6 2025-11-09 15:57:10.184839 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-11-09 15:57:10.237822 | debian-bookworm | Collecting filelock>=3.20 2025-11-09 15:57:10.251769 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-11-09 15:57:10.299751 | debian-bookworm | Collecting packaging>=25 2025-11-09 15:57:10.313840 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-11-09 15:57:10.321691 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.9 MB/s eta 0:00:00 2025-11-09 15:57:10.369425 | debian-bookworm | Collecting platformdirs>=4.5 2025-11-09 15:57:10.383781 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-11-09 15:57:10.423247 | debian-bookworm | Collecting pluggy>=1.6 2025-11-09 15:57:10.437624 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-11-09 15:57:10.476675 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-11-09 15:57:10.491123 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-11-09 15:57:10.618676 | debian-bookworm | Collecting virtualenv>=20.34 2025-11-09 15:57:10.634129 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-11-09 15:57:10.866134 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 26.5 MB/s eta 0:00:00 2025-11-09 15:57:10.989880 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-11-09 15:57:11.004218 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-11-09 15:57:11.027139 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.9 MB/s eta 0:00:00 2025-11-09 15:57:11.178974 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-11-09 15:57:12.042644 | debian-bookworm | Successfully installed cachetools-6.2.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.4 2025-11-09 15:57:12.453890 | debian-bookworm | ok: Runtime: 0:00:02.955151 2025-11-09 15:57:12.461020 | 2025-11-09 15:57:12.461179 | TASK [ensure-tox : Export installed tox_executable path] 2025-11-09 15:57:12.493496 | debian-bookworm | ok 2025-11-09 15:57:12.498801 | 2025-11-09 15:57:12.499152 | TASK [ensure-tox : Output tox version] 2025-11-09 15:57:12.993293 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-11-09 15:57:13.011563 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-11-09 15:57:13.528580 | debian-bookworm | ok: Runtime: 0:00:00.294057 2025-11-09 15:57:13.541760 | 2025-11-09 15:57:13.541933 | TASK [ensure-tox : Make global symlink] 2025-11-09 15:57:13.567986 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:13.581828 | 2025-11-09 15:57:13.581933 | PLAY RECAP 2025-11-09 15:57:13.582022 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-11-09 15:57:13.582059 | 2025-11-09 15:57:13.723034 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-11-09 15:57:13.724129 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-11-09 15:57:14.461854 | 2025-11-09 15:57:14.462032 | PLAY [all] 2025-11-09 15:57:14.478494 | 2025-11-09 15:57:14.478622 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-11-09 15:57:14.951934 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-11-09 15:57:15.015753 | debian-bookworm | ok: Runtime: 0:00:00.030384 2025-11-09 15:57:15.029333 | 2025-11-09 15:57:15.029558 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-11-09 15:57:15.568350 | debian-bookworm | ok: Runtime: 0:00:00.005489 2025-11-09 15:57:15.578272 | 2025-11-09 15:57:15.578412 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-11-09 15:57:15.876892 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-11-09 15:57:15.877829 | debian-bookworm | sudo: a password is required 2025-11-09 15:57:16.109787 | debian-bookworm | ok: Runtime: 0:00:00.023067 2025-11-09 15:57:16.126691 | 2025-11-09 15:57:16.126870 | TASK [tox : Check to see if the constraints file exists] 2025-11-09 15:57:16.162372 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:16.176860 | 2025-11-09 15:57:16.177050 | TASK [tox : Fail if constraints file is missing] 2025-11-09 15:57:16.201777 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:16.207807 | 2025-11-09 15:57:16.207913 | TASK [tox : Record file location] 2025-11-09 15:57:16.231301 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:16.237772 | 2025-11-09 15:57:16.237885 | TASK [tox : Install tox siblings] 2025-11-09 15:57:16.269712 | debian-bookworm | ok 2025-11-09 15:57:16.279095 | debian-bookworm | included: /var/lib/zuul/builds/4dba2bbb623945bc85d21dcd0b3d6ece/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-11-09 15:57:16.284667 | 2025-11-09 15:57:16.284770 | TASK [tox : Run tox without tests] 2025-11-09 15:57:17.410766 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-11-09 15:57:31.549107 | debian-bookworm | test: OK (14.72 seconds) 2025-11-09 15:57:31.549187 | debian-bookworm | congratulations :) (14.74 seconds) 2025-11-09 15:57:31.822659 | debian-bookworm | ok: Runtime: 0:00:15.022621 2025-11-09 15:57:31.833398 | 2025-11-09 15:57:31.833553 | TASK [tox : Create a tempfile to save tox showconfig] 2025-11-09 15:57:32.239940 | debian-bookworm | changed 2025-11-09 15:57:32.254304 | 2025-11-09 15:57:32.254429 | TASK [tox : Get tox envlist config] 2025-11-09 15:57:33.284858 | debian-bookworm | ok: Runtime: 0:00:00.345236 2025-11-09 15:57:33.290620 | 2025-11-09 15:57:33.290732 | TASK [tox : Install any sibling python packages] 2025-11-09 15:57:33.743119 | debian-bookworm | ok: No setup.cfg, no action needed 2025-11-09 15:57:33.752118 | 2025-11-09 15:57:33.752254 | TASK [tox : Remove tempfile] 2025-11-09 15:57:34.183735 | debian-bookworm | changed 2025-11-09 15:57:34.191564 | 2025-11-09 15:57:34.191697 | TASK [tox : Emit tox command] 2025-11-09 15:57:34.236876 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-11-09 15:57:34.244483 | 2025-11-09 15:57:34.244621 | TASK [tox : Run tox] 2025-11-09 15:57:34.865202 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-11-09 15:57:35.491112 | debian-bookworm | ............... 2025-11-09 15:57:35.491163 | debian-bookworm | ---------------------------------------------------------------------- 2025-11-09 15:57:35.491180 | debian-bookworm | Ran 15 tests in 0.167s 2025-11-09 15:57:35.491185 | debian-bookworm | 2025-11-09 15:57:35.491192 | debian-bookworm | OK 2025-11-09 15:57:35.583544 | debian-bookworm | test: OK (0.76=setup[0.04]+cmd[0.71] seconds) 2025-11-09 15:57:35.583633 | debian-bookworm | congratulations :) (0.79 seconds) 2025-11-09 15:57:35.808758 | debian-bookworm | ok: Runtime: 0:00:01.074007 2025-11-09 15:57:35.822429 | 2025-11-09 15:57:35.822596 | TASK [tox : Look for output] 2025-11-09 15:57:36.221551 | debian-bookworm | ok 2025-11-09 15:57:36.237767 | 2025-11-09 15:57:36.237884 | TASK [tox : Return file comments to Zuul] 2025-11-09 15:57:36.271989 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:36.283592 | 2025-11-09 15:57:36.283693 | PLAY RECAP 2025-11-09 15:57:36.283763 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-11-09 15:57:36.283797 | 2025-11-09 15:57:36.402448 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-11-09 15:57:36.405122 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-11-09 15:57:37.117978 | 2025-11-09 15:57:37.118137 | PLAY [all] 2025-11-09 15:57:37.133204 | 2025-11-09 15:57:37.133331 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-11-09 15:57:37.188783 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:37.200887 | 2025-11-09 15:57:37.201127 | TASK [fetch-tox-output : Set tox log path for single node] 2025-11-09 15:57:37.248122 | debian-bookworm | ok 2025-11-09 15:57:37.257064 | 2025-11-09 15:57:37.257200 | TASK [fetch-tox-output : Ensure local tox dir] 2025-11-09 15:57:37.706458 | debian-bookworm -> localhost | changed 2025-11-09 15:57:37.714397 | 2025-11-09 15:57:37.714509 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-11-09 15:57:37.769645 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:37.783133 | 2025-11-09 15:57:37.783319 | TASK [fetch-tox-output : Set envlist fact] 2025-11-09 15:57:37.830514 | debian-bookworm | ok 2025-11-09 15:57:37.839092 | 2025-11-09 15:57:37.839215 | TASK [fetch-tox-output : Get tox version] 2025-11-09 15:57:38.544605 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-11-09 15:57:38.563490 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-11-09 15:57:38.871049 | debian-bookworm | ok: Runtime: 0:00:00.327078 2025-11-09 15:57:38.882672 | 2025-11-09 15:57:38.882877 | TASK [fetch-tox-output : Find all default environments] 2025-11-09 15:57:39.414691 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:39.429265 | 2025-11-09 15:57:39.429559 | TASK [fetch-tox-output : Set envlist fact] 2025-11-09 15:57:39.466805 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:39.480187 | 2025-11-09 15:57:39.480342 | TASK [fetch-tox-output : Find all default environments] 2025-11-09 15:57:40.025491 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:40.039109 | 2025-11-09 15:57:40.039284 | TASK [fetch-tox-output : Set envlist fact] 2025-11-09 15:57:40.084876 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:40.100390 | 2025-11-09 15:57:40.100546 | LOOP [fetch-tox-output : Copy tox logs] 2025-11-09 15:57:40.151532 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:40.163264 | 2025-11-09 15:57:40.163378 | LOOP [fetch-tox-output : Collect tox logs] 2025-11-09 15:57:40.934526 | debian-bookworm | changed: 2025-11-09 15:57:40.934737 | debian-bookworm | created directory /var/lib/zuul/builds/4dba2bbb623945bc85d21dcd0b3d6ece/work/logs/tox/test 2025-11-09 15:57:40.934775 | debian-bookworm | cd+++++++++ ./ 2025-11-09 15:57:40.934800 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-11-09 15:57:40.934859 | 2025-11-09 15:57:40.942384 | 2025-11-09 15:57:40.942464 | PLAY RECAP 2025-11-09 15:57:40.942515 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-11-09 15:57:40.942539 | 2025-11-09 15:57:41.063600 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-11-09 15:57:41.066697 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-09 15:57:41.773889 | 2025-11-09 15:57:41.774077 | PLAY [all] 2025-11-09 15:57:41.789223 | 2025-11-09 15:57:41.789348 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-09 15:57:42.033438 | debian-bookworm | changed: non-zero return code 2025-11-09 15:57:42.039040 | 2025-11-09 15:57:42.039170 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-09 15:57:42.062749 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:42.068387 | 2025-11-09 15:57:42.068495 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-09 15:57:42.097547 | 2025-11-09 15:57:42.097717 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-09 15:57:42.128661 | 2025-11-09 15:57:42.128852 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-09 15:57:42.142782 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:42.150310 | 2025-11-09 15:57:42.150440 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-09 15:57:42.179319 | 2025-11-09 15:57:42.179493 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-09 15:57:42.202485 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:42.209033 | 2025-11-09 15:57:42.209150 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-09 15:57:42.232198 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:42.239847 | 2025-11-09 15:57:42.240014 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-09 15:57:42.263596 | debian-bookworm | skipping: Conditional result was False 2025-11-09 15:57:42.273715 | 2025-11-09 15:57:42.273807 | PLAY RECAP 2025-11-09 15:57:42.273873 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-09 15:57:42.273906 | 2025-11-09 15:57:42.391882 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-09 15:57:42.393011 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-09 15:57:43.095093 | 2025-11-09 15:57:43.095250 | PLAY [Base post] 2025-11-09 15:57:43.109072 | 2025-11-09 15:57:43.109214 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-09 15:57:43.655132 | debian-bookworm | changed 2025-11-09 15:57:43.661509 | 2025-11-09 15:57:43.661612 | PLAY RECAP 2025-11-09 15:57:43.661674 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-09 15:57:43.661736 | 2025-11-09 15:57:43.772545 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-09 15:57:43.773653 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-09 15:57:44.559991 | 2025-11-09 15:57:44.560162 | PLAY [Base post-logs] 2025-11-09 15:57:44.570797 | 2025-11-09 15:57:44.570991 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-09 15:57:45.035024 | localhost | changed 2025-11-09 15:57:45.050770 | 2025-11-09 15:57:45.050989 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-09 15:57:45.089028 | localhost | ok 2025-11-09 15:57:45.096307 | 2025-11-09 15:57:45.096477 | TASK [Set zuul-log-path fact] 2025-11-09 15:57:45.113318 | localhost | ok 2025-11-09 15:57:45.123148 | 2025-11-09 15:57:45.123260 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-09 15:57:45.160142 | localhost | ok 2025-11-09 15:57:45.166982 | 2025-11-09 15:57:45.167162 | TASK [upload-logs : Create log directories] 2025-11-09 15:57:45.656012 | localhost | changed 2025-11-09 15:57:45.660852 | 2025-11-09 15:57:45.661007 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-09 15:57:46.181806 | localhost -> localhost | ok: Runtime: 0:00:00.006222 2025-11-09 15:57:46.186582 | 2025-11-09 15:57:46.186699 | TASK [upload-logs : Upload logs to log server] 2025-11-09 15:57:46.762295 | localhost | Output suppressed because no_log was given 2025-11-09 15:57:46.764603 | 2025-11-09 15:57:46.764730 | LOOP [upload-logs : Compress console log and json output] 2025-11-09 15:57:46.814756 | localhost | skipping: Conditional result was False 2025-11-09 15:57:46.820443 | localhost | skipping: Conditional result was False 2025-11-09 15:57:46.832129 | 2025-11-09 15:57:46.832272 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-09 15:57:46.875286 | localhost | skipping: Conditional result was False 2025-11-09 15:57:46.875597 | 2025-11-09 15:57:46.886821 | localhost | skipping: Conditional result was False 2025-11-09 15:57:46.903578 | 2025-11-09 15:57:46.903776 | LOOP [upload-logs : Upload console log and json output]