2025-12-19 18:46:26.786524 | Job console starting 2025-12-19 18:46:26.810915 | Updating git repos 2025-12-19 18:46:26.855002 | Cloning repos into workspace 2025-12-19 18:46:26.949496 | Restoring repo states 2025-12-19 18:46:26.958806 | Merging changes 2025-12-19 18:46:27.529869 | Checking out repos 2025-12-19 18:46:27.591545 | Preparing playbooks 2025-12-19 18:46:29.016497 | Running Ansible setup 2025-12-19 18:46:35.592443 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-19 18:46:36.934165 | 2025-12-19 18:46:36.934337 | PLAY [Base pre] 2025-12-19 18:46:36.962537 | 2025-12-19 18:46:36.962701 | TASK [Setup log path fact] 2025-12-19 18:46:37.006622 | debian-bookworm | ok 2025-12-19 18:46:37.038007 | 2025-12-19 18:46:37.038187 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-19 18:46:37.145871 | debian-bookworm | ok 2025-12-19 18:46:37.158889 | 2025-12-19 18:46:37.159060 | TASK [emit-job-header : Print job information] 2025-12-19 18:46:37.209968 | # Job Information 2025-12-19 18:46:37.210165 | Ansible Version: 2.16.14 2025-12-19 18:46:37.210201 | Job: tox 2025-12-19 18:46:37.210235 | Pipeline: check 2025-12-19 18:46:37.210258 | Executor: 521e9411259a 2025-12-19 18:46:37.210280 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1045 2025-12-19 18:46:37.210302 | Event ID: 00759510-dd0b-11f0-8501-9560935b984e 2025-12-19 18:46:37.215713 | 2025-12-19 18:46:37.215849 | LOOP [emit-job-header : Print node information] 2025-12-19 18:46:37.437979 | debian-bookworm | ok: 2025-12-19 18:46:37.438177 | debian-bookworm | # Node Information 2025-12-19 18:46:37.438211 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-19 18:46:37.438236 | debian-bookworm | Hostname: debian 2025-12-19 18:46:37.438258 | debian-bookworm | Username: zuul 2025-12-19 18:46:37.438279 | debian-bookworm | Distro: Debian 12.12 2025-12-19 18:46:37.438303 | debian-bookworm | Provider: regiocloud-a 2025-12-19 18:46:37.438323 | debian-bookworm | Region: 2025-12-19 18:46:37.438344 | debian-bookworm | Label: debian-bookworm 2025-12-19 18:46:37.438364 | debian-bookworm | Product Name: OpenStack Nova 2025-12-19 18:46:37.438427 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef3:cb2c 2025-12-19 18:46:37.457200 | 2025-12-19 18:46:37.459035 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-19 18:46:38.360305 | debian-bookworm -> localhost | changed 2025-12-19 18:46:38.370766 | 2025-12-19 18:46:38.371140 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-19 18:46:40.798354 | debian-bookworm -> localhost | changed 2025-12-19 18:46:40.811107 | 2025-12-19 18:46:40.811253 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-19 18:46:41.427236 | debian-bookworm -> localhost | ok 2025-12-19 18:46:41.438782 | 2025-12-19 18:46:41.445113 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-19 18:46:41.518510 | debian-bookworm | ok 2025-12-19 18:46:41.576499 | debian-bookworm | included: /var/lib/zuul/builds/89a8827367d349eaae92b5624f5d643f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-19 18:46:41.583248 | 2025-12-19 18:46:41.583370 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-19 18:46:43.353214 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-19 18:46:43.353491 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/89a8827367d349eaae92b5624f5d643f/work/89a8827367d349eaae92b5624f5d643f_id_rsa 2025-12-19 18:46:43.353538 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/89a8827367d349eaae92b5624f5d643f/work/89a8827367d349eaae92b5624f5d643f_id_rsa.pub 2025-12-19 18:46:43.353564 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-19 18:46:43.353591 | debian-bookworm -> localhost | SHA256:USReGVm+W7PHV5vT4jZcD+bgsilSzmoulIcZcxLBqSU zuul-build-sshkey 2025-12-19 18:46:43.353613 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-19 18:46:43.353646 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-19 18:46:43.353669 | debian-bookworm -> localhost | | ..o ..==. | 2025-12-19 18:46:43.353690 | debian-bookworm -> localhost | | E = . +o. | 2025-12-19 18:46:43.353710 | debian-bookworm -> localhost | | + . o . | 2025-12-19 18:46:43.353731 | debian-bookworm -> localhost | | . + . . . | 2025-12-19 18:46:43.353752 | debian-bookworm -> localhost | | O S . o .| 2025-12-19 18:46:43.353782 | debian-bookworm -> localhost | | = . . .oo+*| 2025-12-19 18:46:43.353804 | debian-bookworm -> localhost | | . . + ..=o**| 2025-12-19 18:46:43.353824 | debian-bookworm -> localhost | | . o + ....=o+| 2025-12-19 18:46:43.353845 | debian-bookworm -> localhost | | +oo .oo ... | 2025-12-19 18:46:43.353865 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-19 18:46:43.353929 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.863640 2025-12-19 18:46:43.360849 | 2025-12-19 18:46:43.360998 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-19 18:46:43.449045 | debian-bookworm | ok 2025-12-19 18:46:43.467738 | debian-bookworm | included: /var/lib/zuul/builds/89a8827367d349eaae92b5624f5d643f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-19 18:46:43.496465 | 2025-12-19 18:46:43.496610 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-19 18:46:43.531128 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:46:43.537570 | 2025-12-19 18:46:43.537688 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-19 18:46:44.459793 | debian-bookworm | changed 2025-12-19 18:46:44.467098 | 2025-12-19 18:46:44.467240 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-19 18:46:44.728052 | debian-bookworm | ok 2025-12-19 18:46:44.738097 | 2025-12-19 18:46:44.738239 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-19 18:46:45.437713 | debian-bookworm | changed 2025-12-19 18:46:45.454607 | 2025-12-19 18:46:45.454761 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-19 18:46:46.133271 | debian-bookworm | changed 2025-12-19 18:46:46.144619 | 2025-12-19 18:46:46.144770 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-19 18:46:46.172122 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:46:46.178965 | 2025-12-19 18:46:46.179221 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-19 18:46:46.905746 | debian-bookworm -> localhost | changed 2025-12-19 18:46:46.917078 | 2025-12-19 18:46:46.917214 | TASK [add-build-sshkey : Add back temp key] 2025-12-19 18:46:47.474748 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/89a8827367d349eaae92b5624f5d643f/work/89a8827367d349eaae92b5624f5d643f_id_rsa (zuul-build-sshkey) 2025-12-19 18:46:47.475092 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014349 2025-12-19 18:46:47.488617 | 2025-12-19 18:46:47.488750 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-19 18:46:47.966616 | debian-bookworm | ok 2025-12-19 18:46:47.975376 | 2025-12-19 18:46:47.975526 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-19 18:46:48.044547 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:46:48.066087 | 2025-12-19 18:46:48.066240 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-19 18:46:48.676833 | debian-bookworm | ok 2025-12-19 18:46:48.696562 | 2025-12-19 18:46:48.696709 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-19 18:46:48.770533 | debian-bookworm | ok 2025-12-19 18:46:48.779406 | 2025-12-19 18:46:48.779557 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-19 18:46:49.195404 | debian-bookworm -> localhost | ok 2025-12-19 18:46:49.203584 | 2025-12-19 18:46:49.203717 | TASK [validate-host : Collect information about the host] 2025-12-19 18:46:50.435421 | debian-bookworm | ok 2025-12-19 18:46:50.458754 | 2025-12-19 18:46:50.458932 | TASK [validate-host : Sanitize hostname] 2025-12-19 18:46:50.621662 | debian-bookworm | ok 2025-12-19 18:46:50.688218 | 2025-12-19 18:46:50.688505 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-19 18:46:51.635706 | debian-bookworm -> localhost | changed 2025-12-19 18:46:51.641855 | 2025-12-19 18:46:51.641978 | TASK [validate-host : Collect information about zuul worker] 2025-12-19 18:46:52.098408 | debian-bookworm | ok 2025-12-19 18:46:52.119526 | 2025-12-19 18:46:52.119687 | TASK [validate-host : Write out all zuul information for each host] 2025-12-19 18:46:53.314712 | debian-bookworm -> localhost | changed 2025-12-19 18:46:53.335036 | 2025-12-19 18:46:53.335174 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-19 18:46:53.613165 | debian-bookworm | ok 2025-12-19 18:46:53.619343 | 2025-12-19 18:46:53.619473 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-19 18:46:54.663538 | debian-bookworm | changed: 2025-12-19 18:46:54.663765 | debian-bookworm | cd+++++++++ src/ 2025-12-19 18:46:54.663814 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-19 18:46:54.663847 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-19 18:46:54.663870 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-19 18:46:54.663891 | debian-bookworm | =6.2 2025-12-19 18:47:16.933715 | debian-bookworm | Downloading cachetools-6.2.4-py3-none-any.whl (11 kB) 2025-12-19 18:47:16.967418 | debian-bookworm | Collecting chardet>=5.2 2025-12-19 18:47:16.980611 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-19 18:47:17.005744 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 9.3 MB/s eta 0:00:00 2025-12-19 18:47:17.043599 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-19 18:47:17.054989 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-19 18:47:17.102399 | debian-bookworm | Collecting filelock>=3.20 2025-12-19 18:47:17.113484 | debian-bookworm | Downloading filelock-3.20.1-py3-none-any.whl (16 kB) 2025-12-19 18:47:17.154856 | debian-bookworm | Collecting packaging>=25 2025-12-19 18:47:17.165784 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-19 18:47:17.176732 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.8 MB/s eta 0:00:00 2025-12-19 18:47:17.234145 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-19 18:47:17.246472 | debian-bookworm | Downloading platformdirs-4.5.1-py3-none-any.whl (18 kB) 2025-12-19 18:47:17.282459 | debian-bookworm | Collecting pluggy>=1.6 2025-12-19 18:47:17.293405 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-19 18:47:17.330088 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-19 18:47:17.342107 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-19 18:47:17.459010 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-19 18:47:17.471759 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-19 18:47:17.786698 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 19.2 MB/s eta 0:00:00 2025-12-19 18:47:17.882648 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-19 18:47:17.893744 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-19 18:47:17.914816 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 24.8 MB/s eta 0:00:00 2025-12-19 18:47:18.048603 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-19 18:47:18.825330 | 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-19 18:47:18.951671 | debian-bookworm | ok: Runtime: 0:00:02.724468 2025-12-19 18:47:18.961049 | 2025-12-19 18:47:18.961188 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-19 18:47:19.022076 | debian-bookworm | ok 2025-12-19 18:47:19.031270 | 2025-12-19 18:47:19.031468 | TASK [ensure-tox : Output tox version] 2025-12-19 18:47:19.597865 | 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-19 18:47:19.614844 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-19 18:47:20.096508 | debian-bookworm | ok: Runtime: 0:00:00.255141 2025-12-19 18:47:20.102432 | 2025-12-19 18:47:20.102551 | TASK [ensure-tox : Make global symlink] 2025-12-19 18:47:20.146979 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:20.155071 | 2025-12-19 18:47:20.155192 | PLAY RECAP 2025-12-19 18:47:20.155250 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-19 18:47:20.155275 | 2025-12-19 18:47:20.379659 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-19 18:47:20.380800 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-19 18:47:21.475776 | 2025-12-19 18:47:21.475958 | PLAY [all] 2025-12-19 18:47:21.494039 | 2025-12-19 18:47:21.494201 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-19 18:47:22.135025 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-19 18:47:22.575343 | debian-bookworm | ok: Runtime: 0:00:00.024067 2025-12-19 18:47:22.581616 | 2025-12-19 18:47:22.581782 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-19 18:47:23.124136 | debian-bookworm | ok: Runtime: 0:00:00.004468 2025-12-19 18:47:23.131226 | 2025-12-19 18:47:23.131369 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-19 18:47:23.445924 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-19 18:47:23.448151 | debian-bookworm | sudo: a password is required 2025-12-19 18:47:23.666320 | debian-bookworm | ok: Runtime: 0:00:00.020073 2025-12-19 18:47:23.691102 | 2025-12-19 18:47:23.691256 | TASK [tox : Check to see if the constraints file exists] 2025-12-19 18:47:23.727567 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:23.737135 | 2025-12-19 18:47:23.737474 | TASK [tox : Fail if constraints file is missing] 2025-12-19 18:47:23.775598 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:23.785159 | 2025-12-19 18:47:23.785306 | TASK [tox : Record file location] 2025-12-19 18:47:23.812994 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:23.826236 | 2025-12-19 18:47:23.826643 | TASK [tox : Install tox siblings] 2025-12-19 18:47:23.875526 | debian-bookworm | ok 2025-12-19 18:47:23.905497 | debian-bookworm | included: /var/lib/zuul/builds/89a8827367d349eaae92b5624f5d643f/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-19 18:47:23.922353 | 2025-12-19 18:47:23.922607 | TASK [tox : Run tox without tests] 2025-12-19 18:47:25.046411 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-19 18:47:39.594842 | debian-bookworm | test: OK (15.09 seconds) 2025-12-19 18:47:39.594994 | debian-bookworm | congratulations :) (15.12 seconds) 2025-12-19 18:47:39.994246 | debian-bookworm | ok: Runtime: 0:00:15.397235 2025-12-19 18:47:40.008262 | 2025-12-19 18:47:40.008444 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-19 18:47:40.451831 | debian-bookworm | changed 2025-12-19 18:47:40.458791 | 2025-12-19 18:47:40.459020 | TASK [tox : Get tox envlist config] 2025-12-19 18:47:41.490910 | debian-bookworm | ok: Runtime: 0:00:00.344745 2025-12-19 18:47:41.504473 | 2025-12-19 18:47:41.504686 | TASK [tox : Install any sibling python packages] 2025-12-19 18:47:41.979908 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-19 18:47:41.987794 | 2025-12-19 18:47:41.987955 | TASK [tox : Remove tempfile] 2025-12-19 18:47:42.508559 | debian-bookworm | changed 2025-12-19 18:47:42.519899 | 2025-12-19 18:47:42.520128 | TASK [tox : Emit tox command] 2025-12-19 18:47:42.569141 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-19 18:47:42.575957 | 2025-12-19 18:47:42.576104 | TASK [tox : Run tox] 2025-12-19 18:47:43.201738 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-19 18:47:43.831188 | debian-bookworm | ............... 2025-12-19 18:47:43.831244 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-19 18:47:43.831263 | debian-bookworm | Ran 15 tests in 0.185s 2025-12-19 18:47:43.831272 | debian-bookworm | 2025-12-19 18:47:43.831282 | debian-bookworm | OK 2025-12-19 18:47:43.938643 | debian-bookworm | test: OK (0.78=setup[0.04]+cmd[0.73] seconds) 2025-12-19 18:47:43.938743 | debian-bookworm | congratulations :) (0.80 seconds) 2025-12-19 18:47:44.145883 | debian-bookworm | ok: Runtime: 0:00:01.101973 2025-12-19 18:47:44.162801 | 2025-12-19 18:47:44.163004 | TASK [tox : Look for output] 2025-12-19 18:47:44.589170 | debian-bookworm | ok 2025-12-19 18:47:44.609216 | 2025-12-19 18:47:44.609516 | TASK [tox : Return file comments to Zuul] 2025-12-19 18:47:44.654687 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:44.665262 | 2025-12-19 18:47:44.665439 | PLAY RECAP 2025-12-19 18:47:44.665561 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-19 18:47:44.665752 | 2025-12-19 18:47:44.811870 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-19 18:47:44.814932 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-19 18:47:45.585351 | 2025-12-19 18:47:45.585560 | PLAY [all] 2025-12-19 18:47:45.601453 | 2025-12-19 18:47:45.601604 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-19 18:47:45.688779 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:45.701945 | 2025-12-19 18:47:45.702160 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-19 18:47:45.750334 | debian-bookworm | ok 2025-12-19 18:47:45.760083 | 2025-12-19 18:47:45.760300 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-19 18:47:46.274111 | debian-bookworm -> localhost | changed 2025-12-19 18:47:46.289234 | 2025-12-19 18:47:46.289452 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-19 18:47:46.365997 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:46.372468 | 2025-12-19 18:47:46.372617 | TASK [fetch-tox-output : Set envlist fact] 2025-12-19 18:47:46.411496 | debian-bookworm | ok 2025-12-19 18:47:46.419062 | 2025-12-19 18:47:46.419372 | TASK [fetch-tox-output : Get tox version] 2025-12-19 18:47:47.148930 | 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-19 18:47:47.167507 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-19 18:47:47.456224 | debian-bookworm | ok: Runtime: 0:00:00.305455 2025-12-19 18:47:47.469875 | 2025-12-19 18:47:47.470091 | TASK [fetch-tox-output : Find all default environments] 2025-12-19 18:47:48.010257 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:48.016512 | 2025-12-19 18:47:48.016659 | TASK [fetch-tox-output : Set envlist fact] 2025-12-19 18:47:48.061144 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:48.069522 | 2025-12-19 18:47:48.069678 | TASK [fetch-tox-output : Find all default environments] 2025-12-19 18:47:48.605253 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:48.615886 | 2025-12-19 18:47:48.616060 | TASK [fetch-tox-output : Set envlist fact] 2025-12-19 18:47:48.651722 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:48.659546 | 2025-12-19 18:47:48.659696 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-19 18:47:48.707106 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:48.730688 | 2025-12-19 18:47:48.730945 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-19 18:47:49.566166 | debian-bookworm | changed: 2025-12-19 18:47:49.566426 | debian-bookworm | created directory /var/lib/zuul/builds/89a8827367d349eaae92b5624f5d643f/work/logs/tox/test 2025-12-19 18:47:49.566465 | debian-bookworm | cd+++++++++ ./ 2025-12-19 18:47:49.566490 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-19 18:47:49.566520 | 2025-12-19 18:47:49.585205 | 2025-12-19 18:47:49.585350 | PLAY RECAP 2025-12-19 18:47:49.585438 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-19 18:47:49.585471 | 2025-12-19 18:47:49.753868 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-19 18:47:49.755028 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-19 18:47:50.611950 | 2025-12-19 18:47:50.612152 | PLAY [all] 2025-12-19 18:47:50.630191 | 2025-12-19 18:47:50.630369 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-19 18:47:50.844200 | debian-bookworm | changed: non-zero return code 2025-12-19 18:47:50.850291 | 2025-12-19 18:47:50.850497 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-19 18:47:50.875192 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:50.884191 | 2025-12-19 18:47:50.884359 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-19 18:47:50.919152 | 2025-12-19 18:47:50.919532 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-19 18:47:50.972704 | 2025-12-19 18:47:50.973005 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-19 18:47:50.999258 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:51.011591 | 2025-12-19 18:47:51.011786 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-19 18:47:51.043478 | 2025-12-19 18:47:51.043712 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-19 18:47:51.079945 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:51.093955 | 2025-12-19 18:47:51.094114 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-19 18:47:51.130693 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:51.137152 | 2025-12-19 18:47:51.137287 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-19 18:47:51.171405 | debian-bookworm | skipping: Conditional result was False 2025-12-19 18:47:51.179636 | 2025-12-19 18:47:51.179741 | PLAY RECAP 2025-12-19 18:47:51.179794 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-19 18:47:51.179819 | 2025-12-19 18:47:51.376519 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-19 18:47:51.378979 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-19 18:47:52.168048 | 2025-12-19 18:47:52.168232 | PLAY [Base post] 2025-12-19 18:47:52.183576 | 2025-12-19 18:47:52.183725 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-19 18:47:52.780662 | debian-bookworm | changed 2025-12-19 18:47:52.791018 | 2025-12-19 18:47:52.791162 | PLAY RECAP 2025-12-19 18:47:52.791240 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-19 18:47:52.791317 | 2025-12-19 18:47:52.915421 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-19 18:47:52.916556 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-19 18:47:53.732590 | 2025-12-19 18:47:53.732764 | PLAY [Base post-logs] 2025-12-19 18:47:53.743795 | 2025-12-19 18:47:53.743940 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-19 18:47:54.243796 | localhost | changed 2025-12-19 18:47:54.254979 | 2025-12-19 18:47:54.255160 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-19 18:47:54.294679 | localhost | ok 2025-12-19 18:47:54.301537 | 2025-12-19 18:47:54.301941 | TASK [Set zuul-log-path fact] 2025-12-19 18:47:54.320803 | localhost | ok 2025-12-19 18:47:54.334865 | 2025-12-19 18:47:54.335050 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-19 18:47:54.374303 | localhost | ok 2025-12-19 18:47:54.381479 | 2025-12-19 18:47:54.381639 | TASK [upload-logs : Create log directories] 2025-12-19 18:47:54.898416 | localhost | changed 2025-12-19 18:47:54.901282 | 2025-12-19 18:47:54.901418 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-19 18:47:55.444555 | localhost -> localhost | ok: Runtime: 0:00:00.008251 2025-12-19 18:47:55.448962 | 2025-12-19 18:47:55.449089 | TASK [upload-logs : Upload logs to log server] 2025-12-19 18:47:56.082299 | localhost | Output suppressed because no_log was given 2025-12-19 18:47:56.086120 | 2025-12-19 18:47:56.086308 | LOOP [upload-logs : Compress console log and json output] 2025-12-19 18:47:56.145850 | localhost | skipping: Conditional result was False 2025-12-19 18:47:56.150731 | localhost | skipping: Conditional result was False 2025-12-19 18:47:56.160110 | 2025-12-19 18:47:56.160383 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-19 18:47:56.207955 | localhost | skipping: Conditional result was False 2025-12-19 18:47:56.209589 | 2025-12-19 18:47:56.211618 | localhost | skipping: Conditional result was False 2025-12-19 18:47:56.225491 | 2025-12-19 18:47:56.225698 | LOOP [upload-logs : Upload console log and json output]