2025-12-31 20:30:01.822080 | Job console starting 2025-12-31 20:30:01.835248 | Updating git repos 2025-12-31 20:30:01.893535 | Cloning repos into workspace 2025-12-31 20:30:02.021421 | Restoring repo states 2025-12-31 20:30:02.041204 | Merging changes 2025-12-31 20:30:02.661762 | Checking out repos 2025-12-31 20:30:02.734999 | Preparing playbooks 2025-12-31 20:30:04.059039 | Running Ansible setup 2025-12-31 20:30:12.179823 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-31 20:30:13.600427 | 2025-12-31 20:30:13.600607 | PLAY [Base pre] 2025-12-31 20:30:13.621602 | 2025-12-31 20:30:13.621791 | TASK [Setup log path fact] 2025-12-31 20:30:13.671616 | debian-bookworm | ok 2025-12-31 20:30:13.714139 | 2025-12-31 20:30:13.735219 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-31 20:30:13.837189 | debian-bookworm | ok 2025-12-31 20:30:13.865952 | 2025-12-31 20:30:13.866111 | TASK [emit-job-header : Print job information] 2025-12-31 20:30:13.969536 | # Job Information 2025-12-31 20:30:13.969742 | Ansible Version: 2.16.14 2025-12-31 20:30:13.969817 | Job: tox 2025-12-31 20:30:13.969852 | Pipeline: check 2025-12-31 20:30:13.969875 | Executor: 521e9411259a 2025-12-31 20:30:13.969896 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1065 2025-12-31 20:30:13.969918 | Event ID: 74e3e070-e687-11f0-850d-f3df79373476 2025-12-31 20:30:13.975558 | 2025-12-31 20:30:13.975714 | LOOP [emit-job-header : Print node information] 2025-12-31 20:30:14.229457 | debian-bookworm | ok: 2025-12-31 20:30:14.229716 | debian-bookworm | # Node Information 2025-12-31 20:30:14.229751 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-31 20:30:14.229797 | debian-bookworm | Hostname: debian 2025-12-31 20:30:14.229819 | debian-bookworm | Username: zuul 2025-12-31 20:30:14.229840 | debian-bookworm | Distro: Debian 12.12 2025-12-31 20:30:14.229865 | debian-bookworm | Provider: regiocloud-a 2025-12-31 20:30:14.229886 | debian-bookworm | Region: 2025-12-31 20:30:14.229907 | debian-bookworm | Label: debian-bookworm 2025-12-31 20:30:14.229928 | debian-bookworm | Product Name: OpenStack Nova 2025-12-31 20:30:14.229948 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe63:3624 2025-12-31 20:30:14.249087 | 2025-12-31 20:30:14.249254 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-31 20:30:15.612238 | debian-bookworm -> localhost | changed 2025-12-31 20:30:15.620316 | 2025-12-31 20:30:15.620456 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-31 20:30:17.976618 | debian-bookworm -> localhost | changed 2025-12-31 20:30:18.000157 | 2025-12-31 20:30:18.000351 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-31 20:30:18.510517 | debian-bookworm -> localhost | ok 2025-12-31 20:30:18.517448 | 2025-12-31 20:30:18.517593 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-31 20:30:18.561507 | debian-bookworm | ok 2025-12-31 20:30:18.602668 | debian-bookworm | included: /var/lib/zuul/builds/169c969f8ab542aea26e66732d33b3be/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-31 20:30:18.614631 | 2025-12-31 20:30:18.614786 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-31 20:30:21.747819 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-31 20:30:21.748644 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/169c969f8ab542aea26e66732d33b3be/work/169c969f8ab542aea26e66732d33b3be_id_rsa 2025-12-31 20:30:21.748685 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/169c969f8ab542aea26e66732d33b3be/work/169c969f8ab542aea26e66732d33b3be_id_rsa.pub 2025-12-31 20:30:21.748712 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-31 20:30:21.748739 | debian-bookworm -> localhost | SHA256:dTk3QmeeQ/y9RHNsLE4fF0PD/A7OECwBGXDmEgV78ss zuul-build-sshkey 2025-12-31 20:30:21.748799 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-31 20:30:21.748838 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-31 20:30:21.748861 | debian-bookworm -> localhost | | ++*+.o..BB.| 2025-12-31 20:30:21.748882 | debian-bookworm -> localhost | | *. ..o*+BO| 2025-12-31 20:30:21.748902 | debian-bookworm -> localhost | | + o ..=+B=B| 2025-12-31 20:30:21.748922 | debian-bookworm -> localhost | | = . ..+o+=| 2025-12-31 20:30:21.748943 | debian-bookworm -> localhost | | S +.o.| 2025-12-31 20:30:21.748974 | debian-bookworm -> localhost | | . . o..| 2025-12-31 20:30:21.748995 | debian-bookworm -> localhost | | E | 2025-12-31 20:30:21.749014 | debian-bookworm -> localhost | | | 2025-12-31 20:30:21.749035 | debian-bookworm -> localhost | | | 2025-12-31 20:30:21.749056 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-31 20:30:21.749120 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.912734 2025-12-31 20:30:21.755927 | 2025-12-31 20:30:21.756053 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-31 20:30:21.804227 | debian-bookworm | ok 2025-12-31 20:30:21.819226 | debian-bookworm | included: /var/lib/zuul/builds/169c969f8ab542aea26e66732d33b3be/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-31 20:30:21.835579 | 2025-12-31 20:30:21.835735 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-31 20:30:21.863367 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:30:21.869857 | 2025-12-31 20:30:21.869988 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-31 20:30:22.521811 | debian-bookworm | changed 2025-12-31 20:30:22.529111 | 2025-12-31 20:30:22.529258 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-31 20:30:22.791488 | debian-bookworm | ok 2025-12-31 20:30:22.801657 | 2025-12-31 20:30:22.801821 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-31 20:30:23.491698 | debian-bookworm | changed 2025-12-31 20:30:23.510878 | 2025-12-31 20:30:23.511219 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-31 20:30:24.276709 | debian-bookworm | changed 2025-12-31 20:30:24.285060 | 2025-12-31 20:30:24.285349 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-31 20:30:24.310388 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:30:24.318969 | 2025-12-31 20:30:24.319153 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-31 20:30:25.151829 | debian-bookworm -> localhost | changed 2025-12-31 20:30:25.166300 | 2025-12-31 20:30:25.166441 | TASK [add-build-sshkey : Add back temp key] 2025-12-31 20:30:25.714395 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/169c969f8ab542aea26e66732d33b3be/work/169c969f8ab542aea26e66732d33b3be_id_rsa (zuul-build-sshkey) 2025-12-31 20:30:25.715023 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020194 2025-12-31 20:30:25.743946 | 2025-12-31 20:30:25.744216 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-31 20:30:26.252066 | debian-bookworm | ok 2025-12-31 20:30:26.257898 | 2025-12-31 20:30:26.258047 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-31 20:30:26.349900 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:30:26.361717 | 2025-12-31 20:30:26.361868 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-31 20:30:26.900672 | debian-bookworm | ok 2025-12-31 20:30:26.944418 | 2025-12-31 20:30:26.944576 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-31 20:30:27.004443 | debian-bookworm | ok 2025-12-31 20:30:27.018226 | 2025-12-31 20:30:27.018361 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-31 20:30:27.507854 | debian-bookworm -> localhost | ok 2025-12-31 20:30:27.514467 | 2025-12-31 20:30:27.514593 | TASK [validate-host : Collect information about the host] 2025-12-31 20:30:28.652206 | debian-bookworm | ok 2025-12-31 20:30:28.663717 | 2025-12-31 20:30:28.663888 | TASK [validate-host : Sanitize hostname] 2025-12-31 20:30:28.807994 | debian-bookworm | ok 2025-12-31 20:30:28.914592 | 2025-12-31 20:30:28.914748 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-31 20:30:29.787918 | debian-bookworm -> localhost | changed 2025-12-31 20:30:29.794028 | 2025-12-31 20:30:29.794171 | TASK [validate-host : Collect information about zuul worker] 2025-12-31 20:30:30.298556 | debian-bookworm | ok 2025-12-31 20:30:30.304040 | 2025-12-31 20:30:30.304174 | TASK [validate-host : Write out all zuul information for each host] 2025-12-31 20:30:30.982064 | debian-bookworm -> localhost | changed 2025-12-31 20:30:30.997268 | 2025-12-31 20:30:30.997411 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-31 20:30:31.313212 | debian-bookworm | ok 2025-12-31 20:30:31.319705 | 2025-12-31 20:30:31.319851 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-31 20:30:32.468121 | debian-bookworm | changed: 2025-12-31 20:30:32.468401 | debian-bookworm | cd+++++++++ src/ 2025-12-31 20:30:32.468456 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-31 20:30:32.468492 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-31 20:30:32.468529 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-12-31 20:30:32.468550 | debian-bookworm | =6.2 2025-12-31 20:30:54.898730 | debian-bookworm | Downloading cachetools-6.2.4-py3-none-any.whl (11 kB) 2025-12-31 20:30:54.932374 | debian-bookworm | Collecting chardet>=5.2 2025-12-31 20:30:54.944540 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-12-31 20:30:54.967532 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 11.4 MB/s eta 0:00:00 2025-12-31 20:30:55.018840 | debian-bookworm | Collecting colorama>=0.4.6 2025-12-31 20:30:55.032514 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-12-31 20:30:55.087740 | debian-bookworm | Collecting filelock>=3.20 2025-12-31 20:30:55.100001 | debian-bookworm | Downloading filelock-3.20.1-py3-none-any.whl (16 kB) 2025-12-31 20:30:55.149406 | debian-bookworm | Collecting packaging>=25 2025-12-31 20:30:55.163580 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-31 20:30:55.174078 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.7 MB/s eta 0:00:00 2025-12-31 20:30:55.240298 | debian-bookworm | Collecting platformdirs>=4.5 2025-12-31 20:30:55.253942 | debian-bookworm | Downloading platformdirs-4.5.1-py3-none-any.whl (18 kB) 2025-12-31 20:30:55.317842 | debian-bookworm | Collecting pluggy>=1.6 2025-12-31 20:30:55.330200 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-31 20:30:55.389733 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-12-31 20:30:55.403570 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-12-31 20:30:55.561988 | debian-bookworm | Collecting virtualenv>=20.34 2025-12-31 20:30:55.574993 | debian-bookworm | Downloading virtualenv-20.35.4-py3-none-any.whl (6.0 MB) 2025-12-31 20:30:55.883141 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 19.7 MB/s eta 0:00:00 2025-12-31 20:30:55.985753 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-12-31 20:30:55.997819 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-12-31 20:30:56.022986 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 35.9 MB/s eta 0:00:00 2025-12-31 20:30:56.165936 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-12-31 20:30:56.990077 | debian-bookworm | Successfully installed cachetools-6.2.4 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.1 packaging-25.0 platformdirs-4.5.1 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.32.0 virtualenv-20.35.4 2025-12-31 20:30:57.315541 | debian-bookworm | ok: Runtime: 0:00:03.007213 2025-12-31 20:30:57.331680 | 2025-12-31 20:30:57.331973 | TASK [ensure-tox : Export installed tox_executable path] 2025-12-31 20:30:57.386916 | debian-bookworm | ok 2025-12-31 20:30:57.396439 | 2025-12-31 20:30:57.396648 | TASK [ensure-tox : Output tox version] 2025-12-31 20:30:57.924413 | 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-12-31 20:30:57.942715 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-31 20:30:58.445883 | debian-bookworm | ok: Runtime: 0:00:00.291535 2025-12-31 20:30:58.452028 | 2025-12-31 20:30:58.452149 | TASK [ensure-tox : Make global symlink] 2025-12-31 20:30:58.490961 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:30:58.502810 | 2025-12-31 20:30:58.502939 | PLAY RECAP 2025-12-31 20:30:58.502997 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-12-31 20:30:58.503022 | 2025-12-31 20:30:58.673748 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-12-31 20:30:58.675129 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-31 20:30:59.693413 | 2025-12-31 20:30:59.693587 | PLAY [all] 2025-12-31 20:30:59.711233 | 2025-12-31 20:30:59.711402 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-12-31 20:31:00.277965 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-31 20:31:00.751273 | debian-bookworm | ok: Runtime: 0:00:00.029553 2025-12-31 20:31:00.759224 | 2025-12-31 20:31:00.759387 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-12-31 20:31:01.312919 | debian-bookworm | ok: Runtime: 0:00:00.006487 2025-12-31 20:31:01.319196 | 2025-12-31 20:31:01.319326 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-12-31 20:31:01.620499 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-12-31 20:31:01.624570 | debian-bookworm | sudo: a password is required 2025-12-31 20:31:01.849788 | debian-bookworm | ok: Runtime: 0:00:00.023461 2025-12-31 20:31:01.860575 | 2025-12-31 20:31:01.860688 | TASK [tox : Check to see if the constraints file exists] 2025-12-31 20:31:01.896109 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:01.902448 | 2025-12-31 20:31:01.902579 | TASK [tox : Fail if constraints file is missing] 2025-12-31 20:31:01.972449 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:01.989277 | 2025-12-31 20:31:01.989457 | TASK [tox : Record file location] 2025-12-31 20:31:02.016413 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:02.027119 | 2025-12-31 20:31:02.027475 | TASK [tox : Install tox siblings] 2025-12-31 20:31:02.065257 | debian-bookworm | ok 2025-12-31 20:31:02.078390 | debian-bookworm | included: /var/lib/zuul/builds/169c969f8ab542aea26e66732d33b3be/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-12-31 20:31:02.088009 | 2025-12-31 20:31:02.088136 | TASK [tox : Run tox without tests] 2025-12-31 20:31:03.303689 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-12-31 20:31:30.727552 | debian-bookworm | test: OK (27.97 seconds) 2025-12-31 20:31:30.727636 | debian-bookworm | congratulations :) (28.00 seconds) 2025-12-31 20:31:31.164676 | debian-bookworm | ok: Runtime: 0:00:28.366857 2025-12-31 20:31:31.175566 | 2025-12-31 20:31:31.175726 | TASK [tox : Create a tempfile to save tox showconfig] 2025-12-31 20:31:31.588514 | debian-bookworm | changed 2025-12-31 20:31:31.596775 | 2025-12-31 20:31:31.596971 | TASK [tox : Get tox envlist config] 2025-12-31 20:31:32.629680 | debian-bookworm | ok: Runtime: 0:00:00.325410 2025-12-31 20:31:32.643402 | 2025-12-31 20:31:32.643597 | TASK [tox : Install any sibling python packages] 2025-12-31 20:31:33.124497 | debian-bookworm | ok: No setup.cfg, no action needed 2025-12-31 20:31:33.133322 | 2025-12-31 20:31:33.133483 | TASK [tox : Remove tempfile] 2025-12-31 20:31:33.589011 | debian-bookworm | changed 2025-12-31 20:31:33.598710 | 2025-12-31 20:31:33.599059 | TASK [tox : Emit tox command] 2025-12-31 20:31:33.655279 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-12-31 20:31:33.664313 | 2025-12-31 20:31:33.664489 | TASK [tox : Run tox] 2025-12-31 20:31:34.302283 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-12-31 20:31:34.918228 | debian-bookworm | ............... 2025-12-31 20:31:34.918760 | debian-bookworm | ---------------------------------------------------------------------- 2025-12-31 20:31:34.918791 | debian-bookworm | Ran 15 tests in 0.168s 2025-12-31 20:31:34.918797 | debian-bookworm | 2025-12-31 20:31:34.918804 | debian-bookworm | OK 2025-12-31 20:31:35.003434 | debian-bookworm | test: OK (0.74=setup[0.04]+cmd[0.70] seconds) 2025-12-31 20:31:35.003508 | debian-bookworm | congratulations :) (0.77 seconds) 2025-12-31 20:31:35.262698 | debian-bookworm | ok: Runtime: 0:00:01.066000 2025-12-31 20:31:35.270513 | 2025-12-31 20:31:35.270639 | TASK [tox : Look for output] 2025-12-31 20:31:35.665174 | debian-bookworm | ok 2025-12-31 20:31:35.688151 | 2025-12-31 20:31:35.688331 | TASK [tox : Return file comments to Zuul] 2025-12-31 20:31:35.724473 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:35.739842 | 2025-12-31 20:31:35.739982 | PLAY RECAP 2025-12-31 20:31:35.740066 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-12-31 20:31:35.740108 | 2025-12-31 20:31:35.896529 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-12-31 20:31:35.897941 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-31 20:31:36.672822 | 2025-12-31 20:31:36.673026 | PLAY [all] 2025-12-31 20:31:36.691654 | 2025-12-31 20:31:36.691849 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-12-31 20:31:36.749191 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:36.755662 | 2025-12-31 20:31:36.755844 | TASK [fetch-tox-output : Set tox log path for single node] 2025-12-31 20:31:36.825145 | debian-bookworm | ok 2025-12-31 20:31:36.836718 | 2025-12-31 20:31:36.837024 | TASK [fetch-tox-output : Ensure local tox dir] 2025-12-31 20:31:37.409235 | debian-bookworm -> localhost | changed 2025-12-31 20:31:37.422070 | 2025-12-31 20:31:37.422226 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-12-31 20:31:37.477646 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:37.492711 | 2025-12-31 20:31:37.492920 | TASK [fetch-tox-output : Set envlist fact] 2025-12-31 20:31:37.540300 | debian-bookworm | ok 2025-12-31 20:31:37.554208 | 2025-12-31 20:31:37.554443 | TASK [fetch-tox-output : Get tox version] 2025-12-31 20:31:38.272421 | 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-12-31 20:31:38.290864 | debian-bookworm | 4.32.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-12-31 20:31:38.607593 | debian-bookworm | ok: Runtime: 0:00:00.284009 2025-12-31 20:31:38.614615 | 2025-12-31 20:31:38.614804 | TASK [fetch-tox-output : Find all default environments] 2025-12-31 20:31:39.145198 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:39.160889 | 2025-12-31 20:31:39.161116 | TASK [fetch-tox-output : Set envlist fact] 2025-12-31 20:31:39.207385 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:39.223043 | 2025-12-31 20:31:39.223441 | TASK [fetch-tox-output : Find all default environments] 2025-12-31 20:31:39.772914 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:39.786820 | 2025-12-31 20:31:39.787133 | TASK [fetch-tox-output : Set envlist fact] 2025-12-31 20:31:39.823328 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:39.837202 | 2025-12-31 20:31:39.837405 | LOOP [fetch-tox-output : Copy tox logs] 2025-12-31 20:31:39.882818 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:39.917043 | 2025-12-31 20:31:39.917259 | LOOP [fetch-tox-output : Collect tox logs] 2025-12-31 20:31:40.882034 | debian-bookworm | changed: 2025-12-31 20:31:40.882300 | debian-bookworm | created directory /var/lib/zuul/builds/169c969f8ab542aea26e66732d33b3be/work/logs/tox/test 2025-12-31 20:31:40.882350 | debian-bookworm | cd+++++++++ ./ 2025-12-31 20:31:40.882386 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-12-31 20:31:40.882429 | 2025-12-31 20:31:40.896146 | 2025-12-31 20:31:40.896256 | PLAY RECAP 2025-12-31 20:31:40.896312 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-12-31 20:31:40.896337 | 2025-12-31 20:31:41.045922 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-12-31 20:31:41.047855 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-31 20:31:41.886596 | 2025-12-31 20:31:41.886809 | PLAY [all] 2025-12-31 20:31:41.904066 | 2025-12-31 20:31:41.904230 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-31 20:31:42.134654 | debian-bookworm | changed: non-zero return code 2025-12-31 20:31:42.141678 | 2025-12-31 20:31:42.141887 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-31 20:31:42.166685 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:42.173146 | 2025-12-31 20:31:42.173278 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-31 20:31:42.205343 | 2025-12-31 20:31:42.205551 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-31 20:31:42.244916 | 2025-12-31 20:31:42.245206 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-31 20:31:42.270641 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:42.279222 | 2025-12-31 20:31:42.279347 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-31 20:31:42.319524 | 2025-12-31 20:31:42.319864 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-31 20:31:42.347308 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:42.356463 | 2025-12-31 20:31:42.356639 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-31 20:31:42.382546 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:42.396450 | 2025-12-31 20:31:42.396627 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-31 20:31:42.432642 | debian-bookworm | skipping: Conditional result was False 2025-12-31 20:31:42.451356 | 2025-12-31 20:31:42.451492 | PLAY RECAP 2025-12-31 20:31:42.451591 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-31 20:31:42.451646 | 2025-12-31 20:31:42.614459 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-31 20:31:42.615582 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-31 20:31:43.408773 | 2025-12-31 20:31:43.409128 | PLAY [Base post] 2025-12-31 20:31:43.439534 | 2025-12-31 20:31:43.439850 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-31 20:31:44.034675 | debian-bookworm | changed 2025-12-31 20:31:44.047111 | 2025-12-31 20:31:44.047263 | PLAY RECAP 2025-12-31 20:31:44.047330 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-31 20:31:44.047849 | 2025-12-31 20:31:44.231226 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-31 20:31:44.232731 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-31 20:31:45.092014 | 2025-12-31 20:31:45.092201 | PLAY [Base post-logs] 2025-12-31 20:31:45.104614 | 2025-12-31 20:31:45.104808 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-31 20:31:45.617352 | localhost | changed 2025-12-31 20:31:45.629285 | 2025-12-31 20:31:45.629468 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-31 20:31:45.666047 | localhost | ok 2025-12-31 20:31:45.671726 | 2025-12-31 20:31:45.671930 | TASK [Set zuul-log-path fact] 2025-12-31 20:31:45.701277 | localhost | ok 2025-12-31 20:31:45.711076 | 2025-12-31 20:31:45.711521 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-31 20:31:45.761212 | localhost | ok 2025-12-31 20:31:45.766337 | 2025-12-31 20:31:45.766533 | TASK [upload-logs : Create log directories] 2025-12-31 20:31:46.319918 | localhost | changed 2025-12-31 20:31:46.325694 | 2025-12-31 20:31:46.325908 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-31 20:31:46.916039 | localhost -> localhost | ok: Runtime: 0:00:00.010180 2025-12-31 20:31:46.923023 | 2025-12-31 20:31:46.923244 | TASK [upload-logs : Upload logs to log server] 2025-12-31 20:31:47.554878 | localhost | Output suppressed because no_log was given 2025-12-31 20:31:47.557445 | 2025-12-31 20:31:47.557602 | LOOP [upload-logs : Compress console log and json output] 2025-12-31 20:31:47.609210 | localhost | skipping: Conditional result was False 2025-12-31 20:31:47.614126 | localhost | skipping: Conditional result was False 2025-12-31 20:31:47.630652 | 2025-12-31 20:31:47.630987 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-31 20:31:47.682464 | localhost | skipping: Conditional result was False 2025-12-31 20:31:47.683206 | 2025-12-31 20:31:47.687230 | localhost | skipping: Conditional result was False 2025-12-31 20:31:47.693164 | 2025-12-31 20:31:47.693339 | LOOP [upload-logs : Upload console log and json output]