2025-09-30 03:09:19.933836 | Job console starting 2025-09-30 03:09:19.943002 | Updating git repos 2025-09-30 03:09:19.965202 | Cloning repos into workspace 2025-09-30 03:09:20.012648 | Restoring repo states 2025-09-30 03:09:20.028121 | Merging changes 2025-09-30 03:09:20.028135 | Checking out repos 2025-09-30 03:09:20.069095 | Preparing playbooks 2025-09-30 03:09:20.657905 | Running Ansible setup 2025-09-30 03:09:24.462818 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-30 03:09:25.340936 | 2025-09-30 03:09:25.341061 | PLAY [Base pre] 2025-09-30 03:09:25.357223 | 2025-09-30 03:09:25.357337 | TASK [Setup log path fact] 2025-09-30 03:09:25.376167 | debian-bookworm | ok 2025-09-30 03:09:25.391740 | 2025-09-30 03:09:25.391867 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-30 03:09:25.422047 | debian-bookworm | ok 2025-09-30 03:09:25.434801 | 2025-09-30 03:09:25.434915 | TASK [emit-job-header : Print job information] 2025-09-30 03:09:25.473827 | # Job Information 2025-09-30 03:09:25.473979 | Ansible Version: 2.16.14 2025-09-30 03:09:25.474013 | Job: tox 2025-09-30 03:09:25.474045 | Pipeline: periodic-daily 2025-09-30 03:09:25.474068 | Executor: 521e9411259a 2025-09-30 03:09:25.474088 | Triggered by: https://github.com/osism/openstack-image-manager 2025-09-30 03:09:25.474111 | Event ID: e8b01642bec64bd5a943668c655ac1ec 2025-09-30 03:09:25.490234 | 2025-09-30 03:09:25.490346 | LOOP [emit-job-header : Print node information] 2025-09-30 03:09:25.619782 | debian-bookworm | ok: 2025-09-30 03:09:25.619943 | debian-bookworm | # Node Information 2025-09-30 03:09:25.619977 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-30 03:09:25.620002 | debian-bookworm | Hostname: debian 2025-09-30 03:09:25.620025 | debian-bookworm | Username: zuul 2025-09-30 03:09:25.620047 | debian-bookworm | Distro: Debian 12.12 2025-09-30 03:09:25.620070 | debian-bookworm | Provider: regiocloud-a 2025-09-30 03:09:25.620092 | debian-bookworm | Region: 2025-09-30 03:09:25.620113 | debian-bookworm | Label: debian-bookworm 2025-09-30 03:09:25.620133 | debian-bookworm | Product Name: OpenStack Nova 2025-09-30 03:09:25.620152 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feaa:e6d0 2025-09-30 03:09:25.638237 | 2025-09-30 03:09:25.638350 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-30 03:09:26.028090 | debian-bookworm -> localhost | changed 2025-09-30 03:09:26.040583 | 2025-09-30 03:09:26.040741 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-30 03:09:27.062519 | debian-bookworm -> localhost | changed 2025-09-30 03:09:27.073036 | 2025-09-30 03:09:27.073141 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-30 03:09:27.438504 | debian-bookworm -> localhost | ok 2025-09-30 03:09:27.444516 | 2025-09-30 03:09:27.444610 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-30 03:09:27.462165 | debian-bookworm | ok 2025-09-30 03:09:27.478155 | debian-bookworm | included: /var/lib/zuul/builds/58c18973e64b46e5a3af257982f51f97/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-30 03:09:27.484113 | 2025-09-30 03:09:27.484193 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-30 03:09:30.048949 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-30 03:09:30.049139 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/58c18973e64b46e5a3af257982f51f97/work/58c18973e64b46e5a3af257982f51f97_id_rsa 2025-09-30 03:09:30.049175 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/58c18973e64b46e5a3af257982f51f97/work/58c18973e64b46e5a3af257982f51f97_id_rsa.pub 2025-09-30 03:09:30.049201 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-30 03:09:30.049224 | debian-bookworm -> localhost | SHA256:I9UmIDfk1xjQORaQudjbbA8paUxeA6mmUWL0z2XA8TI zuul-build-sshkey 2025-09-30 03:09:30.049246 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-30 03:09:30.049278 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-30 03:09:30.049301 | debian-bookworm -> localhost | | .. .oXOoo | 2025-09-30 03:09:30.049323 | debian-bookworm -> localhost | | o..+*=== | 2025-09-30 03:09:30.049343 | debian-bookworm -> localhost | | . o.+E+O.+ | 2025-09-30 03:09:30.049363 | debian-bookworm -> localhost | | . +o+Boo | 2025-09-30 03:09:30.049383 | debian-bookworm -> localhost | | + ++*So | 2025-09-30 03:09:30.049408 | debian-bookworm -> localhost | | . B.*. | 2025-09-30 03:09:30.049440 | debian-bookworm -> localhost | | . o o | 2025-09-30 03:09:30.049461 | debian-bookworm -> localhost | | . | 2025-09-30 03:09:30.049481 | debian-bookworm -> localhost | | | 2025-09-30 03:09:30.049501 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-30 03:09:30.049554 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.945236 2025-09-30 03:09:30.062961 | 2025-09-30 03:09:30.063061 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-30 03:09:30.082108 | debian-bookworm | ok 2025-09-30 03:09:30.093609 | debian-bookworm | included: /var/lib/zuul/builds/58c18973e64b46e5a3af257982f51f97/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-30 03:09:30.102663 | 2025-09-30 03:09:30.102751 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-30 03:09:30.115777 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:09:30.122105 | 2025-09-30 03:09:30.122205 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-30 03:09:30.794440 | debian-bookworm | changed 2025-09-30 03:09:30.801590 | 2025-09-30 03:09:30.801699 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-30 03:09:31.113869 | debian-bookworm | ok 2025-09-30 03:09:31.119207 | 2025-09-30 03:09:31.119301 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-30 03:09:31.884315 | debian-bookworm | changed 2025-09-30 03:09:31.896977 | 2025-09-30 03:09:31.897080 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-30 03:09:32.776067 | debian-bookworm | changed 2025-09-30 03:09:32.792236 | 2025-09-30 03:09:32.792478 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-30 03:09:32.845731 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:09:32.852152 | 2025-09-30 03:09:32.852252 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-30 03:09:33.641386 | debian-bookworm -> localhost | changed 2025-09-30 03:09:33.652322 | 2025-09-30 03:09:33.652471 | TASK [add-build-sshkey : Add back temp key] 2025-09-30 03:09:34.275032 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/58c18973e64b46e5a3af257982f51f97/work/58c18973e64b46e5a3af257982f51f97_id_rsa (zuul-build-sshkey) 2025-09-30 03:09:34.275207 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008902 2025-09-30 03:09:34.281399 | 2025-09-30 03:09:34.281565 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-30 03:09:34.834788 | debian-bookworm | ok 2025-09-30 03:09:34.839048 | 2025-09-30 03:09:34.839125 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-30 03:09:34.872928 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:09:34.882927 | 2025-09-30 03:09:34.883009 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-30 03:09:35.289670 | debian-bookworm | ok 2025-09-30 03:09:35.298725 | 2025-09-30 03:09:35.298805 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-30 03:09:35.325561 | debian-bookworm | ok 2025-09-30 03:09:35.329443 | 2025-09-30 03:09:35.329510 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-30 03:09:35.616474 | debian-bookworm -> localhost | ok 2025-09-30 03:09:35.621578 | 2025-09-30 03:09:35.621653 | TASK [validate-host : Collect information about the host] 2025-09-30 03:09:36.715789 | debian-bookworm | ok 2025-09-30 03:09:36.732630 | 2025-09-30 03:09:36.732716 | TASK [validate-host : Sanitize hostname] 2025-09-30 03:09:36.834138 | debian-bookworm | ok 2025-09-30 03:09:36.872477 | 2025-09-30 03:09:36.872569 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-30 03:09:37.556363 | debian-bookworm -> localhost | changed 2025-09-30 03:09:37.561788 | 2025-09-30 03:09:37.561891 | TASK [validate-host : Collect information about zuul worker] 2025-09-30 03:09:37.932608 | debian-bookworm | ok 2025-09-30 03:09:37.937058 | 2025-09-30 03:09:37.937139 | TASK [validate-host : Write out all zuul information for each host] 2025-09-30 03:09:38.456505 | debian-bookworm -> localhost | changed 2025-09-30 03:09:38.465637 | 2025-09-30 03:09:38.465733 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-30 03:09:38.697750 | debian-bookworm | ok 2025-09-30 03:09:38.704156 | 2025-09-30 03:09:38.704249 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-30 03:09:39.672744 | debian-bookworm | changed: 2025-09-30 03:09:39.672934 | debian-bookworm | cd+++++++++ src/ 2025-09-30 03:09:39.673324 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-30 03:09:39.673735 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-30 03:09:39.673779 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-30 03:09:39.674036 | debian-bookworm | =6.1 2025-09-30 03:10:02.428935 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-30 03:10:02.483307 | debian-bookworm | Collecting chardet>=5.2 2025-09-30 03:10:02.494178 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-30 03:10:02.512729 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 14.6 MB/s eta 0:00:00 2025-09-30 03:10:02.558798 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-30 03:10:02.568731 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-30 03:10:02.631646 | debian-bookworm | Collecting filelock>=3.18 2025-09-30 03:10:02.639599 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-30 03:10:02.698445 | debian-bookworm | Collecting packaging>=25 2025-09-30 03:10:02.706282 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-30 03:10:02.716878 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.5 MB/s eta 0:00:00 2025-09-30 03:10:02.759892 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-30 03:10:02.769649 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-30 03:10:02.813268 | debian-bookworm | Collecting pluggy>=1.6 2025-09-30 03:10:02.821267 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-30 03:10:02.859378 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-30 03:10:02.869094 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-30 03:10:03.036421 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-30 03:10:03.044457 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-30 03:10:03.289801 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 24.8 MB/s eta 0:00:00 2025-09-30 03:10:03.400676 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-30 03:10:03.408563 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-30 03:10:03.432505 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.6 MB/s eta 0:00:00 2025-09-30 03:10:03.583474 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-30 03:10:04.544110 | debian-bookworm | Successfully installed cachetools-6.2.0 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.19.1 packaging-25.0 platformdirs-4.4.0 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.30.2 virtualenv-20.34.0 2025-09-30 03:10:04.872336 | debian-bookworm | ok: Runtime: 0:00:03.028421 2025-09-30 03:10:04.876969 | 2025-09-30 03:10:04.877044 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-30 03:10:04.914638 | debian-bookworm | ok 2025-09-30 03:10:04.918524 | 2025-09-30 03:10:04.918593 | TASK [ensure-tox : Output tox version] 2025-09-30 03:10:05.555949 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-30 03:10:05.575007 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-30 03:10:05.973809 | debian-bookworm | ok: Runtime: 0:00:00.310691 2025-09-30 03:10:05.981815 | 2025-09-30 03:10:05.981901 | TASK [ensure-tox : Make global symlink] 2025-09-30 03:10:06.015726 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:06.022071 | 2025-09-30 03:10:06.022150 | PLAY RECAP 2025-09-30 03:10:06.022203 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-30 03:10:06.022228 | 2025-09-30 03:10:06.113814 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-30 03:10:06.114637 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-30 03:10:06.772770 | 2025-09-30 03:10:06.772889 | PLAY [all] 2025-09-30 03:10:06.789584 | 2025-09-30 03:10:06.789698 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-30 03:10:07.193758 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-30 03:10:07.315465 | debian-bookworm | ok: Runtime: 0:00:00.027729 2025-09-30 03:10:07.327805 | 2025-09-30 03:10:07.327923 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-30 03:10:07.854392 | debian-bookworm | ok: Runtime: 0:00:00.007369 2025-09-30 03:10:07.859985 | 2025-09-30 03:10:07.860082 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-30 03:10:08.196678 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-30 03:10:08.196755 | debian-bookworm | sudo: a password is required 2025-09-30 03:10:08.408538 | debian-bookworm | ok: Runtime: 0:00:00.021236 2025-09-30 03:10:08.416229 | 2025-09-30 03:10:08.416311 | TASK [tox : Check to see if the constraints file exists] 2025-09-30 03:10:08.439087 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:08.443745 | 2025-09-30 03:10:08.443831 | TASK [tox : Fail if constraints file is missing] 2025-09-30 03:10:08.476389 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:08.481142 | 2025-09-30 03:10:08.481224 | TASK [tox : Record file location] 2025-09-30 03:10:08.518438 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:08.522945 | 2025-09-30 03:10:08.523021 | TASK [tox : Install tox siblings] 2025-09-30 03:10:08.539824 | debian-bookworm | ok 2025-09-30 03:10:08.549252 | debian-bookworm | included: /var/lib/zuul/builds/58c18973e64b46e5a3af257982f51f97/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-30 03:10:08.553609 | 2025-09-30 03:10:08.553682 | TASK [tox : Run tox without tests] 2025-09-30 03:10:09.718557 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-30 03:10:22.877983 | debian-bookworm | test: OK (13.79 seconds) 2025-09-30 03:10:22.878052 | debian-bookworm | congratulations :) (13.82 seconds) 2025-09-30 03:10:23.084147 | debian-bookworm | ok: Runtime: 0:00:14.116137 2025-09-30 03:10:23.089393 | 2025-09-30 03:10:23.089482 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-30 03:10:23.471784 | debian-bookworm | changed 2025-09-30 03:10:23.476690 | 2025-09-30 03:10:23.476775 | TASK [tox : Get tox envlist config] 2025-09-30 03:10:24.505552 | debian-bookworm | ok: Runtime: 0:00:00.372829 2025-09-30 03:10:24.519156 | 2025-09-30 03:10:24.519411 | TASK [tox : Install any sibling python packages] 2025-09-30 03:10:24.933582 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-30 03:10:24.938719 | 2025-09-30 03:10:24.938943 | TASK [tox : Remove tempfile] 2025-09-30 03:10:25.339543 | debian-bookworm | changed 2025-09-30 03:10:25.344952 | 2025-09-30 03:10:25.345049 | TASK [tox : Emit tox command] 2025-09-30 03:10:25.382600 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-30 03:10:25.387361 | 2025-09-30 03:10:25.387447 | TASK [tox : Run tox] 2025-09-30 03:10:25.998228 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-30 03:10:26.657456 | debian-bookworm | ............... 2025-09-30 03:10:26.657505 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-30 03:10:26.657522 | debian-bookworm | Ran 15 tests in 0.178s 2025-09-30 03:10:26.657527 | debian-bookworm | 2025-09-30 03:10:26.657535 | debian-bookworm | OK 2025-09-30 03:10:26.749708 | debian-bookworm | test: OK (0.79=setup[0.04]+cmd[0.75] seconds) 2025-09-30 03:10:26.749812 | debian-bookworm | congratulations :) (0.82 seconds) 2025-09-30 03:10:26.936454 | debian-bookworm | ok: Runtime: 0:00:01.113979 2025-09-30 03:10:26.943770 | 2025-09-30 03:10:26.943887 | TASK [tox : Look for output] 2025-09-30 03:10:27.360353 | debian-bookworm | ok 2025-09-30 03:10:27.378795 | 2025-09-30 03:10:27.378957 | TASK [tox : Return file comments to Zuul] 2025-09-30 03:10:27.414623 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:27.423494 | 2025-09-30 03:10:27.423596 | PLAY RECAP 2025-09-30 03:10:27.423650 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-30 03:10:27.423675 | 2025-09-30 03:10:27.585869 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-30 03:10:27.587061 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-30 03:10:28.348531 | 2025-09-30 03:10:28.348701 | PLAY [all] 2025-09-30 03:10:28.364015 | 2025-09-30 03:10:28.364158 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-30 03:10:28.419426 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:28.425979 | 2025-09-30 03:10:28.426142 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-30 03:10:28.468421 | debian-bookworm | ok 2025-09-30 03:10:28.474617 | 2025-09-30 03:10:28.474735 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-30 03:10:29.054877 | debian-bookworm -> localhost | changed 2025-09-30 03:10:29.063187 | 2025-09-30 03:10:29.063361 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-30 03:10:29.120541 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:29.128702 | 2025-09-30 03:10:29.128990 | TASK [fetch-tox-output : Set envlist fact] 2025-09-30 03:10:29.186526 | debian-bookworm | ok 2025-09-30 03:10:29.192427 | 2025-09-30 03:10:29.192528 | TASK [fetch-tox-output : Get tox version] 2025-09-30 03:10:29.854329 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-30 03:10:29.871461 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-30 03:10:30.221234 | debian-bookworm | ok: Runtime: 0:00:00.251833 2025-09-30 03:10:30.234577 | 2025-09-30 03:10:30.234768 | TASK [fetch-tox-output : Find all default environments] 2025-09-30 03:10:30.769423 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:30.783655 | 2025-09-30 03:10:30.783790 | TASK [fetch-tox-output : Set envlist fact] 2025-09-30 03:10:30.812748 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:30.818605 | 2025-09-30 03:10:30.818724 | TASK [fetch-tox-output : Find all default environments] 2025-09-30 03:10:31.356952 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:31.363172 | 2025-09-30 03:10:31.363301 | TASK [fetch-tox-output : Set envlist fact] 2025-09-30 03:10:31.397741 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:31.404683 | 2025-09-30 03:10:31.404798 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-30 03:10:31.451784 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:31.465438 | 2025-09-30 03:10:31.465566 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-30 03:10:32.261563 | debian-bookworm | changed: 2025-09-30 03:10:32.261770 | debian-bookworm | created directory /var/lib/zuul/builds/58c18973e64b46e5a3af257982f51f97/work/logs/tox/test 2025-09-30 03:10:32.261804 | debian-bookworm | cd+++++++++ ./ 2025-09-30 03:10:32.261829 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-30 03:10:32.261858 | 2025-09-30 03:10:32.270408 | 2025-09-30 03:10:32.270503 | PLAY RECAP 2025-09-30 03:10:32.270556 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-30 03:10:32.270580 | 2025-09-30 03:10:32.405282 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-30 03:10:32.406400 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-30 03:10:33.159093 | 2025-09-30 03:10:33.159490 | PLAY [all] 2025-09-30 03:10:33.175433 | 2025-09-30 03:10:33.175572 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-30 03:10:33.409613 | debian-bookworm | changed: non-zero return code 2025-09-30 03:10:33.417645 | 2025-09-30 03:10:33.417812 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-30 03:10:33.445586 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:33.460665 | 2025-09-30 03:10:33.460849 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-30 03:10:33.501615 | 2025-09-30 03:10:33.501860 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-30 03:10:33.537361 | 2025-09-30 03:10:33.537555 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-30 03:10:33.554118 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:33.562086 | 2025-09-30 03:10:33.562216 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-30 03:10:33.592602 | 2025-09-30 03:10:33.592801 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-30 03:10:33.616493 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:33.622474 | 2025-09-30 03:10:33.622589 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-30 03:10:33.638177 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:33.644509 | 2025-09-30 03:10:33.644621 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-30 03:10:33.668029 | debian-bookworm | skipping: Conditional result was False 2025-09-30 03:10:33.675042 | 2025-09-30 03:10:33.675125 | PLAY RECAP 2025-09-30 03:10:33.675175 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-30 03:10:33.675200 | 2025-09-30 03:10:33.812296 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-30 03:10:33.813440 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-30 03:10:34.575607 | 2025-09-30 03:10:34.575771 | PLAY [Base post] 2025-09-30 03:10:34.588837 | 2025-09-30 03:10:34.588963 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-30 03:10:35.157563 | debian-bookworm | changed 2025-09-30 03:10:35.163863 | 2025-09-30 03:10:35.163961 | PLAY RECAP 2025-09-30 03:10:35.164023 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-30 03:10:35.164085 | 2025-09-30 03:10:35.283604 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-30 03:10:35.284731 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-30 03:10:36.059681 | 2025-09-30 03:10:36.059849 | PLAY [Base post-logs] 2025-09-30 03:10:36.070354 | 2025-09-30 03:10:36.070490 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-30 03:10:36.572261 | localhost | changed 2025-09-30 03:10:36.585855 | 2025-09-30 03:10:36.586023 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-30 03:10:36.611985 | localhost | ok 2025-09-30 03:10:36.615597 | 2025-09-30 03:10:36.615721 | TASK [Set zuul-log-path fact] 2025-09-30 03:10:36.642622 | localhost | ok 2025-09-30 03:10:36.650557 | 2025-09-30 03:10:36.650671 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-30 03:10:36.675513 | localhost | ok 2025-09-30 03:10:36.678490 | 2025-09-30 03:10:36.678591 | TASK [upload-logs : Create log directories] 2025-09-30 03:10:37.358072 | localhost | changed 2025-09-30 03:10:37.362442 | 2025-09-30 03:10:37.362561 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-30 03:10:37.874272 | localhost -> localhost | ok: Runtime: 0:00:00.007202 2025-09-30 03:10:37.878356 | 2025-09-30 03:10:37.878471 | TASK [upload-logs : Upload logs to log server] 2025-09-30 03:10:38.552603 | localhost | Output suppressed because no_log was given 2025-09-30 03:10:38.554256 | 2025-09-30 03:10:38.554355 | LOOP [upload-logs : Compress console log and json output] 2025-09-30 03:10:38.596103 | localhost | skipping: Conditional result was False 2025-09-30 03:10:38.603645 | localhost | skipping: Conditional result was False 2025-09-30 03:10:38.609612 | 2025-09-30 03:10:38.609703 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-30 03:10:38.648015 | localhost | skipping: Conditional result was False 2025-09-30 03:10:38.648239 | 2025-09-30 03:10:38.654393 | localhost | skipping: Conditional result was False 2025-09-30 03:10:38.665535 | 2025-09-30 03:10:38.665650 | LOOP [upload-logs : Upload console log and json output]