2025-10-19 17:06:15.277645 | Job console starting 2025-10-19 17:06:15.297760 | Updating git repos 2025-10-19 17:06:15.347446 | Cloning repos into workspace 2025-10-19 17:06:15.413415 | Restoring repo states 2025-10-19 17:06:15.426364 | Merging changes 2025-10-19 17:06:15.939896 | Checking out repos 2025-10-19 17:06:15.983301 | Preparing playbooks 2025-10-19 17:06:16.647597 | Running Ansible setup 2025-10-19 17:06:21.715888 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-19 17:06:22.573447 | 2025-10-19 17:06:22.573572 | PLAY [Base pre] 2025-10-19 17:06:22.592778 | 2025-10-19 17:06:22.592898 | TASK [Setup log path fact] 2025-10-19 17:06:22.620543 | debian-bookworm | ok 2025-10-19 17:06:22.642546 | 2025-10-19 17:06:22.642676 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 17:06:22.699430 | debian-bookworm | ok 2025-10-19 17:06:22.717392 | 2025-10-19 17:06:22.717509 | TASK [emit-job-header : Print job information] 2025-10-19 17:06:22.765799 | # Job Information 2025-10-19 17:06:22.765953 | Ansible Version: 2.16.14 2025-10-19 17:06:22.765986 | Job: tox 2025-10-19 17:06:22.766017 | Pipeline: check 2025-10-19 17:06:22.766039 | Executor: 521e9411259a 2025-10-19 17:06:22.766059 | Triggered by: https://github.com/osism/openstack-image-manager/pull/963 2025-10-19 17:06:22.766080 | Event ID: e6698320-ad0d-11f0-85b6-457a3dc83516 2025-10-19 17:06:22.775043 | 2025-10-19 17:06:22.775166 | LOOP [emit-job-header : Print node information] 2025-10-19 17:06:22.870695 | debian-bookworm | ok: 2025-10-19 17:06:22.870977 | debian-bookworm | # Node Information 2025-10-19 17:06:22.871017 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-19 17:06:22.871042 | debian-bookworm | Hostname: debian 2025-10-19 17:06:22.871064 | debian-bookworm | Username: zuul 2025-10-19 17:06:22.871085 | debian-bookworm | Distro: Debian 12.12 2025-10-19 17:06:22.871108 | debian-bookworm | Provider: regiocloud-a 2025-10-19 17:06:22.871128 | debian-bookworm | Region: 2025-10-19 17:06:22.871149 | debian-bookworm | Label: debian-bookworm 2025-10-19 17:06:22.871169 | debian-bookworm | Product Name: OpenStack Nova 2025-10-19 17:06:22.871189 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3f:37e8 2025-10-19 17:06:22.890944 | 2025-10-19 17:06:22.891049 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-19 17:06:23.368689 | debian-bookworm -> localhost | changed 2025-10-19 17:06:23.375997 | 2025-10-19 17:06:23.376125 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-19 17:06:24.720005 | debian-bookworm -> localhost | changed 2025-10-19 17:06:24.728482 | 2025-10-19 17:06:24.728562 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-19 17:06:25.035638 | debian-bookworm -> localhost | ok 2025-10-19 17:06:25.041414 | 2025-10-19 17:06:25.041501 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-19 17:06:25.087660 | debian-bookworm | ok 2025-10-19 17:06:25.108762 | debian-bookworm | included: /var/lib/zuul/builds/2eb4d074132241f38495983f841f3760/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-19 17:06:25.119021 | 2025-10-19 17:06:25.119104 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-19 17:06:26.536312 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-19 17:06:26.536519 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2eb4d074132241f38495983f841f3760/work/2eb4d074132241f38495983f841f3760_id_rsa 2025-10-19 17:06:26.536560 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2eb4d074132241f38495983f841f3760/work/2eb4d074132241f38495983f841f3760_id_rsa.pub 2025-10-19 17:06:26.536588 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-19 17:06:26.536616 | debian-bookworm -> localhost | SHA256:cfrzTXMta9r1+SMeuZ9iDt7b2bNCyK+PN/xvrphk98A zuul-build-sshkey 2025-10-19 17:06:26.536640 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-19 17:06:26.536673 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-19 17:06:26.536696 | debian-bookworm -> localhost | | | 2025-10-19 17:06:26.536719 | debian-bookworm -> localhost | | | 2025-10-19 17:06:26.536741 | debian-bookworm -> localhost | | . . | 2025-10-19 17:06:26.536762 | debian-bookworm -> localhost | | + | 2025-10-19 17:06:26.536783 | debian-bookworm -> localhost | | S . . | 2025-10-19 17:06:26.536816 | debian-bookworm -> localhost | | . o o. .| 2025-10-19 17:06:26.536841 | debian-bookworm -> localhost | | o.*oE +| 2025-10-19 17:06:26.536863 | debian-bookworm -> localhost | | .=+^=@B| 2025-10-19 17:06:26.536887 | debian-bookworm -> localhost | | +%X&%&| 2025-10-19 17:06:26.536909 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-19 17:06:26.536961 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.818790 2025-10-19 17:06:26.543068 | 2025-10-19 17:06:26.543150 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-19 17:06:26.560273 | debian-bookworm | ok 2025-10-19 17:06:26.571525 | debian-bookworm | included: /var/lib/zuul/builds/2eb4d074132241f38495983f841f3760/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-19 17:06:26.580228 | 2025-10-19 17:06:26.580310 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-19 17:06:26.606369 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:06:26.611752 | 2025-10-19 17:06:26.611836 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-19 17:06:27.211830 | debian-bookworm | changed 2025-10-19 17:06:27.220275 | 2025-10-19 17:06:27.220414 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-19 17:06:27.526540 | debian-bookworm | ok 2025-10-19 17:06:27.532765 | 2025-10-19 17:06:27.532859 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-19 17:06:28.166968 | debian-bookworm | changed 2025-10-19 17:06:28.187765 | 2025-10-19 17:06:28.187870 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-19 17:06:28.776170 | debian-bookworm | changed 2025-10-19 17:06:28.781586 | 2025-10-19 17:06:28.781677 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-19 17:06:28.795337 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:06:28.801134 | 2025-10-19 17:06:28.801221 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-19 17:06:29.308981 | debian-bookworm -> localhost | changed 2025-10-19 17:06:29.319208 | 2025-10-19 17:06:29.319306 | TASK [add-build-sshkey : Add back temp key] 2025-10-19 17:06:29.632693 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2eb4d074132241f38495983f841f3760/work/2eb4d074132241f38495983f841f3760_id_rsa (zuul-build-sshkey) 2025-10-19 17:06:29.632951 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008354 2025-10-19 17:06:29.641594 | 2025-10-19 17:06:29.641698 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-19 17:06:29.995912 | debian-bookworm | ok 2025-10-19 17:06:30.000754 | 2025-10-19 17:06:30.000846 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-19 17:06:30.043785 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:06:30.053735 | 2025-10-19 17:06:30.053851 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-19 17:06:30.504894 | debian-bookworm | ok 2025-10-19 17:06:30.518922 | 2025-10-19 17:06:30.519027 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-19 17:06:30.548883 | debian-bookworm | ok 2025-10-19 17:06:30.554880 | 2025-10-19 17:06:30.554971 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-19 17:06:30.801302 | debian-bookworm -> localhost | ok 2025-10-19 17:06:30.807296 | 2025-10-19 17:06:30.807394 | TASK [validate-host : Collect information about the host] 2025-10-19 17:06:31.884452 | debian-bookworm | ok 2025-10-19 17:06:31.894239 | 2025-10-19 17:06:31.894327 | TASK [validate-host : Sanitize hostname] 2025-10-19 17:06:31.984095 | debian-bookworm | ok 2025-10-19 17:06:32.013578 | 2025-10-19 17:06:32.013675 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-19 17:06:32.720245 | debian-bookworm -> localhost | changed 2025-10-19 17:06:32.726287 | 2025-10-19 17:06:32.726436 | TASK [validate-host : Collect information about zuul worker] 2025-10-19 17:06:33.129756 | debian-bookworm | ok 2025-10-19 17:06:33.134993 | 2025-10-19 17:06:33.135105 | TASK [validate-host : Write out all zuul information for each host] 2025-10-19 17:06:33.756854 | debian-bookworm -> localhost | changed 2025-10-19 17:06:33.770915 | 2025-10-19 17:06:33.771042 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-19 17:06:34.046938 | debian-bookworm | ok 2025-10-19 17:06:34.055158 | 2025-10-19 17:06:34.055287 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-19 17:06:35.202580 | debian-bookworm | changed: 2025-10-19 17:06:35.202795 | debian-bookworm | cd+++++++++ src/ 2025-10-19 17:06:35.202855 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-19 17:06:35.202881 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-19 17:06:35.202903 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-19 17:06:35.202923 | debian-bookworm | =6.2 2025-10-19 17:06:57.297244 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-19 17:06:57.377201 | debian-bookworm | Collecting chardet>=5.2 2025-10-19 17:06:57.391916 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-19 17:06:57.408935 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 14.5 MB/s eta 0:00:00 2025-10-19 17:06:57.448840 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-19 17:06:57.463361 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-19 17:06:57.519694 | debian-bookworm | Collecting filelock>=3.20 2025-10-19 17:06:57.534366 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-19 17:06:57.587391 | debian-bookworm | Collecting packaging>=25 2025-10-19 17:06:57.602166 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-19 17:06:57.610504 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.7 MB/s eta 0:00:00 2025-10-19 17:06:57.674774 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-19 17:06:57.690061 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-19 17:06:57.747583 | debian-bookworm | Collecting pluggy>=1.6 2025-10-19 17:06:57.762721 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-19 17:06:57.808966 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-19 17:06:57.829499 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-19 17:06:57.973803 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-19 17:06:57.987698 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-19 17:06:58.298897 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 19.6 MB/s eta 0:00:00 2025-10-19 17:06:58.415872 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-19 17:06:58.431121 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-19 17:06:58.460246 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 18.8 MB/s eta 0:00:00 2025-10-19 17:06:58.621687 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-19 17:06:59.495616 | debian-bookworm | Successfully installed cachetools-6.2.1 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.31.0 virtualenv-20.35.3 2025-10-19 17:06:59.656340 | debian-bookworm | ok: Runtime: 0:00:03.214913 2025-10-19 17:06:59.662101 | 2025-10-19 17:06:59.662206 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-19 17:06:59.702802 | debian-bookworm | ok 2025-10-19 17:06:59.707813 | 2025-10-19 17:06:59.707923 | TASK [ensure-tox : Output tox version] 2025-10-19 17:07:00.229696 | 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-19 17:07:00.249353 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 17:07:00.737618 | debian-bookworm | ok: Runtime: 0:00:00.321572 2025-10-19 17:07:00.748425 | 2025-10-19 17:07:00.748530 | TASK [ensure-tox : Make global symlink] 2025-10-19 17:07:00.778571 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:00.800651 | 2025-10-19 17:07:00.800788 | PLAY RECAP 2025-10-19 17:07:00.800867 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-19 17:07:00.800906 | 2025-10-19 17:07:00.942275 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-19 17:07:00.943624 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 17:07:01.775357 | 2025-10-19 17:07:01.775534 | PLAY [all] 2025-10-19 17:07:01.790885 | 2025-10-19 17:07:01.791013 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-19 17:07:02.234718 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 17:07:02.324223 | debian-bookworm | ok: Runtime: 0:00:00.026093 2025-10-19 17:07:02.337666 | 2025-10-19 17:07:02.337872 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-19 17:07:02.870823 | debian-bookworm | ok: Runtime: 0:00:00.006585 2025-10-19 17:07:02.885265 | 2025-10-19 17:07:02.885521 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-19 17:07:03.185978 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 17:07:03.187506 | debian-bookworm | sudo: a password is required 2025-10-19 17:07:03.419269 | debian-bookworm | ok: Runtime: 0:00:00.020600 2025-10-19 17:07:03.435895 | 2025-10-19 17:07:03.436077 | TASK [tox : Check to see if the constraints file exists] 2025-10-19 17:07:03.471326 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:03.484457 | 2025-10-19 17:07:03.484622 | TASK [tox : Fail if constraints file is missing] 2025-10-19 17:07:03.519272 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:03.525080 | 2025-10-19 17:07:03.525185 | TASK [tox : Record file location] 2025-10-19 17:07:03.558510 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:03.565955 | 2025-10-19 17:07:03.566069 | TASK [tox : Install tox siblings] 2025-10-19 17:07:03.596824 | debian-bookworm | ok 2025-10-19 17:07:03.604886 | debian-bookworm | included: /var/lib/zuul/builds/2eb4d074132241f38495983f841f3760/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-19 17:07:03.610315 | 2025-10-19 17:07:03.610469 | TASK [tox : Run tox without tests] 2025-10-19 17:07:04.650032 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-19 17:07:18.624400 | debian-bookworm | test: OK (14.47 seconds) 2025-10-19 17:07:18.624522 | debian-bookworm | congratulations :) (14.50 seconds) 2025-10-19 17:07:19.150113 | debian-bookworm | ok: Runtime: 0:00:14.778667 2025-10-19 17:07:19.161860 | 2025-10-19 17:07:19.161999 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-19 17:07:19.549503 | debian-bookworm | changed 2025-10-19 17:07:19.556952 | 2025-10-19 17:07:19.557086 | TASK [tox : Get tox envlist config] 2025-10-19 17:07:20.587729 | debian-bookworm | ok: Runtime: 0:00:00.330753 2025-10-19 17:07:20.602140 | 2025-10-19 17:07:20.602321 | TASK [tox : Install any sibling python packages] 2025-10-19 17:07:21.042164 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-19 17:07:21.050648 | 2025-10-19 17:07:21.050783 | TASK [tox : Remove tempfile] 2025-10-19 17:07:21.481414 | debian-bookworm | changed 2025-10-19 17:07:21.489646 | 2025-10-19 17:07:21.489776 | TASK [tox : Emit tox command] 2025-10-19 17:07:21.531745 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-19 17:07:21.538757 | 2025-10-19 17:07:21.538907 | TASK [tox : Run tox] 2025-10-19 17:07:22.135593 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-19 17:07:22.773835 | debian-bookworm | ............... 2025-10-19 17:07:22.773932 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-19 17:07:22.773963 | debian-bookworm | Ran 15 tests in 0.176s 2025-10-19 17:07:22.773977 | debian-bookworm | 2025-10-19 17:07:22.773996 | debian-bookworm | OK 2025-10-19 17:07:22.869282 | debian-bookworm | test: OK (0.77=setup[0.04]+cmd[0.73] seconds) 2025-10-19 17:07:22.869400 | debian-bookworm | congratulations :) (0.80 seconds) 2025-10-19 17:07:23.103735 | debian-bookworm | ok: Runtime: 0:00:01.077830 2025-10-19 17:07:23.115968 | 2025-10-19 17:07:23.116142 | TASK [tox : Look for output] 2025-10-19 17:07:23.518568 | debian-bookworm | ok 2025-10-19 17:07:23.536346 | 2025-10-19 17:07:23.536522 | TASK [tox : Return file comments to Zuul] 2025-10-19 17:07:23.571321 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:23.585919 | 2025-10-19 17:07:23.586039 | PLAY RECAP 2025-10-19 17:07:23.586127 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-19 17:07:23.586171 | 2025-10-19 17:07:23.715426 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 17:07:23.718160 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 17:07:24.455883 | 2025-10-19 17:07:24.456036 | PLAY [all] 2025-10-19 17:07:24.470767 | 2025-10-19 17:07:24.470921 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-19 17:07:24.526072 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:24.533601 | 2025-10-19 17:07:24.533751 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-19 17:07:24.568637 | debian-bookworm | ok 2025-10-19 17:07:24.576151 | 2025-10-19 17:07:24.576279 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-19 17:07:25.040041 | debian-bookworm -> localhost | changed 2025-10-19 17:07:25.048008 | 2025-10-19 17:07:25.048128 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-19 17:07:25.092823 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:25.099981 | 2025-10-19 17:07:25.100106 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 17:07:25.146298 | debian-bookworm | ok 2025-10-19 17:07:25.156275 | 2025-10-19 17:07:25.156472 | TASK [fetch-tox-output : Get tox version] 2025-10-19 17:07:25.843878 | 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-19 17:07:25.862285 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 17:07:26.188652 | debian-bookworm | ok: Runtime: 0:00:00.289389 2025-10-19 17:07:26.201608 | 2025-10-19 17:07:26.201778 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 17:07:26.733910 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:26.747239 | 2025-10-19 17:07:26.747440 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 17:07:26.783334 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:26.796104 | 2025-10-19 17:07:26.796260 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 17:07:27.342916 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:27.356896 | 2025-10-19 17:07:27.357093 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 17:07:27.392591 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:27.407708 | 2025-10-19 17:07:27.407874 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-19 17:07:27.450292 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:27.463347 | 2025-10-19 17:07:27.463554 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-19 17:07:28.238806 | debian-bookworm | changed: 2025-10-19 17:07:28.239106 | debian-bookworm | created directory /var/lib/zuul/builds/2eb4d074132241f38495983f841f3760/work/logs/tox/test 2025-10-19 17:07:28.239223 | debian-bookworm | cd+++++++++ ./ 2025-10-19 17:07:28.239261 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-19 17:07:28.239295 | 2025-10-19 17:07:28.255527 | 2025-10-19 17:07:28.255622 | PLAY RECAP 2025-10-19 17:07:28.255674 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-19 17:07:28.255698 | 2025-10-19 17:07:28.382005 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 17:07:28.383096 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 17:07:29.080693 | 2025-10-19 17:07:29.080872 | PLAY [all] 2025-10-19 17:07:29.096680 | 2025-10-19 17:07:29.096828 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-19 17:07:29.305809 | debian-bookworm | changed: non-zero return code 2025-10-19 17:07:29.314495 | 2025-10-19 17:07:29.314673 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-19 17:07:29.339847 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:29.349251 | 2025-10-19 17:07:29.349427 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-19 17:07:29.384431 | 2025-10-19 17:07:29.384733 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-19 17:07:29.421922 | 2025-10-19 17:07:29.422151 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-19 17:07:29.446580 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:29.457045 | 2025-10-19 17:07:29.457203 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-19 17:07:29.494249 | 2025-10-19 17:07:29.494572 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-19 17:07:29.519550 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:29.532520 | 2025-10-19 17:07:29.532680 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-19 17:07:29.557463 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:29.569990 | 2025-10-19 17:07:29.570144 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-19 17:07:29.594893 | debian-bookworm | skipping: Conditional result was False 2025-10-19 17:07:29.610607 | 2025-10-19 17:07:29.610784 | PLAY RECAP 2025-10-19 17:07:29.611031 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-19 17:07:29.611101 | 2025-10-19 17:07:29.742078 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 17:07:29.743182 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 17:07:30.463423 | 2025-10-19 17:07:30.463580 | PLAY [Base post] 2025-10-19 17:07:30.477098 | 2025-10-19 17:07:30.477239 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-19 17:07:31.054228 | debian-bookworm | changed 2025-10-19 17:07:31.060745 | 2025-10-19 17:07:31.060855 | PLAY RECAP 2025-10-19 17:07:31.060919 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-19 17:07:31.060983 | 2025-10-19 17:07:31.177891 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 17:07:31.179030 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-19 17:07:31.942930 | 2025-10-19 17:07:31.943096 | PLAY [Base post-logs] 2025-10-19 17:07:31.953467 | 2025-10-19 17:07:31.953601 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-19 17:07:32.434747 | localhost | changed 2025-10-19 17:07:32.445819 | 2025-10-19 17:07:32.445966 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-19 17:07:32.472758 | localhost | ok 2025-10-19 17:07:32.475788 | 2025-10-19 17:07:32.475884 | TASK [Set zuul-log-path fact] 2025-10-19 17:07:32.501046 | localhost | ok 2025-10-19 17:07:32.509551 | 2025-10-19 17:07:32.509664 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 17:07:32.548668 | localhost | ok 2025-10-19 17:07:32.552145 | 2025-10-19 17:07:32.552251 | TASK [upload-logs : Create log directories] 2025-10-19 17:07:33.059547 | localhost | changed 2025-10-19 17:07:33.062895 | 2025-10-19 17:07:33.063010 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-19 17:07:33.553312 | localhost -> localhost | ok: Runtime: 0:00:00.007943 2025-10-19 17:07:33.563148 | 2025-10-19 17:07:33.563343 | TASK [upload-logs : Upload logs to log server] 2025-10-19 17:07:34.141085 | localhost | Output suppressed because no_log was given 2025-10-19 17:07:34.145105 | 2025-10-19 17:07:34.145315 | LOOP [upload-logs : Compress console log and json output] 2025-10-19 17:07:34.204061 | localhost | skipping: Conditional result was False 2025-10-19 17:07:34.207183 | localhost | skipping: Conditional result was False 2025-10-19 17:07:34.213900 | 2025-10-19 17:07:34.214122 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-19 17:07:34.267132 | localhost | skipping: Conditional result was False 2025-10-19 17:07:34.267832 | 2025-10-19 17:07:34.270946 | localhost | skipping: Conditional result was False 2025-10-19 17:07:34.277641 | 2025-10-19 17:07:34.277868 | LOOP [upload-logs : Upload console log and json output]