2025-10-19 18:17:21.336451 | Job console starting 2025-10-19 18:17:21.361829 | Updating git repos 2025-10-19 18:17:21.413751 | Cloning repos into workspace 2025-10-19 18:17:21.479979 | Restoring repo states 2025-10-19 18:17:21.566695 | Merging changes 2025-10-19 18:17:22.432663 | Checking out repos 2025-10-19 18:17:22.530785 | Preparing playbooks 2025-10-19 18:17:23.759078 | Running Ansible setup 2025-10-19 18:17:32.053771 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-19 18:17:33.571984 | 2025-10-19 18:17:33.572102 | PLAY [Base pre] 2025-10-19 18:17:33.595964 | 2025-10-19 18:17:33.596065 | TASK [Setup log path fact] 2025-10-19 18:17:33.629191 | debian-bookworm | ok 2025-10-19 18:17:33.647248 | 2025-10-19 18:17:33.647374 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 18:17:33.697692 | debian-bookworm | ok 2025-10-19 18:17:33.715944 | 2025-10-19 18:17:33.716041 | TASK [emit-job-header : Print job information] 2025-10-19 18:17:33.791525 | # Job Information 2025-10-19 18:17:33.791725 | Ansible Version: 2.16.14 2025-10-19 18:17:33.791755 | Job: tox 2025-10-19 18:17:33.791785 | Pipeline: check 2025-10-19 18:17:33.791807 | Executor: 521e9411259a 2025-10-19 18:17:33.791824 | Triggered by: https://github.com/osism/openstack-image-manager/pull/970 2025-10-19 18:17:33.791843 | Event ID: aa638fb0-ad17-11f0-9855-6a303a11f0e8 2025-10-19 18:17:33.795896 | 2025-10-19 18:17:33.795984 | LOOP [emit-job-header : Print node information] 2025-10-19 18:17:34.042609 | debian-bookworm | ok: 2025-10-19 18:17:34.042826 | debian-bookworm | # Node Information 2025-10-19 18:17:34.042871 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-19 18:17:34.042893 | debian-bookworm | Hostname: debian 2025-10-19 18:17:34.042912 | debian-bookworm | Username: zuul 2025-10-19 18:17:34.042929 | debian-bookworm | Distro: Debian 12.12 2025-10-19 18:17:34.042949 | debian-bookworm | Provider: regiocloud-a 2025-10-19 18:17:34.042967 | debian-bookworm | Region: 2025-10-19 18:17:34.042984 | debian-bookworm | Label: debian-bookworm 2025-10-19 18:17:34.043000 | debian-bookworm | Product Name: OpenStack Nova 2025-10-19 18:17:34.043015 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fecc:8b6c 2025-10-19 18:17:34.070654 | 2025-10-19 18:17:34.070748 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-19 18:17:35.283541 | debian-bookworm -> localhost | changed 2025-10-19 18:17:35.297247 | 2025-10-19 18:17:35.297346 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-19 18:17:37.630200 | debian-bookworm -> localhost | changed 2025-10-19 18:17:37.638795 | 2025-10-19 18:17:37.638895 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-19 18:17:38.541496 | debian-bookworm -> localhost | ok 2025-10-19 18:17:38.546497 | 2025-10-19 18:17:38.546584 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-19 18:17:38.599266 | debian-bookworm | ok 2025-10-19 18:17:38.638563 | debian-bookworm | included: /var/lib/zuul/builds/98ef2c293159425c90c15660c8ba249a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-19 18:17:38.650306 | 2025-10-19 18:17:38.650414 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-19 18:17:41.775581 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-19 18:17:41.775753 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/98ef2c293159425c90c15660c8ba249a/work/98ef2c293159425c90c15660c8ba249a_id_rsa 2025-10-19 18:17:41.775785 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/98ef2c293159425c90c15660c8ba249a/work/98ef2c293159425c90c15660c8ba249a_id_rsa.pub 2025-10-19 18:17:41.775807 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-19 18:17:41.775831 | debian-bookworm -> localhost | SHA256:VnT++EoTQdgklNElilRLHXVWW75PHmGTkINA2yQZ8cc zuul-build-sshkey 2025-10-19 18:17:41.775850 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-19 18:17:41.775877 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-19 18:17:41.775895 | debian-bookworm -> localhost | | oO%XB=+ *| 2025-10-19 18:17:41.775912 | debian-bookworm -> localhost | | . =OO=+.++| 2025-10-19 18:17:41.775928 | debian-bookworm -> localhost | | ..+ooE.*.| 2025-10-19 18:17:41.775944 | debian-bookworm -> localhost | | . .+. +| 2025-10-19 18:17:41.775960 | debian-bookworm -> localhost | | S o .o.| 2025-10-19 18:17:41.775981 | debian-bookworm -> localhost | | . o oo| 2025-10-19 18:17:41.775998 | debian-bookworm -> localhost | | o . o| 2025-10-19 18:17:41.776015 | debian-bookworm -> localhost | | . o | 2025-10-19 18:17:41.776032 | debian-bookworm -> localhost | | . | 2025-10-19 18:17:41.776048 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-19 18:17:41.776089 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.809155 2025-10-19 18:17:41.781238 | 2025-10-19 18:17:41.781327 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-19 18:17:41.828882 | debian-bookworm | ok 2025-10-19 18:17:41.839557 | debian-bookworm | included: /var/lib/zuul/builds/98ef2c293159425c90c15660c8ba249a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-19 18:17:41.857151 | 2025-10-19 18:17:41.857244 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-19 18:17:41.869968 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:17:41.874649 | 2025-10-19 18:17:41.874728 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-19 18:17:42.539007 | debian-bookworm | changed 2025-10-19 18:17:42.543600 | 2025-10-19 18:17:42.543673 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-19 18:17:42.789467 | debian-bookworm | ok 2025-10-19 18:17:42.794327 | 2025-10-19 18:17:42.794431 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-19 18:17:43.508100 | debian-bookworm | changed 2025-10-19 18:17:43.514814 | 2025-10-19 18:17:43.514945 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-19 18:17:44.215905 | debian-bookworm | changed 2025-10-19 18:17:44.230486 | 2025-10-19 18:17:44.230602 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-19 18:17:44.288441 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:17:44.295508 | 2025-10-19 18:17:44.295628 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-19 18:17:45.340131 | debian-bookworm -> localhost | changed 2025-10-19 18:17:45.348724 | 2025-10-19 18:17:45.348806 | TASK [add-build-sshkey : Add back temp key] 2025-10-19 18:17:46.580285 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/98ef2c293159425c90c15660c8ba249a/work/98ef2c293159425c90c15660c8ba249a_id_rsa (zuul-build-sshkey) 2025-10-19 18:17:46.580496 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.036279 2025-10-19 18:17:46.586742 | 2025-10-19 18:17:46.586823 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-19 18:17:47.319635 | debian-bookworm | ok 2025-10-19 18:17:47.323714 | 2025-10-19 18:17:47.323790 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-19 18:17:47.349831 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:17:47.359144 | 2025-10-19 18:17:47.359237 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-19 18:17:47.938113 | debian-bookworm | ok 2025-10-19 18:17:47.961033 | 2025-10-19 18:17:47.961131 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-19 18:17:48.037818 | debian-bookworm | ok 2025-10-19 18:17:48.042506 | 2025-10-19 18:17:48.042586 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-19 18:17:48.750412 | debian-bookworm -> localhost | ok 2025-10-19 18:17:48.765539 | 2025-10-19 18:17:48.765628 | TASK [validate-host : Collect information about the host] 2025-10-19 18:17:49.889998 | debian-bookworm | ok 2025-10-19 18:17:49.905184 | 2025-10-19 18:17:49.905274 | TASK [validate-host : Sanitize hostname] 2025-10-19 18:17:50.069102 | debian-bookworm | ok 2025-10-19 18:17:50.139510 | 2025-10-19 18:17:50.139601 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-19 18:17:50.669528 | debian-bookworm -> localhost | changed 2025-10-19 18:17:50.678803 | 2025-10-19 18:17:50.678918 | TASK [validate-host : Collect information about zuul worker] 2025-10-19 18:17:51.108780 | debian-bookworm | ok 2025-10-19 18:17:51.144538 | 2025-10-19 18:17:51.144638 | TASK [validate-host : Write out all zuul information for each host] 2025-10-19 18:17:51.693891 | debian-bookworm -> localhost | changed 2025-10-19 18:17:51.702955 | 2025-10-19 18:17:51.703036 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-19 18:17:51.972197 | debian-bookworm | ok 2025-10-19 18:17:51.976637 | 2025-10-19 18:17:51.976711 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-19 18:17:52.911527 | debian-bookworm | changed: 2025-10-19 18:17:52.911687 | debian-bookworm | cd+++++++++ src/ 2025-10-19 18:17:52.911721 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-19 18:17:52.911746 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-19 18:17:52.911769 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-19 18:17:52.911789 | debian-bookworm | =6.2 2025-10-19 18:18:14.067896 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-19 18:18:14.100561 | debian-bookworm | Collecting chardet>=5.2 2025-10-19 18:18:14.113515 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-19 18:18:14.123081 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 27.4 MB/s eta 0:00:00 2025-10-19 18:18:14.157481 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-19 18:18:14.169311 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-19 18:18:14.220285 | debian-bookworm | Collecting filelock>=3.20 2025-10-19 18:18:14.232766 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-19 18:18:14.277681 | debian-bookworm | Collecting packaging>=25 2025-10-19 18:18:14.289275 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-19 18:18:14.295846 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 13.5 MB/s eta 0:00:00 2025-10-19 18:18:14.340997 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-19 18:18:14.353039 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-19 18:18:14.393022 | debian-bookworm | Collecting pluggy>=1.6 2025-10-19 18:18:14.404791 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-19 18:18:14.461166 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-19 18:18:14.473437 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-19 18:18:14.602488 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-19 18:18:14.621939 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-19 18:18:14.858538 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 25.8 MB/s eta 0:00:00 2025-10-19 18:18:14.962794 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-19 18:18:14.974321 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-19 18:18:14.995192 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 25.2 MB/s eta 0:00:00 2025-10-19 18:18:15.127739 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-19 18:18:15.863404 | 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 18:18:16.068872 | debian-bookworm | ok: Runtime: 0:00:02.648263 2025-10-19 18:18:16.073455 | 2025-10-19 18:18:16.073533 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-19 18:18:16.110923 | debian-bookworm | ok 2025-10-19 18:18:16.114727 | 2025-10-19 18:18:16.114797 | TASK [ensure-tox : Output tox version] 2025-10-19 18:18:16.589851 | 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 18:18:16.608166 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 18:18:17.140109 | debian-bookworm | ok: Runtime: 0:00:00.290306 2025-10-19 18:18:17.144714 | 2025-10-19 18:18:17.144789 | TASK [ensure-tox : Make global symlink] 2025-10-19 18:18:17.157189 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:17.162091 | 2025-10-19 18:18:17.162144 | PLAY RECAP 2025-10-19 18:18:17.162184 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-19 18:18:17.162204 | 2025-10-19 18:18:17.245121 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-19 18:18:17.246075 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 18:18:18.147815 | 2025-10-19 18:18:18.147937 | PLAY [all] 2025-10-19 18:18:18.167699 | 2025-10-19 18:18:18.167810 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-19 18:18:18.607011 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 18:18:18.697579 | debian-bookworm | ok: Runtime: 0:00:00.031467 2025-10-19 18:18:18.704877 | 2025-10-19 18:18:18.705024 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-19 18:18:19.242292 | debian-bookworm | ok: Runtime: 0:00:00.007391 2025-10-19 18:18:19.246922 | 2025-10-19 18:18:19.247004 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-19 18:18:19.523672 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-19 18:18:19.526903 | debian-bookworm | sudo: a password is required 2025-10-19 18:18:19.768597 | debian-bookworm | ok: Runtime: 0:00:00.021968 2025-10-19 18:18:19.777572 | 2025-10-19 18:18:19.777651 | TASK [tox : Check to see if the constraints file exists] 2025-10-19 18:18:19.810591 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:19.816403 | 2025-10-19 18:18:19.816498 | TASK [tox : Fail if constraints file is missing] 2025-10-19 18:18:19.869792 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:19.874641 | 2025-10-19 18:18:19.874725 | TASK [tox : Record file location] 2025-10-19 18:18:19.917711 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:19.924013 | 2025-10-19 18:18:19.924098 | TASK [tox : Install tox siblings] 2025-10-19 18:18:19.943155 | debian-bookworm | ok 2025-10-19 18:18:19.949507 | debian-bookworm | included: /var/lib/zuul/builds/98ef2c293159425c90c15660c8ba249a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-19 18:18:19.953869 | 2025-10-19 18:18:19.953939 | TASK [tox : Run tox without tests] 2025-10-19 18:18:21.125422 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-19 18:18:34.713819 | debian-bookworm | test: OK (14.20 seconds) 2025-10-19 18:18:34.713903 | debian-bookworm | congratulations :) (14.22 seconds) 2025-10-19 18:18:34.990609 | debian-bookworm | ok: Runtime: 0:00:14.523069 2025-10-19 18:18:35.004153 | 2025-10-19 18:18:35.004305 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-19 18:18:35.446886 | debian-bookworm | changed 2025-10-19 18:18:35.455580 | 2025-10-19 18:18:35.455716 | TASK [tox : Get tox envlist config] 2025-10-19 18:18:36.487206 | debian-bookworm | ok: Runtime: 0:00:00.346998 2025-10-19 18:18:36.498945 | 2025-10-19 18:18:36.499107 | TASK [tox : Install any sibling python packages] 2025-10-19 18:18:36.933652 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-19 18:18:36.944566 | 2025-10-19 18:18:36.945069 | TASK [tox : Remove tempfile] 2025-10-19 18:18:37.381578 | debian-bookworm | changed 2025-10-19 18:18:37.392280 | 2025-10-19 18:18:37.392451 | TASK [tox : Emit tox command] 2025-10-19 18:18:37.439390 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-19 18:18:37.447993 | 2025-10-19 18:18:37.448135 | TASK [tox : Run tox] 2025-10-19 18:18:38.134251 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-19 18:18:38.779016 | debian-bookworm | ............... 2025-10-19 18:18:38.779115 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-19 18:18:38.779134 | debian-bookworm | Ran 15 tests in 0.177s 2025-10-19 18:18:38.779140 | debian-bookworm | 2025-10-19 18:18:38.779149 | debian-bookworm | OK 2025-10-19 18:18:38.896012 | debian-bookworm | test: OK (0.80=setup[0.04]+cmd[0.76] seconds) 2025-10-19 18:18:38.896095 | debian-bookworm | congratulations :) (0.83 seconds) 2025-10-19 18:18:39.027097 | debian-bookworm | ok: Runtime: 0:00:01.132525 2025-10-19 18:18:39.032710 | 2025-10-19 18:18:39.032821 | TASK [tox : Look for output] 2025-10-19 18:18:39.406209 | debian-bookworm | ok 2025-10-19 18:18:39.428857 | 2025-10-19 18:18:39.429021 | TASK [tox : Return file comments to Zuul] 2025-10-19 18:18:39.465036 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:39.473117 | 2025-10-19 18:18:39.473239 | PLAY RECAP 2025-10-19 18:18:39.473321 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-19 18:18:39.473386 | 2025-10-19 18:18:39.615785 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-19 18:18:39.616880 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 18:18:40.359032 | 2025-10-19 18:18:40.359198 | PLAY [all] 2025-10-19 18:18:40.374557 | 2025-10-19 18:18:40.374695 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-19 18:18:40.429939 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:40.442746 | 2025-10-19 18:18:40.442975 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-19 18:18:40.487119 | debian-bookworm | ok 2025-10-19 18:18:40.493841 | 2025-10-19 18:18:40.494033 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-19 18:18:40.973685 | debian-bookworm -> localhost | changed 2025-10-19 18:18:40.986497 | 2025-10-19 18:18:40.986687 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-19 18:18:41.092597 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:41.100660 | 2025-10-19 18:18:41.100828 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 18:18:41.143046 | debian-bookworm | ok 2025-10-19 18:18:41.151648 | 2025-10-19 18:18:41.151764 | TASK [fetch-tox-output : Get tox version] 2025-10-19 18:18:41.830501 | 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 18:18:41.847687 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-19 18:18:42.189417 | debian-bookworm | ok: Runtime: 0:00:00.256872 2025-10-19 18:18:42.196438 | 2025-10-19 18:18:42.196564 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 18:18:42.729387 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:42.735486 | 2025-10-19 18:18:42.735631 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 18:18:42.783827 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:42.789677 | 2025-10-19 18:18:42.789792 | TASK [fetch-tox-output : Find all default environments] 2025-10-19 18:18:43.347329 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:43.357487 | 2025-10-19 18:18:43.357608 | TASK [fetch-tox-output : Set envlist fact] 2025-10-19 18:18:43.403672 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:43.410604 | 2025-10-19 18:18:43.410710 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-19 18:18:43.489053 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:43.512387 | 2025-10-19 18:18:43.513129 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-19 18:18:44.336300 | debian-bookworm | changed: 2025-10-19 18:18:44.336537 | debian-bookworm | created directory /var/lib/zuul/builds/98ef2c293159425c90c15660c8ba249a/work/logs/tox/test 2025-10-19 18:18:44.336575 | debian-bookworm | cd+++++++++ ./ 2025-10-19 18:18:44.336600 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-19 18:18:44.336633 | 2025-10-19 18:18:44.343861 | 2025-10-19 18:18:44.343945 | PLAY RECAP 2025-10-19 18:18:44.343996 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-19 18:18:44.344021 | 2025-10-19 18:18:44.464715 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-19 18:18:44.465747 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 18:18:45.236384 | 2025-10-19 18:18:45.236544 | PLAY [all] 2025-10-19 18:18:45.253903 | 2025-10-19 18:18:45.254047 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-19 18:18:45.448275 | debian-bookworm | changed: non-zero return code 2025-10-19 18:18:45.454075 | 2025-10-19 18:18:45.454206 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-19 18:18:45.492215 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:45.498108 | 2025-10-19 18:18:45.498231 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-19 18:18:45.531940 | 2025-10-19 18:18:45.532141 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-19 18:18:45.563052 | 2025-10-19 18:18:45.563245 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-19 18:18:45.597423 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:45.604405 | 2025-10-19 18:18:45.604531 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-19 18:18:45.633294 | 2025-10-19 18:18:45.633515 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-19 18:18:45.656877 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:45.662800 | 2025-10-19 18:18:45.662956 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-19 18:18:45.686327 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:45.692252 | 2025-10-19 18:18:45.692383 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-19 18:18:45.717178 | debian-bookworm | skipping: Conditional result was False 2025-10-19 18:18:45.724200 | 2025-10-19 18:18:45.724280 | PLAY RECAP 2025-10-19 18:18:45.724332 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-19 18:18:45.724383 | 2025-10-19 18:18:45.849751 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-19 18:18:45.850813 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 18:18:46.583143 | 2025-10-19 18:18:46.583308 | PLAY [Base post] 2025-10-19 18:18:46.596855 | 2025-10-19 18:18:46.597007 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-19 18:18:47.282272 | debian-bookworm | changed 2025-10-19 18:18:47.295441 | 2025-10-19 18:18:47.295577 | PLAY RECAP 2025-10-19 18:18:47.295654 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-19 18:18:47.295729 | 2025-10-19 18:18:47.409998 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 18:18:47.411111 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-19 18:18:48.242560 | 2025-10-19 18:18:48.242720 | PLAY [Base post-logs] 2025-10-19 18:18:48.253131 | 2025-10-19 18:18:48.253260 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-19 18:18:48.716888 | localhost | changed 2025-10-19 18:18:48.728570 | 2025-10-19 18:18:48.728712 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-19 18:18:48.757157 | localhost | ok 2025-10-19 18:18:48.766569 | 2025-10-19 18:18:48.766722 | TASK [Set zuul-log-path fact] 2025-10-19 18:18:48.785714 | localhost | ok 2025-10-19 18:18:48.799599 | 2025-10-19 18:18:48.799716 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 18:18:48.825077 | localhost | ok 2025-10-19 18:18:48.829372 | 2025-10-19 18:18:48.829473 | TASK [upload-logs : Create log directories] 2025-10-19 18:18:49.296015 | localhost | changed 2025-10-19 18:18:49.298823 | 2025-10-19 18:18:49.299052 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-19 18:18:49.778025 | localhost -> localhost | ok: Runtime: 0:00:00.006621 2025-10-19 18:18:49.782119 | 2025-10-19 18:18:49.782235 | TASK [upload-logs : Upload logs to log server] 2025-10-19 18:18:50.365443 | localhost | Output suppressed because no_log was given 2025-10-19 18:18:50.367373 | 2025-10-19 18:18:50.367479 | LOOP [upload-logs : Compress console log and json output] 2025-10-19 18:18:50.414070 | localhost | skipping: Conditional result was False 2025-10-19 18:18:50.421994 | localhost | skipping: Conditional result was False 2025-10-19 18:18:50.435968 | 2025-10-19 18:18:50.436160 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-19 18:18:50.485090 | localhost | skipping: Conditional result was False 2025-10-19 18:18:50.485403 | 2025-10-19 18:18:50.490081 | localhost | skipping: Conditional result was False 2025-10-19 18:18:50.494423 | 2025-10-19 18:18:50.494542 | LOOP [upload-logs : Upload console log and json output]