2025-12-17 20:58:23.660673 | Job console starting 2025-12-17 20:58:23.711805 | Updating git repos 2025-12-17 20:58:23.772561 | Cloning repos into workspace 2025-12-17 20:58:23.839714 | Restoring repo states 2025-12-17 20:58:23.864079 | Merging changes 2025-12-17 20:58:24.469471 | Checking out repos 2025-12-17 20:58:24.529110 | Preparing playbooks 2025-12-17 20:58:25.677260 | Running Ansible setup 2025-12-17 20:58:31.180298 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-17 20:58:32.317103 | 2025-12-17 20:58:32.317276 | PLAY [Base pre] 2025-12-17 20:58:32.354726 | 2025-12-17 20:58:32.354959 | TASK [Setup log path fact] 2025-12-17 20:58:32.394780 | debian-bookworm | ok 2025-12-17 20:58:32.435566 | 2025-12-17 20:58:32.435767 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-17 20:58:32.490657 | debian-bookworm | ok 2025-12-17 20:58:32.513548 | 2025-12-17 20:58:32.513696 | TASK [emit-job-header : Print job information] 2025-12-17 20:58:32.568947 | # Job Information 2025-12-17 20:58:32.569143 | Ansible Version: 2.16.14 2025-12-17 20:58:32.569180 | Job: tox 2025-12-17 20:58:32.569212 | Pipeline: check 2025-12-17 20:58:32.569235 | Executor: 521e9411259a 2025-12-17 20:58:32.569256 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1042 2025-12-17 20:58:32.569278 | Event ID: 195d5710-db8b-11f0-82a3-65a439a476e3 2025-12-17 20:58:32.574720 | 2025-12-17 20:58:32.574883 | LOOP [emit-job-header : Print node information] 2025-12-17 20:58:32.764970 | debian-bookworm | ok: 2025-12-17 20:58:32.765174 | debian-bookworm | # Node Information 2025-12-17 20:58:32.765208 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-17 20:58:32.765233 | debian-bookworm | Hostname: debian 2025-12-17 20:58:32.765255 | debian-bookworm | Username: zuul 2025-12-17 20:58:32.765277 | debian-bookworm | Distro: Debian 12.12 2025-12-17 20:58:32.765300 | debian-bookworm | Provider: regiocloud-a 2025-12-17 20:58:32.765336 | debian-bookworm | Region: 2025-12-17 20:58:32.765358 | debian-bookworm | Label: debian-bookworm 2025-12-17 20:58:32.765383 | debian-bookworm | Product Name: OpenStack Nova 2025-12-17 20:58:32.765403 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec4:cb7d 2025-12-17 20:58:32.809425 | 2025-12-17 20:58:32.809620 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-17 20:58:33.851521 | debian-bookworm -> localhost | changed 2025-12-17 20:58:33.859719 | 2025-12-17 20:58:33.860167 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-17 20:58:36.164814 | debian-bookworm -> localhost | changed 2025-12-17 20:58:36.182189 | 2025-12-17 20:58:36.182338 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-17 20:58:36.864260 | debian-bookworm -> localhost | ok 2025-12-17 20:58:36.875609 | 2025-12-17 20:58:36.875772 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-17 20:58:36.938629 | debian-bookworm | ok 2025-12-17 20:58:36.984244 | debian-bookworm | included: /var/lib/zuul/builds/fdcec113f0e0478fb291ce900fe7ae8b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-17 20:58:37.001201 | 2025-12-17 20:58:37.001342 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-17 20:58:40.330355 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-17 20:58:40.330618 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/fdcec113f0e0478fb291ce900fe7ae8b/work/fdcec113f0e0478fb291ce900fe7ae8b_id_rsa 2025-12-17 20:58:40.330658 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/fdcec113f0e0478fb291ce900fe7ae8b/work/fdcec113f0e0478fb291ce900fe7ae8b_id_rsa.pub 2025-12-17 20:58:40.330684 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-17 20:58:40.330710 | debian-bookworm -> localhost | SHA256:CjPvFFYGuHBO5dJ6RH9Ddc4irY0EbHY1WKI/sQ+nbMY zuul-build-sshkey 2025-12-17 20:58:40.330732 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-17 20:58:40.330797 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-17 20:58:40.330828 | debian-bookworm -> localhost | | o+.. o== . | 2025-12-17 20:58:40.330888 | debian-bookworm -> localhost | | . ++ o++oo = | 2025-12-17 20:58:40.330909 | debian-bookworm -> localhost | | =..+o=.* o o | 2025-12-17 20:58:40.330929 | debian-bookworm -> localhost | | o+ o + O . | 2025-12-17 20:58:40.330949 | debian-bookworm -> localhost | | = + S B o | 2025-12-17 20:58:40.330975 | debian-bookworm -> localhost | | B o o * | 2025-12-17 20:58:40.330996 | debian-bookworm -> localhost | | + E . | 2025-12-17 20:58:40.331015 | debian-bookworm -> localhost | | o o | 2025-12-17 20:58:40.331036 | debian-bookworm -> localhost | | . | 2025-12-17 20:58:40.331055 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-17 20:58:40.331118 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.459935 2025-12-17 20:58:40.344237 | 2025-12-17 20:58:40.344395 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-17 20:58:40.380485 | debian-bookworm | ok 2025-12-17 20:58:40.398675 | debian-bookworm | included: /var/lib/zuul/builds/fdcec113f0e0478fb291ce900fe7ae8b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-17 20:58:40.416620 | 2025-12-17 20:58:40.416816 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-17 20:58:40.441468 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:58:40.447889 | 2025-12-17 20:58:40.448017 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-17 20:58:41.203648 | debian-bookworm | changed 2025-12-17 20:58:41.213374 | 2025-12-17 20:58:41.213508 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-17 20:58:41.473858 | debian-bookworm | ok 2025-12-17 20:58:41.482196 | 2025-12-17 20:58:41.482346 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-17 20:58:42.144315 | debian-bookworm | changed 2025-12-17 20:58:42.157411 | 2025-12-17 20:58:42.157613 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-17 20:58:42.840543 | debian-bookworm | changed 2025-12-17 20:58:42.858258 | 2025-12-17 20:58:42.858536 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-17 20:58:42.904106 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:58:42.915499 | 2025-12-17 20:58:42.915666 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-17 20:58:43.531518 | debian-bookworm -> localhost | changed 2025-12-17 20:58:43.543137 | 2025-12-17 20:58:43.543268 | TASK [add-build-sshkey : Add back temp key] 2025-12-17 20:58:44.006075 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/fdcec113f0e0478fb291ce900fe7ae8b/work/fdcec113f0e0478fb291ce900fe7ae8b_id_rsa (zuul-build-sshkey) 2025-12-17 20:58:44.006343 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.024031 2025-12-17 20:58:44.015315 | 2025-12-17 20:58:44.015458 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-17 20:58:44.466167 | debian-bookworm | ok 2025-12-17 20:58:44.472424 | 2025-12-17 20:58:44.472568 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-17 20:58:44.576084 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:58:44.636087 | 2025-12-17 20:58:44.636245 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-17 20:58:45.210636 | debian-bookworm | ok 2025-12-17 20:58:45.232938 | 2025-12-17 20:58:45.233152 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-17 20:58:45.306696 | debian-bookworm | ok 2025-12-17 20:58:45.315311 | 2025-12-17 20:58:45.315444 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-17 20:58:45.834635 | debian-bookworm -> localhost | ok 2025-12-17 20:58:45.841949 | 2025-12-17 20:58:45.842074 | TASK [validate-host : Collect information about the host] 2025-12-17 20:58:47.049526 | debian-bookworm | ok 2025-12-17 20:58:47.070327 | 2025-12-17 20:58:47.070476 | TASK [validate-host : Sanitize hostname] 2025-12-17 20:58:47.263682 | debian-bookworm | ok 2025-12-17 20:58:47.403895 | 2025-12-17 20:58:47.404051 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-17 20:58:48.480345 | debian-bookworm -> localhost | changed 2025-12-17 20:58:48.488157 | 2025-12-17 20:58:48.488296 | TASK [validate-host : Collect information about zuul worker] 2025-12-17 20:58:49.002045 | debian-bookworm | ok 2025-12-17 20:58:49.013470 | 2025-12-17 20:58:49.013713 | TASK [validate-host : Write out all zuul information for each host] 2025-12-17 20:58:49.940788 | debian-bookworm -> localhost | changed 2025-12-17 20:58:49.963136 | 2025-12-17 20:58:49.963274 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-17 20:58:50.240373 | debian-bookworm | ok 2025-12-17 20:58:50.253965 | 2025-12-17 20:58:50.254107 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-17 20:58:51.300650 | debian-bookworm | changed: 2025-12-17 20:58:51.300936 | debian-bookworm | cd+++++++++ src/ 2025-12-17 20:58:51.300977 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-17 20:58:51.301001 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-17 20:58:51.301023 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-17 20:58:51.301044 | debian-bookworm | =6.2 2025-12-17 20:59:15.933146 | debian-bookworm | Downloading cachetools-6.2.4-py3-none-any.whl (11 kB) 2025-12-17 20:59:15.972570 | debian-bookworm | Collecting chardet>=5.2 2025-12-17 20:59:15.989781 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-17 20:59:16.004521 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 18.0 MB/s eta 0:00:00 2025-12-17 20:59:16.052780 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-17 20:59:16.070378 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-17 20:59:16.147771 | debian-bookworm | Collecting filelock>=3.20 2025-12-17 20:59:16.163270 | debian-bookworm | Downloading filelock-3.20.1-py3-none-any.whl (16 kB) 2025-12-17 20:59:16.226647 | debian-bookworm | Collecting packaging>=25 2025-12-17 20:59:16.242032 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-17 20:59:16.363663 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 941.9 kB/s eta 0:00:00 2025-12-17 20:59:16.432556 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-17 20:59:16.448673 | debian-bookworm | Downloading platformdirs-4.5.1-py3-none-any.whl (18 kB) 2025-12-17 20:59:16.503720 | debian-bookworm | Collecting pluggy>=1.6 2025-12-17 20:59:16.519212 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-17 20:59:16.566291 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-17 20:59:16.586066 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-17 20:59:16.743871 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-17 20:59:16.760681 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-17 20:59:17.112669 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 17.3 MB/s eta 0:00:00 2025-12-17 20:59:17.220708 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-17 20:59:17.236499 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-17 20:59:17.266352 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 17.4 MB/s eta 0:00:00 2025-12-17 20:59:17.417962 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-17 20:59:18.253703 | 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-17 20:59:18.760509 | debian-bookworm | ok: Runtime: 0:00:03.345701 2025-12-17 20:59:18.766657 | 2025-12-17 20:59:18.766804 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-17 20:59:18.824234 | debian-bookworm | ok 2025-12-17 20:59:18.829517 | 2025-12-17 20:59:18.829642 | TASK [ensure-tox : Output tox version] 2025-12-17 20:59:19.363301 | 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-17 20:59:19.382335 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-17 20:59:19.877573 | debian-bookworm | ok: Runtime: 0:00:00.306879 2025-12-17 20:59:19.886525 | 2025-12-17 20:59:19.886661 | TASK [ensure-tox : Make global symlink] 2025-12-17 20:59:19.913860 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:19.921999 | 2025-12-17 20:59:19.922105 | PLAY RECAP 2025-12-17 20:59:19.922162 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-17 20:59:19.922186 | 2025-12-17 20:59:20.143226 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-17 20:59:20.145368 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-17 20:59:21.081567 | 2025-12-17 20:59:21.081845 | PLAY [all] 2025-12-17 20:59:21.104036 | 2025-12-17 20:59:21.104203 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-17 20:59:21.593692 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-17 20:59:21.642523 | debian-bookworm | ok: Runtime: 0:00:00.025481 2025-12-17 20:59:21.648357 | 2025-12-17 20:59:21.648504 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-17 20:59:22.182600 | debian-bookworm | ok: Runtime: 0:00:00.008093 2025-12-17 20:59:22.191941 | 2025-12-17 20:59:22.192091 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-17 20:59:22.503285 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-17 20:59:22.504771 | debian-bookworm | sudo: a password is required 2025-12-17 20:59:22.758526 | debian-bookworm | ok: Runtime: 0:00:00.020482 2025-12-17 20:59:22.776742 | 2025-12-17 20:59:22.776955 | TASK [tox : Check to see if the constraints file exists] 2025-12-17 20:59:22.821498 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:22.829144 | 2025-12-17 20:59:22.829295 | TASK [tox : Fail if constraints file is missing] 2025-12-17 20:59:22.875311 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:22.882568 | 2025-12-17 20:59:22.882709 | TASK [tox : Record file location] 2025-12-17 20:59:22.930659 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:22.945932 | 2025-12-17 20:59:22.946298 | TASK [tox : Install tox siblings] 2025-12-17 20:59:22.983371 | debian-bookworm | ok 2025-12-17 20:59:22.999162 | debian-bookworm | included: /var/lib/zuul/builds/fdcec113f0e0478fb291ce900fe7ae8b/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-17 20:59:23.013322 | 2025-12-17 20:59:23.013480 | TASK [tox : Run tox without tests] 2025-12-17 20:59:24.076381 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-17 20:59:38.318713 | debian-bookworm | test: OK (14.75 seconds) 2025-12-17 20:59:38.318766 | debian-bookworm | congratulations :) (14.78 seconds) 2025-12-17 20:59:38.563681 | debian-bookworm | ok: Runtime: 0:00:15.063541 2025-12-17 20:59:38.583620 | 2025-12-17 20:59:38.583883 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-17 20:59:39.046973 | debian-bookworm | changed 2025-12-17 20:59:39.054243 | 2025-12-17 20:59:39.054405 | TASK [tox : Get tox envlist config] 2025-12-17 20:59:40.089939 | debian-bookworm | ok: Runtime: 0:00:00.326582 2025-12-17 20:59:40.103413 | 2025-12-17 20:59:40.103603 | TASK [tox : Install any sibling python packages] 2025-12-17 20:59:40.559273 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-17 20:59:40.568045 | 2025-12-17 20:59:40.568198 | TASK [tox : Remove tempfile] 2025-12-17 20:59:41.027611 | debian-bookworm | changed 2025-12-17 20:59:41.036355 | 2025-12-17 20:59:41.036535 | TASK [tox : Emit tox command] 2025-12-17 20:59:41.099905 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-17 20:59:41.109421 | 2025-12-17 20:59:41.110017 | TASK [tox : Run tox] 2025-12-17 20:59:41.749861 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-17 20:59:42.369492 | debian-bookworm | ............... 2025-12-17 20:59:42.369549 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-17 20:59:42.369565 | debian-bookworm | Ran 15 tests in 0.167s 2025-12-17 20:59:42.369570 | debian-bookworm | 2025-12-17 20:59:42.369577 | debian-bookworm | OK 2025-12-17 20:59:42.459076 | debian-bookworm | test: OK (0.75=setup[0.04]+cmd[0.71] seconds) 2025-12-17 20:59:42.459137 | debian-bookworm | congratulations :) (0.78 seconds) 2025-12-17 20:59:42.698758 | debian-bookworm | ok: Runtime: 0:00:01.054333 2025-12-17 20:59:42.711034 | 2025-12-17 20:59:42.711190 | TASK [tox : Look for output] 2025-12-17 20:59:43.123038 | debian-bookworm | ok 2025-12-17 20:59:43.142804 | 2025-12-17 20:59:43.142997 | TASK [tox : Return file comments to Zuul] 2025-12-17 20:59:43.188948 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:43.201683 | 2025-12-17 20:59:43.201831 | PLAY RECAP 2025-12-17 20:59:43.201910 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-17 20:59:43.201944 | 2025-12-17 20:59:43.342386 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-17 20:59:43.345210 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-17 20:59:44.183516 | 2025-12-17 20:59:44.183700 | PLAY [all] 2025-12-17 20:59:44.201689 | 2025-12-17 20:59:44.201905 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-17 20:59:44.261283 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:44.276301 | 2025-12-17 20:59:44.276588 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-17 20:59:44.335227 | debian-bookworm | ok 2025-12-17 20:59:44.343266 | 2025-12-17 20:59:44.343412 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-17 20:59:44.855651 | debian-bookworm -> localhost | changed 2025-12-17 20:59:44.864234 | 2025-12-17 20:59:44.864373 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-17 20:59:44.929810 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:44.940235 | 2025-12-17 20:59:44.940429 | TASK [fetch-tox-output : Set envlist fact] 2025-12-17 20:59:44.981673 | debian-bookworm | ok 2025-12-17 20:59:44.987658 | 2025-12-17 20:59:44.987831 | TASK [fetch-tox-output : Get tox version] 2025-12-17 20:59:45.709343 | 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-17 20:59:45.726494 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-17 20:59:46.020333 | debian-bookworm | ok: Runtime: 0:00:00.298171 2025-12-17 20:59:46.035374 | 2025-12-17 20:59:46.035566 | TASK [fetch-tox-output : Find all default environments] 2025-12-17 20:59:46.573211 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:46.588086 | 2025-12-17 20:59:46.588322 | TASK [fetch-tox-output : Set envlist fact] 2025-12-17 20:59:46.616079 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:46.629408 | 2025-12-17 20:59:46.629604 | TASK [fetch-tox-output : Find all default environments] 2025-12-17 20:59:47.165630 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:47.178062 | 2025-12-17 20:59:47.178235 | TASK [fetch-tox-output : Set envlist fact] 2025-12-17 20:59:47.208207 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:47.216367 | 2025-12-17 20:59:47.216525 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-17 20:59:47.273286 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:47.291192 | 2025-12-17 20:59:47.291343 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-17 20:59:48.138287 | debian-bookworm | changed: 2025-12-17 20:59:48.138536 | debian-bookworm | created directory /var/lib/zuul/builds/fdcec113f0e0478fb291ce900fe7ae8b/work/logs/tox/test 2025-12-17 20:59:48.138579 | debian-bookworm | cd+++++++++ ./ 2025-12-17 20:59:48.138609 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-17 20:59:48.138644 | 2025-12-17 20:59:48.162152 | 2025-12-17 20:59:48.162300 | PLAY RECAP 2025-12-17 20:59:48.162392 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-17 20:59:48.162438 | 2025-12-17 20:59:48.324457 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-17 20:59:48.325480 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-17 20:59:49.128727 | 2025-12-17 20:59:49.129135 | PLAY [all] 2025-12-17 20:59:49.148001 | 2025-12-17 20:59:49.148175 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-17 20:59:49.381348 | debian-bookworm | changed: non-zero return code 2025-12-17 20:59:49.391953 | 2025-12-17 20:59:49.392140 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-17 20:59:49.418980 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:49.429454 | 2025-12-17 20:59:49.429633 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-17 20:59:49.461684 | 2025-12-17 20:59:49.461909 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-17 20:59:49.508174 | 2025-12-17 20:59:49.508430 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-17 20:59:49.533269 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:49.539861 | 2025-12-17 20:59:49.539995 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-17 20:59:49.575432 | 2025-12-17 20:59:49.575697 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-17 20:59:49.599992 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:49.606369 | 2025-12-17 20:59:49.606496 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-17 20:59:49.640679 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:49.650381 | 2025-12-17 20:59:49.650583 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-17 20:59:49.686140 | debian-bookworm | skipping: Conditional result was False 2025-12-17 20:59:49.702547 | 2025-12-17 20:59:49.702686 | PLAY RECAP 2025-12-17 20:59:49.702798 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-17 20:59:49.702883 | 2025-12-17 20:59:49.851626 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-17 20:59:49.853481 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-17 20:59:50.665925 | 2025-12-17 20:59:50.666103 | PLAY [Base post] 2025-12-17 20:59:50.688507 | 2025-12-17 20:59:50.688718 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-17 20:59:51.302699 | debian-bookworm | changed 2025-12-17 20:59:51.316410 | 2025-12-17 20:59:51.317658 | PLAY RECAP 2025-12-17 20:59:51.317799 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-17 20:59:51.317899 | 2025-12-17 20:59:51.456349 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-17 20:59:51.459315 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-17 20:59:52.304204 | 2025-12-17 20:59:52.304498 | PLAY [Base post-logs] 2025-12-17 20:59:52.317384 | 2025-12-17 20:59:52.317552 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-17 20:59:52.851148 | localhost | changed 2025-12-17 20:59:52.863112 | 2025-12-17 20:59:52.863327 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-17 20:59:52.902598 | localhost | ok 2025-12-17 20:59:52.910927 | 2025-12-17 20:59:52.911110 | TASK [Set zuul-log-path fact] 2025-12-17 20:59:52.932351 | localhost | ok 2025-12-17 20:59:52.947468 | 2025-12-17 20:59:52.947636 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-17 20:59:52.986013 | localhost | ok 2025-12-17 20:59:52.990173 | 2025-12-17 20:59:52.990306 | TASK [upload-logs : Create log directories] 2025-12-17 20:59:53.523298 | localhost | changed 2025-12-17 20:59:53.528867 | 2025-12-17 20:59:53.529029 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-17 20:59:54.128307 | localhost -> localhost | ok: Runtime: 0:00:00.008496 2025-12-17 20:59:54.137010 | 2025-12-17 20:59:54.137225 | TASK [upload-logs : Upload logs to log server] 2025-12-17 20:59:54.782185 | localhost | Output suppressed because no_log was given 2025-12-17 20:59:54.786106 | 2025-12-17 20:59:54.786299 | LOOP [upload-logs : Compress console log and json output] 2025-12-17 20:59:54.850173 | localhost | skipping: Conditional result was False 2025-12-17 20:59:54.853366 | localhost | skipping: Conditional result was False 2025-12-17 20:59:54.868477 | 2025-12-17 20:59:54.868683 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-17 20:59:54.930013 | localhost | skipping: Conditional result was False 2025-12-17 20:59:54.930324 | 2025-12-17 20:59:54.937318 | localhost | skipping: Conditional result was False 2025-12-17 20:59:54.952097 | 2025-12-17 20:59:54.952367 | LOOP [upload-logs : Upload console log and json output]