2025-10-04 03:09:00.830940 | Job console starting 2025-10-04 03:09:00.841041 | Updating git repos 2025-10-04 03:09:00.880194 | Cloning repos into workspace 2025-10-04 03:09:00.981226 | Restoring repo states 2025-10-04 03:09:01.012497 | Merging changes 2025-10-04 03:09:01.012512 | Checking out repos 2025-10-04 03:09:01.093234 | Preparing playbooks 2025-10-04 03:09:01.860117 | Running Ansible setup 2025-10-04 03:09:07.849987 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-04 03:09:09.132144 | 2025-10-04 03:09:09.132256 | PLAY [Base pre] 2025-10-04 03:09:09.174412 | 2025-10-04 03:09:09.174531 | TASK [Setup log path fact] 2025-10-04 03:09:09.203198 | debian-bookworm | ok 2025-10-04 03:09:09.240262 | 2025-10-04 03:09:09.240379 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-04 03:09:09.311568 | debian-bookworm | ok 2025-10-04 03:09:09.333833 | 2025-10-04 03:09:09.333929 | TASK [emit-job-header : Print job information] 2025-10-04 03:09:09.395243 | # Job Information 2025-10-04 03:09:09.395372 | Ansible Version: 2.16.14 2025-10-04 03:09:09.395401 | Job: tox 2025-10-04 03:09:09.395428 | Pipeline: periodic-daily 2025-10-04 03:09:09.395447 | Executor: 521e9411259a 2025-10-04 03:09:09.395465 | Triggered by: https://github.com/osism/openstack-image-manager 2025-10-04 03:09:09.395483 | Event ID: 102010e914ef45c2ba51bf834bb1edcc 2025-10-04 03:09:09.399362 | 2025-10-04 03:09:09.399438 | LOOP [emit-job-header : Print node information] 2025-10-04 03:09:09.508965 | debian-bookworm | ok: 2025-10-04 03:09:09.509132 | debian-bookworm | # Node Information 2025-10-04 03:09:09.509197 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-04 03:09:09.509224 | debian-bookworm | Hostname: debian 2025-10-04 03:09:09.509243 | debian-bookworm | Username: zuul 2025-10-04 03:09:09.509306 | debian-bookworm | Distro: Debian 12.12 2025-10-04 03:09:09.509332 | debian-bookworm | Provider: regiocloud-a 2025-10-04 03:09:09.509350 | debian-bookworm | Region: 2025-10-04 03:09:09.509368 | debian-bookworm | Label: debian-bookworm 2025-10-04 03:09:09.509384 | debian-bookworm | Product Name: OpenStack Nova 2025-10-04 03:09:09.509400 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe86:5ef6 2025-10-04 03:09:09.538008 | 2025-10-04 03:09:09.538120 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-04 03:09:10.105760 | debian-bookworm -> localhost | changed 2025-10-04 03:09:10.111431 | 2025-10-04 03:09:10.111515 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-04 03:09:11.374200 | debian-bookworm -> localhost | changed 2025-10-04 03:09:11.382659 | 2025-10-04 03:09:11.382762 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-04 03:09:11.783601 | debian-bookworm -> localhost | ok 2025-10-04 03:09:11.788594 | 2025-10-04 03:09:11.788675 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-04 03:09:11.825058 | debian-bookworm | ok 2025-10-04 03:09:11.839346 | debian-bookworm | included: /var/lib/zuul/builds/d7a54b476bb943b79f4d0649ae776b1a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-04 03:09:11.844227 | 2025-10-04 03:09:11.844302 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-04 03:09:12.492022 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-04 03:09:12.492223 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d7a54b476bb943b79f4d0649ae776b1a/work/d7a54b476bb943b79f4d0649ae776b1a_id_rsa 2025-10-04 03:09:12.492259 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d7a54b476bb943b79f4d0649ae776b1a/work/d7a54b476bb943b79f4d0649ae776b1a_id_rsa.pub 2025-10-04 03:09:12.492282 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-04 03:09:12.492304 | debian-bookworm -> localhost | SHA256:5xgyipC7yHj5pVa8CP6o7LVOPLiKKeqdyXcticXLieE zuul-build-sshkey 2025-10-04 03:09:12.492322 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-04 03:09:12.492347 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-04 03:09:12.492366 | debian-bookworm -> localhost | | | 2025-10-04 03:09:12.492384 | debian-bookworm -> localhost | | | 2025-10-04 03:09:12.492401 | debian-bookworm -> localhost | | | 2025-10-04 03:09:12.492417 | debian-bookworm -> localhost | | . | 2025-10-04 03:09:12.492433 | debian-bookworm -> localhost | |o .+ S . | 2025-10-04 03:09:12.492454 | debian-bookworm -> localhost | | o+. oo= = | 2025-10-04 03:09:12.492472 | debian-bookworm -> localhost | |.o.BooB.* . | 2025-10-04 03:09:12.492488 | debian-bookworm -> localhost | |BoX.BE.B . | 2025-10-04 03:09:12.492505 | debian-bookworm -> localhost | |/Oo@= . . | 2025-10-04 03:09:12.492522 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-04 03:09:12.492562 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.233196 2025-10-04 03:09:12.498334 | 2025-10-04 03:09:12.498417 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-04 03:09:12.514489 | debian-bookworm | ok 2025-10-04 03:09:12.522226 | debian-bookworm | included: /var/lib/zuul/builds/d7a54b476bb943b79f4d0649ae776b1a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-04 03:09:12.529813 | 2025-10-04 03:09:12.529895 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-04 03:09:12.543073 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:09:12.549199 | 2025-10-04 03:09:12.549303 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-04 03:09:13.225523 | debian-bookworm | changed 2025-10-04 03:09:13.229968 | 2025-10-04 03:09:13.230043 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-04 03:09:13.524553 | debian-bookworm | ok 2025-10-04 03:09:13.529486 | 2025-10-04 03:09:13.529568 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-04 03:09:14.277321 | debian-bookworm | changed 2025-10-04 03:09:14.283105 | 2025-10-04 03:09:14.283186 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-04 03:09:15.015304 | debian-bookworm | changed 2025-10-04 03:09:15.022725 | 2025-10-04 03:09:15.022806 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-04 03:09:15.068791 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:09:15.074456 | 2025-10-04 03:09:15.074540 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-04 03:09:15.604795 | debian-bookworm -> localhost | changed 2025-10-04 03:09:15.613420 | 2025-10-04 03:09:15.613501 | TASK [add-build-sshkey : Add back temp key] 2025-10-04 03:09:15.908453 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d7a54b476bb943b79f4d0649ae776b1a/work/d7a54b476bb943b79f4d0649ae776b1a_id_rsa (zuul-build-sshkey) 2025-10-04 03:09:15.908663 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013895 2025-10-04 03:09:15.916055 | 2025-10-04 03:09:15.916145 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-04 03:09:16.361051 | debian-bookworm | ok 2025-10-04 03:09:16.365551 | 2025-10-04 03:09:16.365622 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-04 03:09:16.395477 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:09:16.408904 | 2025-10-04 03:09:16.408990 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-04 03:09:16.828877 | debian-bookworm | ok 2025-10-04 03:09:16.840093 | 2025-10-04 03:09:16.840177 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-04 03:09:16.877049 | debian-bookworm | ok 2025-10-04 03:09:16.880999 | 2025-10-04 03:09:16.881069 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-04 03:09:17.174323 | debian-bookworm -> localhost | ok 2025-10-04 03:09:17.179390 | 2025-10-04 03:09:17.179465 | TASK [validate-host : Collect information about the host] 2025-10-04 03:09:18.288131 | debian-bookworm | ok 2025-10-04 03:09:18.305617 | 2025-10-04 03:09:18.305750 | TASK [validate-host : Sanitize hostname] 2025-10-04 03:09:18.431922 | debian-bookworm | ok 2025-10-04 03:09:18.464910 | 2025-10-04 03:09:18.465007 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-04 03:09:19.010279 | debian-bookworm -> localhost | changed 2025-10-04 03:09:19.016307 | 2025-10-04 03:09:19.016406 | TASK [validate-host : Collect information about zuul worker] 2025-10-04 03:09:19.422398 | debian-bookworm | ok 2025-10-04 03:09:19.430275 | 2025-10-04 03:09:19.430410 | TASK [validate-host : Write out all zuul information for each host] 2025-10-04 03:09:19.915713 | debian-bookworm -> localhost | changed 2025-10-04 03:09:19.934238 | 2025-10-04 03:09:19.934356 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-04 03:09:20.211708 | debian-bookworm | ok 2025-10-04 03:09:20.224304 | 2025-10-04 03:09:20.224423 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-04 03:09:21.088146 | debian-bookworm | changed: 2025-10-04 03:09:21.088316 | debian-bookworm | cd+++++++++ src/ 2025-10-04 03:09:21.088350 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-04 03:09:21.088374 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-04 03:09:21.088396 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-04 03:09:21.088416 | debian-bookworm | =6.1 2025-10-04 03:09:43.865932 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-10-04 03:09:43.897275 | debian-bookworm | Collecting chardet>=5.2 2025-10-04 03:09:43.905279 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-04 03:09:43.919282 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 17.9 MB/s eta 0:00:00 2025-10-04 03:09:43.954942 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-04 03:09:43.963045 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-04 03:09:44.013023 | debian-bookworm | Collecting filelock>=3.18 2025-10-04 03:09:44.021400 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-10-04 03:09:44.078274 | debian-bookworm | Collecting packaging>=25 2025-10-04 03:09:44.085949 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-04 03:09:44.097412 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.9 MB/s eta 0:00:00 2025-10-04 03:09:44.153117 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-10-04 03:09:44.161336 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-10-04 03:09:44.211898 | debian-bookworm | Collecting pluggy>=1.6 2025-10-04 03:09:44.219676 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-04 03:09:44.254528 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-04 03:09:44.262451 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-10-04 03:09:44.405449 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-10-04 03:09:44.413593 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-10-04 03:09:44.728221 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 19.4 MB/s eta 0:00:00 2025-10-04 03:09:44.854391 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-04 03:09:44.862735 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-04 03:09:44.894075 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 16.8 MB/s eta 0:00:00 2025-10-04 03:09:45.089486 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-04 03:09:46.069327 | debian-bookworm | Successfully installed cachetools-6.2.0 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.19.1 packaging-25.0 platformdirs-4.4.0 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.30.3 virtualenv-20.34.0 2025-10-04 03:09:46.248324 | debian-bookworm | ok: Runtime: 0:00:03.157107 2025-10-04 03:09:46.256882 | 2025-10-04 03:09:46.256996 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-04 03:09:46.315103 | debian-bookworm | ok 2025-10-04 03:09:46.326760 | 2025-10-04 03:09:46.326913 | TASK [ensure-tox : Output tox version] 2025-10-04 03:09:47.040936 | 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-10-04 03:09:47.059869 | debian-bookworm | 4.30.3 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-04 03:09:47.409985 | debian-bookworm | ok: Runtime: 0:00:00.318512 2025-10-04 03:09:47.416793 | 2025-10-04 03:09:47.416895 | TASK [ensure-tox : Make global symlink] 2025-10-04 03:09:47.470292 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:09:47.480209 | 2025-10-04 03:09:47.480307 | PLAY RECAP 2025-10-04 03:09:47.480366 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-04 03:09:47.480393 | 2025-10-04 03:09:47.700462 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-04 03:09:47.701661 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-04 03:09:49.603618 | 2025-10-04 03:09:49.603742 | PLAY [all] 2025-10-04 03:09:49.637809 | 2025-10-04 03:09:49.637923 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-04 03:09:50.239697 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-04 03:09:50.213335 | debian-bookworm | ok: Runtime: 0:00:00.030946 2025-10-04 03:09:50.218552 | 2025-10-04 03:09:50.218657 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-04 03:09:50.793513 | debian-bookworm | ok: Runtime: 0:00:00.006184 2025-10-04 03:09:50.800500 | 2025-10-04 03:09:50.800637 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-04 03:09:51.230663 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-04 03:09:51.232161 | debian-bookworm | sudo: a password is required 2025-10-04 03:09:51.366957 | debian-bookworm | ok: Runtime: 0:00:00.016678 2025-10-04 03:09:51.376837 | 2025-10-04 03:09:51.376915 | TASK [tox : Check to see if the constraints file exists] 2025-10-04 03:09:51.422087 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:09:51.428651 | 2025-10-04 03:09:51.428738 | TASK [tox : Fail if constraints file is missing] 2025-10-04 03:09:51.483096 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:09:51.488623 | 2025-10-04 03:09:51.488706 | TASK [tox : Record file location] 2025-10-04 03:09:51.521788 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:09:51.527872 | 2025-10-04 03:09:51.528032 | TASK [tox : Install tox siblings] 2025-10-04 03:09:51.588641 | debian-bookworm | ok 2025-10-04 03:09:51.597617 | debian-bookworm | included: /var/lib/zuul/builds/d7a54b476bb943b79f4d0649ae776b1a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-04 03:09:51.603043 | 2025-10-04 03:09:51.603128 | TASK [tox : Run tox without tests] 2025-10-04 03:09:52.683299 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-04 03:10:06.246248 | debian-bookworm | test: OK (13.97 seconds) 2025-10-04 03:10:06.246349 | debian-bookworm | congratulations :) (14.00 seconds) 2025-10-04 03:10:06.650825 | debian-bookworm | ok: Runtime: 0:00:14.264407 2025-10-04 03:10:06.657117 | 2025-10-04 03:10:06.657208 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-04 03:10:07.698769 | debian-bookworm | changed 2025-10-04 03:10:07.707128 | 2025-10-04 03:10:07.707226 | TASK [tox : Get tox envlist config] 2025-10-04 03:10:08.778164 | debian-bookworm | ok: Runtime: 0:00:00.342205 2025-10-04 03:10:08.784771 | 2025-10-04 03:10:08.784932 | TASK [tox : Install any sibling python packages] 2025-10-04 03:10:09.780406 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-04 03:10:09.787384 | 2025-10-04 03:10:09.787499 | TASK [tox : Remove tempfile] 2025-10-04 03:10:10.496969 | debian-bookworm | changed 2025-10-04 03:10:10.502589 | 2025-10-04 03:10:10.502685 | TASK [tox : Emit tox command] 2025-10-04 03:10:10.561558 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-04 03:10:10.566208 | 2025-10-04 03:10:10.566292 | TASK [tox : Run tox] 2025-10-04 03:10:11.315318 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-04 03:10:11.987827 | debian-bookworm | ............... 2025-10-04 03:10:11.987874 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-04 03:10:11.987891 | debian-bookworm | Ran 15 tests in 0.178s 2025-10-04 03:10:11.987897 | debian-bookworm | 2025-10-04 03:10:11.987906 | debian-bookworm | OK 2025-10-04 03:10:12.085733 | debian-bookworm | test: OK (0.81=setup[0.05]+cmd[0.77] seconds) 2025-10-04 03:10:12.085805 | debian-bookworm | congratulations :) (0.84 seconds) 2025-10-04 03:10:12.203195 | debian-bookworm | ok: Runtime: 0:00:01.125247 2025-10-04 03:10:12.207703 | 2025-10-04 03:10:12.207780 | TASK [tox : Look for output] 2025-10-04 03:10:12.783233 | debian-bookworm | ok 2025-10-04 03:10:12.805644 | 2025-10-04 03:10:12.805744 | TASK [tox : Return file comments to Zuul] 2025-10-04 03:10:12.881609 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:12.886636 | 2025-10-04 03:10:12.886705 | PLAY RECAP 2025-10-04 03:10:12.886747 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-04 03:10:12.886767 | 2025-10-04 03:10:13.251427 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-04 03:10:13.252368 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-04 03:10:14.352671 | 2025-10-04 03:10:14.352790 | PLAY [all] 2025-10-04 03:10:14.381998 | 2025-10-04 03:10:14.382111 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-04 03:10:14.445491 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:14.450054 | 2025-10-04 03:10:14.450156 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-04 03:10:14.489325 | debian-bookworm | ok 2025-10-04 03:10:14.493740 | 2025-10-04 03:10:14.493817 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-04 03:10:15.401349 | debian-bookworm -> localhost | changed 2025-10-04 03:10:15.407473 | 2025-10-04 03:10:15.407562 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-04 03:10:15.460373 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:15.464791 | 2025-10-04 03:10:15.464879 | TASK [fetch-tox-output : Set envlist fact] 2025-10-04 03:10:15.511429 | debian-bookworm | ok 2025-10-04 03:10:15.515906 | 2025-10-04 03:10:15.515983 | TASK [fetch-tox-output : Get tox version] 2025-10-04 03:10:16.268857 | 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-10-04 03:10:16.287539 | debian-bookworm | 4.30.3 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-04 03:10:16.553064 | debian-bookworm | ok: Runtime: 0:00:00.310180 2025-10-04 03:10:16.557656 | 2025-10-04 03:10:16.557782 | TASK [fetch-tox-output : Find all default environments] 2025-10-04 03:10:17.114217 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:17.119717 | 2025-10-04 03:10:17.119805 | TASK [fetch-tox-output : Set envlist fact] 2025-10-04 03:10:17.142691 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:17.153160 | 2025-10-04 03:10:17.153252 | TASK [fetch-tox-output : Find all default environments] 2025-10-04 03:10:17.698768 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:17.705003 | 2025-10-04 03:10:17.705135 | TASK [fetch-tox-output : Set envlist fact] 2025-10-04 03:10:17.727844 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:17.733629 | 2025-10-04 03:10:17.733736 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-04 03:10:17.842303 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:17.848923 | 2025-10-04 03:10:17.849015 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-04 03:10:18.833983 | debian-bookworm | changed: 2025-10-04 03:10:18.834200 | debian-bookworm | created directory /var/lib/zuul/builds/d7a54b476bb943b79f4d0649ae776b1a/work/logs/tox/test 2025-10-04 03:10:18.834241 | debian-bookworm | cd+++++++++ ./ 2025-10-04 03:10:18.834267 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-04 03:10:18.834294 | 2025-10-04 03:10:18.840833 | 2025-10-04 03:10:18.840902 | PLAY RECAP 2025-10-04 03:10:18.840950 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-04 03:10:18.840973 | 2025-10-04 03:10:19.019459 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-04 03:10:19.020238 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-04 03:10:19.883900 | 2025-10-04 03:10:19.884016 | PLAY [all] 2025-10-04 03:10:19.902010 | 2025-10-04 03:10:19.902121 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-04 03:10:20.083277 | debian-bookworm | changed: non-zero return code 2025-10-04 03:10:20.088623 | 2025-10-04 03:10:20.088722 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-04 03:10:20.112248 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:20.123873 | 2025-10-04 03:10:20.124027 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-04 03:10:20.161886 | 2025-10-04 03:10:20.162034 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-04 03:10:20.194717 | 2025-10-04 03:10:20.194863 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-04 03:10:20.217161 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:20.221702 | 2025-10-04 03:10:20.221775 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-04 03:10:20.248188 | 2025-10-04 03:10:20.248313 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-04 03:10:20.270494 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:20.274969 | 2025-10-04 03:10:20.275052 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-04 03:10:20.297188 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:20.301810 | 2025-10-04 03:10:20.301882 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-04 03:10:20.314904 | debian-bookworm | skipping: Conditional result was False 2025-10-04 03:10:20.319852 | 2025-10-04 03:10:20.319905 | PLAY RECAP 2025-10-04 03:10:20.319945 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-04 03:10:20.319967 | 2025-10-04 03:10:20.405241 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-04 03:10:20.406051 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-04 03:10:20.948188 | 2025-10-04 03:10:20.948290 | PLAY [Base post] 2025-10-04 03:10:20.963082 | 2025-10-04 03:10:20.963180 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-04 03:10:21.612731 | debian-bookworm | changed 2025-10-04 03:10:21.620502 | 2025-10-04 03:10:21.620621 | PLAY RECAP 2025-10-04 03:10:21.620692 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-04 03:10:21.620765 | 2025-10-04 03:10:21.725206 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-04 03:10:21.726718 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-04 03:10:22.479848 | 2025-10-04 03:10:22.479974 | PLAY [Base post-logs] 2025-10-04 03:10:22.489925 | 2025-10-04 03:10:22.490041 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-04 03:10:23.181384 | localhost | changed 2025-10-04 03:10:23.190578 | 2025-10-04 03:10:23.190691 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-04 03:10:23.215533 | localhost | ok 2025-10-04 03:10:23.218309 | 2025-10-04 03:10:23.218393 | TASK [Set zuul-log-path fact] 2025-10-04 03:10:23.232647 | localhost | ok 2025-10-04 03:10:23.241000 | 2025-10-04 03:10:23.241091 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-04 03:10:23.265666 | localhost | ok 2025-10-04 03:10:23.268813 | 2025-10-04 03:10:23.268919 | TASK [upload-logs : Create log directories] 2025-10-04 03:10:23.777461 | localhost | changed 2025-10-04 03:10:23.783102 | 2025-10-04 03:10:23.783198 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-04 03:10:24.473459 | localhost -> localhost | ok: Runtime: 0:00:00.003604 2025-10-04 03:10:24.476498 | 2025-10-04 03:10:24.476576 | TASK [upload-logs : Upload logs to log server] 2025-10-04 03:10:25.075850 | localhost | Output suppressed because no_log was given 2025-10-04 03:10:25.077215 | 2025-10-04 03:10:25.077284 | LOOP [upload-logs : Compress console log and json output] 2025-10-04 03:10:25.112914 | localhost | skipping: Conditional result was False 2025-10-04 03:10:25.117468 | localhost | skipping: Conditional result was False 2025-10-04 03:10:25.122013 | 2025-10-04 03:10:25.122089 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-04 03:10:25.166390 | localhost | skipping: Conditional result was False 2025-10-04 03:10:25.166656 | 2025-10-04 03:10:25.171142 | localhost | skipping: Conditional result was False 2025-10-04 03:10:25.176428 | 2025-10-04 03:10:25.176510 | LOOP [upload-logs : Upload console log and json output]