2025-01-08 22:22:16.820393 | Job console starting... 2025-01-08 22:22:16.838282 | Updating repositories 2025-01-08 22:22:16.854410 | Preparing job workspace 2025-01-08 22:22:18.268747 | Running Ansible setup... 2025-01-08 22:22:23.944820 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 22:22:24.735707 | 2025-01-08 22:22:24.735868 | PLAY [Base pre] 2025-01-08 22:22:24.764775 | 2025-01-08 22:22:24.764919 | TASK [Setup log path fact] 2025-01-08 22:22:24.808079 | ubuntu-noble | ok 2025-01-08 22:22:24.840027 | 2025-01-08 22:22:24.840173 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:22:24.891791 | ubuntu-noble | ok 2025-01-08 22:22:24.898718 | 2025-01-08 22:22:24.898840 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:22:24.954165 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:24.962081 | 2025-01-08 22:22:24.962214 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:22:25.007364 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:25.015434 | 2025-01-08 22:22:25.015566 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:22:25.040330 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:25.047624 | 2025-01-08 22:22:25.047738 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:22:25.072636 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:25.085786 | 2025-01-08 22:22:25.085936 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:22:25.110900 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:25.124664 | 2025-01-08 22:22:25.124798 | TASK [emit-job-header : Print job information] 2025-01-08 22:22:25.176840 | # Job Information 2025-01-08 22:22:25.177128 | Ansible Version: 2.15.3 2025-01-08 22:22:25.177165 | Job: tox 2025-01-08 22:22:25.177194 | Pipeline: check 2025-01-08 22:22:25.177214 | Executor: 7d211f194f6a 2025-01-08 22:22:25.177234 | Triggered by: https://github.com/osism/openstack-image-manager/pull/870 2025-01-08 22:22:25.177252 | Event ID: e4a0eb70-ce0e-11ef-8a67-bbf7be1d7144 2025-01-08 22:22:25.184561 | 2025-01-08 22:22:25.184697 | LOOP [emit-job-header : Print node information] 2025-01-08 22:22:25.323174 | ubuntu-noble | ok: 2025-01-08 22:22:25.323380 | ubuntu-noble | # Node Information 2025-01-08 22:22:25.323415 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 22:22:25.323440 | ubuntu-noble | Hostname: ubuntu 2025-01-08 22:22:25.323461 | ubuntu-noble | Username: zuul 2025-01-08 22:22:25.323481 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 22:22:25.323500 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 22:22:25.323519 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 22:22:25.323538 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 22:22:25.323557 | ubuntu-noble | Interface IP: 81.163.193.246 2025-01-08 22:22:25.339738 | 2025-01-08 22:22:25.339873 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 22:22:25.871975 | ubuntu-noble -> localhost | changed 2025-01-08 22:22:25.888231 | 2025-01-08 22:22:25.888378 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 22:22:27.034469 | ubuntu-noble -> localhost | changed 2025-01-08 22:22:27.049443 | 2025-01-08 22:22:27.049569 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 22:22:27.340263 | ubuntu-noble -> localhost | ok 2025-01-08 22:22:27.349830 | 2025-01-08 22:22:27.350003 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 22:22:27.381870 | ubuntu-noble | ok 2025-01-08 22:22:27.398899 | ubuntu-noble | included: /var/lib/zuul/builds/df208259a1bc462aa1708a4236488fbb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 22:22:27.407173 | 2025-01-08 22:22:27.407268 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 22:22:28.188672 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 22:22:28.188935 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/df208259a1bc462aa1708a4236488fbb/work/df208259a1bc462aa1708a4236488fbb_id_rsa 2025-01-08 22:22:28.188976 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/df208259a1bc462aa1708a4236488fbb/work/df208259a1bc462aa1708a4236488fbb_id_rsa.pub 2025-01-08 22:22:28.189000 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 22:22:28.189022 | ubuntu-noble -> localhost | SHA256:fcTYcVhf5AtnXC2YK+6zxd3c6Y+UiWSoR8Ow2Ho+HdQ zuul-build-sshkey 2025-01-08 22:22:28.189044 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 22:22:28.189069 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 22:22:28.189091 | ubuntu-noble -> localhost | | .=o.=| 2025-01-08 22:22:28.189111 | ubuntu-noble -> localhost | | ++ooo+| 2025-01-08 22:22:28.189130 | ubuntu-noble -> localhost | | . ..+o =o| 2025-01-08 22:22:28.189149 | ubuntu-noble -> localhost | | o =ooE + .| 2025-01-08 22:22:28.189168 | ubuntu-noble -> localhost | | . So*.+ . | 2025-01-08 22:22:28.189186 | ubuntu-noble -> localhost | | . oo* o =o| 2025-01-08 22:22:28.189205 | ubuntu-noble -> localhost | | . oo..+ =.+| 2025-01-08 22:22:28.189225 | ubuntu-noble -> localhost | | o..+. ... | 2025-01-08 22:22:28.189244 | ubuntu-noble -> localhost | | ...o ..o| 2025-01-08 22:22:28.189263 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 22:22:28.189313 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.206083 2025-01-08 22:22:28.198468 | 2025-01-08 22:22:28.198582 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 22:22:28.231149 | ubuntu-noble | ok 2025-01-08 22:22:28.244091 | ubuntu-noble | included: /var/lib/zuul/builds/df208259a1bc462aa1708a4236488fbb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 22:22:28.257996 | 2025-01-08 22:22:28.259093 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 22:22:28.294225 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:28.302751 | 2025-01-08 22:22:28.302874 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 22:22:29.108358 | ubuntu-noble | changed 2025-01-08 22:22:29.117210 | 2025-01-08 22:22:29.117328 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 22:22:29.428244 | ubuntu-noble | ok 2025-01-08 22:22:29.435994 | 2025-01-08 22:22:29.436113 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 22:22:30.253423 | ubuntu-noble | changed 2025-01-08 22:22:30.263438 | 2025-01-08 22:22:30.263571 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 22:22:31.015244 | ubuntu-noble | changed 2025-01-08 22:22:31.038167 | 2025-01-08 22:22:31.038493 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 22:22:31.098767 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:31.110319 | 2025-01-08 22:22:31.110450 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 22:22:31.721426 | ubuntu-noble -> localhost | changed 2025-01-08 22:22:31.745071 | 2025-01-08 22:22:31.745229 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 22:22:32.197418 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/df208259a1bc462aa1708a4236488fbb/work/df208259a1bc462aa1708a4236488fbb_id_rsa (zuul-build-sshkey) 2025-01-08 22:22:32.197684 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.025051 2025-01-08 22:22:32.246081 | 2025-01-08 22:22:32.246213 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 22:22:32.675824 | ubuntu-noble | ok 2025-01-08 22:22:32.683126 | 2025-01-08 22:22:32.683247 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 22:22:32.718192 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:22:32.733139 | 2025-01-08 22:22:32.733271 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 22:22:33.189316 | ubuntu-noble | ok 2025-01-08 22:22:33.228300 | 2025-01-08 22:22:33.228452 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 22:22:33.288010 | ubuntu-noble | ok 2025-01-08 22:22:33.295375 | 2025-01-08 22:22:33.295493 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 22:22:33.695046 | ubuntu-noble -> localhost | ok 2025-01-08 22:22:33.711435 | 2025-01-08 22:22:33.711600 | TASK [validate-host : Collect information about the host] 2025-01-08 22:22:35.082371 | ubuntu-noble | ok 2025-01-08 22:22:35.097583 | 2025-01-08 22:22:35.097731 | TASK [validate-host : Sanitize hostname] 2025-01-08 22:22:35.191108 | ubuntu-noble | ok 2025-01-08 22:22:35.198211 | 2025-01-08 22:22:35.198344 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 22:22:35.923759 | ubuntu-noble -> localhost | changed 2025-01-08 22:22:35.933196 | 2025-01-08 22:22:35.933331 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 22:22:36.427960 | ubuntu-noble | ok 2025-01-08 22:22:36.437674 | 2025-01-08 22:22:36.437797 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 22:22:37.137037 | ubuntu-noble -> localhost | changed 2025-01-08 22:22:37.153762 | 2025-01-08 22:22:37.153893 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 22:22:37.499460 | ubuntu-noble | ok 2025-01-08 22:22:37.516558 | 2025-01-08 22:22:37.516716 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 22:22:38.445218 | ubuntu-noble | changed: 2025-01-08 22:22:38.445429 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 22:22:38.445461 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 22:22:38.445484 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 22:22:38.445505 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-08 22:22:38.445526 | ubuntu-noble | =5.5 (from tox) 2025-01-08 22:23:09.769323 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl.metadata (5.3 kB) 2025-01-08 22:23:09.831137 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2025-01-08 22:23:09.847507 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2025-01-08 22:23:09.923717 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2025-01-08 22:23:09.939946 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-01-08 22:23:10.025056 | ubuntu-noble | Collecting filelock>=3.16.1 (from tox) 2025-01-08 22:23:10.043625 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl.metadata (2.9 kB) 2025-01-08 22:23:10.122610 | ubuntu-noble | Collecting packaging>=24.1 (from tox) 2025-01-08 22:23:10.137081 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl.metadata (3.2 kB) 2025-01-08 22:23:10.214310 | ubuntu-noble | Collecting platformdirs>=4.3.6 (from tox) 2025-01-08 22:23:10.231179 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl.metadata (11 kB) 2025-01-08 22:23:10.307696 | ubuntu-noble | Collecting pluggy>=1.5 (from tox) 2025-01-08 22:23:10.322680 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl.metadata (4.8 kB) 2025-01-08 22:23:10.389613 | ubuntu-noble | Collecting pyproject-api>=1.8 (from tox) 2025-01-08 22:23:10.407177 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl.metadata (2.7 kB) 2025-01-08 22:23:10.558331 | ubuntu-noble | Collecting virtualenv>=20.26.6 (from tox) 2025-01-08 22:23:10.573844 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl.metadata (4.5 kB) 2025-01-08 22:23:10.696091 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.26.6->tox) 2025-01-08 22:23:10.711788 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl.metadata (5.2 kB) 2025-01-08 22:23:10.783732 | ubuntu-noble | Downloading tox-4.23.2-py3-none-any.whl (166 kB) 2025-01-08 22:23:10.827291 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 166.8/166.8 kB 4.3 MB/s eta 0:00:00 2025-01-08 22:23:10.843802 | ubuntu-noble | Downloading cachetools-5.5.0-py3-none-any.whl (9.5 kB) 2025-01-08 22:23:10.874314 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-01-08 22:23:10.893016 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 14.6 MB/s eta 0:00:00 2025-01-08 22:23:10.909630 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-01-08 22:23:10.935353 | ubuntu-noble | Downloading filelock-3.16.1-py3-none-any.whl (16 kB) 2025-01-08 22:23:10.965119 | ubuntu-noble | Downloading packaging-24.2-py3-none-any.whl (65 kB) 2025-01-08 22:23:10.984778 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 65.5/65.5 kB 4.1 MB/s eta 0:00:00 2025-01-08 22:23:11.001835 | ubuntu-noble | Downloading platformdirs-4.3.6-py3-none-any.whl (18 kB) 2025-01-08 22:23:11.090441 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl (20 kB) 2025-01-08 22:23:11.156261 | ubuntu-noble | Downloading pyproject_api-1.8.0-py3-none-any.whl (13 kB) 2025-01-08 22:23:11.183065 | ubuntu-noble | Downloading virtualenv-20.28.1-py3-none-any.whl (4.3 MB) 2025-01-08 22:23:11.397388 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.3/4.3 MB 20.7 MB/s eta 0:00:00 2025-01-08 22:23:11.414742 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-01-08 22:23:11.448795 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 16.7 MB/s eta 0:00:00 2025-01-08 22:23:11.561704 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-01-08 22:23:12.725120 | ubuntu-noble | Successfully installed cachetools-5.5.0 chardet-5.2.0 colorama-0.4.6 distlib-0.3.9 filelock-3.16.1 packaging-24.2 platformdirs-4.3.6 pluggy-1.5.0 pyproject-api-1.8.0 tox-4.23.2 virtualenv-20.28.1 2025-01-08 22:23:12.931878 | ubuntu-noble | ok: Runtime: 0:00:04.498531 2025-01-08 22:23:12.946806 | 2025-01-08 22:23:12.946954 | TASK [ensure-tox : Export installed tox_executable path] 2025-01-08 22:23:13.000808 | ubuntu-noble | ok 2025-01-08 22:23:13.009005 | 2025-01-08 22:23:13.009134 | TASK [ensure-tox : Output tox version] 2025-01-08 22:23:14.141983 | ubuntu-noble | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-01-08 22:23:14.179116 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-08 22:23:14.552761 | ubuntu-noble | ok: Runtime: 0:00:00.782843 2025-01-08 22:23:14.613888 | 2025-01-08 22:23:14.614046 | TASK [ensure-tox : Make global symlink] 2025-01-08 22:23:14.642197 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:14.675103 | 2025-01-08 22:23:14.675206 | PLAY RECAP 2025-01-08 22:23:14.675259 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-01-08 22:23:14.675284 | 2025-01-08 22:23:14.799410 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2025-01-08 22:23:14.811451 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-08 22:23:15.554298 | 2025-01-08 22:23:15.554462 | PLAY [all] 2025-01-08 22:23:15.583219 | 2025-01-08 22:23:15.583370 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-01-08 22:23:16.621812 | ubuntu-noble | ok: Runtime: 0:00:00.028778 2025-01-08 22:23:16.641716 | 2025-01-08 22:23:16.641948 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-01-08 22:23:17.182583 | ubuntu-noble | ok: Runtime: 0:00:00.006776 2025-01-08 22:23:17.199816 | 2025-01-08 22:23:17.199991 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-01-08 22:23:17.634996 | ubuntu-noble | sudo: a password is required 2025-01-08 22:23:17.755861 | ubuntu-noble | ok: Runtime: 0:00:00.020508 2025-01-08 22:23:17.777003 | 2025-01-08 22:23:17.777131 | TASK [tox : Check to see if the constraints file exists] 2025-01-08 22:23:17.814106 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:17.829267 | 2025-01-08 22:23:17.829413 | TASK [tox : Fail if constraints file is missing] 2025-01-08 22:23:17.876000 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:17.894377 | 2025-01-08 22:23:17.894539 | TASK [tox : Record file location] 2025-01-08 22:23:17.933190 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:17.951048 | 2025-01-08 22:23:17.951194 | TASK [tox : Install tox siblings] 2025-01-08 22:23:17.985483 | ubuntu-noble | ok 2025-01-08 22:23:17.996111 | ubuntu-noble | included: /var/lib/zuul/builds/df208259a1bc462aa1708a4236488fbb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/tox/tasks/siblings.yaml 2025-01-08 22:23:18.004272 | 2025-01-08 22:23:18.004384 | TASK [tox : Run tox without tests] 2025-01-08 22:23:19.309633 | ubuntu-noble | test: install_deps> python -I -m pip install -r requirements.txt 2025-01-08 22:23:39.364169 | ubuntu-noble | test: OK (20.44 seconds) 2025-01-08 22:23:39.427281 | ubuntu-noble | congratulations :) (20.56 seconds) 2025-01-08 22:23:39.581937 | ubuntu-noble | ok: Runtime: 0:00:20.912448 2025-01-08 22:23:39.596784 | 2025-01-08 22:23:39.596946 | TASK [tox : Create a tempfile to save tox showconfig] 2025-01-08 22:23:40.033119 | ubuntu-noble | changed 2025-01-08 22:23:40.044676 | 2025-01-08 22:23:40.044846 | TASK [tox : Get tox envlist config] 2025-01-08 22:23:41.084615 | ubuntu-noble | ok: Runtime: 0:00:00.393255 2025-01-08 22:23:41.100311 | 2025-01-08 22:23:41.100474 | TASK [tox : Install any sibling python packages] 2025-01-08 22:23:41.892408 | ubuntu-noble | ok: No setup.cfg, no action needed 2025-01-08 22:23:41.902443 | 2025-01-08 22:23:41.902559 | TASK [tox : Remove tempfile] 2025-01-08 22:23:42.649484 | ubuntu-noble | changed 2025-01-08 22:23:42.659765 | 2025-01-08 22:23:42.659896 | TASK [tox : Emit tox command] 2025-01-08 22:23:42.707074 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-01-08 22:23:42.716193 | 2025-01-08 22:23:42.716327 | TASK [tox : Run tox] 2025-01-08 22:23:44.049749 | ubuntu-noble | test: commands[0]> python -m unittest discover test/unit 2025-01-08 22:23:45.453365 | ubuntu-noble | ............... 2025-01-08 22:23:45.633706 | ubuntu-noble | ---------------------------------------------------------------------- 2025-01-08 22:23:45.633754 | ubuntu-noble | Ran 15 tests in 0.280s 2025-01-08 22:23:45.633763 | ubuntu-noble | 2025-01-08 22:23:45.633769 | ubuntu-noble | OK 2025-01-08 22:23:45.633782 | ubuntu-noble | test: OK (1.69=setup[0.10]+cmd[1.58] seconds) 2025-01-08 22:23:45.767393 | ubuntu-noble | congratulations :) (1.86 seconds) 2025-01-08 22:23:46.265042 | ubuntu-noble | ok: Runtime: 0:00:02.463650 2025-01-08 22:23:46.279772 | 2025-01-08 22:23:46.279909 | TASK [tox : Look for output] 2025-01-08 22:23:46.885699 | ubuntu-noble | ok 2025-01-08 22:23:46.895485 | 2025-01-08 22:23:46.895610 | TASK [tox : Return file comments to Zuul] 2025-01-08 22:23:46.931152 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:46.963776 | 2025-01-08 22:23:46.963869 | PLAY RECAP 2025-01-08 22:23:46.963925 | ubuntu-noble | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-01-08 22:23:46.963952 | 2025-01-08 22:23:47.086853 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2025-01-08 22:23:47.090844 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-08 22:23:47.801859 | 2025-01-08 22:23:47.802031 | PLAY [all] 2025-01-08 22:23:47.830035 | 2025-01-08 22:23:47.830163 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-01-08 22:23:47.897971 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:47.911585 | 2025-01-08 22:23:47.911790 | TASK [fetch-tox-output : Set tox log path for single node] 2025-01-08 22:23:47.960952 | ubuntu-noble | ok 2025-01-08 22:23:47.970688 | 2025-01-08 22:23:47.970792 | TASK [fetch-tox-output : Ensure local tox dir] 2025-01-08 22:23:48.437266 | ubuntu-noble -> localhost | changed 2025-01-08 22:23:48.453860 | 2025-01-08 22:23:48.454054 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-01-08 22:23:48.522170 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:48.539800 | 2025-01-08 22:23:48.539979 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 22:23:48.598617 | ubuntu-noble | ok 2025-01-08 22:23:48.610611 | 2025-01-08 22:23:48.610800 | TASK [fetch-tox-output : Get tox version] 2025-01-08 22:23:49.728182 | ubuntu-noble | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-01-08 22:23:49.762115 | ubuntu-noble | 4.23.2 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-01-08 22:23:50.156297 | ubuntu-noble | ok: Runtime: 0:00:00.660920 2025-01-08 22:23:50.166724 | 2025-01-08 22:23:50.166846 | TASK [fetch-tox-output : Find all default environments] 2025-01-08 22:23:50.250290 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:50.257681 | 2025-01-08 22:23:50.257801 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 22:23:50.292271 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:50.302023 | 2025-01-08 22:23:50.302151 | TASK [fetch-tox-output : Find all default environments] 2025-01-08 22:23:50.375713 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:50.389606 | 2025-01-08 22:23:50.389795 | TASK [fetch-tox-output : Set envlist fact] 2025-01-08 22:23:50.416924 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:50.431189 | 2025-01-08 22:23:50.431408 | LOOP [fetch-tox-output : Copy tox logs] 2025-01-08 22:23:50.482583 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:50.495865 | 2025-01-08 22:23:50.495995 | LOOP [fetch-tox-output : Collect tox logs] 2025-01-08 22:23:51.277070 | ubuntu-noble | changed: 2025-01-08 22:23:51.277267 | ubuntu-noble | cd+++++++++ ./ 2025-01-08 22:23:51.277301 | ubuntu-noble | >f+++++++++ 1-commands[0].log 2025-01-08 22:23:51.277333 | 2025-01-08 22:23:51.319588 | 2025-01-08 22:23:51.319753 | PLAY RECAP 2025-01-08 22:23:51.319817 | ubuntu-noble | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-01-08 22:23:51.319842 | 2025-01-08 22:23:51.463511 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2025-01-08 22:23:51.467105 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-08 22:23:52.209554 | 2025-01-08 22:23:52.209762 | PLAY [all] 2025-01-08 22:23:52.239655 | 2025-01-08 22:23:52.239783 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-01-08 22:23:52.456172 | ubuntu-noble | changed: non-zero return code 2025-01-08 22:23:52.464290 | 2025-01-08 22:23:52.464428 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-01-08 22:23:52.491699 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:52.508119 | 2025-01-08 22:23:52.508273 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-01-08 22:23:52.553851 | 2025-01-08 22:23:52.554065 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-01-08 22:23:52.589477 | 2025-01-08 22:23:52.589726 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-01-08 22:23:52.614588 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:52.624402 | 2025-01-08 22:23:52.624533 | LOOP [fetch-subunit-output : Generate subunit file] 2025-01-08 22:23:52.656000 | 2025-01-08 22:23:52.656175 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-01-08 22:23:52.680676 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:52.688014 | 2025-01-08 22:23:52.688122 | TASK [fetch-subunit-output : Remove the temporary file] 2025-01-08 22:23:52.722488 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:52.732971 | 2025-01-08 22:23:52.733107 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-01-08 22:23:52.757410 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:23:52.792205 | 2025-01-08 22:23:52.792294 | PLAY RECAP 2025-01-08 22:23:52.792350 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-01-08 22:23:52.792376 | 2025-01-08 22:23:52.918254 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-01-08 22:23:52.926436 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 22:23:53.628979 | 2025-01-08 22:23:53.629154 | PLAY [Base post] 2025-01-08 22:23:53.656191 | 2025-01-08 22:23:53.656335 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-08 22:23:54.457145 | ubuntu-noble | changed 2025-01-08 22:23:54.516030 | 2025-01-08 22:23:54.516202 | PLAY RECAP 2025-01-08 22:23:54.516276 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-08 22:23:54.516339 | 2025-01-08 22:23:54.639319 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 22:23:54.643860 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-08 22:23:55.407235 | 2025-01-08 22:23:55.407387 | PLAY [Base post-logs] 2025-01-08 22:23:55.424053 | 2025-01-08 22:23:55.424179 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-08 22:23:55.873199 | localhost | changed 2025-01-08 22:23:55.880693 | 2025-01-08 22:23:55.880858 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-08 22:23:55.913057 | localhost | ok 2025-01-08 22:23:55.925929 | 2025-01-08 22:23:55.926046 | TASK [Set zuul-log-path fact] 2025-01-08 22:23:55.952671 | localhost | ok 2025-01-08 22:23:55.974823 | 2025-01-08 22:23:55.974938 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:23:56.026184 | localhost | ok 2025-01-08 22:23:56.030656 | 2025-01-08 22:23:56.030786 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:23:56.068067 | localhost | skipping: Conditional result was False 2025-01-08 22:23:56.072941 | 2025-01-08 22:23:56.073063 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:23:56.129961 | localhost | skipping: Conditional result was False 2025-01-08 22:23:56.137985 | 2025-01-08 22:23:56.138240 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:23:56.165990 | localhost | skipping: Conditional result was False 2025-01-08 22:23:56.173305 | 2025-01-08 22:23:56.173530 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:23:56.199956 | localhost | skipping: Conditional result was False 2025-01-08 22:23:56.206480 | 2025-01-08 22:23:56.206762 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:23:56.233318 | localhost | skipping: Conditional result was False 2025-01-08 22:23:56.245222 | 2025-01-08 22:23:56.245453 | TASK [upload-logs : Create log directories] 2025-01-08 22:23:56.817650 | localhost | changed 2025-01-08 22:23:56.822294 | 2025-01-08 22:23:56.822399 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-08 22:23:57.369252 | localhost -> localhost | ok: Runtime: 0:00:00.007978 2025-01-08 22:23:57.376114 | 2025-01-08 22:23:57.376242 | TASK [upload-logs : Upload logs to log server] 2025-01-08 22:23:57.975528 | localhost | Output suppressed because no_log was given 2025-01-08 22:23:57.980957 | 2025-01-08 22:23:57.981111 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 22:23:58.051036 | localhost | skipping: Conditional result was False 2025-01-08 22:23:58.070755 | localhost | skipping: Conditional result was False 2025-01-08 22:23:58.083386 | 2025-01-08 22:23:58.083498 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 22:23:58.169156 | localhost | skipping: Conditional result was False 2025-01-08 22:23:58.169528 | 2025-01-08 22:23:58.192695 | localhost | skipping: Conditional result was False 2025-01-08 22:23:58.204963 | 2025-01-08 22:23:58.205083 | LOOP [upload-logs : Upload console log and json output]