2026-05-14 03:14:59.712541 | Job console starting 2026-05-14 03:14:59.719536 | Updating git repos 2026-05-14 03:14:59.773979 | Cloning repos into workspace 2026-05-14 03:14:59.867729 | Restoring repo states 2026-05-14 03:14:59.898100 | Merging changes 2026-05-14 03:14:59.898118 | Checking out repos 2026-05-14 03:15:00.018805 | Preparing playbooks 2026-05-14 03:15:00.996143 | Running Ansible setup 2026-05-14 03:15:05.396039 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-14 03:15:06.381250 | 2026-05-14 03:15:06.381379 | PLAY [Base pre] 2026-05-14 03:15:06.408186 | 2026-05-14 03:15:06.408332 | TASK [Setup log path fact] 2026-05-14 03:15:06.453577 | debian-bookworm | ok 2026-05-14 03:15:06.513667 | 2026-05-14 03:15:06.513803 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 03:15:06.553207 | debian-bookworm | ok 2026-05-14 03:15:06.571133 | 2026-05-14 03:15:06.571258 | TASK [emit-job-header : Print job information] 2026-05-14 03:15:06.645508 | # Job Information 2026-05-14 03:15:06.645688 | Ansible Version: 2.16.14 2026-05-14 03:15:06.645725 | Job: tox 2026-05-14 03:15:06.645759 | Pipeline: periodic-daily 2026-05-14 03:15:06.645783 | Executor: 521e9411259a 2026-05-14 03:15:06.645804 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-14 03:15:06.645826 | Event ID: ef3289a0ec7540dd869d4354e0921f36 2026-05-14 03:15:06.654313 | 2026-05-14 03:15:06.654435 | LOOP [emit-job-header : Print node information] 2026-05-14 03:15:07.013609 | debian-bookworm | ok: 2026-05-14 03:15:07.013773 | debian-bookworm | # Node Information 2026-05-14 03:15:07.013806 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-14 03:15:07.013832 | debian-bookworm | Hostname: debian 2026-05-14 03:15:07.013854 | debian-bookworm | Username: zuul 2026-05-14 03:15:07.013875 | debian-bookworm | Distro: Debian 12.13 2026-05-14 03:15:07.013900 | debian-bookworm | Provider: regiocloud-a 2026-05-14 03:15:07.013921 | debian-bookworm | Region: 2026-05-14 03:15:07.013942 | debian-bookworm | Label: debian-bookworm 2026-05-14 03:15:07.013962 | debian-bookworm | Product Name: OpenStack Nova 2026-05-14 03:15:07.013981 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3f:5938 2026-05-14 03:15:07.026478 | 2026-05-14 03:15:07.032373 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-14 03:15:07.726731 | debian-bookworm -> localhost | changed 2026-05-14 03:15:07.733721 | 2026-05-14 03:15:07.733833 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-14 03:15:08.893329 | debian-bookworm -> localhost | changed 2026-05-14 03:15:08.904037 | 2026-05-14 03:15:08.904174 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-14 03:15:09.550250 | debian-bookworm -> localhost | ok 2026-05-14 03:15:09.555238 | 2026-05-14 03:15:09.555743 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-14 03:15:09.585010 | debian-bookworm | ok 2026-05-14 03:15:09.611481 | debian-bookworm | included: /var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-14 03:15:09.617012 | 2026-05-14 03:15:09.617104 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-14 03:15:11.083179 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-14 03:15:11.083359 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/work/9017fc7905e04e2e91354d443cbb49e2_id_rsa 2026-05-14 03:15:11.083390 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/work/9017fc7905e04e2e91354d443cbb49e2_id_rsa.pub 2026-05-14 03:15:11.083413 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-14 03:15:11.083433 | debian-bookworm -> localhost | SHA256:X/yOByxD8LsaJn/2YVq+VuWz5MUe2gJj17XIYh4hhoI zuul-build-sshkey 2026-05-14 03:15:11.083452 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-14 03:15:11.083477 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-14 03:15:11.083496 | debian-bookworm -> localhost | | | 2026-05-14 03:15:11.083514 | debian-bookworm -> localhost | | . | 2026-05-14 03:15:11.083531 | debian-bookworm -> localhost | | . .o | 2026-05-14 03:15:11.083548 | debian-bookworm -> localhost | | E . . ooo o| 2026-05-14 03:15:11.083565 | debian-bookworm -> localhost | | . S..o= o+o| 2026-05-14 03:15:11.083584 | debian-bookworm -> localhost | | .+Oo=.*+| 2026-05-14 03:15:11.083602 | debian-bookworm -> localhost | | . o =+Xo*.=| 2026-05-14 03:15:11.083618 | debian-bookworm -> localhost | | + .+*.*.+.| 2026-05-14 03:15:11.083635 | debian-bookworm -> localhost | | o+.o=oo | 2026-05-14 03:15:11.083652 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-14 03:15:11.083696 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.823341 2026-05-14 03:15:11.088902 | 2026-05-14 03:15:11.088988 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-14 03:15:11.125604 | debian-bookworm | ok 2026-05-14 03:15:11.133972 | debian-bookworm | included: /var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-14 03:15:11.149648 | 2026-05-14 03:15:11.149748 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-14 03:15:11.176871 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:15:11.181712 | 2026-05-14 03:15:11.181802 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-14 03:15:12.017189 | debian-bookworm | changed 2026-05-14 03:15:12.021518 | 2026-05-14 03:15:12.021591 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-14 03:15:12.254368 | debian-bookworm | ok 2026-05-14 03:15:12.259912 | 2026-05-14 03:15:12.260008 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-14 03:15:12.882358 | debian-bookworm | changed 2026-05-14 03:15:12.894952 | 2026-05-14 03:15:12.895069 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-14 03:15:13.536660 | debian-bookworm | changed 2026-05-14 03:15:13.542088 | 2026-05-14 03:15:13.542177 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-14 03:15:13.569262 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:15:13.575064 | 2026-05-14 03:15:13.575159 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-14 03:15:14.319632 | debian-bookworm -> localhost | changed 2026-05-14 03:15:14.330729 | 2026-05-14 03:15:14.330867 | TASK [add-build-sshkey : Add back temp key] 2026-05-14 03:15:14.789488 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/work/9017fc7905e04e2e91354d443cbb49e2_id_rsa (zuul-build-sshkey) 2026-05-14 03:15:14.789699 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010569 2026-05-14 03:15:14.797303 | 2026-05-14 03:15:14.797405 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-14 03:15:15.280850 | debian-bookworm | ok 2026-05-14 03:15:15.286523 | 2026-05-14 03:15:15.286631 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-14 03:15:15.367879 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:15:15.380424 | 2026-05-14 03:15:15.380561 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-14 03:15:16.070776 | debian-bookworm | ok 2026-05-14 03:15:16.112268 | 2026-05-14 03:15:16.112425 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-14 03:15:16.144128 | debian-bookworm | ok 2026-05-14 03:15:16.157949 | 2026-05-14 03:15:16.158083 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-14 03:15:17.033140 | debian-bookworm -> localhost | ok 2026-05-14 03:15:17.040725 | 2026-05-14 03:15:17.040866 | TASK [validate-host : Collect information about the host] 2026-05-14 03:15:18.553200 | debian-bookworm | ok 2026-05-14 03:15:18.566812 | 2026-05-14 03:15:18.566916 | TASK [validate-host : Sanitize hostname] 2026-05-14 03:15:18.720378 | debian-bookworm | ok 2026-05-14 03:15:18.804213 | 2026-05-14 03:15:18.804322 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-14 03:15:19.486635 | debian-bookworm -> localhost | changed 2026-05-14 03:15:19.491973 | 2026-05-14 03:15:19.492059 | TASK [validate-host : Collect information about zuul worker] 2026-05-14 03:15:19.955110 | debian-bookworm | ok 2026-05-14 03:15:19.959116 | 2026-05-14 03:15:19.959196 | TASK [validate-host : Write out all zuul information for each host] 2026-05-14 03:15:20.794336 | debian-bookworm -> localhost | changed 2026-05-14 03:15:20.802076 | 2026-05-14 03:15:20.802169 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-14 03:15:21.095461 | debian-bookworm | ok 2026-05-14 03:15:21.101440 | 2026-05-14 03:15:21.101530 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-14 03:15:22.186077 | debian-bookworm | changed: 2026-05-14 03:15:22.186270 | debian-bookworm | cd+++++++++ src/ 2026-05-14 03:15:22.186306 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-14 03:15:22.186330 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-14 03:15:22.186352 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-14 03:15:22.186372 | debian-bookworm | =7.0.3 2026-05-14 03:15:46.144114 | debian-bookworm | Downloading cachetools-7.1.1-py3-none-any.whl (16 kB) 2026-05-14 03:15:46.186120 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-14 03:15:46.198310 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-14 03:15:46.258888 | debian-bookworm | Collecting filelock>=3.25 2026-05-14 03:15:46.270563 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-14 03:15:46.338010 | debian-bookworm | Collecting packaging>=26 2026-05-14 03:15:46.349518 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-14 03:15:46.360954 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.8 MB/s eta 0:00:00 2026-05-14 03:15:46.414781 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-14 03:15:46.426390 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-14 03:15:46.466884 | debian-bookworm | Collecting pluggy>=1.6 2026-05-14 03:15:46.478220 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-14 03:15:46.518424 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-14 03:15:46.531250 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-14 03:15:46.584897 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-14 03:15:46.597457 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-14 03:15:46.638293 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-14 03:15:46.650611 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-14 03:15:46.795922 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-14 03:15:46.807525 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-14 03:15:47.149730 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.5 MB/s eta 0:00:00 2026-05-14 03:15:47.257813 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-14 03:15:47.269314 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-14 03:15:47.298512 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 19.9 MB/s eta 0:00:00 2026-05-14 03:15:47.419988 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-14 03:15:48.275640 | 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.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-14 03:15:48.897773 | debian-bookworm | ok: Runtime: 0:00:03.212933 2026-05-14 03:15:48.904334 | 2026-05-14 03:15:48.904479 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-14 03:15:48.936487 | debian-bookworm | ok 2026-05-14 03:15:48.942580 | 2026-05-14 03:15:48.952977 | TASK [ensure-tox : Output tox version] 2026-05-14 03:15:49.532720 | 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-14 03:15:49.550205 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-14 03:15:50.017881 | debian-bookworm | ok: Runtime: 0:00:00.323732 2026-05-14 03:15:50.031062 | 2026-05-14 03:15:50.031252 | TASK [ensure-tox : Make global symlink] 2026-05-14 03:15:50.056267 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:15:50.063405 | 2026-05-14 03:15:50.063515 | PLAY RECAP 2026-05-14 03:15:50.063568 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-14 03:15:50.063595 | 2026-05-14 03:15:50.233448 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-14 03:15:50.234702 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-14 03:15:51.190910 | 2026-05-14 03:15:51.191085 | PLAY [all] 2026-05-14 03:15:51.213729 | 2026-05-14 03:15:51.213900 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-14 03:15:51.731415 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-14 03:15:51.788818 | debian-bookworm | ok: Runtime: 0:00:00.028500 2026-05-14 03:15:51.797605 | 2026-05-14 03:15:51.797784 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-14 03:15:52.356001 | debian-bookworm | ok: Runtime: 0:00:00.007611 2026-05-14 03:15:52.367301 | 2026-05-14 03:15:52.367492 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-14 03:15:52.680204 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-14 03:15:52.683768 | debian-bookworm | sudo: a password is required 2026-05-14 03:15:52.913907 | debian-bookworm | ok: Runtime: 0:00:00.023238 2026-05-14 03:15:52.935339 | 2026-05-14 03:15:52.935520 | TASK [tox : Check to see if the constraints file exists] 2026-05-14 03:15:52.972599 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:15:52.986214 | 2026-05-14 03:15:52.986415 | TASK [tox : Fail if constraints file is missing] 2026-05-14 03:15:53.015128 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:15:53.021553 | 2026-05-14 03:15:53.021687 | TASK [tox : Record file location] 2026-05-14 03:15:53.045835 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:15:53.053472 | 2026-05-14 03:15:53.053600 | TASK [tox : Install tox siblings] 2026-05-14 03:15:53.081191 | debian-bookworm | ok 2026-05-14 03:15:53.092843 | debian-bookworm | included: /var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-14 03:15:53.099610 | 2026-05-14 03:15:53.099739 | TASK [tox : Run tox without tests] 2026-05-14 03:15:54.258883 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-14 03:16:06.927976 | debian-bookworm | test: OK (13.30 seconds) 2026-05-14 03:16:06.928012 | debian-bookworm | congratulations :) (13.32 seconds) 2026-05-14 03:16:07.138858 | debian-bookworm | ok: Runtime: 0:00:13.581176 2026-05-14 03:16:07.148807 | 2026-05-14 03:16:07.148979 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-14 03:16:07.550248 | debian-bookworm | changed 2026-05-14 03:16:07.556910 | 2026-05-14 03:16:07.557056 | TASK [tox : Detect tox version] 2026-05-14 03:16:08.069959 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-14 03:16:08.588313 | debian-bookworm | ok: Runtime: 0:00:00.272844 2026-05-14 03:16:08.594308 | 2026-05-14 03:16:08.594451 | TASK [tox : Get tox envlist config] 2026-05-14 03:16:09.625849 | debian-bookworm | ok: Runtime: 0:00:00.309558 2026-05-14 03:16:09.637554 | 2026-05-14 03:16:09.637753 | TASK [tox : Install any sibling python packages] 2026-05-14 03:16:10.094118 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-14 03:16:10.104195 | 2026-05-14 03:16:10.104346 | TASK [tox : Remove tempfile] 2026-05-14 03:16:10.614452 | debian-bookworm | changed 2026-05-14 03:16:10.620876 | 2026-05-14 03:16:10.620995 | TASK [tox : Emit tox command] 2026-05-14 03:16:10.663991 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-14 03:16:10.670211 | 2026-05-14 03:16:10.670336 | TASK [tox : Run tox] 2026-05-14 03:16:11.239015 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-14 03:16:11.970777 | debian-bookworm | ............... 2026-05-14 03:16:11.970815 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-14 03:16:11.970828 | debian-bookworm | Ran 15 tests in 0.166s 2026-05-14 03:16:11.970833 | debian-bookworm | 2026-05-14 03:16:11.971268 | debian-bookworm | OK 2026-05-14 03:16:12.036961 | debian-bookworm | test: OK (0.84=setup[0.04]+cmd[0.80] seconds) 2026-05-14 03:16:12.037009 | debian-bookworm | congratulations :) (0.86 seconds) 2026-05-14 03:16:12.208941 | debian-bookworm | ok: Runtime: 0:00:01.122696 2026-05-14 03:16:12.215760 | 2026-05-14 03:16:12.215995 | TASK [tox : Look for output] 2026-05-14 03:16:12.600447 | debian-bookworm | ok 2026-05-14 03:16:12.629274 | 2026-05-14 03:16:12.629877 | TASK [tox : Return file comments to Zuul] 2026-05-14 03:16:12.667578 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:12.674748 | 2026-05-14 03:16:12.674843 | PLAY RECAP 2026-05-14 03:16:12.674897 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-14 03:16:12.674923 | 2026-05-14 03:16:12.909236 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-14 03:16:12.910198 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-14 03:16:13.585107 | 2026-05-14 03:16:13.585252 | PLAY [all] 2026-05-14 03:16:13.599459 | 2026-05-14 03:16:13.599582 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-14 03:16:13.643427 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:13.655206 | 2026-05-14 03:16:13.655349 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-14 03:16:13.694326 | debian-bookworm | ok 2026-05-14 03:16:13.699318 | 2026-05-14 03:16:13.699423 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-14 03:16:14.219888 | debian-bookworm -> localhost | changed 2026-05-14 03:16:14.227812 | 2026-05-14 03:16:14.227918 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-14 03:16:14.271173 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:14.276663 | 2026-05-14 03:16:14.276775 | TASK [fetch-tox-output : Set envlist fact] 2026-05-14 03:16:14.306223 | debian-bookworm | ok 2026-05-14 03:16:14.312085 | 2026-05-14 03:16:14.312194 | TASK [fetch-tox-output : Get tox version] 2026-05-14 03:16:14.997458 | 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-14 03:16:15.016114 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-14 03:16:15.349193 | debian-bookworm | ok: Runtime: 0:00:00.328712 2026-05-14 03:16:15.354895 | 2026-05-14 03:16:15.354986 | TASK [fetch-tox-output : Find all default environments] 2026-05-14 03:16:15.883824 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:15.889423 | 2026-05-14 03:16:15.889526 | TASK [fetch-tox-output : Set envlist fact] 2026-05-14 03:16:15.915800 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:15.923618 | 2026-05-14 03:16:15.923716 | TASK [fetch-tox-output : Find all default environments] 2026-05-14 03:16:16.452018 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:16.463102 | 2026-05-14 03:16:16.463310 | TASK [fetch-tox-output : Set envlist fact] 2026-05-14 03:16:16.511445 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:16.521888 | 2026-05-14 03:16:16.522035 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-14 03:16:16.575131 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:16.582614 | 2026-05-14 03:16:16.582721 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-14 03:16:17.418178 | debian-bookworm | changed: 2026-05-14 03:16:17.418351 | debian-bookworm | created directory /var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/work/logs/tox/test 2026-05-14 03:16:17.418385 | debian-bookworm | cd+++++++++ ./ 2026-05-14 03:16:17.418411 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-14 03:16:17.418440 | 2026-05-14 03:16:17.456415 | 2026-05-14 03:16:17.456763 | PLAY RECAP 2026-05-14 03:16:17.456831 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-14 03:16:17.456857 | 2026-05-14 03:16:17.611710 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-14 03:16:17.613789 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-14 03:16:18.822152 | 2026-05-14 03:16:18.822301 | PLAY [all] 2026-05-14 03:16:18.871244 | 2026-05-14 03:16:18.871394 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-14 03:16:19.139076 | debian-bookworm | changed: non-zero return code 2026-05-14 03:16:19.147156 | 2026-05-14 03:16:19.147292 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-14 03:16:19.180773 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:19.187299 | 2026-05-14 03:16:19.187409 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-14 03:16:19.237163 | 2026-05-14 03:16:19.237338 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-14 03:16:19.291784 | 2026-05-14 03:16:19.291956 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-14 03:16:19.325277 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:19.332612 | 2026-05-14 03:16:19.332797 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-14 03:16:19.379390 | 2026-05-14 03:16:19.379577 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-14 03:16:19.415010 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:19.423154 | 2026-05-14 03:16:19.423272 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-14 03:16:19.457893 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:19.469383 | 2026-05-14 03:16:19.469493 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-14 03:16:19.507136 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:19.516646 | 2026-05-14 03:16:19.516732 | PLAY RECAP 2026-05-14 03:16:19.516784 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-14 03:16:19.516811 | 2026-05-14 03:16:19.839578 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-14 03:16:19.843034 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-14 03:16:20.638078 | 2026-05-14 03:16:20.638201 | PLAY [Base post-fetch] 2026-05-14 03:16:20.665996 | 2026-05-14 03:16:20.666137 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-14 03:16:20.729524 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:20.734279 | 2026-05-14 03:16:20.734383 | TASK [fetch-output : Set log path for single node] 2026-05-14 03:16:20.760420 | debian-bookworm | ok 2026-05-14 03:16:20.764582 | 2026-05-14 03:16:20.764663 | LOOP [fetch-output : Ensure local output dirs] 2026-05-14 03:16:21.157830 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/work/logs" 2026-05-14 03:16:21.392251 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/work/artifacts" 2026-05-14 03:16:21.634726 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9017fc7905e04e2e91354d443cbb49e2/work/docs" 2026-05-14 03:16:21.647634 | 2026-05-14 03:16:21.647726 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-14 03:16:22.511068 | debian-bookworm | changed: 2026-05-14 03:16:22.511272 | debian-bookworm | .d..t...... ./ 2026-05-14 03:16:22.511404 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-14 03:16:22.511445 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-14 03:16:22.511479 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-14 03:16:22.511520 | debian-bookworm | changed: All items complete 2026-05-14 03:16:22.511543 | 2026-05-14 03:16:23.136815 | debian-bookworm | changed: .d..t...... ./ 2026-05-14 03:16:23.718322 | debian-bookworm | changed: .d..t...... ./ 2026-05-14 03:16:23.749320 | 2026-05-14 03:16:23.749444 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-14 03:16:23.785391 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:23.788009 | debian-bookworm | skipping: Conditional result was False 2026-05-14 03:16:23.812061 | 2026-05-14 03:16:23.812182 | PLAY RECAP 2026-05-14 03:16:23.812261 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-14 03:16:23.812304 | 2026-05-14 03:16:23.903223 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-14 03:16:23.905986 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 03:16:24.652080 | 2026-05-14 03:16:24.652242 | PLAY [Base post] 2026-05-14 03:16:24.666315 | 2026-05-14 03:16:24.666439 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-14 03:16:25.210694 | debian-bookworm | changed 2026-05-14 03:16:25.216746 | 2026-05-14 03:16:25.216848 | PLAY RECAP 2026-05-14 03:16:25.216908 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 03:16:25.216970 | 2026-05-14 03:16:25.315647 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 03:16:25.316603 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-14 03:16:26.100952 | 2026-05-14 03:16:26.101086 | PLAY [Base post-logs] 2026-05-14 03:16:26.120432 | 2026-05-14 03:16:26.120568 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-14 03:16:26.531235 | localhost | changed 2026-05-14 03:16:26.553347 | 2026-05-14 03:16:26.553494 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-14 03:16:26.578338 | localhost | ok 2026-05-14 03:16:26.581267 | 2026-05-14 03:16:26.581360 | TASK [Set zuul-log-path fact] 2026-05-14 03:16:26.596371 | localhost | ok 2026-05-14 03:16:26.604126 | 2026-05-14 03:16:26.604235 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 03:16:26.640358 | localhost | ok 2026-05-14 03:16:26.655466 | 2026-05-14 03:16:26.655595 | TASK [upload-logs : Create log directories] 2026-05-14 03:16:27.123812 | localhost | changed 2026-05-14 03:16:27.128140 | 2026-05-14 03:16:27.128264 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-14 03:16:27.568238 | localhost -> localhost | ok: Runtime: 0:00:00.003349 2026-05-14 03:16:27.571834 | 2026-05-14 03:16:27.571932 | TASK [upload-logs : Upload logs to log server] 2026-05-14 03:16:28.266466 | localhost | Output suppressed because no_log was given 2026-05-14 03:16:28.268211 | 2026-05-14 03:16:28.268328 | LOOP [upload-logs : Compress console log and json output] 2026-05-14 03:16:28.351951 | localhost | skipping: Conditional result was False 2026-05-14 03:16:28.359464 | localhost | skipping: Conditional result was False 2026-05-14 03:16:28.364877 | 2026-05-14 03:16:28.364972 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-14 03:16:28.438226 | localhost | skipping: Conditional result was False 2026-05-14 03:16:28.438494 | 2026-05-14 03:16:28.444681 | localhost | skipping: Conditional result was False 2026-05-14 03:16:28.451065 | 2026-05-14 03:16:28.451173 | LOOP [upload-logs : Upload console log and json output]