2025-12-25 00:18:02.632364 | Job console starting 2025-12-25 00:18:02.656145 | Updating git repos 2025-12-25 00:18:02.691739 | Cloning repos into workspace 2025-12-25 00:18:02.857089 | Restoring repo states 2025-12-25 00:18:02.890741 | Merging changes 2025-12-25 00:18:03.476304 | Checking out repos 2025-12-25 00:18:03.551218 | Preparing playbooks 2025-12-25 00:18:04.548287 | Running Ansible setup 2025-12-25 00:18:11.617683 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-25 00:18:13.029250 | 2025-12-25 00:18:13.029432 | PLAY [Base pre] 2025-12-25 00:18:13.077905 | 2025-12-25 00:18:13.078076 | TASK [Setup log path fact] 2025-12-25 00:18:13.137634 | debian-bookworm | ok 2025-12-25 00:18:13.164267 | 2025-12-25 00:18:13.164439 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-25 00:18:13.228681 | debian-bookworm | ok 2025-12-25 00:18:13.250237 | 2025-12-25 00:18:13.250381 | TASK [emit-job-header : Print job information] 2025-12-25 00:18:13.296912 | # Job Information 2025-12-25 00:18:13.297161 | Ansible Version: 2.16.14 2025-12-25 00:18:13.297199 | Job: tox 2025-12-25 00:18:13.297237 | Pipeline: check 2025-12-25 00:18:13.297265 | Executor: 521e9411259a 2025-12-25 00:18:13.297285 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1054 2025-12-25 00:18:13.297309 | Event ID: 27378710-e127-11f0-9dab-512d7d8f9c9b 2025-12-25 00:18:13.306397 | 2025-12-25 00:18:13.306540 | LOOP [emit-job-header : Print node information] 2025-12-25 00:18:13.497769 | debian-bookworm | ok: 2025-12-25 00:18:13.498113 | debian-bookworm | # Node Information 2025-12-25 00:18:13.498158 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-25 00:18:13.498183 | debian-bookworm | Hostname: debian 2025-12-25 00:18:13.498205 | debian-bookworm | Username: zuul 2025-12-25 00:18:13.498226 | debian-bookworm | Distro: Debian 12.12 2025-12-25 00:18:13.498248 | debian-bookworm | Provider: regiocloud-a 2025-12-25 00:18:13.498269 | debian-bookworm | Region: 2025-12-25 00:18:13.498291 | debian-bookworm | Label: debian-bookworm 2025-12-25 00:18:13.498311 | debian-bookworm | Product Name: OpenStack Nova 2025-12-25 00:18:13.498330 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefb:ce6 2025-12-25 00:18:13.529527 | 2025-12-25 00:18:13.529677 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-25 00:18:14.602490 | debian-bookworm -> localhost | changed 2025-12-25 00:18:14.610379 | 2025-12-25 00:18:14.610519 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-25 00:18:16.713155 | debian-bookworm -> localhost | changed 2025-12-25 00:18:16.755418 | 2025-12-25 00:18:16.761370 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-25 00:18:17.483678 | debian-bookworm -> localhost | ok 2025-12-25 00:18:17.491427 | 2025-12-25 00:18:17.491572 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-25 00:18:17.532906 | debian-bookworm | ok 2025-12-25 00:18:17.572028 | debian-bookworm | included: /var/lib/zuul/builds/40cfbc412533446cbc3046fdf0e357db/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-25 00:18:17.583940 | 2025-12-25 00:18:17.584080 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-25 00:18:19.552765 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-25 00:18:19.553021 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/40cfbc412533446cbc3046fdf0e357db/work/40cfbc412533446cbc3046fdf0e357db_id_rsa 2025-12-25 00:18:19.553061 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/40cfbc412533446cbc3046fdf0e357db/work/40cfbc412533446cbc3046fdf0e357db_id_rsa.pub 2025-12-25 00:18:19.553087 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-25 00:18:19.553114 | debian-bookworm -> localhost | SHA256:Xk29zi+j5y4gbEnbAh9Jt8jtM//3oxAOVFhQV3iQd+0 zuul-build-sshkey 2025-12-25 00:18:19.553137 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-25 00:18:19.553173 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-25 00:18:19.553195 | debian-bookworm -> localhost | | .=+ o=..| 2025-12-25 00:18:19.553216 | debian-bookworm -> localhost | | ..o .+ oo| 2025-12-25 00:18:19.553236 | debian-bookworm -> localhost | | o * .. +..| 2025-12-25 00:18:19.553255 | debian-bookworm -> localhost | | . B oo .E| 2025-12-25 00:18:19.553274 | debian-bookworm -> localhost | | =SB... . | 2025-12-25 00:18:19.553302 | debian-bookworm -> localhost | | .O.O .o | 2025-12-25 00:18:19.553324 | debian-bookworm -> localhost | | ..o B o | 2025-12-25 00:18:19.553346 | debian-bookworm -> localhost | | + +o.| 2025-12-25 00:18:19.553367 | debian-bookworm -> localhost | | .O=+=| 2025-12-25 00:18:19.553387 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-25 00:18:19.553437 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.926167 2025-12-25 00:18:19.560582 | 2025-12-25 00:18:19.560860 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-25 00:18:19.611034 | debian-bookworm | ok 2025-12-25 00:18:19.626232 | debian-bookworm | included: /var/lib/zuul/builds/40cfbc412533446cbc3046fdf0e357db/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-25 00:18:19.642776 | 2025-12-25 00:18:19.643235 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-25 00:18:19.668417 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:18:19.674686 | 2025-12-25 00:18:19.674931 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-25 00:18:20.453086 | debian-bookworm | changed 2025-12-25 00:18:20.463622 | 2025-12-25 00:18:20.463771 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-25 00:18:20.735065 | debian-bookworm | ok 2025-12-25 00:18:20.744970 | 2025-12-25 00:18:20.745118 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-25 00:18:21.478562 | debian-bookworm | changed 2025-12-25 00:18:21.503055 | 2025-12-25 00:18:21.503356 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-25 00:18:22.212789 | debian-bookworm | changed 2025-12-25 00:18:22.220422 | 2025-12-25 00:18:22.220553 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-25 00:18:22.244728 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:18:22.260014 | 2025-12-25 00:18:22.260163 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-25 00:18:22.973690 | debian-bookworm -> localhost | changed 2025-12-25 00:18:22.992380 | 2025-12-25 00:18:22.992514 | TASK [add-build-sshkey : Add back temp key] 2025-12-25 00:18:23.633289 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/40cfbc412533446cbc3046fdf0e357db/work/40cfbc412533446cbc3046fdf0e357db_id_rsa (zuul-build-sshkey) 2025-12-25 00:18:23.633531 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.026762 2025-12-25 00:18:23.644044 | 2025-12-25 00:18:23.644178 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-25 00:18:24.162611 | debian-bookworm | ok 2025-12-25 00:18:24.168082 | 2025-12-25 00:18:24.168211 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-25 00:18:24.203498 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:18:24.215423 | 2025-12-25 00:18:24.215569 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-25 00:18:24.731965 | debian-bookworm | ok 2025-12-25 00:18:24.743401 | 2025-12-25 00:18:24.755073 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-25 00:18:24.784655 | debian-bookworm | ok 2025-12-25 00:18:24.790055 | 2025-12-25 00:18:24.790170 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-25 00:18:25.108766 | debian-bookworm -> localhost | ok 2025-12-25 00:18:25.122024 | 2025-12-25 00:18:25.122215 | TASK [validate-host : Collect information about the host] 2025-12-25 00:18:26.404449 | debian-bookworm | ok 2025-12-25 00:18:26.422088 | 2025-12-25 00:18:26.422227 | TASK [validate-host : Sanitize hostname] 2025-12-25 00:18:26.552993 | debian-bookworm | ok 2025-12-25 00:18:26.614487 | 2025-12-25 00:18:26.614628 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-25 00:18:27.527344 | debian-bookworm -> localhost | changed 2025-12-25 00:18:27.535039 | 2025-12-25 00:18:27.535219 | TASK [validate-host : Collect information about zuul worker] 2025-12-25 00:18:27.974616 | debian-bookworm | ok 2025-12-25 00:18:27.983596 | 2025-12-25 00:18:27.984005 | TASK [validate-host : Write out all zuul information for each host] 2025-12-25 00:18:29.356866 | debian-bookworm -> localhost | changed 2025-12-25 00:18:29.367208 | 2025-12-25 00:18:29.367339 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-25 00:18:29.718641 | debian-bookworm | ok 2025-12-25 00:18:29.732725 | 2025-12-25 00:18:29.732899 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-25 00:18:31.245262 | debian-bookworm | changed: 2025-12-25 00:18:31.245470 | debian-bookworm | cd+++++++++ src/ 2025-12-25 00:18:31.245504 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-25 00:18:31.245528 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-25 00:18:31.245549 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-25 00:18:31.245569 | debian-bookworm | =6.2 2025-12-25 00:18:54.832625 | debian-bookworm | Downloading cachetools-6.2.4-py3-none-any.whl (11 kB) 2025-12-25 00:18:54.871801 | debian-bookworm | Collecting chardet>=5.2 2025-12-25 00:18:54.888526 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-25 00:18:54.901218 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 23.1 MB/s eta 0:00:00 2025-12-25 00:18:54.955954 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-25 00:18:54.972720 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-25 00:18:55.042375 | debian-bookworm | Collecting filelock>=3.20 2025-12-25 00:18:55.058687 | debian-bookworm | Downloading filelock-3.20.1-py3-none-any.whl (16 kB) 2025-12-25 00:18:55.109531 | debian-bookworm | Collecting packaging>=25 2025-12-25 00:18:55.124958 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-25 00:18:55.243269 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 992.2 kB/s eta 0:00:00 2025-12-25 00:18:55.314367 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-25 00:18:55.330115 | debian-bookworm | Downloading platformdirs-4.5.1-py3-none-any.whl (18 kB) 2025-12-25 00:18:55.373110 | debian-bookworm | Collecting pluggy>=1.6 2025-12-25 00:18:55.390230 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-25 00:18:55.448676 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-25 00:18:55.465837 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-25 00:18:55.609580 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-25 00:18:55.625568 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-25 00:18:55.955567 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 18.5 MB/s eta 0:00:00 2025-12-25 00:18:56.061539 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-25 00:18:56.077102 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-25 00:18:56.105188 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 18.5 MB/s eta 0:00:00 2025-12-25 00:18:56.255118 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-25 00:18:57.092343 | debian-bookworm | Successfully installed cachetools-6.2.4 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.1 packaging-25.0 platformdirs-4.5.1 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.4 2025-12-25 00:18:57.267257 | debian-bookworm | ok: Runtime: 0:00:03.208437 2025-12-25 00:18:57.281909 | 2025-12-25 00:18:57.282049 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-25 00:18:57.321904 | debian-bookworm | ok 2025-12-25 00:18:57.334995 | 2025-12-25 00:18:57.335152 | TASK [ensure-tox : Output tox version] 2025-12-25 00:18:58.018990 | 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-12-25 00:18:58.038238 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-25 00:18:58.379404 | debian-bookworm | ok: Runtime: 0:00:00.335537 2025-12-25 00:18:58.388270 | 2025-12-25 00:18:58.388394 | TASK [ensure-tox : Make global symlink] 2025-12-25 00:18:58.417322 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:18:58.449934 | 2025-12-25 00:18:58.450066 | PLAY RECAP 2025-12-25 00:18:58.450186 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-25 00:18:58.450214 | 2025-12-25 00:18:58.714450 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-25 00:18:58.715914 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-25 00:18:59.811691 | 2025-12-25 00:18:59.811912 | PLAY [all] 2025-12-25 00:18:59.829443 | 2025-12-25 00:18:59.829602 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-25 00:19:00.296592 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-25 00:19:00.367483 | debian-bookworm | ok: Runtime: 0:00:00.024120 2025-12-25 00:19:00.376552 | 2025-12-25 00:19:00.376706 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-25 00:19:00.911526 | debian-bookworm | ok: Runtime: 0:00:00.006189 2025-12-25 00:19:00.918341 | 2025-12-25 00:19:00.918520 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-25 00:19:01.216019 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-25 00:19:01.219017 | debian-bookworm | sudo: a password is required 2025-12-25 00:19:01.449423 | debian-bookworm | ok: Runtime: 0:00:00.024496 2025-12-25 00:19:01.469704 | 2025-12-25 00:19:01.469895 | TASK [tox : Check to see if the constraints file exists] 2025-12-25 00:19:01.515503 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:01.524162 | 2025-12-25 00:19:01.524318 | TASK [tox : Fail if constraints file is missing] 2025-12-25 00:19:01.550778 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:01.560184 | 2025-12-25 00:19:01.560358 | TASK [tox : Record file location] 2025-12-25 00:19:01.603269 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:01.614614 | 2025-12-25 00:19:01.614787 | TASK [tox : Install tox siblings] 2025-12-25 00:19:01.652579 | debian-bookworm | ok 2025-12-25 00:19:01.667448 | debian-bookworm | included: /var/lib/zuul/builds/40cfbc412533446cbc3046fdf0e357db/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-25 00:19:01.677918 | 2025-12-25 00:19:01.678101 | TASK [tox : Run tox without tests] 2025-12-25 00:19:02.844166 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-25 00:19:17.156346 | debian-bookworm | test: OK (14.90 seconds) 2025-12-25 00:19:17.156506 | debian-bookworm | congratulations :) (14.93 seconds) 2025-12-25 00:19:17.263545 | debian-bookworm | ok: Runtime: 0:00:15.228861 2025-12-25 00:19:17.273037 | 2025-12-25 00:19:17.273188 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-25 00:19:17.699729 | debian-bookworm | changed 2025-12-25 00:19:17.708674 | 2025-12-25 00:19:17.708847 | TASK [tox : Get tox envlist config] 2025-12-25 00:19:18.740908 | debian-bookworm | ok: Runtime: 0:00:00.322734 2025-12-25 00:19:18.754406 | 2025-12-25 00:19:18.754577 | TASK [tox : Install any sibling python packages] 2025-12-25 00:19:19.214948 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-25 00:19:19.224680 | 2025-12-25 00:19:19.224894 | TASK [tox : Remove tempfile] 2025-12-25 00:19:19.647952 | debian-bookworm | changed 2025-12-25 00:19:19.657547 | 2025-12-25 00:19:19.657719 | TASK [tox : Emit tox command] 2025-12-25 00:19:19.716578 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-25 00:19:19.724903 | 2025-12-25 00:19:19.725125 | TASK [tox : Run tox] 2025-12-25 00:19:20.339012 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-25 00:19:20.940529 | debian-bookworm | ............... 2025-12-25 00:19:20.940567 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-25 00:19:20.940580 | debian-bookworm | Ran 15 tests in 0.186s 2025-12-25 00:19:20.940585 | debian-bookworm | 2025-12-25 00:19:20.940592 | debian-bookworm | OK 2025-12-25 00:19:21.004457 | debian-bookworm | test: OK (0.70=setup[0.04]+cmd[0.66] seconds) 2025-12-25 00:19:21.004513 | debian-bookworm | congratulations :) (0.73 seconds) 2025-12-25 00:19:21.311351 | debian-bookworm | ok: Runtime: 0:00:00.985958 2025-12-25 00:19:21.327177 | 2025-12-25 00:19:21.327404 | TASK [tox : Look for output] 2025-12-25 00:19:21.727196 | debian-bookworm | ok 2025-12-25 00:19:21.748201 | 2025-12-25 00:19:21.748389 | TASK [tox : Return file comments to Zuul] 2025-12-25 00:19:21.793823 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:21.812442 | 2025-12-25 00:19:21.812599 | PLAY RECAP 2025-12-25 00:19:21.812693 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-25 00:19:21.812735 | 2025-12-25 00:19:21.959700 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-25 00:19:21.960871 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-25 00:19:22.763458 | 2025-12-25 00:19:22.763641 | PLAY [all] 2025-12-25 00:19:22.779978 | 2025-12-25 00:19:22.780143 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-25 00:19:22.835997 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:22.849202 | 2025-12-25 00:19:22.849424 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-25 00:19:22.904227 | debian-bookworm | ok 2025-12-25 00:19:22.913846 | 2025-12-25 00:19:22.914029 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-25 00:19:23.535298 | debian-bookworm -> localhost | changed 2025-12-25 00:19:23.543504 | 2025-12-25 00:19:23.543642 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-25 00:19:23.599355 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:23.611700 | 2025-12-25 00:19:23.611902 | TASK [fetch-tox-output : Set envlist fact] 2025-12-25 00:19:23.670381 | debian-bookworm | ok 2025-12-25 00:19:23.680268 | 2025-12-25 00:19:23.680426 | TASK [fetch-tox-output : Get tox version] 2025-12-25 00:19:24.396890 | 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-12-25 00:19:24.415705 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-25 00:19:24.713777 | debian-bookworm | ok: Runtime: 0:00:00.314030 2025-12-25 00:19:24.719647 | 2025-12-25 00:19:24.719760 | TASK [fetch-tox-output : Find all default environments] 2025-12-25 00:19:25.251135 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:25.257296 | 2025-12-25 00:19:25.257518 | TASK [fetch-tox-output : Set envlist fact] 2025-12-25 00:19:25.301955 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:25.311003 | 2025-12-25 00:19:25.311179 | TASK [fetch-tox-output : Find all default environments] 2025-12-25 00:19:25.845857 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:25.858894 | 2025-12-25 00:19:25.859112 | TASK [fetch-tox-output : Set envlist fact] 2025-12-25 00:19:25.893337 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:25.900757 | 2025-12-25 00:19:25.900973 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-25 00:19:25.946607 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:25.964688 | 2025-12-25 00:19:25.964873 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-25 00:19:26.863991 | debian-bookworm | changed: 2025-12-25 00:19:26.864251 | debian-bookworm | created directory /var/lib/zuul/builds/40cfbc412533446cbc3046fdf0e357db/work/logs/tox/test 2025-12-25 00:19:26.864297 | debian-bookworm | cd+++++++++ ./ 2025-12-25 00:19:26.864322 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-25 00:19:26.864355 | 2025-12-25 00:19:26.872460 | 2025-12-25 00:19:26.872576 | PLAY RECAP 2025-12-25 00:19:26.872632 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-25 00:19:26.872657 | 2025-12-25 00:19:27.036628 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-25 00:19:27.039418 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-25 00:19:27.857375 | 2025-12-25 00:19:27.857565 | PLAY [all] 2025-12-25 00:19:27.880711 | 2025-12-25 00:19:27.880900 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-25 00:19:28.113473 | debian-bookworm | changed: non-zero return code 2025-12-25 00:19:28.123191 | 2025-12-25 00:19:28.123398 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-25 00:19:28.149565 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:28.156657 | 2025-12-25 00:19:28.156845 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-25 00:19:28.188995 | 2025-12-25 00:19:28.189238 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-25 00:19:28.221781 | 2025-12-25 00:19:28.222009 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-25 00:19:28.238625 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:28.252740 | 2025-12-25 00:19:28.252970 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-25 00:19:28.290611 | 2025-12-25 00:19:28.291110 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-25 00:19:28.316842 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:28.323605 | 2025-12-25 00:19:28.323751 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-25 00:19:28.348171 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:28.362779 | 2025-12-25 00:19:28.363065 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-25 00:19:28.389439 | debian-bookworm | skipping: Conditional result was False 2025-12-25 00:19:28.401685 | 2025-12-25 00:19:28.401862 | PLAY RECAP 2025-12-25 00:19:28.401939 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-25 00:19:28.401973 | 2025-12-25 00:19:28.551395 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-25 00:19:28.552560 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-25 00:19:29.378155 | 2025-12-25 00:19:29.378346 | PLAY [Base post] 2025-12-25 00:19:29.393639 | 2025-12-25 00:19:29.393846 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-25 00:19:29.977411 | debian-bookworm | changed 2025-12-25 00:19:29.985577 | 2025-12-25 00:19:29.985729 | PLAY RECAP 2025-12-25 00:19:29.985869 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-25 00:19:29.985940 | 2025-12-25 00:19:30.135490 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-25 00:19:30.138105 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-25 00:19:31.164661 | 2025-12-25 00:19:31.164957 | PLAY [Base post-logs] 2025-12-25 00:19:31.176702 | 2025-12-25 00:19:31.176894 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-25 00:19:31.706201 | localhost | changed 2025-12-25 00:19:31.724968 | 2025-12-25 00:19:31.725190 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-25 00:19:31.764748 | localhost | ok 2025-12-25 00:19:31.772914 | 2025-12-25 00:19:31.773160 | TASK [Set zuul-log-path fact] 2025-12-25 00:19:31.802579 | localhost | ok 2025-12-25 00:19:31.819436 | 2025-12-25 00:19:31.819622 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-25 00:19:31.845936 | localhost | ok 2025-12-25 00:19:31.849092 | 2025-12-25 00:19:31.849206 | TASK [upload-logs : Create log directories] 2025-12-25 00:19:32.387271 | localhost | changed 2025-12-25 00:19:32.390668 | 2025-12-25 00:19:32.390897 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-25 00:19:32.912955 | localhost -> localhost | ok: Runtime: 0:00:00.007976 2025-12-25 00:19:32.919764 | 2025-12-25 00:19:32.920009 | TASK [upload-logs : Upload logs to log server] 2025-12-25 00:19:33.519737 | localhost | Output suppressed because no_log was given 2025-12-25 00:19:33.522706 | 2025-12-25 00:19:33.522902 | LOOP [upload-logs : Compress console log and json output] 2025-12-25 00:19:33.585338 | localhost | skipping: Conditional result was False 2025-12-25 00:19:33.590863 | localhost | skipping: Conditional result was False 2025-12-25 00:19:33.605214 | 2025-12-25 00:19:33.605463 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-25 00:19:33.665431 | localhost | skipping: Conditional result was False 2025-12-25 00:19:33.666234 | 2025-12-25 00:19:33.669566 | localhost | skipping: Conditional result was False 2025-12-25 00:19:33.677654 | 2025-12-25 00:19:33.677968 | LOOP [upload-logs : Upload console log and json output]