2025-05-13 19:45:02.586211 | Job console starting 2025-05-13 19:45:02.604062 | Updating git repos 2025-05-13 19:45:02.645042 | Cloning repos into workspace 2025-05-13 19:45:02.698740 | Restoring repo states 2025-05-13 19:45:02.719706 | Merging changes 2025-05-13 19:45:03.290859 | Checking out repos 2025-05-13 19:45:03.354866 | Preparing playbooks 2025-05-13 19:45:04.522641 | Running Ansible setup 2025-05-13 19:45:10.429554 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-05-13 19:45:11.442030 | 2025-05-13 19:45:11.442227 | PLAY [Base pre] 2025-05-13 19:45:11.460834 | 2025-05-13 19:45:11.460993 | TASK [Setup log path fact] 2025-05-13 19:45:11.482414 | ubuntu-noble | ok 2025-05-13 19:45:11.503975 | 2025-05-13 19:45:11.504179 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-13 19:45:11.537317 | ubuntu-noble | ok 2025-05-13 19:45:11.566739 | 2025-05-13 19:45:11.566976 | TASK [emit-job-header : Print job information] 2025-05-13 19:45:11.638433 | # Job Information 2025-05-13 19:45:11.638667 | Ansible Version: 2.16.14 2025-05-13 19:45:11.638705 | Job: tox 2025-05-13 19:45:11.638737 | Pipeline: check 2025-05-13 19:45:11.638758 | Executor: 521e9411259a 2025-05-13 19:45:11.638779 | Triggered by: https://github.com/osism/openstack-image-manager/pull/927 2025-05-13 19:45:11.638800 | Event ID: bd7e3b70-3032-11f0-8a5f-3567cac8fbcd 2025-05-13 19:45:11.646310 | 2025-05-13 19:45:11.646442 | LOOP [emit-job-header : Print node information] 2025-05-13 19:45:11.815730 | ubuntu-noble | ok: 2025-05-13 19:45:11.816047 | ubuntu-noble | # Node Information 2025-05-13 19:45:11.816085 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-05-13 19:45:11.816110 | ubuntu-noble | Hostname: ubuntu 2025-05-13 19:45:11.816133 | ubuntu-noble | Username: zuul 2025-05-13 19:45:11.816154 | ubuntu-noble | Distro: Ubuntu 24.04 2025-05-13 19:45:11.816178 | ubuntu-noble | Provider: regiocloud-a 2025-05-13 19:45:11.816200 | ubuntu-noble | Region: 2025-05-13 19:45:11.816221 | ubuntu-noble | Label: ubuntu-noble 2025-05-13 19:45:11.816241 | ubuntu-noble | Product Name: OpenStack Nova 2025-05-13 19:45:11.816261 | ubuntu-noble | Interface IP: 81.163.192.58 2025-05-13 19:45:11.836579 | 2025-05-13 19:45:11.836772 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-05-13 19:45:12.732702 | ubuntu-noble -> localhost | changed 2025-05-13 19:45:12.741156 | 2025-05-13 19:45:12.741306 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-05-13 19:45:14.599388 | ubuntu-noble -> localhost | changed 2025-05-13 19:45:14.615196 | 2025-05-13 19:45:14.615344 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-05-13 19:45:15.083252 | ubuntu-noble -> localhost | ok 2025-05-13 19:45:15.094317 | 2025-05-13 19:45:15.094467 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-05-13 19:45:15.145856 | ubuntu-noble | ok 2025-05-13 19:45:15.183241 | ubuntu-noble | included: /var/lib/zuul/builds/9bd9c2cbcc6844ca99d21e4c1f568c87/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-05-13 19:45:15.196231 | 2025-05-13 19:45:15.196373 | TASK [add-build-sshkey : Create Temp SSH key] 2025-05-13 19:45:16.743760 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-05-13 19:45:16.743964 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/9bd9c2cbcc6844ca99d21e4c1f568c87/work/9bd9c2cbcc6844ca99d21e4c1f568c87_id_rsa 2025-05-13 19:45:16.744003 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/9bd9c2cbcc6844ca99d21e4c1f568c87/work/9bd9c2cbcc6844ca99d21e4c1f568c87_id_rsa.pub 2025-05-13 19:45:16.744030 | ubuntu-noble -> localhost | The key fingerprint is: 2025-05-13 19:45:16.744055 | ubuntu-noble -> localhost | SHA256:+y3FjUasvznHGNU4YtmsPX3j6ga4I/mtXBv2FJatEws zuul-build-sshkey 2025-05-13 19:45:16.744077 | ubuntu-noble -> localhost | The key's randomart image is: 2025-05-13 19:45:16.744098 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-05-13 19:45:16.744133 | ubuntu-noble -> localhost | | | 2025-05-13 19:45:16.744157 | ubuntu-noble -> localhost | | | 2025-05-13 19:45:16.744177 | ubuntu-noble -> localhost | | . + o | 2025-05-13 19:45:16.744197 | ubuntu-noble -> localhost | | * O .| 2025-05-13 19:45:16.744218 | ubuntu-noble -> localhost | | S .E % + | 2025-05-13 19:45:16.744246 | ubuntu-noble -> localhost | | o..X O.o| 2025-05-13 19:45:16.744266 | ubuntu-noble -> localhost | | o .B.O..o| 2025-05-13 19:45:16.744286 | ubuntu-noble -> localhost | | o.+=.Oo+. | 2025-05-13 19:45:16.744306 | ubuntu-noble -> localhost | | o+++*B. | 2025-05-13 19:45:16.744326 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-05-13 19:45:16.744377 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.898916 2025-05-13 19:45:16.750719 | 2025-05-13 19:45:16.750815 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-05-13 19:45:16.784098 | ubuntu-noble | ok 2025-05-13 19:45:16.792839 | ubuntu-noble | included: /var/lib/zuul/builds/9bd9c2cbcc6844ca99d21e4c1f568c87/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-05-13 19:45:16.810394 | 2025-05-13 19:45:16.810480 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-05-13 19:45:16.843963 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:45:16.849610 | 2025-05-13 19:45:16.849707 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-05-13 19:45:17.574010 | ubuntu-noble | changed 2025-05-13 19:45:17.580864 | 2025-05-13 19:45:17.580971 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-05-13 19:45:17.881780 | ubuntu-noble | ok 2025-05-13 19:45:17.897601 | 2025-05-13 19:45:17.897735 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-05-13 19:45:18.642591 | ubuntu-noble | changed 2025-05-13 19:45:18.649109 | 2025-05-13 19:45:18.649199 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-05-13 19:45:19.371219 | ubuntu-noble | changed 2025-05-13 19:45:19.380594 | 2025-05-13 19:45:19.380717 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-05-13 19:45:19.404543 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:45:19.410548 | 2025-05-13 19:45:19.410634 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-05-13 19:45:19.860158 | ubuntu-noble -> localhost | changed 2025-05-13 19:45:19.871001 | 2025-05-13 19:45:19.871106 | TASK [add-build-sshkey : Add back temp key] 2025-05-13 19:45:20.209161 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/9bd9c2cbcc6844ca99d21e4c1f568c87/work/9bd9c2cbcc6844ca99d21e4c1f568c87_id_rsa (zuul-build-sshkey) 2025-05-13 19:45:20.209506 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014868 2025-05-13 19:45:20.216210 | 2025-05-13 19:45:20.216329 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-05-13 19:45:20.760174 | ubuntu-noble | ok 2025-05-13 19:45:20.766785 | 2025-05-13 19:45:20.766936 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-05-13 19:45:20.831341 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:45:20.844095 | 2025-05-13 19:45:20.844233 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-05-13 19:45:21.371320 | ubuntu-noble | ok 2025-05-13 19:45:21.387184 | 2025-05-13 19:45:21.387329 | TASK [validate-host : Define zuul_info_dir fact] 2025-05-13 19:45:21.458859 | ubuntu-noble | ok 2025-05-13 19:45:21.472867 | 2025-05-13 19:45:21.473007 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-05-13 19:45:21.865711 | ubuntu-noble -> localhost | ok 2025-05-13 19:45:21.876152 | 2025-05-13 19:45:21.876279 | TASK [validate-host : Collect information about the host] 2025-05-13 19:45:23.099902 | ubuntu-noble | ok 2025-05-13 19:45:23.120468 | 2025-05-13 19:45:23.120643 | TASK [validate-host : Sanitize hostname] 2025-05-13 19:45:23.216189 | ubuntu-noble | ok 2025-05-13 19:45:23.223650 | 2025-05-13 19:45:23.223813 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-05-13 19:45:23.878720 | ubuntu-noble -> localhost | changed 2025-05-13 19:45:23.885074 | 2025-05-13 19:45:23.885192 | TASK [validate-host : Collect information about zuul worker] 2025-05-13 19:45:24.401334 | ubuntu-noble | ok 2025-05-13 19:45:24.406352 | 2025-05-13 19:45:24.406466 | TASK [validate-host : Write out all zuul information for each host] 2025-05-13 19:45:25.295941 | ubuntu-noble -> localhost | changed 2025-05-13 19:45:25.375110 | 2025-05-13 19:45:25.375246 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-05-13 19:45:25.709654 | ubuntu-noble | ok 2025-05-13 19:45:25.715821 | 2025-05-13 19:45:25.715929 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-05-13 19:45:26.633309 | ubuntu-noble | changed: 2025-05-13 19:45:26.633564 | ubuntu-noble | cd+++++++++ src/ 2025-05-13 19:45:26.633598 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-05-13 19:45:26.633621 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-05-13 19:45:26.633644 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-05-13 19:45:26.633702 | ubuntu-noble | =5.5.1 (from tox) 2025-05-13 19:45:53.884582 | ubuntu-noble | Downloading cachetools-5.5.2-py3-none-any.whl.metadata (5.4 kB) 2025-05-13 19:45:53.933856 | ubuntu-noble | Collecting chardet>=5.2 (from tox) 2025-05-13 19:45:53.948483 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl.metadata (3.4 kB) 2025-05-13 19:45:54.020846 | ubuntu-noble | Collecting colorama>=0.4.6 (from tox) 2025-05-13 19:45:54.033293 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-05-13 19:45:54.107514 | ubuntu-noble | Collecting filelock>=3.16.1 (from tox) 2025-05-13 19:45:54.122199 | ubuntu-noble | Downloading filelock-3.18.0-py3-none-any.whl.metadata (2.9 kB) 2025-05-13 19:45:54.191634 | ubuntu-noble | Collecting packaging>=24.2 (from tox) 2025-05-13 19:45:54.203358 | ubuntu-noble | Downloading packaging-25.0-py3-none-any.whl.metadata (3.3 kB) 2025-05-13 19:45:54.273800 | ubuntu-noble | Collecting platformdirs>=4.3.6 (from tox) 2025-05-13 19:45:54.285595 | ubuntu-noble | Downloading platformdirs-4.3.8-py3-none-any.whl.metadata (12 kB) 2025-05-13 19:45:54.341960 | ubuntu-noble | Collecting pluggy>=1.5 (from tox) 2025-05-13 19:45:54.354524 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl.metadata (4.8 kB) 2025-05-13 19:45:54.413874 | ubuntu-noble | Collecting pyproject-api>=1.8 (from tox) 2025-05-13 19:45:54.427904 | ubuntu-noble | Downloading pyproject_api-1.9.1-py3-none-any.whl.metadata (2.7 kB) 2025-05-13 19:45:54.578678 | ubuntu-noble | Collecting virtualenv>=20.31 (from tox) 2025-05-13 19:45:54.592197 | ubuntu-noble | Downloading virtualenv-20.31.2-py3-none-any.whl.metadata (4.5 kB) 2025-05-13 19:45:54.704254 | ubuntu-noble | Collecting distlib<1,>=0.3.7 (from virtualenv>=20.31->tox) 2025-05-13 19:45:54.717138 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl.metadata (5.2 kB) 2025-05-13 19:45:54.767006 | ubuntu-noble | Downloading tox-4.26.0-py3-none-any.whl (172 kB) 2025-05-13 19:45:54.804982 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 172.8/172.8 kB 5.4 MB/s eta 0:00:00 2025-05-13 19:45:54.819645 | ubuntu-noble | Downloading cachetools-5.5.2-py3-none-any.whl (10 kB) 2025-05-13 19:45:54.845277 | ubuntu-noble | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-05-13 19:45:54.862130 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 16.7 MB/s eta 0:00:00 2025-05-13 19:45:54.875935 | ubuntu-noble | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-05-13 19:45:54.898744 | ubuntu-noble | Downloading filelock-3.18.0-py3-none-any.whl (16 kB) 2025-05-13 19:45:54.921717 | ubuntu-noble | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-05-13 19:45:54.937640 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 5.7 MB/s eta 0:00:00 2025-05-13 19:45:54.950176 | ubuntu-noble | Downloading platformdirs-4.3.8-py3-none-any.whl (18 kB) 2025-05-13 19:45:54.971740 | ubuntu-noble | Downloading pluggy-1.5.0-py3-none-any.whl (20 kB) 2025-05-13 19:45:54.996439 | ubuntu-noble | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-05-13 19:45:55.023065 | ubuntu-noble | Downloading virtualenv-20.31.2-py3-none-any.whl (6.1 MB) 2025-05-13 19:45:55.277362 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.1/6.1 MB 24.6 MB/s eta 0:00:00 2025-05-13 19:45:55.288720 | ubuntu-noble | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-05-13 19:45:55.313446 | ubuntu-noble | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 21.3 MB/s eta 0:00:00 2025-05-13 19:45:55.436845 | ubuntu-noble | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-05-13 19:45:56.481099 | ubuntu-noble | Successfully installed cachetools-5.5.2 chardet-5.2.0 colorama-0.4.6 distlib-0.3.9 filelock-3.18.0 packaging-25.0 platformdirs-4.3.8 pluggy-1.5.0 pyproject-api-1.9.1 tox-4.26.0 virtualenv-20.31.2 2025-05-13 19:45:56.772614 | ubuntu-noble | ok: Runtime: 0:00:03.561916 2025-05-13 19:45:56.779629 | 2025-05-13 19:45:56.779792 | TASK [ensure-tox : Export installed tox_executable path] 2025-05-13 19:45:56.821544 | ubuntu-noble | ok 2025-05-13 19:45:56.826667 | 2025-05-13 19:45:56.826807 | TASK [ensure-tox : Output tox version] 2025-05-13 19:45:57.460672 | ubuntu-noble | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-13 19:45:57.477654 | ubuntu-noble | 4.26.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-05-13 19:45:57.857110 | ubuntu-noble | ok: Runtime: 0:00:00.392642 2025-05-13 19:45:57.865883 | 2025-05-13 19:45:57.866030 | TASK [ensure-tox : Make global symlink] 2025-05-13 19:45:57.891237 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:45:57.899405 | 2025-05-13 19:45:57.899511 | PLAY RECAP 2025-05-13 19:45:57.899570 | ubuntu-noble | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-05-13 19:45:57.899597 | 2025-05-13 19:45:58.092685 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-05-13 19:45:58.094125 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-13 19:45:58.926490 | 2025-05-13 19:45:58.926690 | PLAY [all] 2025-05-13 19:45:58.943223 | 2025-05-13 19:45:58.943368 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-05-13 19:45:59.489318 | ubuntu-noble | ok: Runtime: 0:00:00.023909 2025-05-13 19:45:59.502257 | 2025-05-13 19:45:59.502612 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-05-13 19:46:00.039973 | ubuntu-noble | ok: Runtime: 0:00:00.005371 2025-05-13 19:46:00.053353 | 2025-05-13 19:46:00.055788 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-05-13 19:46:00.401500 | ubuntu-noble | sudo: a password is required 2025-05-13 19:46:00.602277 | ubuntu-noble | ok: Runtime: 0:00:00.017076 2025-05-13 19:46:00.624961 | 2025-05-13 19:46:00.625342 | TASK [tox : Check to see if the constraints file exists] 2025-05-13 19:46:00.662366 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:00.676695 | 2025-05-13 19:46:00.676921 | TASK [tox : Fail if constraints file is missing] 2025-05-13 19:46:00.714873 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:00.729204 | 2025-05-13 19:46:00.729373 | TASK [tox : Record file location] 2025-05-13 19:46:00.765560 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:00.780116 | 2025-05-13 19:46:00.780282 | TASK [tox : Install tox siblings] 2025-05-13 19:46:00.817363 | ubuntu-noble | ok 2025-05-13 19:46:00.828914 | ubuntu-noble | included: /var/lib/zuul/builds/9bd9c2cbcc6844ca99d21e4c1f568c87/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-05-13 19:46:00.835091 | 2025-05-13 19:46:00.835196 | TASK [tox : Run tox without tests] 2025-05-13 19:46:01.783825 | ubuntu-noble | test: install_deps> python -I -m pip install -r requirements.txt 2025-05-13 19:46:18.132387 | ubuntu-noble | test: OK (16.65 seconds) 2025-05-13 19:46:18.132448 | ubuntu-noble | congratulations :) (16.73 seconds) 2025-05-13 19:46:18.385502 | ubuntu-noble | ok: Runtime: 0:00:17.013269 2025-05-13 19:46:18.405421 | 2025-05-13 19:46:18.405581 | TASK [tox : Create a tempfile to save tox showconfig] 2025-05-13 19:46:18.868858 | ubuntu-noble | changed 2025-05-13 19:46:18.876570 | 2025-05-13 19:46:18.876716 | TASK [tox : Get tox envlist config] 2025-05-13 19:46:19.907745 | ubuntu-noble | ok: Runtime: 0:00:00.394019 2025-05-13 19:46:19.921533 | 2025-05-13 19:46:19.921711 | TASK [tox : Install any sibling python packages] 2025-05-13 19:46:20.438939 | ubuntu-noble | ok: No setup.cfg, no action needed 2025-05-13 19:46:20.448391 | 2025-05-13 19:46:20.448539 | TASK [tox : Remove tempfile] 2025-05-13 19:46:20.947978 | ubuntu-noble | changed 2025-05-13 19:46:20.958232 | 2025-05-13 19:46:20.958412 | TASK [tox : Emit tox command] 2025-05-13 19:46:21.018427 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-05-13 19:46:21.026492 | 2025-05-13 19:46:21.026650 | TASK [tox : Run tox] 2025-05-13 19:46:21.720623 | ubuntu-noble | test: commands[0]> python -m unittest discover test/unit 2025-05-13 19:46:22.576775 | ubuntu-noble | ............... 2025-05-13 19:46:22.576860 | ubuntu-noble | ---------------------------------------------------------------------- 2025-05-13 19:46:22.576878 | ubuntu-noble | Ran 15 tests in 0.177s 2025-05-13 19:46:22.576891 | ubuntu-noble | 2025-05-13 19:46:22.576905 | ubuntu-noble | OK 2025-05-13 19:46:22.681347 | ubuntu-noble | test: OK (1.00=setup[0.05]+cmd[0.96] seconds) 2025-05-13 19:46:22.681474 | ubuntu-noble | congratulations :) (1.08 seconds) 2025-05-13 19:46:23.105435 | ubuntu-noble | ok: Runtime: 0:00:01.343942 2025-05-13 19:46:23.120138 | 2025-05-13 19:46:23.120306 | TASK [tox : Look for output] 2025-05-13 19:46:23.548251 | ubuntu-noble | ok 2025-05-13 19:46:23.562218 | 2025-05-13 19:46:23.562348 | TASK [tox : Return file comments to Zuul] 2025-05-13 19:46:23.606526 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:23.620799 | 2025-05-13 19:46:23.620916 | PLAY RECAP 2025-05-13 19:46:23.621006 | ubuntu-noble | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-05-13 19:46:23.621047 | 2025-05-13 19:46:23.762384 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-13 19:46:23.763989 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-13 19:46:24.525452 | 2025-05-13 19:46:24.525617 | PLAY [all] 2025-05-13 19:46:24.541652 | 2025-05-13 19:46:24.541833 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-05-13 19:46:24.597332 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:24.610621 | 2025-05-13 19:46:24.610905 | TASK [fetch-tox-output : Set tox log path for single node] 2025-05-13 19:46:24.656117 | ubuntu-noble | ok 2025-05-13 19:46:24.663794 | 2025-05-13 19:46:24.663927 | TASK [fetch-tox-output : Ensure local tox dir] 2025-05-13 19:46:25.226589 | ubuntu-noble -> localhost | changed 2025-05-13 19:46:25.233948 | 2025-05-13 19:46:25.234075 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-05-13 19:46:25.300085 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:25.315874 | 2025-05-13 19:46:25.316060 | TASK [fetch-tox-output : Set envlist fact] 2025-05-13 19:46:25.374512 | ubuntu-noble | ok 2025-05-13 19:46:25.383518 | 2025-05-13 19:46:25.383653 | TASK [fetch-tox-output : Get tox version] 2025-05-13 19:46:26.168437 | ubuntu-noble | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-13 19:46:26.185611 | ubuntu-noble | 4.26.0 from /home/zuul/.local/tox/lib/python3.12/site-packages/tox/__init__.py 2025-05-13 19:46:26.418178 | ubuntu-noble | ok: Runtime: 0:00:00.336359 2025-05-13 19:46:26.431708 | 2025-05-13 19:46:26.431942 | TASK [fetch-tox-output : Find all default environments] 2025-05-13 19:46:26.966447 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:26.981657 | 2025-05-13 19:46:26.981905 | TASK [fetch-tox-output : Set envlist fact] 2025-05-13 19:46:27.019621 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:27.026379 | 2025-05-13 19:46:27.026516 | TASK [fetch-tox-output : Find all default environments] 2025-05-13 19:46:27.555502 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:27.568884 | 2025-05-13 19:46:27.569066 | TASK [fetch-tox-output : Set envlist fact] 2025-05-13 19:46:27.604699 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:27.617699 | 2025-05-13 19:46:27.617912 | LOOP [fetch-tox-output : Copy tox logs] 2025-05-13 19:46:27.670505 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:27.690738 | 2025-05-13 19:46:27.691006 | LOOP [fetch-tox-output : Collect tox logs] 2025-05-13 19:46:28.488092 | ubuntu-noble | changed: 2025-05-13 19:46:28.488364 | ubuntu-noble | created directory /var/lib/zuul/builds/9bd9c2cbcc6844ca99d21e4c1f568c87/work/logs/tox/test 2025-05-13 19:46:28.488406 | ubuntu-noble | cd+++++++++ ./ 2025-05-13 19:46:28.488437 | ubuntu-noble | >f+++++++++ 1-commands[0].log 2025-05-13 19:46:28.488474 | 2025-05-13 19:46:28.504532 | 2025-05-13 19:46:28.504693 | PLAY RECAP 2025-05-13 19:46:28.504800 | ubuntu-noble | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-05-13 19:46:28.504844 | 2025-05-13 19:46:28.640566 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-13 19:46:28.643665 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-13 19:46:29.405091 | 2025-05-13 19:46:29.405254 | PLAY [all] 2025-05-13 19:46:29.420840 | 2025-05-13 19:46:29.420979 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-05-13 19:46:29.678921 | ubuntu-noble | changed: non-zero return code 2025-05-13 19:46:29.688960 | 2025-05-13 19:46:29.689168 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-05-13 19:46:29.726091 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:29.740472 | 2025-05-13 19:46:29.740660 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-05-13 19:46:29.808768 | 2025-05-13 19:46:29.809245 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-05-13 19:46:29.846285 | 2025-05-13 19:46:29.846566 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-05-13 19:46:29.873035 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:29.885841 | 2025-05-13 19:46:29.886015 | LOOP [fetch-subunit-output : Generate subunit file] 2025-05-13 19:46:29.927437 | 2025-05-13 19:46:29.927764 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-05-13 19:46:29.963545 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:29.978068 | 2025-05-13 19:46:29.978267 | TASK [fetch-subunit-output : Remove the temporary file] 2025-05-13 19:46:30.014345 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:30.027946 | 2025-05-13 19:46:30.028164 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-05-13 19:46:30.054262 | ubuntu-noble | skipping: Conditional result was False 2025-05-13 19:46:30.068055 | 2025-05-13 19:46:30.068200 | PLAY RECAP 2025-05-13 19:46:30.068283 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-05-13 19:46:30.068323 | 2025-05-13 19:46:30.202050 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-13 19:46:30.203205 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-13 19:46:30.949966 | 2025-05-13 19:46:30.950125 | PLAY [Base post] 2025-05-13 19:46:30.963899 | 2025-05-13 19:46:30.964032 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-05-13 19:46:31.617574 | ubuntu-noble | changed 2025-05-13 19:46:31.627480 | 2025-05-13 19:46:31.627735 | PLAY RECAP 2025-05-13 19:46:31.627968 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-05-13 19:46:31.628147 | 2025-05-13 19:46:31.754599 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-13 19:46:31.755750 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-05-13 19:46:32.560539 | 2025-05-13 19:46:32.560722 | PLAY [Base post-logs] 2025-05-13 19:46:32.572802 | 2025-05-13 19:46:32.572976 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-05-13 19:46:33.058721 | localhost | changed 2025-05-13 19:46:33.069211 | 2025-05-13 19:46:33.069393 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-05-13 19:46:33.106758 | localhost | ok 2025-05-13 19:46:33.110671 | 2025-05-13 19:46:33.110811 | TASK [Set zuul-log-path fact] 2025-05-13 19:46:33.153243 | localhost | ok 2025-05-13 19:46:33.165395 | 2025-05-13 19:46:33.165529 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-13 19:46:33.191434 | localhost | ok 2025-05-13 19:46:33.195074 | 2025-05-13 19:46:33.195188 | TASK [upload-logs : Create log directories] 2025-05-13 19:46:33.715722 | localhost | changed 2025-05-13 19:46:33.721019 | 2025-05-13 19:46:33.721184 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-05-13 19:46:34.277391 | localhost -> localhost | ok: Runtime: 0:00:00.006463 2025-05-13 19:46:34.282652 | 2025-05-13 19:46:34.282810 | TASK [upload-logs : Upload logs to log server] 2025-05-13 19:46:34.859928 | localhost | Output suppressed because no_log was given 2025-05-13 19:46:34.863520 | 2025-05-13 19:46:34.863709 | LOOP [upload-logs : Compress console log and json output] 2025-05-13 19:46:34.924269 | localhost | skipping: Conditional result was False 2025-05-13 19:46:34.929921 | localhost | skipping: Conditional result was False 2025-05-13 19:46:34.937138 | 2025-05-13 19:46:34.937368 | LOOP [upload-logs : Upload compressed console log and json output] 2025-05-13 19:46:34.986754 | localhost | skipping: Conditional result was False 2025-05-13 19:46:34.987380 | 2025-05-13 19:46:34.991340 | localhost | skipping: Conditional result was False 2025-05-13 19:46:34.997490 | 2025-05-13 19:46:34.997805 | LOOP [upload-logs : Upload console log and json output]