2026-05-11 05:08:57.087746 | Job console starting 2026-05-11 05:08:57.126765 | Updating git repos 2026-05-11 05:08:57.193869 | Cloning repos into workspace 2026-05-11 05:08:57.284623 | Restoring repo states 2026-05-11 05:08:57.309652 | Merging changes 2026-05-11 05:08:58.074476 | Checking out repos 2026-05-11 05:08:58.131867 | Preparing playbooks 2026-05-11 05:08:59.691675 | Running Ansible setup 2026-05-11 05:09:07.275888 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 05:09:09.070257 | 2026-05-11 05:09:09.070393 | PLAY [Base pre] 2026-05-11 05:09:09.096678 | 2026-05-11 05:09:09.096788 | TASK [Setup log path fact] 2026-05-11 05:09:09.113788 | debian-bookworm | ok 2026-05-11 05:09:09.131182 | 2026-05-11 05:09:09.131295 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:09:09.168735 | debian-bookworm | ok 2026-05-11 05:09:09.180853 | 2026-05-11 05:09:09.180952 | TASK [emit-job-header : Print job information] 2026-05-11 05:09:09.284753 | # Job Information 2026-05-11 05:09:09.284908 | Ansible Version: 2.16.14 2026-05-11 05:09:09.284938 | Job: tox 2026-05-11 05:09:09.284964 | Pipeline: check 2026-05-11 05:09:09.284983 | Executor: 521e9411259a 2026-05-11 05:09:09.285000 | Triggered by: https://github.com/osism/openstack-image-manager/pull/944 2026-05-11 05:09:09.285018 | Event ID: 7d15a680-4cf7-11f1-8130-cd8ff56e0e11 2026-05-11 05:09:09.289106 | 2026-05-11 05:09:09.289179 | LOOP [emit-job-header : Print node information] 2026-05-11 05:09:09.405534 | debian-bookworm | ok: 2026-05-11 05:09:09.405715 | debian-bookworm | # Node Information 2026-05-11 05:09:09.405744 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-11 05:09:09.405764 | debian-bookworm | Hostname: debian 2026-05-11 05:09:09.405782 | debian-bookworm | Username: zuul 2026-05-11 05:09:09.405799 | debian-bookworm | Distro: Debian 12.13 2026-05-11 05:09:09.405818 | debian-bookworm | Provider: regiocloud-a 2026-05-11 05:09:09.405835 | debian-bookworm | Region: 2026-05-11 05:09:09.405851 | debian-bookworm | Label: debian-bookworm 2026-05-11 05:09:09.405868 | debian-bookworm | Product Name: OpenStack Nova 2026-05-11 05:09:09.405884 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe97:3e69 2026-05-11 05:09:09.425732 | 2026-05-11 05:09:09.425835 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 05:09:10.255277 | debian-bookworm -> localhost | changed 2026-05-11 05:09:10.261081 | 2026-05-11 05:09:10.261168 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 05:09:12.207888 | debian-bookworm -> localhost | changed 2026-05-11 05:09:12.228407 | 2026-05-11 05:09:12.228512 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 05:09:12.692271 | debian-bookworm -> localhost | ok 2026-05-11 05:09:12.697193 | 2026-05-11 05:09:12.697281 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 05:09:12.716828 | debian-bookworm | ok 2026-05-11 05:09:12.731497 | debian-bookworm | included: /var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 05:09:12.736408 | 2026-05-11 05:09:12.736483 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 05:09:15.975053 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-11 05:09:15.975268 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/work/0a2e4d25fadf4fcd88284e57571d3d39_id_rsa 2026-05-11 05:09:15.975317 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/work/0a2e4d25fadf4fcd88284e57571d3d39_id_rsa.pub 2026-05-11 05:09:15.975344 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-11 05:09:15.975368 | debian-bookworm -> localhost | SHA256:bnj805/4IycpUwITwVY+Q8uk/knrPvl/UN0FdwrHKn4 zuul-build-sshkey 2026-05-11 05:09:15.975391 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-11 05:09:15.975422 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-11 05:09:15.975444 | debian-bookworm -> localhost | | ..o+ ..o.o| 2026-05-11 05:09:15.975465 | debian-bookworm -> localhost | | +* . o.oo| 2026-05-11 05:09:15.975485 | debian-bookworm -> localhost | | ...* .. +| 2026-05-11 05:09:15.975504 | debian-bookworm -> localhost | | .o .o. +| 2026-05-11 05:09:15.975524 | debian-bookworm -> localhost | | S+.. . | 2026-05-11 05:09:15.975546 | debian-bookworm -> localhost | | + oooE . | 2026-05-11 05:09:15.975566 | debian-bookworm -> localhost | | . = +* . . | 2026-05-11 05:09:15.975585 | debian-bookworm -> localhost | | o o* =.o..| 2026-05-11 05:09:15.975605 | debian-bookworm -> localhost | | .+*oB=o | 2026-05-11 05:09:15.975625 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-11 05:09:15.975677 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.481072 2026-05-11 05:09:15.981799 | 2026-05-11 05:09:15.981906 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 05:09:15.999965 | debian-bookworm | ok 2026-05-11 05:09:16.010636 | debian-bookworm | included: /var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 05:09:16.020522 | 2026-05-11 05:09:16.020638 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 05:09:16.034568 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:16.041161 | 2026-05-11 05:09:16.041277 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 05:09:16.761617 | debian-bookworm | changed 2026-05-11 05:09:16.768601 | 2026-05-11 05:09:16.768703 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 05:09:17.052795 | debian-bookworm | ok 2026-05-11 05:09:17.058284 | 2026-05-11 05:09:17.058391 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 05:09:17.804291 | debian-bookworm | changed 2026-05-11 05:09:17.811346 | 2026-05-11 05:09:17.811439 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 05:09:18.551329 | debian-bookworm | changed 2026-05-11 05:09:18.561214 | 2026-05-11 05:09:18.561344 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 05:09:18.597012 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:18.602931 | 2026-05-11 05:09:18.603039 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 05:09:19.354744 | debian-bookworm -> localhost | changed 2026-05-11 05:09:19.363921 | 2026-05-11 05:09:19.364013 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 05:09:20.282594 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/work/0a2e4d25fadf4fcd88284e57571d3d39_id_rsa (zuul-build-sshkey) 2026-05-11 05:09:20.282792 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022476 2026-05-11 05:09:20.289357 | 2026-05-11 05:09:20.289457 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 05:09:20.683131 | debian-bookworm | ok 2026-05-11 05:09:20.691798 | 2026-05-11 05:09:20.691921 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 05:09:20.725803 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:20.742108 | 2026-05-11 05:09:20.742215 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-11 05:09:21.191503 | debian-bookworm | ok 2026-05-11 05:09:21.207587 | 2026-05-11 05:09:21.207691 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-11 05:09:21.237935 | debian-bookworm | ok 2026-05-11 05:09:21.257714 | 2026-05-11 05:09:21.257829 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-11 05:09:21.727684 | debian-bookworm -> localhost | ok 2026-05-11 05:09:21.733788 | 2026-05-11 05:09:21.733885 | TASK [validate-host : Collect information about the host] 2026-05-11 05:09:22.887316 | debian-bookworm | ok 2026-05-11 05:09:22.909178 | 2026-05-11 05:09:22.909325 | TASK [validate-host : Sanitize hostname] 2026-05-11 05:09:23.028701 | debian-bookworm | ok 2026-05-11 05:09:23.093703 | 2026-05-11 05:09:23.093819 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-11 05:09:24.354010 | debian-bookworm -> localhost | changed 2026-05-11 05:09:24.359535 | 2026-05-11 05:09:24.359641 | TASK [validate-host : Collect information about zuul worker] 2026-05-11 05:09:25.071927 | debian-bookworm | ok 2026-05-11 05:09:25.077549 | 2026-05-11 05:09:25.077654 | TASK [validate-host : Write out all zuul information for each host] 2026-05-11 05:09:25.706178 | debian-bookworm -> localhost | changed 2026-05-11 05:09:25.715649 | 2026-05-11 05:09:25.715760 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-11 05:09:26.015344 | debian-bookworm | ok 2026-05-11 05:09:26.021556 | 2026-05-11 05:09:26.021655 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-11 05:09:26.949034 | debian-bookworm | changed: 2026-05-11 05:09:26.949217 | debian-bookworm | cd+++++++++ src/ 2026-05-11 05:09:26.949252 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-11 05:09:26.949277 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-11 05:09:26.949311 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-11 05:09:26.949333 | debian-bookworm | =7.0.3 2026-05-11 05:09:52.155498 | debian-bookworm | Downloading cachetools-7.1.1-py3-none-any.whl (16 kB) 2026-05-11 05:09:52.207906 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-11 05:09:52.220155 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-11 05:09:52.283620 | debian-bookworm | Collecting filelock>=3.25 2026-05-11 05:09:52.295458 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-11 05:09:52.368946 | debian-bookworm | Collecting packaging>=26 2026-05-11 05:09:52.380270 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-11 05:09:52.391483 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.3 MB/s eta 0:00:00 2026-05-11 05:09:52.457212 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-11 05:09:52.469337 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-11 05:09:52.523779 | debian-bookworm | Collecting pluggy>=1.6 2026-05-11 05:09:52.535572 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-11 05:09:52.696415 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-11 05:09:52.709335 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-11 05:09:52.744916 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-11 05:09:52.756629 | debian-bookworm | Downloading python_discovery-1.3.0-py3-none-any.whl (33 kB) 2026-05-11 05:09:52.790760 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-11 05:09:52.802158 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-11 05:09:52.958035 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-11 05:09:52.969930 | debian-bookworm | Downloading virtualenv-21.3.1-py3-none-any.whl (7.6 MB) 2026-05-11 05:09:53.306154 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.9 MB/s eta 0:00:00 2026-05-11 05:09:53.430302 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-11 05:09:53.442711 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-11 05:09:53.464322 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 25.4 MB/s eta 0:00:00 2026-05-11 05:09:53.571263 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-11 05:09:54.341667 | debian-bookworm | Successfully installed cachetools-7.1.1 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.9.6 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.3.0 tomli-w-1.2.0 tox-4.53.1 virtualenv-21.3.1 2026-05-11 05:09:54.509061 | debian-bookworm | ok: Runtime: 0:00:03.158246 2026-05-11 05:09:54.525049 | 2026-05-11 05:09:54.525207 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-11 05:09:54.594440 | debian-bookworm | ok 2026-05-11 05:09:54.610227 | 2026-05-11 05:09:54.610402 | TASK [ensure-tox : Output tox version] 2026-05-11 05:09:55.227985 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-11 05:09:55.245494 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-11 05:09:55.686194 | debian-bookworm | ok: Runtime: 0:00:00.329133 2026-05-11 05:09:55.692635 | 2026-05-11 05:09:55.692753 | TASK [ensure-tox : Make global symlink] 2026-05-11 05:09:55.727897 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:55.746412 | 2026-05-11 05:09:55.746546 | PLAY RECAP 2026-05-11 05:09:55.746606 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-11 05:09:55.746631 | 2026-05-11 05:09:56.036160 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-11 05:09:56.037371 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-11 05:09:57.190589 | 2026-05-11 05:09:57.190721 | PLAY [all] 2026-05-11 05:09:57.218328 | 2026-05-11 05:09:57.218466 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-11 05:09:57.770833 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-11 05:09:58.280244 | debian-bookworm | ok: Runtime: 0:00:00.030001 2026-05-11 05:09:58.304594 | 2026-05-11 05:09:58.304735 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-11 05:09:58.848186 | debian-bookworm | ok: Runtime: 0:00:00.008162 2026-05-11 05:09:58.852988 | 2026-05-11 05:09:58.853074 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-11 05:09:59.224454 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-11 05:09:59.228235 | debian-bookworm | sudo: a password is required 2026-05-11 05:09:59.393653 | debian-bookworm | ok: Runtime: 0:00:00.024034 2026-05-11 05:09:59.403341 | 2026-05-11 05:09:59.403450 | TASK [tox : Check to see if the constraints file exists] 2026-05-11 05:09:59.426950 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:59.432716 | 2026-05-11 05:09:59.432828 | TASK [tox : Fail if constraints file is missing] 2026-05-11 05:09:59.456081 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:59.462008 | 2026-05-11 05:09:59.462124 | TASK [tox : Record file location] 2026-05-11 05:09:59.486211 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:09:59.491930 | 2026-05-11 05:09:59.492036 | TASK [tox : Install tox siblings] 2026-05-11 05:09:59.513310 | debian-bookworm | ok 2026-05-11 05:09:59.523384 | debian-bookworm | included: /var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-11 05:09:59.528910 | 2026-05-11 05:09:59.529014 | TASK [tox : Run tox without tests] 2026-05-11 05:10:00.807389 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-11 05:10:14.161488 | debian-bookworm | test: OK (14.10 seconds) 2026-05-11 05:10:14.161539 | debian-bookworm | congratulations :) (14.13 seconds) 2026-05-11 05:10:14.570904 | debian-bookworm | ok: Runtime: 0:00:14.431081 2026-05-11 05:10:14.582708 | 2026-05-11 05:10:14.582918 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-11 05:10:15.017430 | debian-bookworm | changed 2026-05-11 05:10:15.026001 | 2026-05-11 05:10:15.026180 | TASK [tox : Detect tox version] 2026-05-11 05:10:15.586225 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-11 05:10:16.060678 | debian-bookworm | ok: Runtime: 0:00:00.315291 2026-05-11 05:10:16.070972 | 2026-05-11 05:10:16.071188 | TASK [tox : Get tox envlist config] 2026-05-11 05:10:17.105362 | debian-bookworm | ok: Runtime: 0:00:00.368466 2026-05-11 05:10:17.118900 | 2026-05-11 05:10:17.119140 | TASK [tox : Install any sibling python packages] 2026-05-11 05:10:17.604218 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-11 05:10:17.613086 | 2026-05-11 05:10:17.613240 | TASK [tox : Remove tempfile] 2026-05-11 05:10:18.095792 | debian-bookworm | changed 2026-05-11 05:10:18.101398 | 2026-05-11 05:10:18.101513 | TASK [tox : Emit tox command] 2026-05-11 05:10:18.154461 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-11 05:10:18.161211 | 2026-05-11 05:10:18.161374 | TASK [tox : Run tox] 2026-05-11 05:10:18.805560 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-11 05:10:19.562462 | debian-bookworm | ............... 2026-05-11 05:10:19.562590 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-11 05:10:19.562629 | debian-bookworm | Ran 15 tests in 0.177s 2026-05-11 05:10:19.562644 | debian-bookworm | 2026-05-11 05:10:19.562663 | debian-bookworm | OK 2026-05-11 05:10:19.672601 | debian-bookworm | test: OK (0.92=setup[0.06]+cmd[0.86] seconds) 2026-05-11 05:10:19.672751 | debian-bookworm | congratulations :) (0.95 seconds) 2026-05-11 05:10:20.191991 | debian-bookworm | ok: Runtime: 0:00:01.249086 2026-05-11 05:10:20.199785 | 2026-05-11 05:10:20.199930 | TASK [tox : Look for output] 2026-05-11 05:10:20.663483 | debian-bookworm | ok 2026-05-11 05:10:20.681778 | 2026-05-11 05:10:20.683189 | TASK [tox : Return file comments to Zuul] 2026-05-11 05:10:20.750267 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:20.767088 | 2026-05-11 05:10:20.767214 | PLAY RECAP 2026-05-11 05:10:20.767274 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-11 05:10:20.767319 | 2026-05-11 05:10:20.908903 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-11 05:10:20.910408 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-11 05:10:21.649598 | 2026-05-11 05:10:21.649843 | PLAY [all] 2026-05-11 05:10:21.665837 | 2026-05-11 05:10:21.666006 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-11 05:10:21.721520 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:21.731512 | 2026-05-11 05:10:21.731667 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-11 05:10:21.771964 | debian-bookworm | ok 2026-05-11 05:10:21.777947 | 2026-05-11 05:10:21.778087 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-11 05:10:22.298741 | debian-bookworm -> localhost | changed 2026-05-11 05:10:22.307448 | 2026-05-11 05:10:22.307595 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-11 05:10:22.352658 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:22.367542 | 2026-05-11 05:10:22.367743 | TASK [fetch-tox-output : Set envlist fact] 2026-05-11 05:10:22.423015 | debian-bookworm | ok 2026-05-11 05:10:22.432127 | 2026-05-11 05:10:22.432267 | TASK [fetch-tox-output : Get tox version] 2026-05-11 05:10:23.149796 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-11 05:10:23.167671 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-11 05:10:23.464053 | debian-bookworm | ok: Runtime: 0:00:00.306106 2026-05-11 05:10:23.469928 | 2026-05-11 05:10:23.470040 | TASK [fetch-tox-output : Find all default environments] 2026-05-11 05:10:24.001951 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:24.017623 | 2026-05-11 05:10:24.017825 | TASK [fetch-tox-output : Set envlist fact] 2026-05-11 05:10:24.055610 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:24.066181 | 2026-05-11 05:10:24.066404 | TASK [fetch-tox-output : Find all default environments] 2026-05-11 05:10:24.601189 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:24.608456 | 2026-05-11 05:10:24.608601 | TASK [fetch-tox-output : Set envlist fact] 2026-05-11 05:10:24.643186 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:24.651585 | 2026-05-11 05:10:24.651737 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-11 05:10:24.704148 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:24.715082 | 2026-05-11 05:10:24.715225 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-11 05:10:25.540148 | debian-bookworm | changed: 2026-05-11 05:10:25.540402 | debian-bookworm | created directory /var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/work/logs/tox/test 2026-05-11 05:10:25.540450 | debian-bookworm | cd+++++++++ ./ 2026-05-11 05:10:25.540482 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-11 05:10:25.540521 | 2026-05-11 05:10:25.553265 | 2026-05-11 05:10:25.553417 | PLAY RECAP 2026-05-11 05:10:25.553472 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-11 05:10:25.553497 | 2026-05-11 05:10:25.705546 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-11 05:10:25.706730 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-11 05:10:26.592829 | 2026-05-11 05:10:26.592995 | PLAY [all] 2026-05-11 05:10:26.610553 | 2026-05-11 05:10:26.610716 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-11 05:10:26.832897 | debian-bookworm | changed: non-zero return code 2026-05-11 05:10:26.838738 | 2026-05-11 05:10:26.838919 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-11 05:10:26.863425 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:26.869921 | 2026-05-11 05:10:26.870156 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-11 05:10:26.902954 | 2026-05-11 05:10:26.903202 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-11 05:10:26.937605 | 2026-05-11 05:10:26.937853 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-11 05:10:26.963909 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:26.975018 | 2026-05-11 05:10:26.975193 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-11 05:10:27.009606 | 2026-05-11 05:10:27.009889 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-11 05:10:27.025577 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:27.039440 | 2026-05-11 05:10:27.039641 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-11 05:10:27.065193 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:27.079035 | 2026-05-11 05:10:27.079232 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-11 05:10:27.105874 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:27.121160 | 2026-05-11 05:10:27.121348 | PLAY RECAP 2026-05-11 05:10:27.121434 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-11 05:10:27.121470 | 2026-05-11 05:10:27.296034 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-11 05:10:27.297176 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 05:10:28.062080 | 2026-05-11 05:10:28.062266 | PLAY [Base post-fetch] 2026-05-11 05:10:28.077992 | 2026-05-11 05:10:28.078159 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-11 05:10:28.133435 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:10:28.142418 | 2026-05-11 05:10:28.142629 | TASK [fetch-output : Set log path for single node] 2026-05-11 05:10:28.183245 | debian-bookworm | ok 2026-05-11 05:10:28.188997 | 2026-05-11 05:10:28.189118 | LOOP [fetch-output : Ensure local output dirs] 2026-05-11 05:10:28.738487 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/work/logs" 2026-05-11 05:10:29.000830 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/work/artifacts" 2026-05-11 05:10:29.246676 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0a2e4d25fadf4fcd88284e57571d3d39/work/docs" 2026-05-11 05:10:29.266465 | 2026-05-11 05:10:29.266593 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 05:10:30.072878 | debian-bookworm | changed: 2026-05-11 05:10:30.073126 | debian-bookworm | .d..t...... ./ 2026-05-11 05:10:30.073163 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-11 05:10:30.073193 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-11 05:10:30.073219 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-11 05:10:30.073263 | debian-bookworm | changed: All items complete 2026-05-11 05:10:30.073307 | 2026-05-11 05:10:30.607795 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:10:31.154328 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:10:31.183340 | 2026-05-11 05:10:31.183519 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 05:10:31.702972 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010356 2026-05-11 05:10:32.000251 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006527 2026-05-11 05:10:32.018361 | 2026-05-11 05:10:32.018510 | PLAY RECAP 2026-05-11 05:10:32.018591 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-11 05:10:32.018632 | 2026-05-11 05:10:32.161928 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 05:10:32.163853 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:10:32.974593 | 2026-05-11 05:10:32.974767 | PLAY [Base post] 2026-05-11 05:10:32.989603 | 2026-05-11 05:10:32.989742 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 05:10:33.590501 | debian-bookworm | changed 2026-05-11 05:10:33.599476 | 2026-05-11 05:10:33.599595 | PLAY RECAP 2026-05-11 05:10:33.599669 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 05:10:33.599744 | 2026-05-11 05:10:33.724940 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:10:33.728135 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 05:10:34.660724 | 2026-05-11 05:10:34.660910 | PLAY [Base post-logs] 2026-05-11 05:10:34.672524 | 2026-05-11 05:10:34.672716 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 05:10:35.167501 | localhost | changed 2026-05-11 05:10:35.186032 | 2026-05-11 05:10:35.186222 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 05:10:35.226745 | localhost | ok 2026-05-11 05:10:35.233868 | 2026-05-11 05:10:35.234036 | TASK [Set zuul-log-path fact] 2026-05-11 05:10:35.251534 | localhost | ok 2026-05-11 05:10:35.263484 | 2026-05-11 05:10:35.263621 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:10:35.290019 | localhost | ok 2026-05-11 05:10:35.295005 | 2026-05-11 05:10:35.295146 | TASK [upload-logs : Create log directories] 2026-05-11 05:10:35.817783 | localhost | changed 2026-05-11 05:10:35.823450 | 2026-05-11 05:10:35.823618 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 05:10:36.371332 | localhost -> localhost | ok: Runtime: 0:00:00.005636 2026-05-11 05:10:36.379568 | 2026-05-11 05:10:36.379727 | TASK [upload-logs : Upload logs to log server] 2026-05-11 05:10:37.028095 | localhost | Output suppressed because no_log was given 2026-05-11 05:10:37.031975 | 2026-05-11 05:10:37.032156 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 05:10:37.106124 | localhost | skipping: Conditional result was False 2026-05-11 05:10:37.122365 | localhost | skipping: Conditional result was False 2026-05-11 05:10:37.138544 | 2026-05-11 05:10:37.138958 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 05:10:37.193398 | localhost | skipping: Conditional result was False 2026-05-11 05:10:37.194176 | 2026-05-11 05:10:37.197008 | localhost | skipping: Conditional result was False 2026-05-11 05:10:37.204823 | 2026-05-11 05:10:37.205046 | LOOP [upload-logs : Upload console log and json output]