2026-05-09 21:37:36.066709 | Job console starting 2026-05-09 21:37:36.104055 | Updating git repos 2026-05-09 21:37:36.182118 | Cloning repos into workspace 2026-05-09 21:37:36.236438 | Restoring repo states 2026-05-09 21:37:36.247872 | Merging changes 2026-05-09 21:37:36.845246 | Checking out repos 2026-05-09 21:37:36.948119 | Preparing playbooks 2026-05-09 21:37:38.156058 | Running Ansible setup 2026-05-09 21:37:44.196642 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-09 21:37:45.257532 | 2026-05-09 21:37:45.257741 | PLAY [Base pre] 2026-05-09 21:37:45.297943 | 2026-05-09 21:37:45.298156 | TASK [Setup log path fact] 2026-05-09 21:37:45.332869 | debian-bookworm | ok 2026-05-09 21:37:45.371356 | 2026-05-09 21:37:45.371552 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 21:37:45.421578 | debian-bookworm | ok 2026-05-09 21:37:45.451324 | 2026-05-09 21:37:45.451489 | TASK [emit-job-header : Print job information] 2026-05-09 21:37:45.522824 | # Job Information 2026-05-09 21:37:45.523075 | Ansible Version: 2.16.14 2026-05-09 21:37:45.523113 | Job: tox 2026-05-09 21:37:45.523148 | Pipeline: check 2026-05-09 21:37:45.523172 | Executor: 521e9411259a 2026-05-09 21:37:45.523194 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1218 2026-05-09 21:37:45.523216 | Event ID: 4450d0d0-4bef-11f1-9503-12226fd15d34 2026-05-09 21:37:45.541415 | 2026-05-09 21:37:45.541578 | LOOP [emit-job-header : Print node information] 2026-05-09 21:37:45.792535 | debian-bookworm | ok: 2026-05-09 21:37:45.792876 | debian-bookworm | # Node Information 2026-05-09 21:37:45.792915 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-09 21:37:45.792941 | debian-bookworm | Hostname: debian 2026-05-09 21:37:45.792980 | debian-bookworm | Username: zuul 2026-05-09 21:37:45.793002 | debian-bookworm | Distro: Debian 12.13 2026-05-09 21:37:45.793026 | debian-bookworm | Provider: regiocloud-a 2026-05-09 21:37:45.793048 | debian-bookworm | Region: 2026-05-09 21:37:45.793069 | debian-bookworm | Label: debian-bookworm 2026-05-09 21:37:45.793089 | debian-bookworm | Product Name: OpenStack Nova 2026-05-09 21:37:45.793107 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee4:a8d6 2026-05-09 21:37:45.816790 | 2026-05-09 21:37:45.816949 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-09 21:37:46.279027 | debian-bookworm -> localhost | changed 2026-05-09 21:37:46.285980 | 2026-05-09 21:37:46.286090 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-09 21:37:47.418845 | debian-bookworm -> localhost | changed 2026-05-09 21:37:47.427612 | 2026-05-09 21:37:47.427695 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-09 21:37:47.854442 | debian-bookworm -> localhost | ok 2026-05-09 21:37:47.859488 | 2026-05-09 21:37:47.859585 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-09 21:37:47.898031 | debian-bookworm | ok 2026-05-09 21:37:47.912136 | debian-bookworm | included: /var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-09 21:37:47.936613 | 2026-05-09 21:37:47.936706 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-09 21:37:50.830227 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-09 21:37:50.830405 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/work/ded44ad653784bfdac1531254d1f0801_id_rsa 2026-05-09 21:37:50.830436 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/work/ded44ad653784bfdac1531254d1f0801_id_rsa.pub 2026-05-09 21:37:50.830458 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-09 21:37:50.830478 | debian-bookworm -> localhost | SHA256:UYMR3voTq3ow6xJpV3QaH8yui0WBr3Op7NZhl6farfI zuul-build-sshkey 2026-05-09 21:37:50.830496 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-09 21:37:50.830524 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-09 21:37:50.830543 | debian-bookworm -> localhost | | .o*o | 2026-05-09 21:37:50.830560 | debian-bookworm -> localhost | | ..=o=. | 2026-05-09 21:37:50.830577 | debian-bookworm -> localhost | | ooB.. | 2026-05-09 21:37:50.830593 | debian-bookworm -> localhost | | =oo | 2026-05-09 21:37:50.830609 | debian-bookworm -> localhost | | . +So.. | 2026-05-09 21:37:50.830627 | debian-bookworm -> localhost | | + * B.oo. | 2026-05-09 21:37:50.830644 | debian-bookworm -> localhost | | . + % ++o | 2026-05-09 21:37:50.830660 | debian-bookworm -> localhost | | . * =o.o | 2026-05-09 21:37:50.830677 | debian-bookworm -> localhost | | =+oo+E.. | 2026-05-09 21:37:50.830694 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-09 21:37:50.830737 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.264186 2026-05-09 21:37:50.836008 | 2026-05-09 21:37:50.836084 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-09 21:37:50.862604 | debian-bookworm | ok 2026-05-09 21:37:50.869925 | debian-bookworm | included: /var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-09 21:37:50.877054 | 2026-05-09 21:37:50.877124 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-09 21:37:50.899856 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:37:50.904682 | 2026-05-09 21:37:50.904759 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-09 21:37:51.530717 | debian-bookworm | changed 2026-05-09 21:37:51.538099 | 2026-05-09 21:37:51.538195 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-09 21:37:51.803870 | debian-bookworm | ok 2026-05-09 21:37:51.813492 | 2026-05-09 21:37:51.813748 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-09 21:37:52.525877 | debian-bookworm | changed 2026-05-09 21:37:52.536351 | 2026-05-09 21:37:52.536480 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-09 21:37:53.162349 | debian-bookworm | changed 2026-05-09 21:37:53.171769 | 2026-05-09 21:37:53.171928 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-09 21:37:53.205318 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:37:53.212193 | 2026-05-09 21:37:53.212346 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-09 21:37:53.659643 | debian-bookworm -> localhost | changed 2026-05-09 21:37:53.670011 | 2026-05-09 21:37:53.670112 | TASK [add-build-sshkey : Add back temp key] 2026-05-09 21:37:54.006414 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/work/ded44ad653784bfdac1531254d1f0801_id_rsa (zuul-build-sshkey) 2026-05-09 21:37:54.006635 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010877 2026-05-09 21:37:54.014313 | 2026-05-09 21:37:54.014410 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-09 21:37:54.405805 | debian-bookworm | ok 2026-05-09 21:37:54.410783 | 2026-05-09 21:37:54.410884 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-09 21:37:54.474011 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:37:54.496258 | 2026-05-09 21:37:54.496376 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-09 21:37:54.956346 | debian-bookworm | ok 2026-05-09 21:37:54.966734 | 2026-05-09 21:37:54.966850 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-09 21:37:54.995149 | debian-bookworm | ok 2026-05-09 21:37:55.000274 | 2026-05-09 21:37:55.000382 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-09 21:37:55.307157 | debian-bookworm -> localhost | ok 2026-05-09 21:37:55.313366 | 2026-05-09 21:37:55.313512 | TASK [validate-host : Collect information about the host] 2026-05-09 21:37:56.496238 | debian-bookworm | ok 2026-05-09 21:37:56.510587 | 2026-05-09 21:37:56.510736 | TASK [validate-host : Sanitize hostname] 2026-05-09 21:37:56.678750 | debian-bookworm | ok 2026-05-09 21:37:56.765672 | 2026-05-09 21:37:56.765835 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-09 21:37:57.764180 | debian-bookworm -> localhost | changed 2026-05-09 21:37:57.771118 | 2026-05-09 21:37:57.771283 | TASK [validate-host : Collect information about zuul worker] 2026-05-09 21:37:58.232295 | debian-bookworm | ok 2026-05-09 21:37:58.238934 | 2026-05-09 21:37:58.239082 | TASK [validate-host : Write out all zuul information for each host] 2026-05-09 21:37:58.946034 | debian-bookworm -> localhost | changed 2026-05-09 21:37:58.958270 | 2026-05-09 21:37:58.958403 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-09 21:37:59.260051 | debian-bookworm | ok 2026-05-09 21:37:59.266222 | 2026-05-09 21:37:59.266328 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-09 21:38:00.265446 | debian-bookworm | changed: 2026-05-09 21:38:00.265701 | debian-bookworm | cd+++++++++ src/ 2026-05-09 21:38:00.265737 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-09 21:38:00.265762 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-09 21:38:00.265784 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-09 21:38:00.265804 | debian-bookworm | =7.0.3 2026-05-09 21:38:26.687793 | debian-bookworm | Downloading cachetools-7.1.1-py3-none-any.whl (16 kB) 2026-05-09 21:38:26.744970 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-09 21:38:26.757463 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-09 21:38:26.823884 | debian-bookworm | Collecting filelock>=3.25 2026-05-09 21:38:26.836205 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-09 21:38:26.889150 | debian-bookworm | Collecting packaging>=26 2026-05-09 21:38:26.901430 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-09 21:38:26.911661 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 12.6 MB/s eta 0:00:00 2026-05-09 21:38:26.983438 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-09 21:38:26.996930 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-09 21:38:27.039068 | debian-bookworm | Collecting pluggy>=1.6 2026-05-09 21:38:27.050926 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-09 21:38:27.099976 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-09 21:38:27.115557 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-09 21:38:27.154923 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-09 21:38:27.167648 | debian-bookworm | Downloading python_discovery-1.3.0-py3-none-any.whl (33 kB) 2026-05-09 21:38:27.208503 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-09 21:38:27.220131 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-09 21:38:27.385736 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-09 21:38:27.400924 | debian-bookworm | Downloading virtualenv-21.3.1-py3-none-any.whl (7.6 MB) 2026-05-09 21:38:27.830642 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 17.9 MB/s eta 0:00:00 2026-05-09 21:38:27.938761 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-09 21:38:27.950835 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-09 21:38:27.978146 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 20.9 MB/s eta 0:00:00 2026-05-09 21:38:28.096151 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-09 21:38:28.931579 | 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-09 21:38:29.546491 | debian-bookworm | ok: Runtime: 0:00:03.242519 2026-05-09 21:38:29.553476 | 2026-05-09 21:38:29.553642 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-09 21:38:29.617704 | debian-bookworm | ok 2026-05-09 21:38:29.623109 | 2026-05-09 21:38:29.623267 | TASK [ensure-tox : Output tox version] 2026-05-09 21:38:30.209362 | 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-09 21:38:30.226985 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-09 21:38:30.674098 | debian-bookworm | ok: Runtime: 0:00:00.332885 2026-05-09 21:38:30.681785 | 2026-05-09 21:38:30.682000 | TASK [ensure-tox : Make global symlink] 2026-05-09 21:38:30.707199 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:30.715277 | 2026-05-09 21:38:30.715387 | PLAY RECAP 2026-05-09 21:38:30.715447 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-09 21:38:30.715473 | 2026-05-09 21:38:30.927770 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-09 21:38:30.929193 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-09 21:38:31.932959 | 2026-05-09 21:38:31.933136 | PLAY [all] 2026-05-09 21:38:31.948896 | 2026-05-09 21:38:31.949067 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-09 21:38:32.485162 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-09 21:38:33.023170 | debian-bookworm | ok: Runtime: 0:00:00.033267 2026-05-09 21:38:33.040528 | 2026-05-09 21:38:33.040775 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-09 21:38:33.601947 | debian-bookworm | ok: Runtime: 0:00:00.008074 2026-05-09 21:38:33.609606 | 2026-05-09 21:38:33.609731 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-09 21:38:33.945953 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-09 21:38:33.948218 | debian-bookworm | sudo: a password is required 2026-05-09 21:38:34.143415 | debian-bookworm | ok: Runtime: 0:00:00.026368 2026-05-09 21:38:34.154670 | 2026-05-09 21:38:34.154812 | TASK [tox : Check to see if the constraints file exists] 2026-05-09 21:38:34.188581 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:34.198227 | 2026-05-09 21:38:34.198408 | TASK [tox : Fail if constraints file is missing] 2026-05-09 21:38:34.244330 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:34.252072 | 2026-05-09 21:38:34.252255 | TASK [tox : Record file location] 2026-05-09 21:38:34.278049 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:34.285368 | 2026-05-09 21:38:34.285537 | TASK [tox : Install tox siblings] 2026-05-09 21:38:34.318252 | debian-bookworm | ok 2026-05-09 21:38:34.329089 | debian-bookworm | included: /var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-09 21:38:34.335893 | 2026-05-09 21:38:34.336122 | TASK [tox : Run tox without tests] 2026-05-09 21:38:35.824757 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-09 21:38:50.109687 | debian-bookworm | test: OK (15.13 seconds) 2026-05-09 21:38:50.109799 | debian-bookworm | congratulations :) (15.15 seconds) 2026-05-09 21:38:50.393527 | debian-bookworm | ok: Runtime: 0:00:15.465353 2026-05-09 21:38:50.407150 | 2026-05-09 21:38:50.407341 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-09 21:38:50.837009 | debian-bookworm | changed 2026-05-09 21:38:50.845290 | 2026-05-09 21:38:50.845427 | TASK [tox : Detect tox version] 2026-05-09 21:38:51.399801 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-09 21:38:51.881435 | debian-bookworm | ok: Runtime: 0:00:00.315786 2026-05-09 21:38:51.888384 | 2026-05-09 21:38:51.888520 | TASK [tox : Get tox envlist config] 2026-05-09 21:38:52.920996 | debian-bookworm | ok: Runtime: 0:00:00.375064 2026-05-09 21:38:52.933766 | 2026-05-09 21:38:52.933946 | TASK [tox : Install any sibling python packages] 2026-05-09 21:38:53.393104 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-09 21:38:53.400105 | 2026-05-09 21:38:53.400278 | TASK [tox : Remove tempfile] 2026-05-09 21:38:53.902214 | debian-bookworm | changed 2026-05-09 21:38:53.908097 | 2026-05-09 21:38:53.908228 | TASK [tox : Emit tox command] 2026-05-09 21:38:53.960594 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-09 21:38:53.967664 | 2026-05-09 21:38:53.968035 | TASK [tox : Run tox] 2026-05-09 21:38:54.586218 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-09 21:38:55.388152 | debian-bookworm | ............... 2026-05-09 21:38:55.388672 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-09 21:38:55.388700 | debian-bookworm | Ran 15 tests in 0.179s 2026-05-09 21:38:55.388706 | debian-bookworm | 2026-05-09 21:38:55.388714 | debian-bookworm | OK 2026-05-09 21:38:55.516079 | debian-bookworm | test: OK (0.98=setup[0.05]+cmd[0.93] seconds) 2026-05-09 21:38:55.516168 | debian-bookworm | congratulations :) (1.00 seconds) 2026-05-09 21:38:56.002237 | debian-bookworm | ok: Runtime: 0:00:01.320362 2026-05-09 21:38:56.012251 | 2026-05-09 21:38:56.012406 | TASK [tox : Look for output] 2026-05-09 21:38:56.435333 | debian-bookworm | ok 2026-05-09 21:38:56.457961 | 2026-05-09 21:38:56.458122 | TASK [tox : Return file comments to Zuul] 2026-05-09 21:38:56.503376 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:56.523511 | 2026-05-09 21:38:56.523670 | PLAY RECAP 2026-05-09 21:38:56.523763 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-09 21:38:56.523807 | 2026-05-09 21:38:56.684340 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-09 21:38:56.685587 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-09 21:38:57.480014 | 2026-05-09 21:38:57.480235 | PLAY [all] 2026-05-09 21:38:57.497292 | 2026-05-09 21:38:57.497482 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-09 21:38:57.553016 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:57.559401 | 2026-05-09 21:38:57.559580 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-09 21:38:57.600376 | debian-bookworm | ok 2026-05-09 21:38:57.606057 | 2026-05-09 21:38:57.606225 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-09 21:38:58.194355 | debian-bookworm -> localhost | changed 2026-05-09 21:38:58.209267 | 2026-05-09 21:38:58.209462 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-09 21:38:58.278186 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:58.284506 | 2026-05-09 21:38:58.284645 | TASK [fetch-tox-output : Set envlist fact] 2026-05-09 21:38:58.334157 | debian-bookworm | ok 2026-05-09 21:38:58.340350 | 2026-05-09 21:38:58.340472 | TASK [fetch-tox-output : Get tox version] 2026-05-09 21:38:59.076558 | 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-09 21:38:59.094052 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-09 21:38:59.371608 | debian-bookworm | ok: Runtime: 0:00:00.307019 2026-05-09 21:38:59.383691 | 2026-05-09 21:38:59.383944 | TASK [fetch-tox-output : Find all default environments] 2026-05-09 21:38:59.917396 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:59.923738 | 2026-05-09 21:38:59.923889 | TASK [fetch-tox-output : Set envlist fact] 2026-05-09 21:38:59.968803 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:38:59.977522 | 2026-05-09 21:38:59.977670 | TASK [fetch-tox-output : Find all default environments] 2026-05-09 21:39:00.513107 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:00.519578 | 2026-05-09 21:39:00.519718 | TASK [fetch-tox-output : Set envlist fact] 2026-05-09 21:39:00.551842 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:00.559526 | 2026-05-09 21:39:00.559661 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-09 21:39:00.609579 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:00.624102 | 2026-05-09 21:39:00.624267 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-09 21:39:01.522625 | debian-bookworm | changed: 2026-05-09 21:39:01.522915 | debian-bookworm | created directory /var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/work/logs/tox/test 2026-05-09 21:39:01.522957 | debian-bookworm | cd+++++++++ ./ 2026-05-09 21:39:01.522983 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-09 21:39:01.523016 | 2026-05-09 21:39:01.538283 | 2026-05-09 21:39:01.538417 | PLAY RECAP 2026-05-09 21:39:01.538481 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-09 21:39:01.538510 | 2026-05-09 21:39:01.690210 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-09 21:39:01.691678 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-09 21:39:02.462494 | 2026-05-09 21:39:02.462670 | PLAY [all] 2026-05-09 21:39:02.480471 | 2026-05-09 21:39:02.480633 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-09 21:39:02.750936 | debian-bookworm | changed: non-zero return code 2026-05-09 21:39:02.760526 | 2026-05-09 21:39:02.760667 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-09 21:39:02.784969 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:02.790965 | 2026-05-09 21:39:02.791088 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-09 21:39:02.821491 | 2026-05-09 21:39:02.821705 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-09 21:39:02.864225 | 2026-05-09 21:39:02.864444 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-09 21:39:02.888754 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:02.895400 | 2026-05-09 21:39:02.895524 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-09 21:39:02.925823 | 2026-05-09 21:39:02.926044 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-09 21:39:02.950379 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:02.956831 | 2026-05-09 21:39:02.957004 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-09 21:39:02.981650 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:02.988296 | 2026-05-09 21:39:02.988410 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-09 21:39:03.012944 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:03.022658 | 2026-05-09 21:39:03.022769 | PLAY RECAP 2026-05-09 21:39:03.022863 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-09 21:39:03.022922 | 2026-05-09 21:39:03.159856 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-09 21:39:03.162611 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-09 21:39:03.932667 | 2026-05-09 21:39:03.932847 | PLAY [Base post-fetch] 2026-05-09 21:39:03.958140 | 2026-05-09 21:39:03.958302 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-09 21:39:04.013608 | debian-bookworm | skipping: Conditional result was False 2026-05-09 21:39:04.019710 | 2026-05-09 21:39:04.019904 | TASK [fetch-output : Set log path for single node] 2026-05-09 21:39:04.058627 | debian-bookworm | ok 2026-05-09 21:39:04.064219 | 2026-05-09 21:39:04.064356 | LOOP [fetch-output : Ensure local output dirs] 2026-05-09 21:39:04.574599 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/work/logs" 2026-05-09 21:39:04.895011 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/work/artifacts" 2026-05-09 21:39:05.218425 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/ded44ad653784bfdac1531254d1f0801/work/docs" 2026-05-09 21:39:05.236188 | 2026-05-09 21:39:05.236347 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-09 21:39:06.106217 | debian-bookworm | changed: 2026-05-09 21:39:06.106660 | debian-bookworm | .d..t...... ./ 2026-05-09 21:39:06.106779 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-09 21:39:06.106951 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-09 21:39:06.107010 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-09 21:39:06.108466 | debian-bookworm | changed: All items complete 2026-05-09 21:39:06.108588 | 2026-05-09 21:39:06.797089 | debian-bookworm | changed: .d..t...... ./ 2026-05-09 21:39:07.440683 | debian-bookworm | changed: .d..t...... ./ 2026-05-09 21:39:07.467016 | 2026-05-09 21:39:07.467189 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-09 21:39:08.129013 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011111 2026-05-09 21:39:08.449178 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008792 2026-05-09 21:39:08.467481 | 2026-05-09 21:39:08.467650 | PLAY RECAP 2026-05-09 21:39:08.467723 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-09 21:39:08.467757 | 2026-05-09 21:39:08.643823 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-09 21:39:08.646501 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 21:39:09.455680 | 2026-05-09 21:39:09.455884 | PLAY [Base post] 2026-05-09 21:39:09.471039 | 2026-05-09 21:39:09.471214 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-09 21:39:10.117781 | debian-bookworm | changed 2026-05-09 21:39:10.127279 | 2026-05-09 21:39:10.127424 | PLAY RECAP 2026-05-09 21:39:10.127509 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-09 21:39:10.127583 | 2026-05-09 21:39:10.264852 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-09 21:39:10.266026 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-09 21:39:11.066636 | 2026-05-09 21:39:11.066817 | PLAY [Base post-logs] 2026-05-09 21:39:11.083065 | 2026-05-09 21:39:11.083309 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-09 21:39:11.566564 | localhost | changed 2026-05-09 21:39:11.585107 | 2026-05-09 21:39:11.585310 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-09 21:39:11.613637 | localhost | ok 2026-05-09 21:39:11.617561 | 2026-05-09 21:39:11.617696 | TASK [Set zuul-log-path fact] 2026-05-09 21:39:11.645474 | localhost | ok 2026-05-09 21:39:11.658414 | 2026-05-09 21:39:11.658661 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-09 21:39:11.696096 | localhost | ok 2026-05-09 21:39:11.700021 | 2026-05-09 21:39:11.700146 | TASK [upload-logs : Create log directories] 2026-05-09 21:39:12.233594 | localhost | changed 2026-05-09 21:39:12.238114 | 2026-05-09 21:39:12.238274 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-09 21:39:12.805770 | localhost -> localhost | ok: Runtime: 0:00:00.007876 2026-05-09 21:39:12.814095 | 2026-05-09 21:39:12.814296 | TASK [upload-logs : Upload logs to log server] 2026-05-09 21:39:13.505230 | localhost | Output suppressed because no_log was given 2026-05-09 21:39:13.507359 | 2026-05-09 21:39:13.507488 | LOOP [upload-logs : Compress console log and json output] 2026-05-09 21:39:13.571554 | localhost | skipping: Conditional result was False 2026-05-09 21:39:13.578750 | localhost | skipping: Conditional result was False 2026-05-09 21:39:13.592618 | 2026-05-09 21:39:13.593084 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-09 21:39:13.653921 | localhost | skipping: Conditional result was False 2026-05-09 21:39:13.654628 | 2026-05-09 21:39:13.657242 | localhost | skipping: Conditional result was False 2026-05-09 21:39:13.672459 | 2026-05-09 21:39:13.672712 | LOOP [upload-logs : Upload console log and json output]