2026-05-06 03:16:08.397279 | Job console starting 2026-05-06 03:16:08.412172 | Updating git repos 2026-05-06 03:16:08.460459 | Cloning repos into workspace 2026-05-06 03:16:08.592953 | Restoring repo states 2026-05-06 03:16:08.622449 | Merging changes 2026-05-06 03:16:08.622474 | Checking out repos 2026-05-06 03:16:08.712927 | Preparing playbooks 2026-05-06 03:16:09.933141 | Running Ansible setup 2026-05-06 03:16:16.628854 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-06 03:16:18.276903 | 2026-05-06 03:16:18.277087 | PLAY [Base pre] 2026-05-06 03:16:18.369048 | 2026-05-06 03:16:18.369226 | TASK [Setup log path fact] 2026-05-06 03:16:18.417120 | debian-bookworm | ok 2026-05-06 03:16:18.463630 | 2026-05-06 03:16:18.463823 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 03:16:18.541137 | debian-bookworm | ok 2026-05-06 03:16:18.586128 | 2026-05-06 03:16:18.586302 | TASK [emit-job-header : Print job information] 2026-05-06 03:16:18.710138 | # Job Information 2026-05-06 03:16:18.710292 | Ansible Version: 2.16.14 2026-05-06 03:16:18.710321 | Job: tox 2026-05-06 03:16:18.710348 | Pipeline: periodic-daily 2026-05-06 03:16:18.710368 | Executor: 521e9411259a 2026-05-06 03:16:18.710384 | Triggered by: https://github.com/osism/openstack-image-manager 2026-05-06 03:16:18.710403 | Event ID: d039b17f07384ead90a5cc9b84568654 2026-05-06 03:16:18.715146 | 2026-05-06 03:16:18.715238 | LOOP [emit-job-header : Print node information] 2026-05-06 03:16:18.877408 | debian-bookworm | ok: 2026-05-06 03:16:18.877603 | debian-bookworm | # Node Information 2026-05-06 03:16:18.877640 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-06 03:16:18.877665 | debian-bookworm | Hostname: debian 2026-05-06 03:16:18.877687 | debian-bookworm | Username: zuul 2026-05-06 03:16:18.877708 | debian-bookworm | Distro: Debian 12.13 2026-05-06 03:16:18.877732 | debian-bookworm | Provider: regiocloud-a 2026-05-06 03:16:18.877753 | debian-bookworm | Region: 2026-05-06 03:16:18.877875 | debian-bookworm | Label: debian-bookworm 2026-05-06 03:16:18.877909 | debian-bookworm | Product Name: OpenStack Nova 2026-05-06 03:16:18.877931 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe33:74c4 2026-05-06 03:16:18.895301 | 2026-05-06 03:16:18.895402 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-06 03:16:19.993216 | debian-bookworm -> localhost | changed 2026-05-06 03:16:20.004088 | 2026-05-06 03:16:20.004190 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-06 03:16:22.234290 | debian-bookworm -> localhost | changed 2026-05-06 03:16:22.247971 | 2026-05-06 03:16:22.248091 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-06 03:16:22.860371 | debian-bookworm -> localhost | ok 2026-05-06 03:16:22.867249 | 2026-05-06 03:16:22.867364 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-06 03:16:22.907895 | debian-bookworm | ok 2026-05-06 03:16:22.951313 | debian-bookworm | included: /var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-06 03:16:22.966193 | 2026-05-06 03:16:22.966297 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-06 03:16:25.385383 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-06 03:16:25.385608 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/work/2e581c1af8274d06a3365d8df49215c3_id_rsa 2026-05-06 03:16:25.385647 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/work/2e581c1af8274d06a3365d8df49215c3_id_rsa.pub 2026-05-06 03:16:25.385673 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-06 03:16:25.385697 | debian-bookworm -> localhost | SHA256:xFrZY8bGQgIz8k7NKyqfjIdf348oMFfVaaXW1nL0jCc zuul-build-sshkey 2026-05-06 03:16:25.385720 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-06 03:16:25.385762 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-06 03:16:25.385788 | debian-bookworm -> localhost | | . +.. .. o. . | 2026-05-06 03:16:25.385812 | debian-bookworm -> localhost | | o = +.=+o o + | 2026-05-06 03:16:25.385834 | debian-bookworm -> localhost | | o o.*.@ + E +| 2026-05-06 03:16:25.385856 | debian-bookworm -> localhost | | o .= * o o o | 2026-05-06 03:16:25.385878 | debian-bookworm -> localhost | | o.o S | 2026-05-06 03:16:25.385902 | debian-bookworm -> localhost | | o... | 2026-05-06 03:16:25.385925 | debian-bookworm -> localhost | |...+. | 2026-05-06 03:16:25.385948 | debian-bookworm -> localhost | |.=.o.. o . | 2026-05-06 03:16:25.385970 | debian-bookworm -> localhost | |.o= .o o.. | 2026-05-06 03:16:25.385992 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-06 03:16:25.386046 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.512580 2026-05-06 03:16:25.394064 | 2026-05-06 03:16:25.394178 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-06 03:16:25.437832 | debian-bookworm | ok 2026-05-06 03:16:25.458616 | debian-bookworm | included: /var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-06 03:16:25.484632 | 2026-05-06 03:16:25.484743 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-06 03:16:25.522376 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:16:25.529423 | 2026-05-06 03:16:25.529552 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-06 03:16:26.230389 | debian-bookworm | changed 2026-05-06 03:16:26.239327 | 2026-05-06 03:16:26.239444 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-06 03:16:26.540054 | debian-bookworm | ok 2026-05-06 03:16:26.548172 | 2026-05-06 03:16:26.548281 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-06 03:16:27.271728 | debian-bookworm | changed 2026-05-06 03:16:27.282985 | 2026-05-06 03:16:27.283098 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-06 03:16:27.963865 | debian-bookworm | changed 2026-05-06 03:16:27.969789 | 2026-05-06 03:16:27.969877 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-06 03:16:28.028855 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:16:28.033826 | 2026-05-06 03:16:28.033915 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-06 03:16:29.312260 | debian-bookworm -> localhost | changed 2026-05-06 03:16:29.334219 | 2026-05-06 03:16:29.334330 | TASK [add-build-sshkey : Add back temp key] 2026-05-06 03:16:30.001677 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/work/2e581c1af8274d06a3365d8df49215c3_id_rsa (zuul-build-sshkey) 2026-05-06 03:16:30.002039 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030975 2026-05-06 03:16:30.014525 | 2026-05-06 03:16:30.014615 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-06 03:16:30.450055 | debian-bookworm | ok 2026-05-06 03:16:30.464345 | 2026-05-06 03:16:30.465514 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-06 03:16:30.541564 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:16:30.567637 | 2026-05-06 03:16:30.567742 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-06 03:16:31.381227 | debian-bookworm | ok 2026-05-06 03:16:31.406138 | 2026-05-06 03:16:31.406252 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-06 03:16:31.482304 | debian-bookworm | ok 2026-05-06 03:16:31.488296 | 2026-05-06 03:16:31.488396 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-06 03:16:31.908720 | debian-bookworm -> localhost | ok 2026-05-06 03:16:31.915607 | 2026-05-06 03:16:31.915706 | TASK [validate-host : Collect information about the host] 2026-05-06 03:16:33.218465 | debian-bookworm | ok 2026-05-06 03:16:33.247968 | 2026-05-06 03:16:33.248097 | TASK [validate-host : Sanitize hostname] 2026-05-06 03:16:33.371894 | debian-bookworm | ok 2026-05-06 03:16:33.450167 | 2026-05-06 03:16:33.450280 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-06 03:16:34.202717 | debian-bookworm -> localhost | changed 2026-05-06 03:16:34.207453 | 2026-05-06 03:16:34.207564 | TASK [validate-host : Collect information about zuul worker] 2026-05-06 03:16:34.594030 | debian-bookworm | ok 2026-05-06 03:16:34.598147 | 2026-05-06 03:16:34.598237 | TASK [validate-host : Write out all zuul information for each host] 2026-05-06 03:16:35.016047 | debian-bookworm -> localhost | changed 2026-05-06 03:16:35.023888 | 2026-05-06 03:16:35.023976 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-06 03:16:35.275333 | debian-bookworm | ok 2026-05-06 03:16:35.279756 | 2026-05-06 03:16:35.279844 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-06 03:16:36.260928 | debian-bookworm | changed: 2026-05-06 03:16:36.261088 | debian-bookworm | cd+++++++++ src/ 2026-05-06 03:16:36.261117 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-06 03:16:36.261137 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-06 03:16:36.261154 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-06 03:16:36.261171 | debian-bookworm | =7.0.3 2026-05-06 03:17:02.001320 | debian-bookworm | Downloading cachetools-7.1.1-py3-none-any.whl (16 kB) 2026-05-06 03:17:02.056068 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-06 03:17:02.067804 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-06 03:17:02.150077 | debian-bookworm | Collecting filelock>=3.25 2026-05-06 03:17:02.162206 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-06 03:17:02.218447 | debian-bookworm | Collecting packaging>=26 2026-05-06 03:17:02.230865 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-06 03:17:02.245575 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.6 MB/s eta 0:00:00 2026-05-06 03:17:02.314829 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-06 03:17:02.327182 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-06 03:17:02.374018 | debian-bookworm | Collecting pluggy>=1.6 2026-05-06 03:17:02.386374 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-06 03:17:02.438221 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-06 03:17:02.451874 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-06 03:17:02.498383 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-06 03:17:02.511774 | debian-bookworm | Downloading python_discovery-1.3.0-py3-none-any.whl (33 kB) 2026-05-06 03:17:02.553922 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-06 03:17:02.566538 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-06 03:17:02.816611 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-06 03:17:02.829439 | debian-bookworm | Downloading virtualenv-21.3.1-py3-none-any.whl (7.6 MB) 2026-05-06 03:17:03.173093 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.4 MB/s eta 0:00:00 2026-05-06 03:17:03.290767 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-06 03:17:03.303732 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-06 03:17:03.326772 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 23.9 MB/s eta 0:00:00 2026-05-06 03:17:03.463482 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-06 03:17:04.347378 | 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-06 03:17:04.760411 | debian-bookworm | ok: Runtime: 0:00:03.481456 2026-05-06 03:17:04.766632 | 2026-05-06 03:17:04.781107 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-06 03:17:04.841623 | debian-bookworm | ok 2026-05-06 03:17:04.870866 | 2026-05-06 03:17:04.871024 | TASK [ensure-tox : Output tox version] 2026-05-06 03:17:05.503845 | 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-06 03:17:05.520776 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-06 03:17:05.947651 | debian-bookworm | ok: Runtime: 0:00:00.327608 2026-05-06 03:17:05.953756 | 2026-05-06 03:17:05.953876 | TASK [ensure-tox : Make global symlink] 2026-05-06 03:17:05.978138 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:05.985069 | 2026-05-06 03:17:05.985159 | PLAY RECAP 2026-05-06 03:17:05.985210 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-06 03:17:05.985244 | 2026-05-06 03:17:06.172156 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-06 03:17:06.173349 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-06 03:17:07.067598 | 2026-05-06 03:17:07.068732 | PLAY [all] 2026-05-06 03:17:07.142617 | 2026-05-06 03:17:07.142808 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-06 03:17:08.190429 | debian-bookworm | ok: Runtime: 0:00:00.017617 2026-05-06 03:17:08.197366 | 2026-05-06 03:17:08.197562 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-06 03:17:08.736918 | debian-bookworm | ok: Runtime: 0:00:00.006382 2026-05-06 03:17:08.743997 | 2026-05-06 03:17:08.744139 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-06 03:17:09.066363 | debian-bookworm | sudo: a password is required 2026-05-06 03:17:09.295923 | debian-bookworm | ok: Runtime: 0:00:00.010067 2026-05-06 03:17:09.314722 | 2026-05-06 03:17:09.314890 | TASK [tox : Check to see if the constraints file exists] 2026-05-06 03:17:09.366316 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:09.373410 | 2026-05-06 03:17:09.373574 | TASK [tox : Fail if constraints file is missing] 2026-05-06 03:17:09.437218 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:09.444620 | 2026-05-06 03:17:09.444951 | TASK [tox : Record file location] 2026-05-06 03:17:09.483007 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:09.490081 | 2026-05-06 03:17:09.490216 | TASK [tox : Install tox siblings] 2026-05-06 03:17:09.557188 | debian-bookworm | ok 2026-05-06 03:17:09.579863 | debian-bookworm | included: /var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-06 03:17:09.586242 | 2026-05-06 03:17:09.586384 | TASK [tox : Run tox without tests] 2026-05-06 03:17:10.841371 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-06 03:17:24.533217 | debian-bookworm | test: OK (14.34 seconds) 2026-05-06 03:17:24.533253 | debian-bookworm | congratulations :) (14.36 seconds) 2026-05-06 03:17:24.674990 | debian-bookworm | ok: Runtime: 0:00:14.626170 2026-05-06 03:17:24.691018 | 2026-05-06 03:17:24.691157 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-06 03:17:25.115649 | debian-bookworm | changed 2026-05-06 03:17:25.121272 | 2026-05-06 03:17:25.121403 | TASK [tox : Detect tox version] 2026-05-06 03:17:25.659540 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-06 03:17:26.150068 | debian-bookworm | ok: Runtime: 0:00:00.315778 2026-05-06 03:17:26.155927 | 2026-05-06 03:17:26.156065 | TASK [tox : Get tox envlist config] 2026-05-06 03:17:27.218106 | debian-bookworm | ok: Runtime: 0:00:00.306461 2026-05-06 03:17:27.224342 | 2026-05-06 03:17:27.224469 | TASK [tox : Install any sibling python packages] 2026-05-06 03:17:27.780899 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-06 03:17:27.789258 | 2026-05-06 03:17:27.789396 | TASK [tox : Remove tempfile] 2026-05-06 03:17:28.316357 | debian-bookworm | changed 2026-05-06 03:17:28.326707 | 2026-05-06 03:17:28.326922 | TASK [tox : Emit tox command] 2026-05-06 03:17:28.391451 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-06 03:17:28.405514 | 2026-05-06 03:17:28.405715 | TASK [tox : Run tox] 2026-05-06 03:17:29.203434 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-06 03:17:30.103444 | debian-bookworm | ............... 2026-05-06 03:17:30.103547 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-06 03:17:30.103582 | debian-bookworm | Ran 15 tests in 0.248s 2026-05-06 03:17:30.103596 | debian-bookworm | 2026-05-06 03:17:30.103614 | debian-bookworm | OK 2026-05-06 03:17:30.235146 | debian-bookworm | test: OK (1.08=setup[0.05]+cmd[1.03] seconds) 2026-05-06 03:17:30.235284 | debian-bookworm | congratulations :) (1.10 seconds) 2026-05-06 03:17:30.482589 | debian-bookworm | ok: Runtime: 0:00:01.551437 2026-05-06 03:17:30.488705 | 2026-05-06 03:17:30.488821 | TASK [tox : Look for output] 2026-05-06 03:17:30.935463 | debian-bookworm | ok 2026-05-06 03:17:30.955200 | 2026-05-06 03:17:30.955360 | TASK [tox : Return file comments to Zuul] 2026-05-06 03:17:30.998237 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:31.007229 | 2026-05-06 03:17:31.007360 | PLAY RECAP 2026-05-06 03:17:31.007435 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-06 03:17:31.007469 | 2026-05-06 03:17:31.194345 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-06 03:17:31.195554 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-06 03:17:32.005177 | 2026-05-06 03:17:32.005363 | PLAY [all] 2026-05-06 03:17:32.022149 | 2026-05-06 03:17:32.022325 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-06 03:17:32.067426 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:32.073512 | 2026-05-06 03:17:32.073686 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-06 03:17:32.114870 | debian-bookworm | ok 2026-05-06 03:17:32.123169 | 2026-05-06 03:17:32.123342 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-06 03:17:32.628049 | debian-bookworm -> localhost | changed 2026-05-06 03:17:32.637584 | 2026-05-06 03:17:32.637807 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-06 03:17:32.682630 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:32.696423 | 2026-05-06 03:17:32.696663 | TASK [fetch-tox-output : Set envlist fact] 2026-05-06 03:17:32.744944 | debian-bookworm | ok 2026-05-06 03:17:32.753556 | 2026-05-06 03:17:32.753781 | TASK [fetch-tox-output : Get tox version] 2026-05-06 03:17:33.447934 | 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-06 03:17:33.465245 | debian-bookworm | 4.53.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-06 03:17:33.785788 | debian-bookworm | ok: Runtime: 0:00:00.294662 2026-05-06 03:17:33.797989 | 2026-05-06 03:17:33.798169 | TASK [fetch-tox-output : Find all default environments] 2026-05-06 03:17:34.334002 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:34.349212 | 2026-05-06 03:17:34.349561 | TASK [fetch-tox-output : Set envlist fact] 2026-05-06 03:17:34.385380 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:34.394549 | 2026-05-06 03:17:34.394704 | TASK [fetch-tox-output : Find all default environments] 2026-05-06 03:17:34.923143 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:34.929858 | 2026-05-06 03:17:34.929989 | TASK [fetch-tox-output : Set envlist fact] 2026-05-06 03:17:34.964480 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:34.972580 | 2026-05-06 03:17:34.972707 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-06 03:17:35.033741 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:35.044664 | 2026-05-06 03:17:35.044808 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-06 03:17:35.897620 | debian-bookworm | changed: 2026-05-06 03:17:35.897930 | debian-bookworm | created directory /var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/work/logs/tox/test 2026-05-06 03:17:35.897991 | debian-bookworm | cd+++++++++ ./ 2026-05-06 03:17:35.898035 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-06 03:17:35.898084 | 2026-05-06 03:17:35.927824 | 2026-05-06 03:17:35.927980 | PLAY RECAP 2026-05-06 03:17:35.928268 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-06 03:17:35.928315 | 2026-05-06 03:17:36.095740 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-06 03:17:36.098633 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-06 03:17:36.996089 | 2026-05-06 03:17:36.996262 | PLAY [all] 2026-05-06 03:17:37.021233 | 2026-05-06 03:17:37.021407 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-06 03:17:37.240873 | debian-bookworm | changed: non-zero return code 2026-05-06 03:17:37.249557 | 2026-05-06 03:17:37.249729 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-06 03:17:37.275302 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:37.281431 | 2026-05-06 03:17:37.281589 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-06 03:17:37.312430 | 2026-05-06 03:17:37.312687 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-06 03:17:37.362170 | 2026-05-06 03:17:37.362457 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-06 03:17:37.387738 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:37.396935 | 2026-05-06 03:17:37.397094 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-06 03:17:37.430895 | 2026-05-06 03:17:37.431302 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-06 03:17:37.456337 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:37.467404 | 2026-05-06 03:17:37.467621 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-06 03:17:37.492421 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:37.498417 | 2026-05-06 03:17:37.498581 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-06 03:17:37.522463 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:37.529798 | 2026-05-06 03:17:37.529894 | PLAY RECAP 2026-05-06 03:17:37.529949 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-06 03:17:37.529973 | 2026-05-06 03:17:37.669591 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-06 03:17:37.673544 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 03:17:38.456374 | 2026-05-06 03:17:38.456579 | PLAY [Base post-fetch] 2026-05-06 03:17:38.472221 | 2026-05-06 03:17:38.472389 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-06 03:17:38.527613 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:38.533871 | 2026-05-06 03:17:38.534034 | TASK [fetch-output : Set log path for single node] 2026-05-06 03:17:38.587851 | debian-bookworm | ok 2026-05-06 03:17:38.597761 | 2026-05-06 03:17:38.597919 | LOOP [fetch-output : Ensure local output dirs] 2026-05-06 03:17:39.155885 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/work/logs" 2026-05-06 03:17:39.438671 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/work/artifacts" 2026-05-06 03:17:39.720803 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/2e581c1af8274d06a3365d8df49215c3/work/docs" 2026-05-06 03:17:39.752396 | 2026-05-06 03:17:39.752567 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-06 03:17:40.981942 | debian-bookworm | changed: 2026-05-06 03:17:40.985350 | debian-bookworm | .d..t...... ./ 2026-05-06 03:17:40.985448 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-06 03:17:40.985507 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-06 03:17:40.985542 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-06 03:17:40.985595 | debian-bookworm | changed: All items complete 2026-05-06 03:17:40.985622 | 2026-05-06 03:17:41.747017 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 03:17:42.734273 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 03:17:42.748766 | 2026-05-06 03:17:42.748910 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-06 03:17:42.789012 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:42.796085 | debian-bookworm | skipping: Conditional result was False 2026-05-06 03:17:42.810558 | 2026-05-06 03:17:42.810651 | PLAY RECAP 2026-05-06 03:17:42.810702 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-06 03:17:42.810727 | 2026-05-06 03:17:42.982982 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 03:17:42.984176 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 03:17:43.802472 | 2026-05-06 03:17:43.802710 | PLAY [Base post] 2026-05-06 03:17:43.817702 | 2026-05-06 03:17:43.817867 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-06 03:17:44.447832 | debian-bookworm | changed 2026-05-06 03:17:44.454773 | 2026-05-06 03:17:44.456047 | PLAY RECAP 2026-05-06 03:17:44.456185 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-06 03:17:44.456253 | 2026-05-06 03:17:44.608204 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 03:17:44.609364 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-06 03:17:45.640243 | 2026-05-06 03:17:45.640420 | PLAY [Base post-logs] 2026-05-06 03:17:45.651472 | 2026-05-06 03:17:45.651649 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-06 03:17:46.279626 | localhost | changed 2026-05-06 03:17:46.295441 | 2026-05-06 03:17:46.295646 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-06 03:17:46.357446 | localhost | ok 2026-05-06 03:17:46.361369 | 2026-05-06 03:17:46.361525 | TASK [Set zuul-log-path fact] 2026-05-06 03:17:46.412393 | localhost | ok 2026-05-06 03:17:46.425897 | 2026-05-06 03:17:46.428370 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 03:17:46.460344 | localhost | ok 2026-05-06 03:17:46.463551 | 2026-05-06 03:17:46.463668 | TASK [upload-logs : Create log directories] 2026-05-06 03:17:47.457849 | localhost | changed 2026-05-06 03:17:47.460975 | 2026-05-06 03:17:47.461086 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-06 03:17:48.301537 | localhost -> localhost | ok: Runtime: 0:00:00.013889 2026-05-06 03:17:48.305724 | 2026-05-06 03:17:48.305845 | TASK [upload-logs : Upload logs to log server] 2026-05-06 03:17:49.103112 | localhost | Output suppressed because no_log was given 2026-05-06 03:17:49.105066 | 2026-05-06 03:17:49.105178 | LOOP [upload-logs : Compress console log and json output] 2026-05-06 03:17:49.194567 | localhost | skipping: Conditional result was False 2026-05-06 03:17:49.205623 | localhost | skipping: Conditional result was False 2026-05-06 03:17:49.223731 | 2026-05-06 03:17:49.223876 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-06 03:17:49.308254 | localhost | skipping: Conditional result was False 2026-05-06 03:17:49.308577 | 2026-05-06 03:17:49.313119 | localhost | skipping: Conditional result was False 2026-05-06 03:17:49.322364 | 2026-05-06 03:17:49.322538 | LOOP [upload-logs : Upload console log and json output]