2026-05-01 03:03:10.576507 | Job console starting 2026-05-01 03:03:10.605624 | Updating git repos 2026-05-01 03:03:10.656849 | Cloning repos into workspace 2026-05-01 03:03:10.737020 | Restoring repo states 2026-05-01 03:03:10.794363 | Merging changes 2026-05-01 03:03:10.794387 | Checking out repos 2026-05-01 03:03:10.908913 | Preparing playbooks 2026-05-01 03:03:11.862895 | Running Ansible setup 2026-05-01 03:03:17.607179 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-01 03:03:19.443305 | 2026-05-01 03:03:19.443486 | PLAY [Base pre] 2026-05-01 03:03:19.491104 | 2026-05-01 03:03:19.491275 | TASK [Setup log path fact] 2026-05-01 03:03:19.521397 | debian-bookworm | ok 2026-05-01 03:03:19.554329 | 2026-05-01 03:03:19.554516 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-01 03:03:19.626517 | debian-bookworm | ok 2026-05-01 03:03:19.640041 | 2026-05-01 03:03:19.640351 | TASK [emit-job-header : Print job information] 2026-05-01 03:03:19.720328 | # Job Information 2026-05-01 03:03:19.720543 | Ansible Version: 2.16.14 2026-05-01 03:03:19.720579 | Job: tox 2026-05-01 03:03:19.720613 | Pipeline: periodic-daily 2026-05-01 03:03:19.720636 | Executor: 521e9411259a 2026-05-01 03:03:19.720657 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-01 03:03:19.720681 | Event ID: dee37956e11d4e5886a8de9f0ddaaf2a 2026-05-01 03:03:19.726685 | 2026-05-01 03:03:19.726824 | LOOP [emit-job-header : Print node information] 2026-05-01 03:03:19.950484 | debian-bookworm | ok: 2026-05-01 03:03:19.950764 | debian-bookworm | # Node Information 2026-05-01 03:03:19.950805 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-01 03:03:19.950852 | debian-bookworm | Hostname: debian 2026-05-01 03:03:19.950893 | debian-bookworm | Username: zuul 2026-05-01 03:03:19.950915 | debian-bookworm | Distro: Debian 12.13 2026-05-01 03:03:19.950941 | debian-bookworm | Provider: regiocloud-a 2026-05-01 03:03:19.950963 | debian-bookworm | Region: 2026-05-01 03:03:19.950984 | debian-bookworm | Label: debian-bookworm 2026-05-01 03:03:19.951005 | debian-bookworm | Product Name: OpenStack Nova 2026-05-01 03:03:19.951025 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb5:77cd 2026-05-01 03:03:19.972365 | 2026-05-01 03:03:19.972516 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-01 03:03:21.063443 | debian-bookworm -> localhost | changed 2026-05-01 03:03:21.071373 | 2026-05-01 03:03:21.071513 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-01 03:03:22.849429 | debian-bookworm -> localhost | changed 2026-05-01 03:03:22.867244 | 2026-05-01 03:03:22.867355 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-01 03:03:23.347461 | debian-bookworm -> localhost | ok 2026-05-01 03:03:23.353359 | 2026-05-01 03:03:23.353465 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-01 03:03:23.392831 | debian-bookworm | ok 2026-05-01 03:03:23.407588 | debian-bookworm | included: /var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-01 03:03:23.413374 | 2026-05-01 03:03:23.413465 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-01 03:03:26.388664 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-01 03:03:26.388841 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/work/f986445aee3b46f8b66b629db47db7c5_id_rsa 2026-05-01 03:03:26.388896 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/work/f986445aee3b46f8b66b629db47db7c5_id_rsa.pub 2026-05-01 03:03:26.388920 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-01 03:03:26.388941 | debian-bookworm -> localhost | SHA256:XeROqjDA+rzDZF70NZqkj6Yi9MGdyQjpveP4P2liHY0 zuul-build-sshkey 2026-05-01 03:03:26.388960 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-01 03:03:26.388987 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-01 03:03:26.389005 | debian-bookworm -> localhost | | . | 2026-05-01 03:03:26.389023 | debian-bookworm -> localhost | | . o | 2026-05-01 03:03:26.389041 | debian-bookworm -> localhost | | . o + | 2026-05-01 03:03:26.389058 | debian-bookworm -> localhost | | o . .. ..o= | 2026-05-01 03:03:26.389075 | debian-bookworm -> localhost | |. = +.B+S+o.. | 2026-05-01 03:03:26.389093 | debian-bookworm -> localhost | | o BoEo++. | 2026-05-01 03:03:26.389110 | debian-bookworm -> localhost | |. .=*.oo. | 2026-05-01 03:03:26.389126 | debian-bookworm -> localhost | |. o*+*o . | 2026-05-01 03:03:26.389143 | debian-bookworm -> localhost | | o++B=. | 2026-05-01 03:03:26.389160 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-01 03:03:26.389207 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.144359 2026-05-01 03:03:26.395439 | 2026-05-01 03:03:26.395529 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-01 03:03:26.428058 | debian-bookworm | ok 2026-05-01 03:03:26.443398 | debian-bookworm | included: /var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-01 03:03:26.451326 | 2026-05-01 03:03:26.451415 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-01 03:03:26.484250 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:03:26.489331 | 2026-05-01 03:03:26.489426 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-01 03:03:27.173260 | debian-bookworm | changed 2026-05-01 03:03:27.181310 | 2026-05-01 03:03:27.181405 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-01 03:03:27.425334 | debian-bookworm | ok 2026-05-01 03:03:27.430920 | 2026-05-01 03:03:27.431023 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-01 03:03:28.141843 | debian-bookworm | changed 2026-05-01 03:03:28.153973 | 2026-05-01 03:03:28.154122 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-01 03:03:28.789177 | debian-bookworm | changed 2026-05-01 03:03:28.797758 | 2026-05-01 03:03:28.797891 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-01 03:03:28.833094 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:03:28.839243 | 2026-05-01 03:03:28.839374 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-01 03:03:29.431023 | debian-bookworm -> localhost | changed 2026-05-01 03:03:29.444042 | 2026-05-01 03:03:29.444150 | TASK [add-build-sshkey : Add back temp key] 2026-05-01 03:03:29.905865 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/work/f986445aee3b46f8b66b629db47db7c5_id_rsa (zuul-build-sshkey) 2026-05-01 03:03:29.906093 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023997 2026-05-01 03:03:29.914053 | 2026-05-01 03:03:29.914168 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-01 03:03:30.482703 | debian-bookworm | ok 2026-05-01 03:03:30.488844 | 2026-05-01 03:03:30.488978 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-01 03:03:30.513343 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:03:30.528486 | 2026-05-01 03:03:30.528592 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-01 03:03:30.952634 | debian-bookworm | ok 2026-05-01 03:03:30.978028 | 2026-05-01 03:03:30.978135 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-01 03:03:31.006085 | debian-bookworm | ok 2026-05-01 03:03:31.015325 | 2026-05-01 03:03:31.015475 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-01 03:03:31.610235 | debian-bookworm -> localhost | ok 2026-05-01 03:03:31.617551 | 2026-05-01 03:03:31.617679 | TASK [validate-host : Collect information about the host] 2026-05-01 03:03:33.878102 | debian-bookworm | ok 2026-05-01 03:03:33.899065 | 2026-05-01 03:03:33.899202 | TASK [validate-host : Sanitize hostname] 2026-05-01 03:03:34.098361 | debian-bookworm | ok 2026-05-01 03:03:34.191063 | 2026-05-01 03:03:34.191215 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-01 03:03:35.366155 | debian-bookworm -> localhost | changed 2026-05-01 03:03:35.374056 | 2026-05-01 03:03:35.374191 | TASK [validate-host : Collect information about zuul worker] 2026-05-01 03:03:36.106358 | debian-bookworm | ok 2026-05-01 03:03:36.117519 | 2026-05-01 03:03:36.117661 | TASK [validate-host : Write out all zuul information for each host] 2026-05-01 03:03:37.080312 | debian-bookworm -> localhost | changed 2026-05-01 03:03:37.090882 | 2026-05-01 03:03:37.091016 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-01 03:03:37.384423 | debian-bookworm | ok 2026-05-01 03:03:37.391665 | 2026-05-01 03:03:37.391797 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-01 03:03:38.405319 | debian-bookworm | changed: 2026-05-01 03:03:38.405545 | debian-bookworm | cd+++++++++ src/ 2026-05-01 03:03:38.405580 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-01 03:03:38.405605 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-01 03:03:38.405627 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-01 03:03:38.405647 | debian-bookworm | =7.0.3 2026-05-01 03:04:07.712724 | debian-bookworm | Downloading cachetools-7.0.6-py3-none-any.whl (13 kB) 2026-05-01 03:04:07.770932 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-01 03:04:07.782756 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-01 03:04:07.856924 | debian-bookworm | Collecting filelock>=3.25 2026-05-01 03:04:07.869177 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-01 03:04:07.945751 | debian-bookworm | Collecting packaging>=26 2026-05-01 03:04:07.957331 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-01 03:04:07.978136 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 6.7 MB/s eta 0:00:00 2026-05-01 03:04:08.055953 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-01 03:04:08.068206 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-01 03:04:08.110928 | debian-bookworm | Collecting pluggy>=1.6 2026-05-01 03:04:08.122632 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-01 03:04:08.174766 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-01 03:04:08.187346 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-01 03:04:08.232262 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-01 03:04:08.244610 | debian-bookworm | Downloading python_discovery-1.2.2-py3-none-any.whl (31 kB) 2026-05-01 03:04:08.291744 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-01 03:04:08.304077 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-01 03:04:08.457459 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-01 03:04:08.471072 | debian-bookworm | Downloading virtualenv-21.3.0-py3-none-any.whl (7.6 MB) 2026-05-01 03:04:08.864033 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 19.6 MB/s eta 0:00:00 2026-05-01 03:04:08.980227 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-01 03:04:08.993304 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-01 03:04:09.022656 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 17.6 MB/s eta 0:00:00 2026-05-01 03:04:09.134523 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-01 03:04:10.064637 | debian-bookworm | Successfully installed cachetools-7.0.6 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.0 virtualenv-21.3.0 2026-05-01 03:04:10.417526 | debian-bookworm | ok: Runtime: 0:00:03.450691 2026-05-01 03:04:10.435165 | 2026-05-01 03:04:10.435336 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-01 03:04:10.529180 | debian-bookworm | ok 2026-05-01 03:04:10.549136 | 2026-05-01 03:04:10.549251 | TASK [ensure-tox : Output tox version] 2026-05-01 03:04:11.309355 | 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-01 03:04:11.328316 | debian-bookworm | 4.53.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-01 03:04:11.648585 | debian-bookworm | ok: Runtime: 0:00:00.336334 2026-05-01 03:04:11.658689 | 2026-05-01 03:04:11.658888 | TASK [ensure-tox : Make global symlink] 2026-05-01 03:04:11.719046 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:11.739629 | 2026-05-01 03:04:11.739718 | PLAY RECAP 2026-05-01 03:04:11.739777 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-01 03:04:11.739864 | 2026-05-01 03:04:12.071693 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-01 03:04:12.072671 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-01 03:04:13.819864 | 2026-05-01 03:04:13.820003 | PLAY [all] 2026-05-01 03:04:13.861257 | 2026-05-01 03:04:13.861428 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-01 03:04:14.661858 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-01 03:04:14.927130 | debian-bookworm | ok: Runtime: 0:00:00.022389 2026-05-01 03:04:14.932698 | 2026-05-01 03:04:14.932849 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-01 03:04:15.498995 | debian-bookworm | ok: Runtime: 0:00:00.004344 2026-05-01 03:04:15.504748 | 2026-05-01 03:04:15.504859 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-01 03:04:16.058729 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-01 03:04:16.061671 | debian-bookworm | sudo: a password is required 2026-05-01 03:04:16.107344 | debian-bookworm | ok: Runtime: 0:00:00.026681 2026-05-01 03:04:16.119943 | 2026-05-01 03:04:16.120042 | TASK [tox : Check to see if the constraints file exists] 2026-05-01 03:04:16.215887 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:16.227756 | 2026-05-01 03:04:16.228391 | TASK [tox : Fail if constraints file is missing] 2026-05-01 03:04:16.368088 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:16.376684 | 2026-05-01 03:04:16.377518 | TASK [tox : Record file location] 2026-05-01 03:04:16.510455 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:16.518741 | 2026-05-01 03:04:16.519656 | TASK [tox : Install tox siblings] 2026-05-01 03:04:16.590421 | debian-bookworm | ok 2026-05-01 03:04:16.617192 | debian-bookworm | included: /var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-01 03:04:16.634821 | 2026-05-01 03:04:16.634961 | TASK [tox : Run tox without tests] 2026-05-01 03:04:18.032560 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-01 03:04:33.262710 | debian-bookworm | test: OK (15.92 seconds) 2026-05-01 03:04:33.262816 | debian-bookworm | congratulations :) (15.95 seconds) 2026-05-01 03:04:33.708997 | debian-bookworm | ok: Runtime: 0:00:16.228126 2026-05-01 03:04:33.715467 | 2026-05-01 03:04:33.715594 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-01 03:04:34.694367 | debian-bookworm | changed 2026-05-01 03:04:34.706313 | 2026-05-01 03:04:34.706454 | TASK [tox : Get tox envlist config] 2026-05-01 03:04:35.884073 | debian-bookworm | ok: Runtime: 0:00:00.373537 2026-05-01 03:04:35.890731 | 2026-05-01 03:04:35.891009 | TASK [tox : Install any sibling python packages] 2026-05-01 03:04:36.737414 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-01 03:04:36.756668 | 2026-05-01 03:04:36.759861 | TASK [tox : Remove tempfile] 2026-05-01 03:04:37.303112 | debian-bookworm | changed 2026-05-01 03:04:37.320179 | 2026-05-01 03:04:37.320334 | TASK [tox : Emit tox command] 2026-05-01 03:04:37.402907 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-01 03:04:37.408274 | 2026-05-01 03:04:37.408404 | TASK [tox : Run tox] 2026-05-01 03:04:38.214244 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-01 03:04:38.883675 | debian-bookworm | ............... 2026-05-01 03:04:38.883739 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-01 03:04:38.884120 | debian-bookworm | Ran 15 tests in 0.165s 2026-05-01 03:04:38.884132 | debian-bookworm | 2026-05-01 03:04:38.884139 | debian-bookworm | OK 2026-05-01 03:04:38.952183 | debian-bookworm | test: OK (0.78=setup[0.04]+cmd[0.74] seconds) 2026-05-01 03:04:38.952231 | debian-bookworm | congratulations :) (0.81 seconds) 2026-05-01 03:04:39.103733 | debian-bookworm | ok: Runtime: 0:00:01.070162 2026-05-01 03:04:39.109402 | 2026-05-01 03:04:39.109492 | TASK [tox : Look for output] 2026-05-01 03:04:39.725972 | debian-bookworm | ok 2026-05-01 03:04:39.772256 | 2026-05-01 03:04:39.772374 | TASK [tox : Return file comments to Zuul] 2026-05-01 03:04:39.847131 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:39.863696 | 2026-05-01 03:04:39.863787 | PLAY RECAP 2026-05-01 03:04:39.863849 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-01 03:04:39.863876 | 2026-05-01 03:04:40.214755 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-01 03:04:40.215623 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-01 03:04:41.386889 | 2026-05-01 03:04:41.387021 | PLAY [all] 2026-05-01 03:04:41.402746 | 2026-05-01 03:04:41.402975 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-01 03:04:41.473337 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:41.478935 | 2026-05-01 03:04:41.479077 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-01 03:04:41.517274 | debian-bookworm | ok 2026-05-01 03:04:41.522458 | 2026-05-01 03:04:41.522557 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-01 03:04:42.260434 | debian-bookworm -> localhost | changed 2026-05-01 03:04:42.267902 | 2026-05-01 03:04:42.268004 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-01 03:04:42.337524 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:42.342919 | 2026-05-01 03:04:42.343026 | TASK [fetch-tox-output : Set envlist fact] 2026-05-01 03:04:42.381420 | debian-bookworm | ok 2026-05-01 03:04:42.386921 | 2026-05-01 03:04:42.387023 | TASK [fetch-tox-output : Get tox version] 2026-05-01 03:04:43.097365 | 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-01 03:04:43.115771 | debian-bookworm | 4.53.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-01 03:04:43.444324 | debian-bookworm | ok: Runtime: 0:00:00.272624 2026-05-01 03:04:43.449645 | 2026-05-01 03:04:43.449741 | TASK [fetch-tox-output : Find all default environments] 2026-05-01 03:04:43.997668 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:44.002637 | 2026-05-01 03:04:44.002747 | TASK [fetch-tox-output : Set envlist fact] 2026-05-01 03:04:44.049378 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:44.054207 | 2026-05-01 03:04:44.054300 | TASK [fetch-tox-output : Find all default environments] 2026-05-01 03:04:44.596358 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:44.602165 | 2026-05-01 03:04:44.602268 | TASK [fetch-tox-output : Set envlist fact] 2026-05-01 03:04:44.645901 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:44.651179 | 2026-05-01 03:04:44.651276 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-01 03:04:44.701768 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:44.720670 | 2026-05-01 03:04:44.720769 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-01 03:04:45.767320 | debian-bookworm | changed: 2026-05-01 03:04:45.767464 | debian-bookworm | created directory /var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/work/logs/tox/test 2026-05-01 03:04:45.767492 | debian-bookworm | cd+++++++++ ./ 2026-05-01 03:04:45.767513 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-01 03:04:45.767535 | 2026-05-01 03:04:45.775701 | 2026-05-01 03:04:45.775777 | PLAY RECAP 2026-05-01 03:04:45.775837 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-01 03:04:45.775862 | 2026-05-01 03:04:45.903319 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-01 03:04:45.904250 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-01 03:04:46.715863 | 2026-05-01 03:04:46.715975 | PLAY [all] 2026-05-01 03:04:46.730611 | 2026-05-01 03:04:46.730727 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-01 03:04:46.950906 | debian-bookworm | changed: non-zero return code 2026-05-01 03:04:46.955368 | 2026-05-01 03:04:46.955460 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-01 03:04:46.978356 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:46.983323 | 2026-05-01 03:04:46.983420 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-01 03:04:47.000759 | 2026-05-01 03:04:47.000925 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-01 03:04:47.029830 | 2026-05-01 03:04:47.029982 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-01 03:04:47.042634 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:47.047503 | 2026-05-01 03:04:47.047581 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-01 03:04:47.064429 | 2026-05-01 03:04:47.064571 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-01 03:04:47.077531 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:47.081954 | 2026-05-01 03:04:47.082038 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-01 03:04:47.094687 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:47.099807 | 2026-05-01 03:04:47.099897 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-01 03:04:47.122823 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:47.128296 | 2026-05-01 03:04:47.128358 | PLAY RECAP 2026-05-01 03:04:47.128397 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-01 03:04:47.128416 | 2026-05-01 03:04:47.222154 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-01 03:04:47.223220 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-01 03:04:47.994209 | 2026-05-01 03:04:47.994392 | PLAY [Base post-fetch] 2026-05-01 03:04:48.012502 | 2026-05-01 03:04:48.012663 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-01 03:04:48.057213 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:48.063460 | 2026-05-01 03:04:48.063601 | TASK [fetch-output : Set log path for single node] 2026-05-01 03:04:48.115285 | debian-bookworm | ok 2026-05-01 03:04:48.120524 | 2026-05-01 03:04:48.120627 | LOOP [fetch-output : Ensure local output dirs] 2026-05-01 03:04:48.635420 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/work/logs" 2026-05-01 03:04:48.987653 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/work/artifacts" 2026-05-01 03:04:49.291952 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f986445aee3b46f8b66b629db47db7c5/work/docs" 2026-05-01 03:04:49.307583 | 2026-05-01 03:04:49.307689 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-01 03:04:50.113645 | debian-bookworm | changed: 2026-05-01 03:04:50.114064 | debian-bookworm | .d..t...... ./ 2026-05-01 03:04:50.114117 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-01 03:04:50.114156 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-01 03:04:50.114188 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-01 03:04:50.114231 | debian-bookworm | changed: All items complete 2026-05-01 03:04:50.114256 | 2026-05-01 03:04:50.730379 | debian-bookworm | changed: .d..t...... ./ 2026-05-01 03:04:51.575171 | debian-bookworm | changed: .d..t...... ./ 2026-05-01 03:04:51.656144 | 2026-05-01 03:04:51.656288 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-01 03:04:51.825081 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:51.832708 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:04:51.851151 | 2026-05-01 03:04:51.851281 | PLAY RECAP 2026-05-01 03:04:51.851344 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-01 03:04:51.851707 | 2026-05-01 03:04:52.326518 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-01 03:04:52.330369 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-01 03:04:54.353369 | 2026-05-01 03:04:54.364248 | PLAY [Base post] 2026-05-01 03:04:54.397234 | 2026-05-01 03:04:54.397415 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-01 03:04:55.215193 | debian-bookworm | changed 2026-05-01 03:04:55.229762 | 2026-05-01 03:04:55.229938 | PLAY RECAP 2026-05-01 03:04:55.230008 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-01 03:04:55.230071 | 2026-05-01 03:04:55.534060 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-01 03:04:55.535236 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-01 03:04:56.928090 | 2026-05-01 03:04:56.928271 | PLAY [Base post-logs] 2026-05-01 03:04:56.950631 | 2026-05-01 03:04:56.950866 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-01 03:04:57.868017 | localhost | changed 2026-05-01 03:04:57.878759 | 2026-05-01 03:04:57.878954 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-01 03:04:57.929581 | localhost | ok 2026-05-01 03:04:57.933401 | 2026-05-01 03:04:57.933502 | TASK [Set zuul-log-path fact] 2026-05-01 03:04:57.968122 | localhost | ok 2026-05-01 03:04:57.976150 | 2026-05-01 03:04:57.976257 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-01 03:04:58.031120 | localhost | ok 2026-05-01 03:04:58.034142 | 2026-05-01 03:04:58.034235 | TASK [upload-logs : Create log directories] 2026-05-01 03:04:58.780708 | localhost | changed 2026-05-01 03:04:58.783408 | 2026-05-01 03:04:58.783503 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-01 03:05:00.083197 | localhost -> localhost | ok: Runtime: 0:00:00.006105 2026-05-01 03:05:00.088754 | 2026-05-01 03:05:00.088937 | TASK [upload-logs : Upload logs to log server] 2026-05-01 03:05:01.000074 | localhost | Output suppressed because no_log was given 2026-05-01 03:05:01.001428 | 2026-05-01 03:05:01.001501 | LOOP [upload-logs : Compress console log and json output] 2026-05-01 03:05:01.099930 | localhost | skipping: Conditional result was False 2026-05-01 03:05:01.117113 | localhost | skipping: Conditional result was False 2026-05-01 03:05:01.119722 | 2026-05-01 03:05:01.119833 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-01 03:05:01.164361 | localhost | skipping: Conditional result was False 2026-05-01 03:05:01.164603 | 2026-05-01 03:05:01.170887 | localhost | skipping: Conditional result was False 2026-05-01 03:05:01.174409 | 2026-05-01 03:05:01.174494 | LOOP [upload-logs : Upload console log and json output]