2026-05-28 08:19:22.237082 | Job console starting 2026-05-28 08:19:22.271341 | Updating git repos 2026-05-28 08:19:22.373842 | Cloning repos into workspace 2026-05-28 08:19:22.423074 | Restoring repo states 2026-05-28 08:19:22.436249 | Merging changes 2026-05-28 08:19:23.045947 | Checking out repos 2026-05-28 08:19:23.113809 | Preparing playbooks 2026-05-28 08:19:24.671855 | Running Ansible setup 2026-05-28 08:19:30.123697 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 08:19:31.250047 | 2026-05-28 08:19:31.250275 | PLAY [Base pre] 2026-05-28 08:19:31.268988 | 2026-05-28 08:19:31.269147 | TASK [Setup log path fact] 2026-05-28 08:19:31.288590 | debian-bookworm | ok 2026-05-28 08:19:31.315761 | 2026-05-28 08:19:31.315947 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 08:19:31.345540 | debian-bookworm | ok 2026-05-28 08:19:31.356579 | 2026-05-28 08:19:31.356720 | TASK [emit-job-header : Print job information] 2026-05-28 08:19:31.397365 | # Job Information 2026-05-28 08:19:31.397604 | Ansible Version: 2.16.14 2026-05-28 08:19:31.397641 | Job: tox 2026-05-28 08:19:31.397674 | Pipeline: check 2026-05-28 08:19:31.397697 | Executor: 521e9411259a 2026-05-28 08:19:31.397717 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1221 2026-05-28 08:19:31.397740 | Event ID: e868e680-5a6d-11f1-82e3-e6e47bc7dbc5 2026-05-28 08:19:31.403043 | 2026-05-28 08:19:31.403171 | LOOP [emit-job-header : Print node information] 2026-05-28 08:19:31.520697 | debian-bookworm | ok: 2026-05-28 08:19:31.521004 | debian-bookworm | # Node Information 2026-05-28 08:19:31.521046 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-28 08:19:31.521072 | debian-bookworm | Hostname: debian 2026-05-28 08:19:31.521094 | debian-bookworm | Username: zuul 2026-05-28 08:19:31.521114 | debian-bookworm | Distro: Debian 12.14 2026-05-28 08:19:31.521138 | debian-bookworm | Provider: regiocloud-a 2026-05-28 08:19:31.521159 | debian-bookworm | Region: 2026-05-28 08:19:31.521179 | debian-bookworm | Label: debian-bookworm 2026-05-28 08:19:31.521199 | debian-bookworm | Product Name: OpenStack Nova 2026-05-28 08:19:31.521218 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feae:aa63 2026-05-28 08:19:31.544182 | 2026-05-28 08:19:31.544329 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-28 08:19:32.525530 | debian-bookworm -> localhost | changed 2026-05-28 08:19:32.534600 | 2026-05-28 08:19:32.534747 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-28 08:19:34.589782 | debian-bookworm -> localhost | changed 2026-05-28 08:19:34.612155 | 2026-05-28 08:19:34.612303 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-28 08:19:35.148666 | debian-bookworm -> localhost | ok 2026-05-28 08:19:35.154073 | 2026-05-28 08:19:35.154192 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-28 08:19:35.192783 | debian-bookworm | ok 2026-05-28 08:19:35.214569 | debian-bookworm | included: /var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-28 08:19:35.223321 | 2026-05-28 08:19:35.223408 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-28 08:19:36.424437 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-28 08:19:36.424614 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/work/1d2856f5924c4367b66d8a1907f5a914_id_rsa 2026-05-28 08:19:36.424645 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/work/1d2856f5924c4367b66d8a1907f5a914_id_rsa.pub 2026-05-28 08:19:36.424668 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-28 08:19:36.424688 | debian-bookworm -> localhost | SHA256:knq/+unpYqGEfW4TpoBEyv/dAdyQVS1sqldvkKvEahA zuul-build-sshkey 2026-05-28 08:19:36.424706 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-28 08:19:36.424731 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-28 08:19:36.424750 | debian-bookworm -> localhost | | o.o.. | 2026-05-28 08:19:36.424767 | debian-bookworm -> localhost | | . o + . | 2026-05-28 08:19:36.424784 | debian-bookworm -> localhost | |+ . o o o | 2026-05-28 08:19:36.424800 | debian-bookworm -> localhost | |.o E + o + | 2026-05-28 08:19:36.424817 | debian-bookworm -> localhost | |...o + S . + | 2026-05-28 08:19:36.424836 | debian-bookworm -> localhost | |. o.oo=o = . o | 2026-05-28 08:19:36.424853 | debian-bookworm -> localhost | | oo*+o= o . | 2026-05-28 08:19:36.424869 | debian-bookworm -> localhost | | oo*= = | 2026-05-28 08:19:36.424886 | debian-bookworm -> localhost | | ooBO. | 2026-05-28 08:19:36.424903 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-28 08:19:36.424940 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.553830 2026-05-28 08:19:36.437361 | 2026-05-28 08:19:36.437452 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-28 08:19:36.505515 | debian-bookworm | ok 2026-05-28 08:19:36.517370 | debian-bookworm | included: /var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-28 08:19:36.533140 | 2026-05-28 08:19:36.533240 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-28 08:19:36.580385 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:19:36.586756 | 2026-05-28 08:19:36.586861 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-28 08:19:37.283108 | debian-bookworm | changed 2026-05-28 08:19:37.287432 | 2026-05-28 08:19:37.287514 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-28 08:19:37.537387 | debian-bookworm | ok 2026-05-28 08:19:37.544259 | 2026-05-28 08:19:37.544360 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-28 08:19:38.193046 | debian-bookworm | changed 2026-05-28 08:19:38.202933 | 2026-05-28 08:19:38.203052 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-28 08:19:38.854876 | debian-bookworm | changed 2026-05-28 08:19:38.860657 | 2026-05-28 08:19:38.860750 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-28 08:19:38.901812 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:19:38.910181 | 2026-05-28 08:19:38.910282 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-28 08:19:39.665156 | debian-bookworm -> localhost | changed 2026-05-28 08:19:39.679636 | 2026-05-28 08:19:39.679744 | TASK [add-build-sshkey : Add back temp key] 2026-05-28 08:19:40.258621 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/work/1d2856f5924c4367b66d8a1907f5a914_id_rsa (zuul-build-sshkey) 2026-05-28 08:19:40.259008 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025724 2026-05-28 08:19:40.279554 | 2026-05-28 08:19:40.282008 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-28 08:19:41.305360 | debian-bookworm | ok 2026-05-28 08:19:41.324402 | 2026-05-28 08:19:41.325710 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-28 08:19:41.376900 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:19:41.422994 | 2026-05-28 08:19:41.423149 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-28 08:19:42.598156 | debian-bookworm | ok 2026-05-28 08:19:42.631086 | 2026-05-28 08:19:42.631206 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-28 08:19:42.676935 | debian-bookworm | ok 2026-05-28 08:19:42.681812 | 2026-05-28 08:19:42.681902 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-28 08:19:43.202403 | debian-bookworm -> localhost | ok 2026-05-28 08:19:43.210186 | 2026-05-28 08:19:43.210286 | TASK [validate-host : Collect information about the host] 2026-05-28 08:19:44.481112 | debian-bookworm | ok 2026-05-28 08:19:44.505020 | 2026-05-28 08:19:44.505127 | TASK [validate-host : Sanitize hostname] 2026-05-28 08:19:44.762050 | debian-bookworm | ok 2026-05-28 08:19:44.847819 | 2026-05-28 08:19:44.847932 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-28 08:19:46.472165 | debian-bookworm -> localhost | changed 2026-05-28 08:19:46.478761 | 2026-05-28 08:19:46.478860 | TASK [validate-host : Collect information about zuul worker] 2026-05-28 08:19:47.220404 | debian-bookworm | ok 2026-05-28 08:19:47.225221 | 2026-05-28 08:19:47.225342 | TASK [validate-host : Write out all zuul information for each host] 2026-05-28 08:19:48.788556 | debian-bookworm -> localhost | changed 2026-05-28 08:19:48.797534 | 2026-05-28 08:19:48.797640 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-28 08:19:49.138998 | debian-bookworm | ok 2026-05-28 08:19:49.144629 | 2026-05-28 08:19:49.144732 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-28 08:19:50.489548 | debian-bookworm | changed: 2026-05-28 08:19:50.489721 | debian-bookworm | cd+++++++++ src/ 2026-05-28 08:19:50.489757 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-28 08:19:50.489782 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-28 08:19:50.489804 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-28 08:19:50.489825 | debian-bookworm | =7.0.3 2026-05-28 08:20:17.543329 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-05-28 08:20:17.600469 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-28 08:20:17.612327 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-28 08:20:17.674603 | debian-bookworm | Collecting filelock>=3.25 2026-05-28 08:20:17.686914 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-28 08:20:17.765432 | debian-bookworm | Collecting packaging>=26 2026-05-28 08:20:17.777188 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-28 08:20:17.792333 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 10.6 MB/s eta 0:00:00 2026-05-28 08:20:17.863784 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-28 08:20:17.875896 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-05-28 08:20:17.917429 | debian-bookworm | Collecting pluggy>=1.6 2026-05-28 08:20:17.929239 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-28 08:20:17.982053 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-28 08:20:17.994371 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-28 08:20:18.031068 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-28 08:20:18.042881 | debian-bookworm | Downloading python_discovery-1.4.0-py3-none-any.whl (33 kB) 2026-05-28 08:20:18.093046 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-28 08:20:18.104996 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-28 08:20:18.251616 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-28 08:20:18.263405 | debian-bookworm | Downloading virtualenv-21.4.1-py3-none-any.whl (7.6 MB) 2026-05-28 08:20:18.600004 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.8 MB/s eta 0:00:00 2026-05-28 08:20:18.703713 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-28 08:20:18.715458 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-28 08:20:18.741744 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.6 MB/s eta 0:00:00 2026-05-28 08:20:18.866558 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-28 08:20:19.716263 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.10.0 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.4.0 tomli-w-1.2.0 tox-4.55.0 virtualenv-21.4.1 2026-05-28 08:20:19.891843 | debian-bookworm | ok: Runtime: 0:00:03.204232 2026-05-28 08:20:19.906094 | 2026-05-28 08:20:19.906250 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-28 08:20:20.006859 | debian-bookworm | ok 2026-05-28 08:20:20.023285 | 2026-05-28 08:20:20.023806 | TASK [ensure-tox : Output tox version] 2026-05-28 08:20:20.755164 | 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-28 08:20:20.773309 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-28 08:20:21.097180 | debian-bookworm | ok: Runtime: 0:00:00.329943 2026-05-28 08:20:21.102960 | 2026-05-28 08:20:21.103055 | TASK [ensure-tox : Make global symlink] 2026-05-28 08:20:21.126178 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:21.136253 | 2026-05-28 08:20:21.136333 | PLAY RECAP 2026-05-28 08:20:21.136385 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-28 08:20:21.136410 | 2026-05-28 08:20:21.321606 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-28 08:20:21.322607 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-28 08:20:23.126431 | 2026-05-28 08:20:23.136048 | PLAY [all] 2026-05-28 08:20:23.208108 | 2026-05-28 08:20:23.208258 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-28 08:20:23.768332 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-28 08:20:23.821832 | debian-bookworm | ok: Runtime: 0:00:00.030972 2026-05-28 08:20:23.848588 | 2026-05-28 08:20:23.848727 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-28 08:20:24.466164 | debian-bookworm | ok: Runtime: 0:00:00.007466 2026-05-28 08:20:24.472819 | 2026-05-28 08:20:24.472923 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-28 08:20:24.791759 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-28 08:20:24.793273 | debian-bookworm | sudo: a password is required 2026-05-28 08:20:25.024817 | debian-bookworm | ok: Runtime: 0:00:00.020616 2026-05-28 08:20:25.044170 | 2026-05-28 08:20:25.044282 | TASK [tox : Check to see if the constraints file exists] 2026-05-28 08:20:25.085821 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:25.091736 | 2026-05-28 08:20:25.091844 | TASK [tox : Fail if constraints file is missing] 2026-05-28 08:20:25.126493 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:25.132375 | 2026-05-28 08:20:25.132484 | TASK [tox : Record file location] 2026-05-28 08:20:25.156221 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:25.162764 | 2026-05-28 08:20:25.162910 | TASK [tox : Install tox siblings] 2026-05-28 08:20:25.208495 | debian-bookworm | ok 2026-05-28 08:20:25.219387 | debian-bookworm | included: /var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-28 08:20:25.225521 | 2026-05-28 08:20:25.225633 | TASK [tox : Run tox without tests] 2026-05-28 08:20:26.687344 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-28 08:20:40.239620 | debian-bookworm | test: OK (14.40 seconds) 2026-05-28 08:20:40.239671 | debian-bookworm | congratulations :) (14.43 seconds) 2026-05-28 08:20:40.770617 | debian-bookworm | ok: Runtime: 0:00:14.737194 2026-05-28 08:20:40.776695 | 2026-05-28 08:20:40.776791 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-28 08:20:41.270774 | debian-bookworm | changed 2026-05-28 08:20:41.279407 | 2026-05-28 08:20:41.279510 | TASK [tox : Detect tox version] 2026-05-28 08:20:41.852882 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-28 08:20:42.321661 | debian-bookworm | ok: Runtime: 0:00:00.342511 2026-05-28 08:20:42.329729 | 2026-05-28 08:20:42.329864 | TASK [tox : Get tox envlist config] 2026-05-28 08:20:43.389625 | debian-bookworm | ok: Runtime: 0:00:00.373562 2026-05-28 08:20:43.396342 | 2026-05-28 08:20:43.396448 | TASK [tox : Install any sibling python packages] 2026-05-28 08:20:43.961762 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-28 08:20:43.973419 | 2026-05-28 08:20:43.973551 | TASK [tox : Remove tempfile] 2026-05-28 08:20:44.468888 | debian-bookworm | changed 2026-05-28 08:20:44.489907 | 2026-05-28 08:20:44.490003 | TASK [tox : Emit tox command] 2026-05-28 08:20:44.567252 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-28 08:20:44.571402 | 2026-05-28 08:20:44.571489 | TASK [tox : Run tox] 2026-05-28 08:20:45.189336 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-28 08:20:45.942202 | debian-bookworm | ............... 2026-05-28 08:20:45.942254 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-28 08:20:45.942677 | debian-bookworm | Ran 15 tests in 0.170s 2026-05-28 08:20:45.942692 | debian-bookworm | 2026-05-28 08:20:45.942700 | debian-bookworm | OK 2026-05-28 08:20:46.035325 | debian-bookworm | test: OK (0.90=setup[0.06]+cmd[0.84] seconds) 2026-05-28 08:20:46.035386 | debian-bookworm | congratulations :) (0.93 seconds) 2026-05-28 08:20:46.135577 | debian-bookworm | ok: Runtime: 0:00:01.240208 2026-05-28 08:20:46.142043 | 2026-05-28 08:20:46.142159 | TASK [tox : Look for output] 2026-05-28 08:20:46.570209 | debian-bookworm | ok 2026-05-28 08:20:46.588453 | 2026-05-28 08:20:46.588588 | TASK [tox : Return file comments to Zuul] 2026-05-28 08:20:46.622056 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:46.631425 | 2026-05-28 08:20:46.631511 | PLAY RECAP 2026-05-28 08:20:46.631572 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-28 08:20:46.631606 | 2026-05-28 08:20:46.792205 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-28 08:20:46.793036 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-28 08:20:47.584304 | 2026-05-28 08:20:47.584436 | PLAY [all] 2026-05-28 08:20:47.618095 | 2026-05-28 08:20:47.618239 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-28 08:20:47.662199 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:47.666869 | 2026-05-28 08:20:47.666982 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-28 08:20:47.704097 | debian-bookworm | ok 2026-05-28 08:20:47.708134 | 2026-05-28 08:20:47.708215 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-28 08:20:48.247216 | debian-bookworm -> localhost | changed 2026-05-28 08:20:48.253442 | 2026-05-28 08:20:48.253541 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-28 08:20:48.337140 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:48.341775 | 2026-05-28 08:20:48.341866 | TASK [fetch-tox-output : Set envlist fact] 2026-05-28 08:20:48.391739 | debian-bookworm | ok 2026-05-28 08:20:48.397114 | 2026-05-28 08:20:48.397208 | TASK [fetch-tox-output : Get tox version] 2026-05-28 08:20:49.183343 | 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-28 08:20:49.200812 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-28 08:20:49.449702 | debian-bookworm | ok: Runtime: 0:00:00.313006 2026-05-28 08:20:49.455071 | 2026-05-28 08:20:49.455162 | TASK [fetch-tox-output : Find all default environments] 2026-05-28 08:20:49.981901 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:49.988933 | 2026-05-28 08:20:49.989049 | TASK [fetch-tox-output : Set envlist fact] 2026-05-28 08:20:50.035233 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:50.041019 | 2026-05-28 08:20:50.041121 | TASK [fetch-tox-output : Find all default environments] 2026-05-28 08:20:50.602491 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:50.622914 | 2026-05-28 08:20:50.623060 | TASK [fetch-tox-output : Set envlist fact] 2026-05-28 08:20:50.656646 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:50.662770 | 2026-05-28 08:20:50.662891 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-28 08:20:50.699309 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:50.713034 | 2026-05-28 08:20:50.713139 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-28 08:20:51.514784 | debian-bookworm | changed: 2026-05-28 08:20:51.515650 | debian-bookworm | created directory /var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/work/logs/tox/test 2026-05-28 08:20:51.515700 | debian-bookworm | cd+++++++++ ./ 2026-05-28 08:20:51.515726 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-28 08:20:51.515759 | 2026-05-28 08:20:51.529491 | 2026-05-28 08:20:51.529585 | PLAY RECAP 2026-05-28 08:20:51.529639 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-28 08:20:51.529663 | 2026-05-28 08:20:51.785758 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-28 08:20:51.786711 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-28 08:20:52.584568 | 2026-05-28 08:20:52.584697 | PLAY [all] 2026-05-28 08:20:52.599284 | 2026-05-28 08:20:52.599394 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-28 08:20:52.819636 | debian-bookworm | changed: non-zero return code 2026-05-28 08:20:52.828035 | 2026-05-28 08:20:52.828186 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-28 08:20:52.852650 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:52.862548 | 2026-05-28 08:20:52.862716 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-28 08:20:52.903811 | 2026-05-28 08:20:52.904087 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-28 08:20:52.945191 | 2026-05-28 08:20:52.945441 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-28 08:20:52.969823 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:52.978803 | 2026-05-28 08:20:52.978953 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-28 08:20:53.015479 | 2026-05-28 08:20:53.015774 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-28 08:20:53.040279 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:53.067687 | 2026-05-28 08:20:53.067821 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-28 08:20:53.082061 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:53.088371 | 2026-05-28 08:20:53.088474 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-28 08:20:53.101700 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:53.108377 | 2026-05-28 08:20:53.108443 | PLAY RECAP 2026-05-28 08:20:53.108493 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-28 08:20:53.108518 | 2026-05-28 08:20:53.200644 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-28 08:20:53.201614 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-28 08:20:54.184212 | 2026-05-28 08:20:54.184447 | PLAY [Base post-fetch] 2026-05-28 08:20:54.211426 | 2026-05-28 08:20:54.211627 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-28 08:20:54.384205 | debian-bookworm | skipping: Conditional result was False 2026-05-28 08:20:54.390749 | 2026-05-28 08:20:54.390969 | TASK [fetch-output : Set log path for single node] 2026-05-28 08:20:54.435737 | debian-bookworm | ok 2026-05-28 08:20:54.442086 | 2026-05-28 08:20:54.442230 | LOOP [fetch-output : Ensure local output dirs] 2026-05-28 08:20:55.592193 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/work/logs" 2026-05-28 08:20:56.015850 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/work/artifacts" 2026-05-28 08:20:56.422114 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1d2856f5924c4367b66d8a1907f5a914/work/docs" 2026-05-28 08:20:56.442327 | 2026-05-28 08:20:56.442488 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-28 08:20:57.808239 | debian-bookworm | changed: 2026-05-28 08:20:57.808506 | debian-bookworm | .d..t...... ./ 2026-05-28 08:20:57.808583 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-28 08:20:57.808617 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-28 08:20:57.808644 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-28 08:20:57.808688 | debian-bookworm | changed: All items complete 2026-05-28 08:20:57.808714 | 2026-05-28 08:20:58.570346 | debian-bookworm | changed: .d..t...... ./ 2026-05-28 08:20:59.147579 | debian-bookworm | changed: .d..t...... ./ 2026-05-28 08:20:59.171074 | 2026-05-28 08:20:59.171228 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-28 08:20:59.916030 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006995 2026-05-28 08:21:00.402587 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.013892 2026-05-28 08:21:00.411050 | 2026-05-28 08:21:00.411154 | PLAY RECAP 2026-05-28 08:21:00.411206 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-28 08:21:00.411231 | 2026-05-28 08:21:00.553071 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-28 08:21:00.554245 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 08:21:01.560272 | 2026-05-28 08:21:01.560489 | PLAY [Base post] 2026-05-28 08:21:01.588838 | 2026-05-28 08:21:01.589122 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-28 08:21:02.619257 | debian-bookworm | changed 2026-05-28 08:21:02.631072 | 2026-05-28 08:21:02.631205 | PLAY RECAP 2026-05-28 08:21:02.631272 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 08:21:02.631335 | 2026-05-28 08:21:02.856503 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 08:21:02.857642 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-28 08:21:03.861590 | 2026-05-28 08:21:03.861778 | PLAY [Base post-logs] 2026-05-28 08:21:03.872365 | 2026-05-28 08:21:03.872520 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-28 08:21:04.338739 | localhost | changed 2026-05-28 08:21:04.347660 | 2026-05-28 08:21:04.347790 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-28 08:21:04.378221 | localhost | ok 2026-05-28 08:21:04.381126 | 2026-05-28 08:21:04.381199 | TASK [Set zuul-log-path fact] 2026-05-28 08:21:04.395161 | localhost | ok 2026-05-28 08:21:04.401294 | 2026-05-28 08:21:04.401392 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 08:21:04.425453 | localhost | ok 2026-05-28 08:21:04.427861 | 2026-05-28 08:21:04.427935 | TASK [upload-logs : Create log directories] 2026-05-28 08:21:04.931814 | localhost | changed 2026-05-28 08:21:04.934071 | 2026-05-28 08:21:04.934167 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-28 08:21:05.664710 | localhost -> localhost | ok: Runtime: 0:00:00.008876 2026-05-28 08:21:05.668011 | 2026-05-28 08:21:05.668100 | TASK [upload-logs : Upload logs to log server] 2026-05-28 08:21:06.373034 | localhost | Output suppressed because no_log was given 2026-05-28 08:21:06.374463 | 2026-05-28 08:21:06.374535 | LOOP [upload-logs : Compress console log and json output] 2026-05-28 08:21:06.465138 | localhost | skipping: Conditional result was False 2026-05-28 08:21:06.485874 | localhost | skipping: Conditional result was False 2026-05-28 08:21:06.491664 | 2026-05-28 08:21:06.491757 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-28 08:21:06.577591 | localhost | skipping: Conditional result was False 2026-05-28 08:21:06.577808 | 2026-05-28 08:21:06.593171 | localhost | skipping: Conditional result was False 2026-05-28 08:21:06.597371 | 2026-05-28 08:21:06.597460 | LOOP [upload-logs : Upload console log and json output]