2026-05-04 06:48:19.476040 | Job console starting 2026-05-04 06:48:19.507675 | Updating git repos 2026-05-04 06:48:19.569933 | Cloning repos into workspace 2026-05-04 06:48:19.639046 | Restoring repo states 2026-05-04 06:48:19.654755 | Merging changes 2026-05-04 06:48:20.252263 | Checking out repos 2026-05-04 06:48:20.319160 | Preparing playbooks 2026-05-04 06:48:21.211549 | Running Ansible setup 2026-05-04 06:48:26.793952 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-04 06:48:27.968219 | 2026-05-04 06:48:27.968397 | PLAY [Base pre] 2026-05-04 06:48:27.988916 | 2026-05-04 06:48:27.989110 | TASK [Setup log path fact] 2026-05-04 06:48:28.020207 | debian-bookworm | ok 2026-05-04 06:48:28.061648 | 2026-05-04 06:48:28.061858 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-04 06:48:28.124067 | debian-bookworm | ok 2026-05-04 06:48:28.149753 | 2026-05-04 06:48:28.149982 | TASK [emit-job-header : Print job information] 2026-05-04 06:48:28.232808 | # Job Information 2026-05-04 06:48:28.233033 | Ansible Version: 2.16.14 2026-05-04 06:48:28.233072 | Job: tox 2026-05-04 06:48:28.233108 | Pipeline: check 2026-05-04 06:48:28.233132 | Executor: 521e9411259a 2026-05-04 06:48:28.233153 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1216 2026-05-04 06:48:28.233175 | Event ID: 3697b350-4785-11f1-94f3-8abf345cf141 2026-05-04 06:48:28.241870 | 2026-05-04 06:48:28.242044 | LOOP [emit-job-header : Print node information] 2026-05-04 06:48:28.518331 | debian-bookworm | ok: 2026-05-04 06:48:28.518634 | debian-bookworm | # Node Information 2026-05-04 06:48:28.518680 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-04 06:48:28.518713 | debian-bookworm | Hostname: debian 2026-05-04 06:48:28.518739 | debian-bookworm | Username: zuul 2026-05-04 06:48:28.518776 | debian-bookworm | Distro: Debian 12.13 2026-05-04 06:48:28.518815 | debian-bookworm | Provider: regiocloud-a 2026-05-04 06:48:28.518872 | debian-bookworm | Region: 2026-05-04 06:48:28.518894 | debian-bookworm | Label: debian-bookworm 2026-05-04 06:48:28.518915 | debian-bookworm | Product Name: OpenStack Nova 2026-05-04 06:48:28.518941 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8b:95e3 2026-05-04 06:48:28.546670 | 2026-05-04 06:48:28.546882 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-04 06:48:29.425085 | debian-bookworm -> localhost | changed 2026-05-04 06:48:29.432918 | 2026-05-04 06:48:29.433102 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-04 06:48:31.020418 | debian-bookworm -> localhost | changed 2026-05-04 06:48:31.030895 | 2026-05-04 06:48:31.031029 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-04 06:48:31.490620 | debian-bookworm -> localhost | ok 2026-05-04 06:48:31.496852 | 2026-05-04 06:48:31.496976 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-04 06:48:31.536793 | debian-bookworm | ok 2026-05-04 06:48:31.553177 | debian-bookworm | included: /var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-04 06:48:31.559203 | 2026-05-04 06:48:31.559294 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-04 06:48:34.019537 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-04 06:48:34.019728 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/work/84a10a9a807945c0b27437acb4a98954_id_rsa 2026-05-04 06:48:34.019760 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/work/84a10a9a807945c0b27437acb4a98954_id_rsa.pub 2026-05-04 06:48:34.019782 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-04 06:48:34.019802 | debian-bookworm -> localhost | SHA256:ZXaCb3v8VrCVlA4NU/t/ZIDzA8heJa1F+qy3X7w8drg zuul-build-sshkey 2026-05-04 06:48:34.019820 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-04 06:48:34.019848 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-04 06:48:34.019866 | debian-bookworm -> localhost | | .=*..| 2026-05-04 06:48:34.019885 | debian-bookworm -> localhost | | o . *++.| 2026-05-04 06:48:34.019901 | debian-bookworm -> localhost | | . B Bo=..| 2026-05-04 06:48:34.019917 | debian-bookworm -> localhost | | * +.*.+o| 2026-05-04 06:48:34.019933 | debian-bookworm -> localhost | | S + =++| 2026-05-04 06:48:34.019951 | debian-bookworm -> localhost | | . o ..=o| 2026-05-04 06:48:34.019980 | debian-bookworm -> localhost | | . + .o=| 2026-05-04 06:48:34.019996 | debian-bookworm -> localhost | | . o++=| 2026-05-04 06:48:34.020013 | debian-bookworm -> localhost | | .E=+| 2026-05-04 06:48:34.020030 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-04 06:48:34.020071 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.782703 2026-05-04 06:48:34.025288 | 2026-05-04 06:48:34.025382 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-04 06:48:34.064998 | debian-bookworm | ok 2026-05-04 06:48:34.074554 | debian-bookworm | included: /var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-04 06:48:34.083488 | 2026-05-04 06:48:34.083588 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-04 06:48:34.096760 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:48:34.102502 | 2026-05-04 06:48:34.102591 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-04 06:48:34.786930 | debian-bookworm | changed 2026-05-04 06:48:34.800956 | 2026-05-04 06:48:34.801146 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-04 06:48:35.055612 | debian-bookworm | ok 2026-05-04 06:48:35.061493 | 2026-05-04 06:48:35.061591 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-04 06:48:35.688706 | debian-bookworm | changed 2026-05-04 06:48:35.695187 | 2026-05-04 06:48:35.695280 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-04 06:48:36.327893 | debian-bookworm | changed 2026-05-04 06:48:36.333704 | 2026-05-04 06:48:36.333816 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-04 06:48:36.348183 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:48:36.356416 | 2026-05-04 06:48:36.356524 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-04 06:48:36.853158 | debian-bookworm -> localhost | changed 2026-05-04 06:48:36.865042 | 2026-05-04 06:48:36.865146 | TASK [add-build-sshkey : Add back temp key] 2026-05-04 06:48:37.233754 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/work/84a10a9a807945c0b27437acb4a98954_id_rsa (zuul-build-sshkey) 2026-05-04 06:48:37.233984 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015591 2026-05-04 06:48:37.242759 | 2026-05-04 06:48:37.243029 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-04 06:48:37.637778 | debian-bookworm | ok 2026-05-04 06:48:37.642765 | 2026-05-04 06:48:37.642895 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-04 06:48:37.680623 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:48:37.695307 | 2026-05-04 06:48:37.695417 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-04 06:48:38.129264 | debian-bookworm | ok 2026-05-04 06:48:38.140230 | 2026-05-04 06:48:38.140338 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-04 06:48:38.168523 | debian-bookworm | ok 2026-05-04 06:48:38.173625 | 2026-05-04 06:48:38.173728 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-04 06:48:38.449771 | debian-bookworm -> localhost | ok 2026-05-04 06:48:38.467041 | 2026-05-04 06:48:38.467173 | TASK [validate-host : Collect information about the host] 2026-05-04 06:48:39.514061 | debian-bookworm | ok 2026-05-04 06:48:39.530101 | 2026-05-04 06:48:39.530254 | TASK [validate-host : Sanitize hostname] 2026-05-04 06:48:39.704179 | debian-bookworm | ok 2026-05-04 06:48:39.753804 | 2026-05-04 06:48:39.753939 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-04 06:48:40.319274 | debian-bookworm -> localhost | changed 2026-05-04 06:48:40.325098 | 2026-05-04 06:48:40.325200 | TASK [validate-host : Collect information about zuul worker] 2026-05-04 06:48:40.747145 | debian-bookworm | ok 2026-05-04 06:48:40.754248 | 2026-05-04 06:48:40.754374 | TASK [validate-host : Write out all zuul information for each host] 2026-05-04 06:48:41.426766 | debian-bookworm -> localhost | changed 2026-05-04 06:48:41.435916 | 2026-05-04 06:48:41.436031 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-04 06:48:41.706084 | debian-bookworm | ok 2026-05-04 06:48:41.720434 | 2026-05-04 06:48:41.720586 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-04 06:48:42.672226 | debian-bookworm | changed: 2026-05-04 06:48:42.672454 | debian-bookworm | cd+++++++++ src/ 2026-05-04 06:48:42.672490 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-04 06:48:42.672515 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-04 06:48:42.672537 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-04 06:48:42.672557 | debian-bookworm | =7.0.3 2026-05-04 06:49:09.279274 | debian-bookworm | Downloading cachetools-7.1.1-py3-none-any.whl (16 kB) 2026-05-04 06:49:09.324349 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-04 06:49:09.336543 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-04 06:49:09.416849 | debian-bookworm | Collecting filelock>=3.25 2026-05-04 06:49:09.428953 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-04 06:49:09.482436 | debian-bookworm | Collecting packaging>=26 2026-05-04 06:49:09.494207 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-04 06:49:09.506343 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.4 MB/s eta 0:00:00 2026-05-04 06:49:09.561679 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-04 06:49:09.574143 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-04 06:49:09.634235 | debian-bookworm | Collecting pluggy>=1.6 2026-05-04 06:49:09.645663 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-04 06:49:09.708023 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-04 06:49:09.721531 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-04 06:49:09.768688 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-04 06:49:09.782569 | debian-bookworm | Downloading python_discovery-1.2.2-py3-none-any.whl (31 kB) 2026-05-04 06:49:09.835516 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-04 06:49:09.847883 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-04 06:49:09.995499 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-04 06:49:10.007459 | debian-bookworm | Downloading virtualenv-21.3.0-py3-none-any.whl (7.6 MB) 2026-05-04 06:49:10.338633 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 23.2 MB/s eta 0:00:00 2026-05-04 06:49:10.477160 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-04 06:49:10.490136 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-04 06:49:10.513825 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.4 MB/s eta 0:00:00 2026-05-04 06:49:10.633403 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-04 06:49:11.497161 | 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.2.2 tomli-w-1.2.0 tox-4.53.1 virtualenv-21.3.0 2026-05-04 06:49:12.121402 | debian-bookworm | ok: Runtime: 0:00:03.259664 2026-05-04 06:49:12.127616 | 2026-05-04 06:49:12.127753 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-04 06:49:12.169299 | debian-bookworm | ok 2026-05-04 06:49:12.174538 | 2026-05-04 06:49:12.174686 | TASK [ensure-tox : Output tox version] 2026-05-04 06:49:12.745106 | 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-04 06:49:12.763087 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-04 06:49:13.232392 | debian-bookworm | ok: Runtime: 0:00:00.333476 2026-05-04 06:49:13.238566 | 2026-05-04 06:49:13.238683 | TASK [ensure-tox : Make global symlink] 2026-05-04 06:49:13.277872 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:13.293977 | 2026-05-04 06:49:13.294100 | PLAY RECAP 2026-05-04 06:49:13.294159 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-04 06:49:13.294184 | 2026-05-04 06:49:13.449210 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-04 06:49:13.450346 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-04 06:49:14.332893 | 2026-05-04 06:49:14.333135 | PLAY [all] 2026-05-04 06:49:14.351836 | 2026-05-04 06:49:14.352059 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-04 06:49:14.889773 | debian-bookworm | ok: Runtime: 0:00:00.018356 2026-05-04 06:49:14.897211 | 2026-05-04 06:49:14.897394 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-04 06:49:15.428014 | debian-bookworm | ok: Runtime: 0:00:00.007923 2026-05-04 06:49:15.441731 | 2026-05-04 06:49:15.441988 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-04 06:49:15.744073 | debian-bookworm | sudo: a password is required 2026-05-04 06:49:15.980271 | debian-bookworm | ok: Runtime: 0:00:00.009022 2026-05-04 06:49:15.990538 | 2026-05-04 06:49:15.990668 | TASK [tox : Check to see if the constraints file exists] 2026-05-04 06:49:16.048049 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:16.055867 | 2026-05-04 06:49:16.056030 | TASK [tox : Fail if constraints file is missing] 2026-05-04 06:49:16.090537 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:16.097181 | 2026-05-04 06:49:16.097331 | TASK [tox : Record file location] 2026-05-04 06:49:16.121567 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:16.127881 | 2026-05-04 06:49:16.128073 | TASK [tox : Install tox siblings] 2026-05-04 06:49:16.159702 | debian-bookworm | ok 2026-05-04 06:49:16.171190 | debian-bookworm | included: /var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-04 06:49:16.177881 | 2026-05-04 06:49:16.178044 | TASK [tox : Run tox without tests] 2026-05-04 06:49:17.580349 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-04 06:49:31.537801 | debian-bookworm | test: OK (14.78 seconds) 2026-05-04 06:49:31.537891 | debian-bookworm | congratulations :) (14.82 seconds) 2026-05-04 06:49:31.727540 | debian-bookworm | ok: Runtime: 0:00:15.133666 2026-05-04 06:49:31.741596 | 2026-05-04 06:49:31.741781 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-04 06:49:32.199064 | debian-bookworm | changed 2026-05-04 06:49:32.207852 | 2026-05-04 06:49:32.208049 | TASK [tox : Get tox envlist config] 2026-05-04 06:49:33.252379 | debian-bookworm | ok: Runtime: 0:00:00.328930 2026-05-04 06:49:33.263748 | 2026-05-04 06:49:33.263914 | TASK [tox : Install any sibling python packages] 2026-05-04 06:49:33.695559 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-04 06:49:33.701820 | 2026-05-04 06:49:33.701950 | TASK [tox : Remove tempfile] 2026-05-04 06:49:34.179879 | debian-bookworm | changed 2026-05-04 06:49:34.185923 | 2026-05-04 06:49:34.186063 | TASK [tox : Emit tox command] 2026-05-04 06:49:34.262640 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-04 06:49:34.271784 | 2026-05-04 06:49:34.272097 | TASK [tox : Run tox] 2026-05-04 06:49:34.910697 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-04 06:49:35.635118 | debian-bookworm | ............... 2026-05-04 06:49:35.635171 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-04 06:49:35.635188 | debian-bookworm | Ran 15 tests in 0.176s 2026-05-04 06:49:35.635193 | debian-bookworm | 2026-05-04 06:49:35.635200 | debian-bookworm | OK 2026-05-04 06:49:35.755327 | debian-bookworm | test: OK (0.89=setup[0.04]+cmd[0.84] seconds) 2026-05-04 06:49:35.755390 | debian-bookworm | congratulations :) (0.91 seconds) 2026-05-04 06:49:35.878218 | debian-bookworm | ok: Runtime: 0:00:01.202855 2026-05-04 06:49:35.890801 | 2026-05-04 06:49:35.891069 | TASK [tox : Look for output] 2026-05-04 06:49:36.294286 | debian-bookworm | ok 2026-05-04 06:49:36.321108 | 2026-05-04 06:49:36.321326 | TASK [tox : Return file comments to Zuul] 2026-05-04 06:49:36.358388 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:36.365519 | 2026-05-04 06:49:36.365681 | PLAY RECAP 2026-05-04 06:49:36.365743 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-04 06:49:36.365769 | 2026-05-04 06:49:36.519554 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-04 06:49:36.520687 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-04 06:49:37.350378 | 2026-05-04 06:49:37.350616 | PLAY [all] 2026-05-04 06:49:37.368354 | 2026-05-04 06:49:37.368571 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-04 06:49:37.427455 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:37.434195 | 2026-05-04 06:49:37.434374 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-04 06:49:37.495509 | debian-bookworm | ok 2026-05-04 06:49:37.505014 | 2026-05-04 06:49:37.505192 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-04 06:49:38.055755 | debian-bookworm -> localhost | changed 2026-05-04 06:49:38.064343 | 2026-05-04 06:49:38.064493 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-04 06:49:38.119909 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:38.130940 | 2026-05-04 06:49:38.131129 | TASK [fetch-tox-output : Set envlist fact] 2026-05-04 06:49:38.177929 | debian-bookworm | ok 2026-05-04 06:49:38.187465 | 2026-05-04 06:49:38.187636 | TASK [fetch-tox-output : Get tox version] 2026-05-04 06:49:38.913133 | 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-04 06:49:38.930770 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-04 06:49:39.220959 | debian-bookworm | ok: Runtime: 0:00:00.334300 2026-05-04 06:49:39.243681 | 2026-05-04 06:49:39.243837 | TASK [fetch-tox-output : Find all default environments] 2026-05-04 06:49:39.795374 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:39.811203 | 2026-05-04 06:49:39.811470 | TASK [fetch-tox-output : Set envlist fact] 2026-05-04 06:49:39.839925 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:39.853517 | 2026-05-04 06:49:39.853924 | TASK [fetch-tox-output : Find all default environments] 2026-05-04 06:49:40.388107 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:40.407191 | 2026-05-04 06:49:40.407387 | TASK [fetch-tox-output : Set envlist fact] 2026-05-04 06:49:40.442912 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:40.457437 | 2026-05-04 06:49:40.457605 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-04 06:49:40.508803 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:40.527004 | 2026-05-04 06:49:40.527167 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-04 06:49:41.335960 | debian-bookworm | changed: 2026-05-04 06:49:41.336241 | debian-bookworm | created directory /var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/work/logs/tox/test 2026-05-04 06:49:41.336296 | debian-bookworm | cd+++++++++ ./ 2026-05-04 06:49:41.336338 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-04 06:49:41.336386 | 2026-05-04 06:49:41.353497 | 2026-05-04 06:49:41.353590 | PLAY RECAP 2026-05-04 06:49:41.353640 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-04 06:49:41.353665 | 2026-05-04 06:49:41.449091 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-04 06:49:41.451573 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-04 06:49:42.161156 | 2026-05-04 06:49:42.161284 | PLAY [all] 2026-05-04 06:49:42.175576 | 2026-05-04 06:49:42.175695 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-04 06:49:42.406565 | debian-bookworm | changed: non-zero return code 2026-05-04 06:49:42.412012 | 2026-05-04 06:49:42.412127 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-04 06:49:42.445675 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:42.455838 | 2026-05-04 06:49:42.456007 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-04 06:49:42.505484 | 2026-05-04 06:49:42.505786 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-04 06:49:42.556326 | 2026-05-04 06:49:42.556622 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-04 06:49:42.581664 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:42.593143 | 2026-05-04 06:49:42.593321 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-04 06:49:42.631954 | 2026-05-04 06:49:42.632500 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-04 06:49:42.657647 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:42.666631 | 2026-05-04 06:49:42.666784 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-04 06:49:42.690936 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:42.704002 | 2026-05-04 06:49:42.704343 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-04 06:49:42.731589 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:42.738487 | 2026-05-04 06:49:42.738577 | PLAY RECAP 2026-05-04 06:49:42.738629 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-04 06:49:42.738656 | 2026-05-04 06:49:42.878675 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-04 06:49:42.881347 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-04 06:49:43.701193 | 2026-05-04 06:49:43.701428 | PLAY [Base post-fetch] 2026-05-04 06:49:43.727811 | 2026-05-04 06:49:43.728019 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-04 06:49:43.789767 | debian-bookworm | skipping: Conditional result was False 2026-05-04 06:49:43.802559 | 2026-05-04 06:49:43.802794 | TASK [fetch-output : Set log path for single node] 2026-05-04 06:49:43.850940 | debian-bookworm | ok 2026-05-04 06:49:43.860190 | 2026-05-04 06:49:43.860536 | LOOP [fetch-output : Ensure local output dirs] 2026-05-04 06:49:44.421257 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/work/logs" 2026-05-04 06:49:44.696108 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/work/artifacts" 2026-05-04 06:49:44.963230 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/84a10a9a807945c0b27437acb4a98954/work/docs" 2026-05-04 06:49:44.993191 | 2026-05-04 06:49:44.993482 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-04 06:49:45.833654 | debian-bookworm | changed: 2026-05-04 06:49:45.834209 | debian-bookworm | .d..t...... ./ 2026-05-04 06:49:45.834306 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-04 06:49:45.834387 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-04 06:49:45.834456 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-04 06:49:45.834565 | debian-bookworm | changed: All items complete 2026-05-04 06:49:45.834634 | 2026-05-04 06:49:46.462052 | debian-bookworm | changed: .d..t...... ./ 2026-05-04 06:49:47.077434 | debian-bookworm | changed: .d..t...... ./ 2026-05-04 06:49:47.091816 | 2026-05-04 06:49:47.092015 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-04 06:49:47.662524 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010939 2026-05-04 06:49:47.970359 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009655 2026-05-04 06:49:47.985361 | 2026-05-04 06:49:47.985505 | PLAY RECAP 2026-05-04 06:49:47.985565 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-04 06:49:47.985622 | 2026-05-04 06:49:48.148395 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-04 06:49:48.150415 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-04 06:49:48.914170 | 2026-05-04 06:49:48.914359 | PLAY [Base post] 2026-05-04 06:49:48.929892 | 2026-05-04 06:49:48.930084 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-04 06:49:49.528215 | debian-bookworm | changed 2026-05-04 06:49:49.538695 | 2026-05-04 06:49:49.538888 | PLAY RECAP 2026-05-04 06:49:49.539023 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-04 06:49:49.539110 | 2026-05-04 06:49:49.665940 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-04 06:49:49.667289 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-04 06:49:50.466868 | 2026-05-04 06:49:50.467088 | PLAY [Base post-logs] 2026-05-04 06:49:50.477957 | 2026-05-04 06:49:50.478118 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-04 06:49:50.987081 | localhost | changed 2026-05-04 06:49:51.004756 | 2026-05-04 06:49:51.004962 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-04 06:49:51.046495 | localhost | ok 2026-05-04 06:49:51.052281 | 2026-05-04 06:49:51.052465 | TASK [Set zuul-log-path fact] 2026-05-04 06:49:51.072385 | localhost | ok 2026-05-04 06:49:51.089647 | 2026-05-04 06:49:51.089831 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-04 06:49:51.128091 | localhost | ok 2026-05-04 06:49:51.132336 | 2026-05-04 06:49:51.132474 | TASK [upload-logs : Create log directories] 2026-05-04 06:49:51.734764 | localhost | changed 2026-05-04 06:49:51.740043 | 2026-05-04 06:49:51.740243 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-04 06:49:52.335754 | localhost -> localhost | ok: Runtime: 0:00:00.006728 2026-05-04 06:49:52.340207 | 2026-05-04 06:49:52.340328 | TASK [upload-logs : Upload logs to log server] 2026-05-04 06:49:52.997528 | localhost | Output suppressed because no_log was given 2026-05-04 06:49:53.004950 | 2026-05-04 06:49:53.005200 | LOOP [upload-logs : Compress console log and json output] 2026-05-04 06:49:53.066583 | localhost | skipping: Conditional result was False 2026-05-04 06:49:53.071735 | localhost | skipping: Conditional result was False 2026-05-04 06:49:53.079679 | 2026-05-04 06:49:53.079919 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-04 06:49:53.133305 | localhost | skipping: Conditional result was False 2026-05-04 06:49:53.134375 | 2026-05-04 06:49:53.137308 | localhost | skipping: Conditional result was False 2026-05-04 06:49:53.153407 | 2026-05-04 06:49:53.153629 | LOOP [upload-logs : Upload console log and json output]