2025-12-01 09:36:06.970036 | Job console starting 2025-12-01 09:36:07.015877 | Updating git repos 2025-12-01 09:36:07.061904 | Cloning repos into workspace 2025-12-01 09:36:07.237374 | Restoring repo states 2025-12-01 09:36:07.253707 | Merging changes 2025-12-01 09:36:07.794017 | Checking out repos 2025-12-01 09:36:07.942782 | Preparing playbooks 2025-12-01 09:36:09.050646 | Running Ansible setup 2025-12-01 09:36:14.203682 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-01 09:36:15.529401 | 2025-12-01 09:36:15.529589 | PLAY [Base pre] 2025-12-01 09:36:15.562073 | 2025-12-01 09:36:15.562244 | TASK [Setup log path fact] 2025-12-01 09:36:15.597558 | debian-bookworm | ok 2025-12-01 09:36:15.620771 | 2025-12-01 09:36:15.620967 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-01 09:36:15.651116 | debian-bookworm | ok 2025-12-01 09:36:15.661183 | 2025-12-01 09:36:15.661321 | TASK [emit-job-header : Print job information] 2025-12-01 09:36:15.710742 | # Job Information 2025-12-01 09:36:15.710982 | Ansible Version: 2.16.14 2025-12-01 09:36:15.711021 | Job: tox 2025-12-01 09:36:15.711054 | Pipeline: check 2025-12-01 09:36:15.711077 | Executor: 521e9411259a 2025-12-01 09:36:15.711097 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1014 2025-12-01 09:36:15.711119 | Event ID: 23c422a0-ce99-11f0-96fc-3e6095c70f22 2025-12-01 09:36:15.720199 | 2025-12-01 09:36:15.720333 | LOOP [emit-job-header : Print node information] 2025-12-01 09:36:15.889960 | debian-bookworm | ok: 2025-12-01 09:36:15.890194 | debian-bookworm | # Node Information 2025-12-01 09:36:15.890230 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-01 09:36:15.890256 | debian-bookworm | Hostname: debian 2025-12-01 09:36:15.890278 | debian-bookworm | Username: zuul 2025-12-01 09:36:15.890300 | debian-bookworm | Distro: Debian 12.12 2025-12-01 09:36:15.890324 | debian-bookworm | Provider: regiocloud-a 2025-12-01 09:36:15.890345 | debian-bookworm | Region: 2025-12-01 09:36:15.890777 | debian-bookworm | Label: debian-bookworm 2025-12-01 09:36:15.890879 | debian-bookworm | Product Name: OpenStack Nova 2025-12-01 09:36:15.890905 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee1:a51e 2025-12-01 09:36:15.913451 | 2025-12-01 09:36:15.913663 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-01 09:36:16.883408 | debian-bookworm -> localhost | changed 2025-12-01 09:36:16.892489 | 2025-12-01 09:36:16.892635 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-01 09:36:18.735642 | debian-bookworm -> localhost | changed 2025-12-01 09:36:18.747122 | 2025-12-01 09:36:18.747250 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-01 09:36:19.230747 | debian-bookworm -> localhost | ok 2025-12-01 09:36:19.237380 | 2025-12-01 09:36:19.237522 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-01 09:36:19.268946 | debian-bookworm | ok 2025-12-01 09:36:19.308007 | debian-bookworm | included: /var/lib/zuul/builds/4ae040b79c004cb587a717025352710d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-01 09:36:19.316688 | 2025-12-01 09:36:19.316886 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-01 09:36:21.100597 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-01 09:36:21.100947 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4ae040b79c004cb587a717025352710d/work/4ae040b79c004cb587a717025352710d_id_rsa 2025-12-01 09:36:21.100992 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4ae040b79c004cb587a717025352710d/work/4ae040b79c004cb587a717025352710d_id_rsa.pub 2025-12-01 09:36:21.101020 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-01 09:36:21.101048 | debian-bookworm -> localhost | SHA256:1SIZuXeOci0rw+dNb120ukJuSBBbtBufQX1f/sq+Nb0 zuul-build-sshkey 2025-12-01 09:36:21.101072 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-01 09:36:21.101114 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-01 09:36:21.101136 | debian-bookworm -> localhost | | oo .. | 2025-12-01 09:36:21.101159 | debian-bookworm -> localhost | | ..+o. . ..| 2025-12-01 09:36:21.101181 | debian-bookworm -> localhost | | *+o.. ..o| 2025-12-01 09:36:21.101201 | debian-bookworm -> localhost | | o.o=.+ +| 2025-12-01 09:36:21.101222 | debian-bookworm -> localhost | | So B .o| 2025-12-01 09:36:21.101249 | debian-bookworm -> localhost | | ..+.o .+| 2025-12-01 09:36:21.101270 | debian-bookworm -> localhost | | ..o+o...++| 2025-12-01 09:36:21.101290 | debian-bookworm -> localhost | | +.o* o+.+| 2025-12-01 09:36:21.101311 | debian-bookworm -> localhost | | =o o+=E | 2025-12-01 09:36:21.101333 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-01 09:36:21.101393 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.964006 2025-12-01 09:36:21.108997 | 2025-12-01 09:36:21.109151 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-01 09:36:21.162566 | debian-bookworm | ok 2025-12-01 09:36:21.181959 | debian-bookworm | included: /var/lib/zuul/builds/4ae040b79c004cb587a717025352710d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-01 09:36:21.206155 | 2025-12-01 09:36:21.206316 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-01 09:36:21.251454 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:36:21.259536 | 2025-12-01 09:36:21.259703 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-01 09:36:22.027510 | debian-bookworm | changed 2025-12-01 09:36:22.038713 | 2025-12-01 09:36:22.038939 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-01 09:36:22.309379 | debian-bookworm | ok 2025-12-01 09:36:22.317483 | 2025-12-01 09:36:22.317618 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-01 09:36:23.044175 | debian-bookworm | changed 2025-12-01 09:36:23.054660 | 2025-12-01 09:36:23.054913 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-01 09:36:23.711962 | debian-bookworm | changed 2025-12-01 09:36:23.719266 | 2025-12-01 09:36:23.719388 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-01 09:36:23.774697 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:36:23.783524 | 2025-12-01 09:36:23.783688 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-01 09:36:24.356292 | debian-bookworm -> localhost | changed 2025-12-01 09:36:24.367999 | 2025-12-01 09:36:24.368146 | TASK [add-build-sshkey : Add back temp key] 2025-12-01 09:36:24.865714 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4ae040b79c004cb587a717025352710d/work/4ae040b79c004cb587a717025352710d_id_rsa (zuul-build-sshkey) 2025-12-01 09:36:24.866043 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.032800 2025-12-01 09:36:24.875305 | 2025-12-01 09:36:24.875429 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-01 09:36:25.354176 | debian-bookworm | ok 2025-12-01 09:36:25.363117 | 2025-12-01 09:36:25.363254 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-01 09:36:25.390161 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:36:25.401868 | 2025-12-01 09:36:25.401999 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-01 09:36:25.853000 | debian-bookworm | ok 2025-12-01 09:36:25.868786 | 2025-12-01 09:36:25.868994 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-01 09:36:25.901444 | debian-bookworm | ok 2025-12-01 09:36:25.907167 | 2025-12-01 09:36:25.907288 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-01 09:36:26.245580 | debian-bookworm -> localhost | ok 2025-12-01 09:36:26.268120 | 2025-12-01 09:36:26.268309 | TASK [validate-host : Collect information about the host] 2025-12-01 09:36:27.500396 | debian-bookworm | ok 2025-12-01 09:36:27.511001 | 2025-12-01 09:36:27.511123 | TASK [validate-host : Sanitize hostname] 2025-12-01 09:36:27.629198 | debian-bookworm | ok 2025-12-01 09:36:27.689836 | 2025-12-01 09:36:27.690030 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-01 09:36:28.492381 | debian-bookworm -> localhost | changed 2025-12-01 09:36:28.498618 | 2025-12-01 09:36:28.498726 | TASK [validate-host : Collect information about zuul worker] 2025-12-01 09:36:28.944195 | debian-bookworm | ok 2025-12-01 09:36:28.951135 | 2025-12-01 09:36:28.951284 | TASK [validate-host : Write out all zuul information for each host] 2025-12-01 09:36:29.578102 | debian-bookworm -> localhost | changed 2025-12-01 09:36:29.589218 | 2025-12-01 09:36:29.589393 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-01 09:36:29.888255 | debian-bookworm | ok 2025-12-01 09:36:29.894662 | 2025-12-01 09:36:29.894781 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-01 09:36:30.996878 | debian-bookworm | changed: 2025-12-01 09:36:30.997108 | debian-bookworm | cd+++++++++ src/ 2025-12-01 09:36:30.997144 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-01 09:36:30.997169 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-01 09:36:30.997190 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-01 09:36:30.997211 | debian-bookworm | =6.2 2025-12-01 09:36:53.242075 | debian-bookworm | Downloading cachetools-6.2.2-py3-none-any.whl (11 kB) 2025-12-01 09:36:53.293751 | debian-bookworm | Collecting chardet>=5.2 2025-12-01 09:36:53.306926 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-01 09:36:53.317416 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 27.5 MB/s eta 0:00:00 2025-12-01 09:36:53.361325 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-01 09:36:53.372655 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-01 09:36:53.426101 | debian-bookworm | Collecting filelock>=3.20 2025-12-01 09:36:53.437293 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-12-01 09:36:53.489625 | debian-bookworm | Collecting packaging>=25 2025-12-01 09:36:53.500422 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-01 09:36:53.579788 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 1.6 MB/s eta 0:00:00 2025-12-01 09:36:53.650581 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-01 09:36:53.662298 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-12-01 09:36:53.715632 | debian-bookworm | Collecting pluggy>=1.6 2025-12-01 09:36:53.726981 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-01 09:36:53.786608 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-01 09:36:53.799017 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-01 09:36:53.932992 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-01 09:36:53.944252 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-01 09:36:54.179304 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 26.1 MB/s eta 0:00:00 2025-12-01 09:36:54.283256 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-01 09:36:54.294419 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-01 09:36:54.323624 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 19.5 MB/s eta 0:00:00 2025-12-01 09:36:54.465876 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-01 09:36:55.214638 | debian-bookworm | Successfully installed cachetools-6.2.2 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.4 2025-12-01 09:36:55.737453 | debian-bookworm | ok: Runtime: 0:00:02.809280 2025-12-01 09:36:55.751583 | 2025-12-01 09:36:55.751736 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-01 09:36:55.794915 | debian-bookworm | ok 2025-12-01 09:36:55.800388 | 2025-12-01 09:36:55.800500 | TASK [ensure-tox : Output tox version] 2025-12-01 09:36:56.295238 | 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-01 09:36:56.313621 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-01 09:36:56.842095 | debian-bookworm | ok: Runtime: 0:00:00.267848 2025-12-01 09:36:56.848309 | 2025-12-01 09:36:56.848432 | TASK [ensure-tox : Make global symlink] 2025-12-01 09:36:56.883078 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:36:56.891183 | 2025-12-01 09:36:56.891292 | PLAY RECAP 2025-12-01 09:36:56.891349 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-01 09:36:56.891373 | 2025-12-01 09:36:57.213416 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-01 09:36:57.219662 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-01 09:36:58.125525 | 2025-12-01 09:36:58.125718 | PLAY [all] 2025-12-01 09:36:58.144326 | 2025-12-01 09:36:58.144498 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-01 09:36:58.611045 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-01 09:36:58.675550 | debian-bookworm | ok: Runtime: 0:00:00.031866 2025-12-01 09:36:58.686746 | 2025-12-01 09:36:58.686987 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-01 09:36:59.240924 | debian-bookworm | ok: Runtime: 0:00:00.006828 2025-12-01 09:36:59.254661 | 2025-12-01 09:36:59.254890 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-01 09:36:59.555681 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-01 09:36:59.557247 | debian-bookworm | sudo: a password is required 2025-12-01 09:36:59.790045 | debian-bookworm | ok: Runtime: 0:00:00.021951 2025-12-01 09:36:59.808755 | 2025-12-01 09:36:59.808966 | TASK [tox : Check to see if the constraints file exists] 2025-12-01 09:36:59.844139 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:36:59.852033 | 2025-12-01 09:36:59.852176 | TASK [tox : Fail if constraints file is missing] 2025-12-01 09:36:59.876869 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:36:59.883548 | 2025-12-01 09:36:59.883679 | TASK [tox : Record file location] 2025-12-01 09:36:59.909096 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:36:59.915096 | 2025-12-01 09:36:59.915224 | TASK [tox : Install tox siblings] 2025-12-01 09:36:59.937456 | debian-bookworm | ok 2025-12-01 09:36:59.947679 | debian-bookworm | included: /var/lib/zuul/builds/4ae040b79c004cb587a717025352710d/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-01 09:36:59.953356 | 2025-12-01 09:36:59.953465 | TASK [tox : Run tox without tests] 2025-12-01 09:37:01.039646 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-01 09:37:14.887484 | debian-bookworm | test: OK (14.40 seconds) 2025-12-01 09:37:14.887542 | debian-bookworm | congratulations :) (14.43 seconds) 2025-12-01 09:37:15.007041 | debian-bookworm | ok: Runtime: 0:00:14.719190 2025-12-01 09:37:15.015778 | 2025-12-01 09:37:15.015970 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-01 09:37:15.433885 | debian-bookworm | changed 2025-12-01 09:37:15.440913 | 2025-12-01 09:37:15.441050 | TASK [tox : Get tox envlist config] 2025-12-01 09:37:16.473404 | debian-bookworm | ok: Runtime: 0:00:00.348162 2025-12-01 09:37:16.490445 | 2025-12-01 09:37:16.490662 | TASK [tox : Install any sibling python packages] 2025-12-01 09:37:16.968293 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-01 09:37:16.977577 | 2025-12-01 09:37:16.977726 | TASK [tox : Remove tempfile] 2025-12-01 09:37:17.409251 | debian-bookworm | changed 2025-12-01 09:37:17.417068 | 2025-12-01 09:37:17.417203 | TASK [tox : Emit tox command] 2025-12-01 09:37:17.472863 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-01 09:37:17.481347 | 2025-12-01 09:37:17.481514 | TASK [tox : Run tox] 2025-12-01 09:37:18.136945 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-01 09:37:18.771604 | debian-bookworm | ............... 2025-12-01 09:37:18.771656 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-01 09:37:18.771672 | debian-bookworm | Ran 15 tests in 0.167s 2025-12-01 09:37:18.771678 | debian-bookworm | 2025-12-01 09:37:18.771685 | debian-bookworm | OK 2025-12-01 09:37:18.861213 | debian-bookworm | test: OK (0.77=setup[0.05]+cmd[0.72] seconds) 2025-12-01 09:37:18.861321 | debian-bookworm | congratulations :) (0.80 seconds) 2025-12-01 09:37:19.057749 | debian-bookworm | ok: Runtime: 0:00:01.077969 2025-12-01 09:37:19.069977 | 2025-12-01 09:37:19.070161 | TASK [tox : Look for output] 2025-12-01 09:37:19.483234 | debian-bookworm | ok 2025-12-01 09:37:19.506204 | 2025-12-01 09:37:19.506372 | TASK [tox : Return file comments to Zuul] 2025-12-01 09:37:19.551534 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:19.565233 | 2025-12-01 09:37:19.565362 | PLAY RECAP 2025-12-01 09:37:19.565439 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-01 09:37:19.565474 | 2025-12-01 09:37:19.711555 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-01 09:37:19.714424 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-01 09:37:20.483079 | 2025-12-01 09:37:20.483247 | PLAY [all] 2025-12-01 09:37:20.499878 | 2025-12-01 09:37:20.500091 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-01 09:37:20.556428 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:20.569705 | 2025-12-01 09:37:20.569960 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-01 09:37:20.626931 | debian-bookworm | ok 2025-12-01 09:37:20.635398 | 2025-12-01 09:37:20.635551 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-01 09:37:21.128793 | debian-bookworm -> localhost | changed 2025-12-01 09:37:21.145622 | 2025-12-01 09:37:21.145797 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-01 09:37:21.201111 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:21.215237 | 2025-12-01 09:37:21.215457 | TASK [fetch-tox-output : Set envlist fact] 2025-12-01 09:37:21.262551 | debian-bookworm | ok 2025-12-01 09:37:21.271238 | 2025-12-01 09:37:21.271366 | TASK [fetch-tox-output : Get tox version] 2025-12-01 09:37:21.965045 | 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-01 09:37:22.014436 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-01 09:37:22.304052 | debian-bookworm | ok: Runtime: 0:00:00.331041 2025-12-01 09:37:22.318484 | 2025-12-01 09:37:22.318697 | TASK [fetch-tox-output : Find all default environments] 2025-12-01 09:37:22.853369 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:22.867759 | 2025-12-01 09:37:22.868089 | TASK [fetch-tox-output : Set envlist fact] 2025-12-01 09:37:22.905171 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:22.918593 | 2025-12-01 09:37:22.918792 | TASK [fetch-tox-output : Find all default environments] 2025-12-01 09:37:23.456809 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:23.471612 | 2025-12-01 09:37:23.471880 | TASK [fetch-tox-output : Set envlist fact] 2025-12-01 09:37:23.508611 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:23.522351 | 2025-12-01 09:37:23.522509 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-01 09:37:23.573268 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:23.594119 | 2025-12-01 09:37:23.594312 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-01 09:37:24.449191 | debian-bookworm | changed: 2025-12-01 09:37:24.449597 | debian-bookworm | created directory /var/lib/zuul/builds/4ae040b79c004cb587a717025352710d/work/logs/tox/test 2025-12-01 09:37:24.449660 | debian-bookworm | cd+++++++++ ./ 2025-12-01 09:37:24.449707 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-01 09:37:24.449760 | 2025-12-01 09:37:24.472916 | 2025-12-01 09:37:24.473076 | PLAY RECAP 2025-12-01 09:37:24.473158 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-01 09:37:24.473197 | 2025-12-01 09:37:24.607068 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-01 09:37:24.610554 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-01 09:37:25.379946 | 2025-12-01 09:37:25.380125 | PLAY [all] 2025-12-01 09:37:25.397274 | 2025-12-01 09:37:25.397524 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-01 09:37:25.618478 | debian-bookworm | changed: non-zero return code 2025-12-01 09:37:25.626536 | 2025-12-01 09:37:25.626714 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-01 09:37:25.652007 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:25.662505 | 2025-12-01 09:37:25.662698 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-01 09:37:25.711103 | 2025-12-01 09:37:25.711412 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-01 09:37:25.762948 | 2025-12-01 09:37:25.763239 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-01 09:37:25.788225 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:25.799592 | 2025-12-01 09:37:25.799767 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-01 09:37:25.837992 | 2025-12-01 09:37:25.838319 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-01 09:37:25.863470 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:25.873051 | 2025-12-01 09:37:25.873228 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-01 09:37:25.898327 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:25.908494 | 2025-12-01 09:37:25.908664 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-01 09:37:25.933806 | debian-bookworm | skipping: Conditional result was False 2025-12-01 09:37:25.945986 | 2025-12-01 09:37:25.946116 | PLAY RECAP 2025-12-01 09:37:25.946197 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-01 09:37:25.946236 | 2025-12-01 09:37:26.085303 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-01 09:37:26.086439 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-01 09:37:26.875753 | 2025-12-01 09:37:26.875957 | PLAY [Base post] 2025-12-01 09:37:26.890408 | 2025-12-01 09:37:26.890560 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-01 09:37:27.477570 | debian-bookworm | changed 2025-12-01 09:37:27.487023 | 2025-12-01 09:37:27.487154 | PLAY RECAP 2025-12-01 09:37:27.487227 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-01 09:37:27.487301 | 2025-12-01 09:37:27.631108 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-01 09:37:27.632810 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-01 09:37:28.464624 | 2025-12-01 09:37:28.464870 | PLAY [Base post-logs] 2025-12-01 09:37:28.476447 | 2025-12-01 09:37:28.476596 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-01 09:37:28.969331 | localhost | changed 2025-12-01 09:37:28.981013 | 2025-12-01 09:37:28.981193 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-01 09:37:29.007989 | localhost | ok 2025-12-01 09:37:29.011526 | 2025-12-01 09:37:29.011639 | TASK [Set zuul-log-path fact] 2025-12-01 09:37:29.028074 | localhost | ok 2025-12-01 09:37:29.037195 | 2025-12-01 09:37:29.037319 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-01 09:37:29.075371 | localhost | ok 2025-12-01 09:37:29.080015 | 2025-12-01 09:37:29.080160 | TASK [upload-logs : Create log directories] 2025-12-01 09:37:29.627619 | localhost | changed 2025-12-01 09:37:29.631060 | 2025-12-01 09:37:29.631191 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-01 09:37:30.194006 | localhost -> localhost | ok: Runtime: 0:00:00.004729 2025-12-01 09:37:30.199012 | 2025-12-01 09:37:30.199164 | TASK [upload-logs : Upload logs to log server] 2025-12-01 09:37:30.749042 | localhost | Output suppressed because no_log was given 2025-12-01 09:37:30.751909 | 2025-12-01 09:37:30.752047 | LOOP [upload-logs : Compress console log and json output] 2025-12-01 09:37:30.803634 | localhost | skipping: Conditional result was False 2025-12-01 09:37:30.809448 | localhost | skipping: Conditional result was False 2025-12-01 09:37:30.823736 | 2025-12-01 09:37:30.824026 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-01 09:37:30.873132 | localhost | skipping: Conditional result was False 2025-12-01 09:37:30.874113 | 2025-12-01 09:37:30.876394 | localhost | skipping: Conditional result was False 2025-12-01 09:37:30.891060 | 2025-12-01 09:37:30.891321 | LOOP [upload-logs : Upload console log and json output]