2026-05-18 09:45:41.379093 | Job console starting 2026-05-18 09:45:41.390939 | Updating git repos 2026-05-18 09:45:41.432385 | Cloning repos into workspace 2026-05-18 09:45:41.528352 | Restoring repo states 2026-05-18 09:45:41.546272 | Merging changes 2026-05-18 09:45:42.066847 | Checking out repos 2026-05-18 09:45:42.082338 | Preparing playbooks 2026-05-18 09:45:42.655705 | Running Ansible setup 2026-05-18 09:45:47.892225 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 09:45:48.838067 | 2026-05-18 09:45:48.838231 | PLAY [Base pre] 2026-05-18 09:45:48.859973 | 2026-05-18 09:45:48.860128 | TASK [Setup log path fact] 2026-05-18 09:45:48.889019 | debian-bookworm | ok 2026-05-18 09:45:48.913743 | 2026-05-18 09:45:48.913982 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 09:45:48.955317 | debian-bookworm | ok 2026-05-18 09:45:48.966039 | 2026-05-18 09:45:48.966167 | TASK [emit-job-header : Print job information] 2026-05-18 09:45:49.011223 | # Job Information 2026-05-18 09:45:49.011562 | Ansible Version: 2.16.14 2026-05-18 09:45:49.011618 | Job: tox 2026-05-18 09:45:49.011670 | Pipeline: check 2026-05-18 09:45:49.011705 | Executor: 521e9411259a 2026-05-18 09:45:49.011737 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1218 2026-05-18 09:45:49.011770 | Event ID: 237c5fe0-529e-11f1-9264-2e5cd283f088 2026-05-18 09:45:49.018990 | 2026-05-18 09:45:49.019138 | LOOP [emit-job-header : Print node information] 2026-05-18 09:45:49.153939 | debian-bookworm | ok: 2026-05-18 09:45:49.154249 | debian-bookworm | # Node Information 2026-05-18 09:45:49.154333 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 09:45:49.154361 | debian-bookworm | Hostname: debian 2026-05-18 09:45:49.154384 | debian-bookworm | Username: zuul 2026-05-18 09:45:49.154406 | debian-bookworm | Distro: Debian 12.14 2026-05-18 09:45:49.154430 | debian-bookworm | Provider: regiocloud-a 2026-05-18 09:45:49.154451 | debian-bookworm | Region: 2026-05-18 09:45:49.154472 | debian-bookworm | Label: debian-bookworm 2026-05-18 09:45:49.154492 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 09:45:49.154511 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe79:e789 2026-05-18 09:45:49.177665 | 2026-05-18 09:45:49.177833 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 09:45:50.198652 | debian-bookworm -> localhost | changed 2026-05-18 09:45:50.214875 | 2026-05-18 09:45:50.215090 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 09:45:51.861060 | debian-bookworm -> localhost | changed 2026-05-18 09:45:51.900398 | 2026-05-18 09:45:51.900802 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 09:45:52.595127 | debian-bookworm -> localhost | ok 2026-05-18 09:45:52.603317 | 2026-05-18 09:45:52.603513 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 09:45:52.663268 | debian-bookworm | ok 2026-05-18 09:45:52.707487 | debian-bookworm | included: /var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 09:45:52.725440 | 2026-05-18 09:45:52.725594 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 09:45:54.270312 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 09:45:54.270551 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/work/b9106b1c3bcf4af2bac013d509653beb_id_rsa 2026-05-18 09:45:54.270587 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/work/b9106b1c3bcf4af2bac013d509653beb_id_rsa.pub 2026-05-18 09:45:54.270614 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 09:45:54.270638 | debian-bookworm -> localhost | SHA256:Ef0FPZN0vWuGqc/zuAvkZSZymcMNnrVPjk8yOY41rPQ zuul-build-sshkey 2026-05-18 09:45:54.270661 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 09:45:54.270694 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 09:45:54.270716 | debian-bookworm -> localhost | | .. .+..o| 2026-05-18 09:45:54.270738 | debian-bookworm -> localhost | | .. *..| 2026-05-18 09:45:54.270758 | debian-bookworm -> localhost | | . o o o.| 2026-05-18 09:45:54.270779 | debian-bookworm -> localhost | | .o O .. | 2026-05-18 09:45:54.270798 | debian-bookworm -> localhost | | S. @ *o..| 2026-05-18 09:45:54.270825 | debian-bookworm -> localhost | | = Bo*+ | 2026-05-18 09:45:54.270868 | debian-bookworm -> localhost | | +.Xo+ | 2026-05-18 09:45:54.270889 | debian-bookworm -> localhost | | ..O.O | 2026-05-18 09:45:54.270910 | debian-bookworm -> localhost | | o.E=o | 2026-05-18 09:45:54.270930 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 09:45:54.270982 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.635038 2026-05-18 09:45:54.277316 | 2026-05-18 09:45:54.277425 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 09:45:54.295843 | debian-bookworm | ok 2026-05-18 09:45:54.308585 | debian-bookworm | included: /var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 09:45:54.318027 | 2026-05-18 09:45:54.318142 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 09:45:54.351928 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:45:54.358326 | 2026-05-18 09:45:54.358461 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 09:45:54.950110 | debian-bookworm | changed 2026-05-18 09:45:54.955661 | 2026-05-18 09:45:54.955751 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 09:45:55.196790 | debian-bookworm | ok 2026-05-18 09:45:55.202369 | 2026-05-18 09:45:55.202462 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 09:45:55.858145 | debian-bookworm | changed 2026-05-18 09:45:55.868677 | 2026-05-18 09:45:55.868788 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 09:45:56.456468 | debian-bookworm | changed 2026-05-18 09:45:56.466735 | 2026-05-18 09:45:56.466875 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 09:45:56.511236 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:45:56.517578 | 2026-05-18 09:45:56.517691 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 09:45:57.452745 | debian-bookworm -> localhost | changed 2026-05-18 09:45:57.464170 | 2026-05-18 09:45:57.464281 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 09:45:58.053979 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/work/b9106b1c3bcf4af2bac013d509653beb_id_rsa (zuul-build-sshkey) 2026-05-18 09:45:58.054196 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.040242 2026-05-18 09:45:58.061698 | 2026-05-18 09:45:58.061795 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 09:45:58.608043 | debian-bookworm | ok 2026-05-18 09:45:58.612958 | 2026-05-18 09:45:58.613048 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 09:45:58.653516 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:45:58.663563 | 2026-05-18 09:45:58.663662 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 09:45:59.247238 | debian-bookworm | ok 2026-05-18 09:45:59.261562 | 2026-05-18 09:45:59.261662 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 09:45:59.301105 | debian-bookworm | ok 2026-05-18 09:45:59.305894 | 2026-05-18 09:45:59.305974 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 09:45:59.687816 | debian-bookworm -> localhost | ok 2026-05-18 09:45:59.694006 | 2026-05-18 09:45:59.694094 | TASK [validate-host : Collect information about the host] 2026-05-18 09:46:00.832667 | debian-bookworm | ok 2026-05-18 09:46:00.857242 | 2026-05-18 09:46:00.857379 | TASK [validate-host : Sanitize hostname] 2026-05-18 09:46:01.021391 | debian-bookworm | ok 2026-05-18 09:46:01.150072 | 2026-05-18 09:46:01.150216 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 09:46:02.112376 | debian-bookworm -> localhost | changed 2026-05-18 09:46:02.127978 | 2026-05-18 09:46:02.128239 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 09:46:02.735585 | debian-bookworm | ok 2026-05-18 09:46:02.742424 | 2026-05-18 09:46:02.742547 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 09:46:03.605612 | debian-bookworm -> localhost | changed 2026-05-18 09:46:03.616878 | 2026-05-18 09:46:03.616993 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 09:46:03.907182 | debian-bookworm | ok 2026-05-18 09:46:03.913261 | 2026-05-18 09:46:03.913400 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 09:46:04.944397 | debian-bookworm | changed: 2026-05-18 09:46:04.944972 | debian-bookworm | cd+++++++++ src/ 2026-05-18 09:46:04.945128 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 09:46:04.945494 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 09:46:04.945647 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-18 09:46:04.945677 | debian-bookworm | =7.0.3 2026-05-18 09:46:31.915088 | debian-bookworm | Downloading cachetools-7.1.2-py3-none-any.whl (16 kB) 2026-05-18 09:46:31.972080 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-18 09:46:31.984858 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-18 09:46:32.060257 | debian-bookworm | Collecting filelock>=3.25 2026-05-18 09:46:32.072178 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-18 09:46:32.123080 | debian-bookworm | Collecting packaging>=26 2026-05-18 09:46:32.134873 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-18 09:46:32.145928 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.6 MB/s eta 0:00:00 2026-05-18 09:46:32.196358 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-18 09:46:32.208441 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-18 09:46:32.247974 | debian-bookworm | Collecting pluggy>=1.6 2026-05-18 09:46:32.260478 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-18 09:46:32.309779 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-18 09:46:32.322379 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-18 09:46:32.371053 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-18 09:46:32.383669 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-18 09:46:32.427438 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-18 09:46:32.439889 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-18 09:46:32.584735 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-18 09:46:32.596730 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-18 09:46:32.949905 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 21.9 MB/s eta 0:00:00 2026-05-18 09:46:33.066458 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-18 09:46:33.078062 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-18 09:46:33.107684 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 17.5 MB/s eta 0:00:00 2026-05-18 09:46:33.232360 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-18 09:46:34.044186 | debian-bookworm | Successfully installed cachetools-7.1.2 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.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-18 09:46:34.284458 | debian-bookworm | ok: Runtime: 0:00:03.146362 2026-05-18 09:46:34.290912 | 2026-05-18 09:46:34.291066 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-18 09:46:34.362504 | debian-bookworm | ok 2026-05-18 09:46:34.374903 | 2026-05-18 09:46:34.375070 | TASK [ensure-tox : Output tox version] 2026-05-18 09:46:35.072885 | 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-18 09:46:35.090965 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 09:46:35.438640 | debian-bookworm | ok: Runtime: 0:00:00.351912 2026-05-18 09:46:35.445135 | 2026-05-18 09:46:35.445252 | TASK [ensure-tox : Make global symlink] 2026-05-18 09:46:35.470103 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:46:35.477243 | 2026-05-18 09:46:35.477330 | PLAY RECAP 2026-05-18 09:46:35.477387 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-18 09:46:35.477412 | 2026-05-18 09:46:35.654541 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-18 09:46:35.655710 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-18 09:46:36.497339 | 2026-05-18 09:46:36.497556 | PLAY [all] 2026-05-18 09:46:36.514046 | 2026-05-18 09:46:36.514203 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-18 09:46:37.005780 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-18 09:46:37.068365 | debian-bookworm | ok: Runtime: 0:00:00.025373 2026-05-18 09:46:37.074083 | 2026-05-18 09:46:37.074229 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-18 09:46:37.603622 | debian-bookworm | ok: Runtime: 0:00:00.007672 2026-05-18 09:46:37.619081 | 2026-05-18 09:46:37.619279 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-18 09:46:37.936286 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-18 09:46:37.938008 | debian-bookworm | sudo: a password is required 2026-05-18 09:46:38.153937 | debian-bookworm | ok: Runtime: 0:00:00.018567 2026-05-18 09:46:38.164296 | 2026-05-18 09:46:38.164469 | TASK [tox : Check to see if the constraints file exists] 2026-05-18 09:46:38.199824 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:46:38.206899 | 2026-05-18 09:46:38.207049 | TASK [tox : Fail if constraints file is missing] 2026-05-18 09:46:38.242119 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:46:38.249332 | 2026-05-18 09:46:38.249492 | TASK [tox : Record file location] 2026-05-18 09:46:38.284933 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:46:38.292275 | 2026-05-18 09:46:38.292456 | TASK [tox : Install tox siblings] 2026-05-18 09:46:38.325268 | debian-bookworm | ok 2026-05-18 09:46:38.335199 | debian-bookworm | included: /var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-18 09:46:38.342336 | 2026-05-18 09:46:38.342498 | TASK [tox : Run tox without tests] 2026-05-18 09:46:39.727809 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-18 09:46:53.370076 | debian-bookworm | test: OK (14.43 seconds) 2026-05-18 09:46:53.370171 | debian-bookworm | congratulations :) (14.46 seconds) 2026-05-18 09:46:53.905564 | debian-bookworm | ok: Runtime: 0:00:14.757662 2026-05-18 09:46:53.919658 | 2026-05-18 09:46:53.919862 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-18 09:46:54.357986 | debian-bookworm | changed 2026-05-18 09:46:54.365558 | 2026-05-18 09:46:54.365705 | TASK [tox : Detect tox version] 2026-05-18 09:46:54.944968 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 09:46:55.400523 | debian-bookworm | ok: Runtime: 0:00:00.340892 2026-05-18 09:46:55.406992 | 2026-05-18 09:46:55.407141 | TASK [tox : Get tox envlist config] 2026-05-18 09:46:56.438951 | debian-bookworm | ok: Runtime: 0:00:00.377970 2026-05-18 09:46:56.450964 | 2026-05-18 09:46:56.451139 | TASK [tox : Install any sibling python packages] 2026-05-18 09:46:56.939199 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-18 09:46:56.949152 | 2026-05-18 09:46:56.949309 | TASK [tox : Remove tempfile] 2026-05-18 09:46:57.466037 | debian-bookworm | changed 2026-05-18 09:46:57.472869 | 2026-05-18 09:46:57.473045 | TASK [tox : Emit tox command] 2026-05-18 09:46:57.531666 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-18 09:46:57.540700 | 2026-05-18 09:46:57.540894 | TASK [tox : Run tox] 2026-05-18 09:46:58.149194 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-18 09:46:58.795242 | debian-bookworm | ............... 2026-05-18 09:46:58.795277 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-18 09:46:58.795287 | debian-bookworm | Ran 15 tests in 0.166s 2026-05-18 09:46:58.795293 | debian-bookworm | 2026-05-18 09:46:58.795742 | debian-bookworm | OK 2026-05-18 09:46:58.858964 | debian-bookworm | test: OK (0.75=setup[0.04]+cmd[0.71] seconds) 2026-05-18 09:46:58.859018 | debian-bookworm | congratulations :) (0.78 seconds) 2026-05-18 09:46:59.077074 | debian-bookworm | ok: Runtime: 0:00:01.043952 2026-05-18 09:46:59.088244 | 2026-05-18 09:46:59.088401 | TASK [tox : Look for output] 2026-05-18 09:46:59.483847 | debian-bookworm | ok 2026-05-18 09:46:59.502126 | 2026-05-18 09:46:59.502281 | TASK [tox : Return file comments to Zuul] 2026-05-18 09:46:59.547531 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:46:59.558486 | 2026-05-18 09:46:59.558614 | PLAY RECAP 2026-05-18 09:46:59.558685 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-18 09:46:59.558716 | 2026-05-18 09:46:59.739403 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-18 09:46:59.740628 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-18 09:47:00.502064 | 2026-05-18 09:47:00.502232 | PLAY [all] 2026-05-18 09:47:00.517703 | 2026-05-18 09:47:00.517847 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-18 09:47:00.577923 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:00.583975 | 2026-05-18 09:47:00.584121 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-18 09:47:00.637197 | debian-bookworm | ok 2026-05-18 09:47:00.644247 | 2026-05-18 09:47:00.644391 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-18 09:47:01.193414 | debian-bookworm -> localhost | changed 2026-05-18 09:47:01.206916 | 2026-05-18 09:47:01.207099 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-18 09:47:01.256405 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:01.270678 | 2026-05-18 09:47:01.270942 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 09:47:01.317992 | debian-bookworm | ok 2026-05-18 09:47:01.327142 | 2026-05-18 09:47:01.327303 | TASK [fetch-tox-output : Get tox version] 2026-05-18 09:47:02.015321 | 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-18 09:47:02.031857 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-18 09:47:02.361055 | debian-bookworm | ok: Runtime: 0:00:00.269294 2026-05-18 09:47:02.374037 | 2026-05-18 09:47:02.374217 | TASK [fetch-tox-output : Find all default environments] 2026-05-18 09:47:02.908898 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:02.922994 | 2026-05-18 09:47:02.923207 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 09:47:02.948989 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:02.962654 | 2026-05-18 09:47:02.962825 | TASK [fetch-tox-output : Find all default environments] 2026-05-18 09:47:03.500984 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:03.514764 | 2026-05-18 09:47:03.515000 | TASK [fetch-tox-output : Set envlist fact] 2026-05-18 09:47:03.550925 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:03.566399 | 2026-05-18 09:47:03.566593 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-18 09:47:03.619983 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:03.637062 | 2026-05-18 09:47:03.637249 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-18 09:47:04.444804 | debian-bookworm | changed: 2026-05-18 09:47:04.445033 | debian-bookworm | created directory /var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/work/logs/tox/test 2026-05-18 09:47:04.445069 | debian-bookworm | cd+++++++++ ./ 2026-05-18 09:47:04.445095 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-18 09:47:04.445126 | 2026-05-18 09:47:04.452711 | 2026-05-18 09:47:04.452811 | PLAY RECAP 2026-05-18 09:47:04.452864 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-18 09:47:04.452888 | 2026-05-18 09:47:04.601052 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-18 09:47:04.602278 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-18 09:47:05.437836 | 2026-05-18 09:47:05.438013 | PLAY [all] 2026-05-18 09:47:05.455684 | 2026-05-18 09:47:05.455846 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-18 09:47:05.719741 | debian-bookworm | changed: non-zero return code 2026-05-18 09:47:05.728389 | 2026-05-18 09:47:05.728608 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-18 09:47:05.753778 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:05.764699 | 2026-05-18 09:47:05.764893 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-18 09:47:05.803219 | 2026-05-18 09:47:05.803564 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-18 09:47:05.850646 | 2026-05-18 09:47:05.850935 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-18 09:47:05.876174 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:05.885448 | 2026-05-18 09:47:05.885626 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-18 09:47:05.920037 | 2026-05-18 09:47:05.920319 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-18 09:47:05.945390 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:05.955654 | 2026-05-18 09:47:05.955823 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-18 09:47:05.980677 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:05.994323 | 2026-05-18 09:47:05.994496 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-18 09:47:06.019602 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:06.034598 | 2026-05-18 09:47:06.034780 | PLAY RECAP 2026-05-18 09:47:06.034936 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-18 09:47:06.034983 | 2026-05-18 09:47:06.195346 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-18 09:47:06.196448 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 09:47:06.978252 | 2026-05-18 09:47:06.978417 | PLAY [Base post-fetch] 2026-05-18 09:47:06.992914 | 2026-05-18 09:47:06.993046 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-18 09:47:07.059260 | debian-bookworm | skipping: Conditional result was False 2026-05-18 09:47:07.072968 | 2026-05-18 09:47:07.073215 | TASK [fetch-output : Set log path for single node] 2026-05-18 09:47:07.132111 | debian-bookworm | ok 2026-05-18 09:47:07.139698 | 2026-05-18 09:47:07.139864 | LOOP [fetch-output : Ensure local output dirs] 2026-05-18 09:47:07.659850 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/work/logs" 2026-05-18 09:47:07.931280 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/work/artifacts" 2026-05-18 09:47:08.227629 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/b9106b1c3bcf4af2bac013d509653beb/work/docs" 2026-05-18 09:47:08.246331 | 2026-05-18 09:47:08.246488 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 09:47:09.068691 | debian-bookworm | changed: 2026-05-18 09:47:09.073043 | debian-bookworm | .d..t...... ./ 2026-05-18 09:47:09.073141 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 09:47:09.073179 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 09:47:09.073206 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 09:47:09.073260 | debian-bookworm | changed: All items complete 2026-05-18 09:47:09.073288 | 2026-05-18 09:47:09.670376 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 09:47:10.274140 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 09:47:10.304095 | 2026-05-18 09:47:10.304251 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 09:47:10.861524 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009982 2026-05-18 09:47:11.165736 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.018125 2026-05-18 09:47:11.178407 | 2026-05-18 09:47:11.178628 | PLAY RECAP 2026-05-18 09:47:11.178689 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-18 09:47:11.178716 | 2026-05-18 09:47:11.332397 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 09:47:11.335211 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 09:47:12.119031 | 2026-05-18 09:47:12.119239 | PLAY [Base post] 2026-05-18 09:47:12.134686 | 2026-05-18 09:47:12.134910 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 09:47:12.727213 | debian-bookworm | changed 2026-05-18 09:47:12.733907 | 2026-05-18 09:47:12.734025 | PLAY RECAP 2026-05-18 09:47:12.734088 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 09:47:12.734151 | 2026-05-18 09:47:12.889497 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 09:47:12.891643 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 09:47:13.772814 | 2026-05-18 09:47:13.773004 | PLAY [Base post-logs] 2026-05-18 09:47:13.784904 | 2026-05-18 09:47:13.785076 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 09:47:14.256794 | localhost | changed 2026-05-18 09:47:14.267311 | 2026-05-18 09:47:14.267495 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 09:47:14.293637 | localhost | ok 2026-05-18 09:47:14.296886 | 2026-05-18 09:47:14.296999 | TASK [Set zuul-log-path fact] 2026-05-18 09:47:14.322785 | localhost | ok 2026-05-18 09:47:14.331911 | 2026-05-18 09:47:14.332053 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 09:47:14.357967 | localhost | ok 2026-05-18 09:47:14.361237 | 2026-05-18 09:47:14.361348 | TASK [upload-logs : Create log directories] 2026-05-18 09:47:15.084737 | localhost | changed 2026-05-18 09:47:15.089210 | 2026-05-18 09:47:15.089344 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 09:47:15.634978 | localhost -> localhost | ok: Runtime: 0:00:00.008007 2026-05-18 09:47:15.639490 | 2026-05-18 09:47:15.639630 | TASK [upload-logs : Upload logs to log server] 2026-05-18 09:47:16.272106 | localhost | Output suppressed because no_log was given 2026-05-18 09:47:16.275223 | 2026-05-18 09:47:16.275348 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 09:47:16.350008 | localhost | skipping: Conditional result was False 2026-05-18 09:47:16.363046 | localhost | skipping: Conditional result was False 2026-05-18 09:47:16.369380 | 2026-05-18 09:47:16.369512 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 09:47:16.462818 | localhost | skipping: Conditional result was False 2026-05-18 09:47:16.464435 | 2026-05-18 09:47:16.474356 | localhost | skipping: Conditional result was False 2026-05-18 09:47:16.481447 | 2026-05-18 09:47:16.481609 | LOOP [upload-logs : Upload console log and json output]