2025-10-14 03:18:04.018755 | Job console starting 2025-10-14 03:18:04.024316 | Updating git repos 2025-10-14 03:18:04.054597 | Cloning repos into workspace 2025-10-14 03:18:04.146145 | Restoring repo states 2025-10-14 03:18:04.168200 | Merging changes 2025-10-14 03:18:04.168215 | Checking out repos 2025-10-14 03:18:04.231812 | Preparing playbooks 2025-10-14 03:18:04.971859 | Running Ansible setup 2025-10-14 03:18:09.471126 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-14 03:18:10.084779 | 2025-10-14 03:18:10.084886 | PLAY [Base pre] 2025-10-14 03:18:10.096995 | 2025-10-14 03:18:10.097083 | TASK [Setup log path fact] 2025-10-14 03:18:10.117322 | debian-bookworm | ok 2025-10-14 03:18:10.130793 | 2025-10-14 03:18:10.130916 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 03:18:10.163129 | debian-bookworm | ok 2025-10-14 03:18:10.188859 | 2025-10-14 03:18:10.189018 | TASK [emit-job-header : Print job information] 2025-10-14 03:18:10.228295 | # Job Information 2025-10-14 03:18:10.228436 | Ansible Version: 2.16.14 2025-10-14 03:18:10.228470 | Job: tox 2025-10-14 03:18:10.228503 | Pipeline: periodic-daily 2025-10-14 03:18:10.228526 | Executor: 521e9411259a 2025-10-14 03:18:10.228548 | Triggered by: https://github.com/osism/openstack-image-manager 2025-10-14 03:18:10.228570 | Event ID: e189443a4034425fbf0ec23128bd99de 2025-10-14 03:18:10.233355 | 2025-10-14 03:18:10.233440 | LOOP [emit-job-header : Print node information] 2025-10-14 03:18:10.327836 | debian-bookworm | ok: 2025-10-14 03:18:10.327994 | debian-bookworm | # Node Information 2025-10-14 03:18:10.328034 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-14 03:18:10.328064 | debian-bookworm | Hostname: debian 2025-10-14 03:18:10.328092 | debian-bookworm | Username: zuul 2025-10-14 03:18:10.328117 | debian-bookworm | Distro: Debian 12.12 2025-10-14 03:18:10.328146 | debian-bookworm | Provider: regiocloud-a 2025-10-14 03:18:10.328172 | debian-bookworm | Region: 2025-10-14 03:18:10.328199 | debian-bookworm | Label: debian-bookworm 2025-10-14 03:18:10.328224 | debian-bookworm | Product Name: OpenStack Nova 2025-10-14 03:18:10.328250 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feab:c49 2025-10-14 03:18:10.338758 | 2025-10-14 03:18:10.338877 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-14 03:18:10.796273 | debian-bookworm -> localhost | changed 2025-10-14 03:18:10.803751 | 2025-10-14 03:18:10.803846 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-14 03:18:11.718494 | debian-bookworm -> localhost | changed 2025-10-14 03:18:11.729121 | 2025-10-14 03:18:11.729215 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-14 03:18:11.987609 | debian-bookworm -> localhost | ok 2025-10-14 03:18:11.993413 | 2025-10-14 03:18:11.993505 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-14 03:18:12.020714 | debian-bookworm | ok 2025-10-14 03:18:12.036491 | debian-bookworm | included: /var/lib/zuul/builds/d76ba7d3bc074c3ea55198ba9866f2c6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-14 03:18:12.042200 | 2025-10-14 03:18:12.042279 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-14 03:18:12.842759 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-14 03:18:12.842953 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d76ba7d3bc074c3ea55198ba9866f2c6/work/d76ba7d3bc074c3ea55198ba9866f2c6_id_rsa 2025-10-14 03:18:12.842989 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d76ba7d3bc074c3ea55198ba9866f2c6/work/d76ba7d3bc074c3ea55198ba9866f2c6_id_rsa.pub 2025-10-14 03:18:12.843015 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-14 03:18:12.843040 | debian-bookworm -> localhost | SHA256:l7SY6QvfrcbTyQs1zixIA8bfTMWi97PYsThTPlW+7nI zuul-build-sshkey 2025-10-14 03:18:12.843061 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-14 03:18:12.843091 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-14 03:18:12.843113 | debian-bookworm -> localhost | | .. | 2025-10-14 03:18:12.843134 | debian-bookworm -> localhost | | . ... | 2025-10-14 03:18:12.843163 | debian-bookworm -> localhost | | + .o. | 2025-10-14 03:18:12.843183 | debian-bookworm -> localhost | | . o.O.o .| 2025-10-14 03:18:12.843203 | debian-bookworm -> localhost | | S.*.o o | 2025-10-14 03:18:12.843228 | debian-bookworm -> localhost | | o + ==.. .| 2025-10-14 03:18:12.843249 | debian-bookworm -> localhost | | . o.oO+B .| 2025-10-14 03:18:12.843270 | debian-bookworm -> localhost | | o oO+X. E | 2025-10-14 03:18:12.843291 | debian-bookworm -> localhost | | o.o=oo=o | 2025-10-14 03:18:12.843310 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-14 03:18:12.843361 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.326079 2025-10-14 03:18:12.849596 | 2025-10-14 03:18:12.849682 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-14 03:18:12.877484 | debian-bookworm | ok 2025-10-14 03:18:12.886387 | debian-bookworm | included: /var/lib/zuul/builds/d76ba7d3bc074c3ea55198ba9866f2c6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-14 03:18:12.895085 | 2025-10-14 03:18:12.895166 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-14 03:18:12.908063 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:18:12.913638 | 2025-10-14 03:18:12.913726 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-14 03:18:13.477745 | debian-bookworm | changed 2025-10-14 03:18:13.483079 | 2025-10-14 03:18:13.483168 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-14 03:18:13.727571 | debian-bookworm | ok 2025-10-14 03:18:13.741212 | 2025-10-14 03:18:13.741452 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-14 03:18:14.477963 | debian-bookworm | changed 2025-10-14 03:18:14.484695 | 2025-10-14 03:18:14.484790 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-14 03:18:15.151025 | debian-bookworm | changed 2025-10-14 03:18:15.159127 | 2025-10-14 03:18:15.159226 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-14 03:18:15.222759 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:18:15.229492 | 2025-10-14 03:18:15.229624 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-14 03:18:15.933654 | debian-bookworm -> localhost | changed 2025-10-14 03:18:15.946110 | 2025-10-14 03:18:15.946207 | TASK [add-build-sshkey : Add back temp key] 2025-10-14 03:18:16.318453 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d76ba7d3bc074c3ea55198ba9866f2c6/work/d76ba7d3bc074c3ea55198ba9866f2c6_id_rsa (zuul-build-sshkey) 2025-10-14 03:18:16.318705 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015146 2025-10-14 03:18:16.325612 | 2025-10-14 03:18:16.325693 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-14 03:18:16.783124 | debian-bookworm | ok 2025-10-14 03:18:16.797226 | 2025-10-14 03:18:16.797316 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-14 03:18:16.851728 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:18:16.874657 | 2025-10-14 03:18:16.874766 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-14 03:18:17.372862 | debian-bookworm | ok 2025-10-14 03:18:17.395095 | 2025-10-14 03:18:17.395200 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-14 03:18:17.433169 | debian-bookworm | ok 2025-10-14 03:18:17.437972 | 2025-10-14 03:18:17.438060 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-14 03:18:17.826375 | debian-bookworm -> localhost | ok 2025-10-14 03:18:17.832421 | 2025-10-14 03:18:17.832504 | TASK [validate-host : Collect information about the host] 2025-10-14 03:18:18.845122 | debian-bookworm | ok 2025-10-14 03:18:18.863401 | 2025-10-14 03:18:18.863511 | TASK [validate-host : Sanitize hostname] 2025-10-14 03:18:19.034883 | debian-bookworm | ok 2025-10-14 03:18:19.081554 | 2025-10-14 03:18:19.081683 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-14 03:18:19.917549 | debian-bookworm -> localhost | changed 2025-10-14 03:18:19.922823 | 2025-10-14 03:18:19.922922 | TASK [validate-host : Collect information about zuul worker] 2025-10-14 03:18:20.355969 | debian-bookworm | ok 2025-10-14 03:18:20.360453 | 2025-10-14 03:18:20.360540 | TASK [validate-host : Write out all zuul information for each host] 2025-10-14 03:18:21.019004 | debian-bookworm -> localhost | changed 2025-10-14 03:18:21.027991 | 2025-10-14 03:18:21.028086 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-14 03:18:21.270198 | debian-bookworm | ok 2025-10-14 03:18:21.275570 | 2025-10-14 03:18:21.275732 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-14 03:18:22.113180 | debian-bookworm | changed: 2025-10-14 03:18:22.113319 | debian-bookworm | cd+++++++++ src/ 2025-10-14 03:18:22.113346 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-14 03:18:22.113367 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-14 03:18:22.113385 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-14 03:18:22.113401 | debian-bookworm | =6.2 2025-10-14 03:18:42.479250 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-14 03:18:42.508706 | debian-bookworm | Collecting chardet>=5.2 2025-10-14 03:18:42.515771 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-14 03:18:42.538643 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 9.8 MB/s eta 0:00:00 2025-10-14 03:18:42.575495 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-14 03:18:42.583248 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-14 03:18:42.643136 | debian-bookworm | Collecting filelock>=3.20 2025-10-14 03:18:42.650644 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-14 03:18:42.751108 | debian-bookworm | Collecting packaging>=25 2025-10-14 03:18:42.760973 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-14 03:18:42.772488 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.0 MB/s eta 0:00:00 2025-10-14 03:18:42.828496 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-14 03:18:42.835899 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-14 03:18:42.870087 | debian-bookworm | Collecting pluggy>=1.6 2025-10-14 03:18:42.878586 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-14 03:18:42.922612 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-14 03:18:42.930662 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-14 03:18:43.075697 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-14 03:18:43.083169 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-14 03:18:43.367713 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 21.3 MB/s eta 0:00:00 2025-10-14 03:18:43.466914 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-14 03:18:43.473994 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-14 03:18:43.494633 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 25.5 MB/s eta 0:00:00 2025-10-14 03:18:43.627755 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-14 03:18:44.448530 | debian-bookworm | Successfully installed cachetools-6.2.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.31.0 virtualenv-20.35.3 2025-10-14 03:18:44.564826 | debian-bookworm | ok: Runtime: 0:00:02.768422 2025-10-14 03:18:44.570088 | 2025-10-14 03:18:44.570178 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-14 03:18:44.599046 | debian-bookworm | ok 2025-10-14 03:18:44.603728 | 2025-10-14 03:18:44.603819 | TASK [ensure-tox : Output tox version] 2025-10-14 03:18:45.050860 | 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-10-14 03:18:45.068211 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-14 03:18:45.135735 | debian-bookworm | ok: Runtime: 0:00:00.260803 2025-10-14 03:18:45.144076 | 2025-10-14 03:18:45.144181 | TASK [ensure-tox : Make global symlink] 2025-10-14 03:18:45.175080 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:18:45.187571 | 2025-10-14 03:18:45.188381 | PLAY RECAP 2025-10-14 03:18:45.188961 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-14 03:18:45.189641 | 2025-10-14 03:18:45.414202 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-14 03:18:45.415271 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-14 03:18:46.221184 | 2025-10-14 03:18:46.221306 | PLAY [all] 2025-10-14 03:18:46.239781 | 2025-10-14 03:18:46.239903 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-14 03:18:46.640129 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-14 03:18:46.767818 | debian-bookworm | ok: Runtime: 0:00:00.022809 2025-10-14 03:18:46.773220 | 2025-10-14 03:18:46.773328 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-14 03:18:47.299532 | debian-bookworm | ok: Runtime: 0:00:00.006564 2025-10-14 03:18:47.306583 | 2025-10-14 03:18:47.306734 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-14 03:18:47.576174 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-14 03:18:47.577590 | debian-bookworm | sudo: a password is required 2025-10-14 03:18:47.833489 | debian-bookworm | ok: Runtime: 0:00:00.016481 2025-10-14 03:18:47.842999 | 2025-10-14 03:18:47.843093 | TASK [tox : Check to see if the constraints file exists] 2025-10-14 03:18:47.866474 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:18:47.872263 | 2025-10-14 03:18:47.872355 | TASK [tox : Fail if constraints file is missing] 2025-10-14 03:18:47.895493 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:18:47.901065 | 2025-10-14 03:18:47.901153 | TASK [tox : Record file location] 2025-10-14 03:18:47.934513 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:18:47.944117 | 2025-10-14 03:18:47.944242 | TASK [tox : Install tox siblings] 2025-10-14 03:18:47.965144 | debian-bookworm | ok 2025-10-14 03:18:47.977497 | debian-bookworm | included: /var/lib/zuul/builds/d76ba7d3bc074c3ea55198ba9866f2c6/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-14 03:18:47.982725 | 2025-10-14 03:18:47.982809 | TASK [tox : Run tox without tests] 2025-10-14 03:18:48.967411 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-14 03:19:00.800118 | debian-bookworm | test: OK (12.30 seconds) 2025-10-14 03:19:00.800167 | debian-bookworm | congratulations :) (12.33 seconds) 2025-10-14 03:19:01.016961 | debian-bookworm | ok: Runtime: 0:00:12.567443 2025-10-14 03:19:01.030253 | 2025-10-14 03:19:01.030412 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-14 03:19:01.430912 | debian-bookworm | changed 2025-10-14 03:19:01.439066 | 2025-10-14 03:19:01.439209 | TASK [tox : Get tox envlist config] 2025-10-14 03:19:02.470666 | debian-bookworm | ok: Runtime: 0:00:00.284117 2025-10-14 03:19:02.484576 | 2025-10-14 03:19:02.484817 | TASK [tox : Install any sibling python packages] 2025-10-14 03:19:02.954753 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-14 03:19:02.960923 | 2025-10-14 03:19:02.961035 | TASK [tox : Remove tempfile] 2025-10-14 03:19:03.408943 | debian-bookworm | changed 2025-10-14 03:19:03.417243 | 2025-10-14 03:19:03.417366 | TASK [tox : Emit tox command] 2025-10-14 03:19:03.457189 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-14 03:19:03.462228 | 2025-10-14 03:19:03.462403 | TASK [tox : Run tox] 2025-10-14 03:19:04.048810 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-14 03:19:04.625834 | debian-bookworm | ............... 2025-10-14 03:19:04.625871 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-14 03:19:04.625882 | debian-bookworm | Ran 15 tests in 0.170s 2025-10-14 03:19:04.625888 | debian-bookworm | 2025-10-14 03:19:04.625894 | debian-bookworm | OK 2025-10-14 03:19:04.683808 | debian-bookworm | test: OK (0.67=setup[0.04]+cmd[0.63] seconds) 2025-10-14 03:19:04.683853 | debian-bookworm | congratulations :) (0.70 seconds) 2025-10-14 03:19:05.024565 | debian-bookworm | ok: Runtime: 0:00:00.959181 2025-10-14 03:19:05.036340 | 2025-10-14 03:19:05.036496 | TASK [tox : Look for output] 2025-10-14 03:19:05.438437 | debian-bookworm | ok 2025-10-14 03:19:05.457012 | 2025-10-14 03:19:05.457154 | TASK [tox : Return file comments to Zuul] 2025-10-14 03:19:05.491609 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:05.503664 | 2025-10-14 03:19:05.503783 | PLAY RECAP 2025-10-14 03:19:05.503864 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-14 03:19:05.503904 | 2025-10-14 03:19:05.634289 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-14 03:19:05.637145 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-14 03:19:06.429339 | 2025-10-14 03:19:06.429500 | PLAY [all] 2025-10-14 03:19:06.445257 | 2025-10-14 03:19:06.445395 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-14 03:19:06.490782 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:06.501036 | 2025-10-14 03:19:06.501226 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-14 03:19:06.547659 | debian-bookworm | ok 2025-10-14 03:19:06.556266 | 2025-10-14 03:19:06.556412 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-14 03:19:07.036967 | debian-bookworm -> localhost | changed 2025-10-14 03:19:07.049160 | 2025-10-14 03:19:07.049295 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-14 03:19:07.094097 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:07.106931 | 2025-10-14 03:19:07.107104 | TASK [fetch-tox-output : Set envlist fact] 2025-10-14 03:19:07.149489 | debian-bookworm | ok 2025-10-14 03:19:07.155105 | 2025-10-14 03:19:07.155206 | TASK [fetch-tox-output : Get tox version] 2025-10-14 03:19:07.826236 | 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-10-14 03:19:07.845010 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-14 03:19:08.200885 | debian-bookworm | ok: Runtime: 0:00:00.294596 2025-10-14 03:19:08.214737 | 2025-10-14 03:19:08.214932 | TASK [fetch-tox-output : Find all default environments] 2025-10-14 03:19:08.747483 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:08.761768 | 2025-10-14 03:19:08.761947 | TASK [fetch-tox-output : Set envlist fact] 2025-10-14 03:19:08.798104 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:08.810126 | 2025-10-14 03:19:08.810358 | TASK [fetch-tox-output : Find all default environments] 2025-10-14 03:19:09.343366 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:09.357442 | 2025-10-14 03:19:09.357670 | TASK [fetch-tox-output : Set envlist fact] 2025-10-14 03:19:09.393264 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:09.408205 | 2025-10-14 03:19:09.408364 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-14 03:19:09.458677 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:09.480366 | 2025-10-14 03:19:09.480514 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-14 03:19:10.323110 | debian-bookworm | changed: 2025-10-14 03:19:10.323306 | debian-bookworm | created directory /var/lib/zuul/builds/d76ba7d3bc074c3ea55198ba9866f2c6/work/logs/tox/test 2025-10-14 03:19:10.323342 | debian-bookworm | cd+++++++++ ./ 2025-10-14 03:19:10.323367 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-14 03:19:10.323398 | 2025-10-14 03:19:10.340632 | 2025-10-14 03:19:10.340811 | PLAY RECAP 2025-10-14 03:19:10.340884 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-14 03:19:10.340975 | 2025-10-14 03:19:10.465850 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-14 03:19:10.466993 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-14 03:19:11.245072 | 2025-10-14 03:19:11.245227 | PLAY [all] 2025-10-14 03:19:11.260394 | 2025-10-14 03:19:11.260516 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-14 03:19:11.478987 | debian-bookworm | changed: non-zero return code 2025-10-14 03:19:11.489389 | 2025-10-14 03:19:11.489577 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-14 03:19:11.524874 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:11.537576 | 2025-10-14 03:19:11.537767 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-14 03:19:11.575465 | 2025-10-14 03:19:11.575749 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-14 03:19:11.623885 | 2025-10-14 03:19:11.624127 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-14 03:19:11.648309 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:11.661334 | 2025-10-14 03:19:11.661492 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-14 03:19:11.698182 | 2025-10-14 03:19:11.698500 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-14 03:19:11.722963 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:11.734219 | 2025-10-14 03:19:11.734364 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-14 03:19:11.759041 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:11.770537 | 2025-10-14 03:19:11.770746 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-14 03:19:11.785173 | debian-bookworm | skipping: Conditional result was False 2025-10-14 03:19:11.796459 | 2025-10-14 03:19:11.796557 | PLAY RECAP 2025-10-14 03:19:11.796647 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-14 03:19:11.796688 | 2025-10-14 03:19:11.913334 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-14 03:19:11.914477 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 03:19:12.677813 | 2025-10-14 03:19:12.677974 | PLAY [Base post] 2025-10-14 03:19:12.691453 | 2025-10-14 03:19:12.691578 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-14 03:19:13.239253 | debian-bookworm | changed 2025-10-14 03:19:13.248913 | 2025-10-14 03:19:13.249009 | PLAY RECAP 2025-10-14 03:19:13.249067 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-14 03:19:13.249127 | 2025-10-14 03:19:13.370717 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 03:19:13.372897 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-14 03:19:14.204733 | 2025-10-14 03:19:14.204888 | PLAY [Base post-logs] 2025-10-14 03:19:14.216147 | 2025-10-14 03:19:14.216282 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-14 03:19:14.685397 | localhost | changed 2025-10-14 03:19:14.695513 | 2025-10-14 03:19:14.695692 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-14 03:19:14.721047 | localhost | ok 2025-10-14 03:19:14.724214 | 2025-10-14 03:19:14.724316 | TASK [Set zuul-log-path fact] 2025-10-14 03:19:14.738979 | localhost | ok 2025-10-14 03:19:14.746918 | 2025-10-14 03:19:14.747023 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 03:19:14.771391 | localhost | ok 2025-10-14 03:19:14.774221 | 2025-10-14 03:19:14.774319 | TASK [upload-logs : Create log directories] 2025-10-14 03:19:15.272653 | localhost | changed 2025-10-14 03:19:15.279857 | 2025-10-14 03:19:15.279988 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-14 03:19:15.769211 | localhost -> localhost | ok: Runtime: 0:00:00.007009 2025-10-14 03:19:15.778803 | 2025-10-14 03:19:15.779039 | TASK [upload-logs : Upload logs to log server] 2025-10-14 03:19:16.335783 | localhost | Output suppressed because no_log was given 2025-10-14 03:19:16.338579 | 2025-10-14 03:19:16.338757 | LOOP [upload-logs : Compress console log and json output] 2025-10-14 03:19:16.392141 | localhost | skipping: Conditional result was False 2025-10-14 03:19:16.400140 | localhost | skipping: Conditional result was False 2025-10-14 03:19:16.411927 | 2025-10-14 03:19:16.412155 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-14 03:19:16.459710 | localhost | skipping: Conditional result was False 2025-10-14 03:19:16.460009 | 2025-10-14 03:19:16.466754 | localhost | skipping: Conditional result was False 2025-10-14 03:19:16.478343 | 2025-10-14 03:19:16.478456 | LOOP [upload-logs : Upload console log and json output]