2026-05-18 16:55:53.471556 | Job console starting 2026-05-18 16:55:53.481911 | Updating git repos 2026-05-18 16:55:53.512883 | Cloning repos into workspace 2026-05-18 16:55:53.579912 | Restoring repo states 2026-05-18 16:55:53.634214 | Merging changes 2026-05-18 16:55:54.378635 | Checking out repos 2026-05-18 16:55:54.430770 | Preparing playbooks 2026-05-18 16:55:55.624885 | Running Ansible setup 2026-05-18 16:56:02.508155 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 16:56:04.067522 | 2026-05-18 16:56:04.067684 | PLAY [Base pre] 2026-05-18 16:56:04.083482 | 2026-05-18 16:56:04.083621 | TASK [Setup log path fact] 2026-05-18 16:56:04.122133 | debian-bookworm | ok 2026-05-18 16:56:04.163846 | 2026-05-18 16:56:04.163987 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 16:56:04.206321 | debian-bookworm | ok 2026-05-18 16:56:04.228287 | 2026-05-18 16:56:04.228405 | TASK [emit-job-header : Print job information] 2026-05-18 16:56:04.288334 | # Job Information 2026-05-18 16:56:04.288504 | Ansible Version: 2.16.14 2026-05-18 16:56:04.288541 | Job: tox 2026-05-18 16:56:04.288587 | Pipeline: check 2026-05-18 16:56:04.288611 | Executor: 521e9411259a 2026-05-18 16:56:04.288632 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1194 2026-05-18 16:56:04.288655 | Event ID: 4e354c60-52da-11f1-8b17-ccfbba37ca4d 2026-05-18 16:56:04.297443 | 2026-05-18 16:56:04.297592 | LOOP [emit-job-header : Print node information] 2026-05-18 16:56:04.469882 | debian-bookworm | ok: 2026-05-18 16:56:04.470102 | debian-bookworm | # Node Information 2026-05-18 16:56:04.470138 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 16:56:04.470163 | debian-bookworm | Hostname: debian 2026-05-18 16:56:04.470185 | debian-bookworm | Username: zuul 2026-05-18 16:56:04.470206 | debian-bookworm | Distro: Debian 12.14 2026-05-18 16:56:04.470229 | debian-bookworm | Provider: regiocloud-a 2026-05-18 16:56:04.470251 | debian-bookworm | Region: 2026-05-18 16:56:04.470272 | debian-bookworm | Label: debian-bookworm 2026-05-18 16:56:04.470292 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 16:56:04.470312 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea8:2fe2 2026-05-18 16:56:04.497068 | 2026-05-18 16:56:04.497175 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 16:56:05.294012 | debian-bookworm -> localhost | changed 2026-05-18 16:56:05.300067 | 2026-05-18 16:56:05.300160 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 16:56:07.034973 | debian-bookworm -> localhost | changed 2026-05-18 16:56:07.053631 | 2026-05-18 16:56:07.053739 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 16:56:07.522794 | debian-bookworm -> localhost | ok 2026-05-18 16:56:07.527911 | 2026-05-18 16:56:07.528007 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 16:56:07.567155 | debian-bookworm | ok 2026-05-18 16:56:07.588543 | debian-bookworm | included: /var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 16:56:07.599864 | 2026-05-18 16:56:07.599962 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 16:56:09.771410 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 16:56:09.771622 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/work/28be24182f1e48d69737a4a7c81ff8a0_id_rsa 2026-05-18 16:56:09.771657 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/work/28be24182f1e48d69737a4a7c81ff8a0_id_rsa.pub 2026-05-18 16:56:09.771680 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 16:56:09.771699 | debian-bookworm -> localhost | SHA256:11s6/2TE65VCzfzUIJIyIeWV4sLdo5b5D4itiaoLSLM zuul-build-sshkey 2026-05-18 16:56:09.771718 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 16:56:09.771745 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 16:56:09.771763 | debian-bookworm -> localhost | | ..o .. | 2026-05-18 16:56:09.771781 | debian-bookworm -> localhost | | o.o.. | 2026-05-18 16:56:09.771798 | debian-bookworm -> localhost | | . o+oo . . | 2026-05-18 16:56:09.771814 | debian-bookworm -> localhost | | o oooo .+o.| 2026-05-18 16:56:09.771831 | debian-bookworm -> localhost | | o .S+.....+=| 2026-05-18 16:56:09.771850 | debian-bookworm -> localhost | |o o o=o .+ o+| 2026-05-18 16:56:09.771868 | debian-bookworm -> localhost | |oE ..o.. +. o=| 2026-05-18 16:56:09.771884 | debian-bookworm -> localhost | |. . o .. ooo.| 2026-05-18 16:56:09.771900 | debian-bookworm -> localhost | |oo... o .. .o.| 2026-05-18 16:56:09.771917 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 16:56:09.771959 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.042413 2026-05-18 16:56:09.777042 | 2026-05-18 16:56:09.777131 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 16:56:09.804385 | debian-bookworm | ok 2026-05-18 16:56:09.811711 | debian-bookworm | included: /var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 16:56:09.823446 | 2026-05-18 16:56:09.823541 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 16:56:09.857520 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:56:09.862384 | 2026-05-18 16:56:09.862479 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 16:56:10.782300 | debian-bookworm | changed 2026-05-18 16:56:10.787041 | 2026-05-18 16:56:10.787124 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 16:56:11.070453 | debian-bookworm | ok 2026-05-18 16:56:11.088091 | 2026-05-18 16:56:11.088195 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 16:56:11.882500 | debian-bookworm | changed 2026-05-18 16:56:11.888064 | 2026-05-18 16:56:11.888153 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 16:56:12.690173 | debian-bookworm | changed 2026-05-18 16:56:12.695377 | 2026-05-18 16:56:12.695460 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 16:56:12.729700 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:56:12.736033 | 2026-05-18 16:56:12.736136 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 16:56:14.018955 | debian-bookworm -> localhost | changed 2026-05-18 16:56:14.036577 | 2026-05-18 16:56:14.037050 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 16:56:14.617752 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/work/28be24182f1e48d69737a4a7c81ff8a0_id_rsa (zuul-build-sshkey) 2026-05-18 16:56:14.617967 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011827 2026-05-18 16:56:14.624267 | 2026-05-18 16:56:14.624360 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 16:56:15.078562 | debian-bookworm | ok 2026-05-18 16:56:15.094253 | 2026-05-18 16:56:15.094376 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 16:56:15.131980 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:56:15.156278 | 2026-05-18 16:56:15.156393 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 16:56:15.617114 | debian-bookworm | ok 2026-05-18 16:56:15.636051 | 2026-05-18 16:56:15.636177 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 16:56:15.683738 | debian-bookworm | ok 2026-05-18 16:56:15.697697 | 2026-05-18 16:56:15.697818 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 16:56:16.117839 | debian-bookworm -> localhost | ok 2026-05-18 16:56:16.124696 | 2026-05-18 16:56:16.124795 | TASK [validate-host : Collect information about the host] 2026-05-18 16:56:17.643815 | debian-bookworm | ok 2026-05-18 16:56:17.663504 | 2026-05-18 16:56:17.666219 | TASK [validate-host : Sanitize hostname] 2026-05-18 16:56:17.823958 | debian-bookworm | ok 2026-05-18 16:56:17.867217 | 2026-05-18 16:56:17.867320 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 16:56:18.932496 | debian-bookworm -> localhost | changed 2026-05-18 16:56:18.938171 | 2026-05-18 16:56:18.938270 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 16:56:19.531563 | debian-bookworm | ok 2026-05-18 16:56:19.536049 | 2026-05-18 16:56:19.536140 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 16:56:20.545576 | debian-bookworm -> localhost | changed 2026-05-18 16:56:20.554041 | 2026-05-18 16:56:20.554137 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 16:56:20.861929 | debian-bookworm | ok 2026-05-18 16:56:20.867107 | 2026-05-18 16:56:20.867316 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 16:56:22.523735 | debian-bookworm | changed: 2026-05-18 16:56:22.523938 | debian-bookworm | cd+++++++++ src/ 2026-05-18 16:56:22.523993 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 16:56:22.524022 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 16:56:22.524046 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-18 16:56:22.524068 | debian-bookworm | =7.0.3 2026-05-18 16:56:50.535382 | debian-bookworm | Downloading cachetools-7.1.2-py3-none-any.whl (16 kB) 2026-05-18 16:56:50.577636 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-18 16:56:50.590856 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-18 16:56:50.664212 | debian-bookworm | Collecting filelock>=3.25 2026-05-18 16:56:50.676277 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-18 16:56:50.728405 | debian-bookworm | Collecting packaging>=26 2026-05-18 16:56:50.740154 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-18 16:56:50.750452 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 13.0 MB/s eta 0:00:00 2026-05-18 16:56:50.809907 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-18 16:56:50.821498 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-18 16:56:50.860958 | debian-bookworm | Collecting pluggy>=1.6 2026-05-18 16:56:50.872776 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-18 16:56:50.913229 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-18 16:56:50.925455 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-18 16:56:50.957034 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-18 16:56:50.968364 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-18 16:56:51.012216 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-18 16:56:51.024626 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-18 16:56:51.166398 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-18 16:56:51.178150 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-18 16:56:51.476628 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 25.8 MB/s eta 0:00:00 2026-05-18 16:56:51.579160 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-18 16:56:51.592487 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-18 16:56:51.617191 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.8 MB/s eta 0:00:00 2026-05-18 16:56:51.734206 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-18 16:56:52.574889 | debian-bookworm | Successfully installed cachetools-7.1.2 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.9.6 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.3.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-18 16:56:52.851817 | debian-bookworm | ok: Runtime: 0:00:03.107560 2026-05-18 16:56:52.858324 | 2026-05-18 16:56:52.858454 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-18 16:56:52.937295 | debian-bookworm | ok 2026-05-18 16:56:52.952514 | 2026-05-18 16:56:52.954235 | TASK [ensure-tox : Output tox version] 2026-05-18 16:56:53.580651 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-18 16:56:53.599974 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 16:56:54.014160 | debian-bookworm | ok: Runtime: 0:00:00.346775 2026-05-18 16:56:54.036349 | 2026-05-18 16:56:54.036497 | TASK [ensure-tox : Make global symlink] 2026-05-18 16:56:54.087451 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:56:54.110309 | 2026-05-18 16:56:54.110439 | PLAY RECAP 2026-05-18 16:56:54.110497 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-18 16:56:54.110522 | 2026-05-18 16:56:54.507545 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-18 16:56:54.508782 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-18 16:56:55.892144 | 2026-05-18 16:56:55.892327 | PLAY [all] 2026-05-18 16:56:55.916917 | 2026-05-18 16:56:55.917100 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-18 16:56:56.514942 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-18 16:56:56.955339 | debian-bookworm | ok: Runtime: 0:00:00.028387 2026-05-18 16:56:56.962803 | 2026-05-18 16:56:56.962961 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-18 16:56:57.537874 | debian-bookworm | ok: Runtime: 0:00:00.006996 2026-05-18 16:56:57.543557 | 2026-05-18 16:56:57.543657 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-18 16:56:57.896556 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-18 16:56:57.898366 | debian-bookworm | sudo: a password is required 2026-05-18 16:56:58.103655 | debian-bookworm | ok: Runtime: 0:00:00.019222 2026-05-18 16:56:58.113248 | 2026-05-18 16:56:58.113348 | TASK [tox : Check to see if the constraints file exists] 2026-05-18 16:56:58.157187 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:56:58.163115 | 2026-05-18 16:56:58.163225 | TASK [tox : Fail if constraints file is missing] 2026-05-18 16:56:58.236845 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:56:58.243127 | 2026-05-18 16:56:58.243244 | TASK [tox : Record file location] 2026-05-18 16:56:58.291299 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:56:58.297683 | 2026-05-18 16:56:58.297811 | TASK [tox : Install tox siblings] 2026-05-18 16:56:58.336887 | debian-bookworm | ok 2026-05-18 16:56:58.345533 | debian-bookworm | included: /var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-18 16:56:58.364070 | 2026-05-18 16:56:58.364185 | TASK [tox : Run tox without tests] 2026-05-18 16:56:59.662533 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-18 16:57:12.897251 | debian-bookworm | test: OK (13.92 seconds) 2026-05-18 16:57:12.897331 | debian-bookworm | congratulations :) (13.95 seconds) 2026-05-18 16:57:13.425908 | debian-bookworm | ok: Runtime: 0:00:14.261282 2026-05-18 16:57:13.433489 | 2026-05-18 16:57:13.433596 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-18 16:57:13.826897 | debian-bookworm | changed 2026-05-18 16:57:13.832034 | 2026-05-18 16:57:13.832133 | TASK [tox : Detect tox version] 2026-05-18 16:57:14.403619 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 16:57:14.859483 | debian-bookworm | ok: Runtime: 0:00:00.333731 2026-05-18 16:57:14.866453 | 2026-05-18 16:57:14.866535 | TASK [tox : Get tox envlist config] 2026-05-18 16:57:15.895373 | debian-bookworm | ok: Runtime: 0:00:00.357276 2026-05-18 16:57:15.900058 | 2026-05-18 16:57:15.900143 | TASK [tox : Install any sibling python packages] 2026-05-18 16:57:16.356063 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-18 16:57:16.367145 | 2026-05-18 16:57:16.367245 | TASK [tox : Remove tempfile] 2026-05-18 16:57:16.876111 | debian-bookworm | changed 2026-05-18 16:57:16.882383 | 2026-05-18 16:57:16.882474 | TASK [tox : Emit tox command] 2026-05-18 16:57:16.910127 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-18 16:57:16.915967 | 2026-05-18 16:57:16.916072 | TASK [tox : Run tox] 2026-05-18 16:57:17.538838 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-18 16:57:18.282697 | debian-bookworm | ............... 2026-05-18 16:57:18.282745 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-18 16:57:18.282756 | debian-bookworm | Ran 15 tests in 0.169s 2026-05-18 16:57:18.282761 | debian-bookworm | 2026-05-18 16:57:18.282768 | debian-bookworm | OK 2026-05-18 16:57:18.378315 | debian-bookworm | test: OK (0.90=setup[0.06]+cmd[0.84] seconds) 2026-05-18 16:57:18.378377 | debian-bookworm | congratulations :) (0.93 seconds) 2026-05-18 16:57:18.481812 | debian-bookworm | ok: Runtime: 0:00:01.254110 2026-05-18 16:57:18.486607 | 2026-05-18 16:57:18.486689 | TASK [tox : Look for output] 2026-05-18 16:57:18.928509 | debian-bookworm | ok 2026-05-18 16:57:18.946797 | 2026-05-18 16:57:18.946953 | TASK [tox : Return file comments to Zuul] 2026-05-18 16:57:18.974711 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:18.987973 | 2026-05-18 16:57:18.988066 | PLAY RECAP 2026-05-18 16:57:18.988118 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-18 16:57:18.988144 | 2026-05-18 16:57:19.117323 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-18 16:57:19.118307 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-18 16:57:19.894369 | 2026-05-18 16:57:19.894495 | PLAY [all] 2026-05-18 16:57:19.906352 | 2026-05-18 16:57:19.906489 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-18 16:57:19.939675 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:19.944203 | 2026-05-18 16:57:19.944302 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-18 16:57:19.970553 | debian-bookworm | ok 2026-05-18 16:57:19.974581 | 2026-05-18 16:57:19.974667 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-18 16:57:20.405208 | debian-bookworm -> localhost | changed 2026-05-18 16:57:20.413182 | 2026-05-18 16:57:20.413287 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-18 16:57:20.506988 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:20.513911 | 2026-05-18 16:57:20.514024 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 16:57:20.592915 | debian-bookworm | ok 2026-05-18 16:57:20.607285 | 2026-05-18 16:57:20.607392 | TASK [fetch-tox-output : Get tox version] 2026-05-18 16:57:21.456135 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-18 16:57:21.473715 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 16:57:21.672850 | debian-bookworm | ok: Runtime: 0:00:00.325892 2026-05-18 16:57:21.678103 | 2026-05-18 16:57:21.678197 | TASK [fetch-tox-output : Find all default environments] 2026-05-18 16:57:22.209554 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:22.217787 | 2026-05-18 16:57:22.217936 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 16:57:22.251721 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:22.263869 | 2026-05-18 16:57:22.263978 | TASK [fetch-tox-output : Find all default environments] 2026-05-18 16:57:22.794111 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:22.799915 | 2026-05-18 16:57:22.800025 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 16:57:22.843206 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:22.850064 | 2026-05-18 16:57:22.850166 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-18 16:57:22.891135 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:22.899682 | 2026-05-18 16:57:22.899801 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-18 16:57:23.716130 | debian-bookworm | changed: 2026-05-18 16:57:23.716321 | debian-bookworm | created directory /var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/work/logs/tox/test 2026-05-18 16:57:23.716362 | debian-bookworm | cd+++++++++ ./ 2026-05-18 16:57:23.716392 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-18 16:57:23.716428 | 2026-05-18 16:57:23.749507 | 2026-05-18 16:57:23.749599 | PLAY RECAP 2026-05-18 16:57:23.749655 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-18 16:57:23.749682 | 2026-05-18 16:57:23.847379 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-18 16:57:23.848360 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-18 16:57:24.598204 | 2026-05-18 16:57:24.598395 | PLAY [all] 2026-05-18 16:57:24.640558 | 2026-05-18 16:57:24.640730 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-18 16:57:24.888391 | debian-bookworm | changed: non-zero return code 2026-05-18 16:57:24.894577 | 2026-05-18 16:57:24.894725 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-18 16:57:24.919279 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:24.925501 | 2026-05-18 16:57:24.925630 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-18 16:57:24.956525 | 2026-05-18 16:57:24.956750 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-18 16:57:24.989845 | 2026-05-18 16:57:24.990057 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-18 16:57:25.014049 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:25.021296 | 2026-05-18 16:57:25.021428 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-18 16:57:25.072777 | 2026-05-18 16:57:25.072998 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-18 16:57:25.097025 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:25.103473 | 2026-05-18 16:57:25.103609 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-18 16:57:25.127419 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:25.133568 | 2026-05-18 16:57:25.133681 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-18 16:57:25.160368 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:25.169528 | 2026-05-18 16:57:25.169640 | PLAY RECAP 2026-05-18 16:57:25.169695 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-18 16:57:25.169719 | 2026-05-18 16:57:25.326363 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-18 16:57:25.327514 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 16:57:26.179544 | 2026-05-18 16:57:26.179732 | PLAY [Base post-fetch] 2026-05-18 16:57:26.207918 | 2026-05-18 16:57:26.208104 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-18 16:57:26.273822 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:57:26.280354 | 2026-05-18 16:57:26.280638 | TASK [fetch-output : Set log path for single node] 2026-05-18 16:57:26.333632 | debian-bookworm | ok 2026-05-18 16:57:26.341603 | 2026-05-18 16:57:26.341787 | LOOP [fetch-output : Ensure local output dirs] 2026-05-18 16:57:26.909555 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/work/logs" 2026-05-18 16:57:27.193891 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/work/artifacts" 2026-05-18 16:57:27.536958 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/28be24182f1e48d69737a4a7c81ff8a0/work/docs" 2026-05-18 16:57:27.555838 | 2026-05-18 16:57:27.555981 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 16:57:28.411075 | debian-bookworm | changed: 2026-05-18 16:57:28.411465 | debian-bookworm | .d..t...... ./ 2026-05-18 16:57:28.411505 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 16:57:28.411539 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 16:57:28.411569 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 16:57:28.412254 | debian-bookworm | changed: All items complete 2026-05-18 16:57:28.412833 | 2026-05-18 16:57:29.203969 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 16:57:29.797783 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 16:57:29.825453 | 2026-05-18 16:57:29.825599 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 16:57:30.471598 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.026425 2026-05-18 16:57:30.905307 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007965 2026-05-18 16:57:30.928007 | 2026-05-18 16:57:30.928133 | PLAY RECAP 2026-05-18 16:57:30.928192 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-18 16:57:30.928220 | 2026-05-18 16:57:31.246013 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 16:57:31.248259 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 16:57:32.105003 | 2026-05-18 16:57:32.105179 | PLAY [Base post] 2026-05-18 16:57:32.119330 | 2026-05-18 16:57:32.119487 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 16:57:32.761230 | debian-bookworm | changed 2026-05-18 16:57:32.771135 | 2026-05-18 16:57:32.771321 | PLAY RECAP 2026-05-18 16:57:32.771389 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 16:57:32.771451 | 2026-05-18 16:57:33.021250 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 16:57:33.024047 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 16:57:34.084954 | 2026-05-18 16:57:34.085214 | PLAY [Base post-logs] 2026-05-18 16:57:34.107928 | 2026-05-18 16:57:34.108112 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 16:57:34.952276 | localhost | changed 2026-05-18 16:57:34.976262 | 2026-05-18 16:57:34.976729 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 16:57:35.011700 | localhost | ok 2026-05-18 16:57:35.024913 | 2026-05-18 16:57:35.026585 | TASK [Set zuul-log-path fact] 2026-05-18 16:57:35.082956 | localhost | ok 2026-05-18 16:57:35.104674 | 2026-05-18 16:57:35.104885 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 16:57:35.188295 | localhost | ok 2026-05-18 16:57:35.195747 | 2026-05-18 16:57:35.197116 | TASK [upload-logs : Create log directories] 2026-05-18 16:57:35.971116 | localhost | changed 2026-05-18 16:57:35.996614 | 2026-05-18 16:57:35.996849 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 16:57:36.763390 | localhost -> localhost | ok: Runtime: 0:00:00.025412 2026-05-18 16:57:36.768463 | 2026-05-18 16:57:36.768594 | TASK [upload-logs : Upload logs to log server] 2026-05-18 16:57:37.486908 | localhost | Output suppressed because no_log was given 2026-05-18 16:57:37.488820 | 2026-05-18 16:57:37.488923 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 16:57:37.542645 | localhost | skipping: Conditional result was False 2026-05-18 16:57:37.549487 | localhost | skipping: Conditional result was False 2026-05-18 16:57:37.558033 | 2026-05-18 16:57:37.558164 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 16:57:37.607335 | localhost | skipping: Conditional result was False 2026-05-18 16:57:37.607637 | 2026-05-18 16:57:37.612534 | localhost | skipping: Conditional result was False 2026-05-18 16:57:37.616186 | 2026-05-18 16:57:37.616329 | LOOP [upload-logs : Upload console log and json output]