2025-09-09 03:09:26.186809 | Job console starting 2025-09-09 03:09:26.208227 | Updating git repos 2025-09-09 03:09:26.233247 | Cloning repos into workspace 2025-09-09 03:09:26.318465 | Restoring repo states 2025-09-09 03:09:26.342027 | Merging changes 2025-09-09 03:09:26.342050 | Checking out repos 2025-09-09 03:09:26.393705 | Preparing playbooks 2025-09-09 03:09:27.805586 | Running Ansible setup 2025-09-09 03:09:33.505663 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-09 03:09:34.540404 | 2025-09-09 03:09:34.540558 | PLAY [Base pre] 2025-09-09 03:09:34.581405 | 2025-09-09 03:09:34.581546 | TASK [Setup log path fact] 2025-09-09 03:09:34.624567 | debian-bookworm | ok 2025-09-09 03:09:34.650718 | 2025-09-09 03:09:34.650876 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-09 03:09:34.700182 | debian-bookworm | ok 2025-09-09 03:09:34.718352 | 2025-09-09 03:09:34.718476 | TASK [emit-job-header : Print job information] 2025-09-09 03:09:34.837981 | # Job Information 2025-09-09 03:09:34.838168 | Ansible Version: 2.16.14 2025-09-09 03:09:34.838203 | Job: tox 2025-09-09 03:09:34.838236 | Pipeline: periodic-daily 2025-09-09 03:09:34.838258 | Executor: 521e9411259a 2025-09-09 03:09:34.838279 | Triggered by: https://github.com/osism/openstack-image-manager 2025-09-09 03:09:34.838301 | Event ID: 0e5f172e037b423896744019da86e0f6 2025-09-09 03:09:34.843333 | 2025-09-09 03:09:34.843439 | LOOP [emit-job-header : Print node information] 2025-09-09 03:09:35.176193 | debian-bookworm | ok: 2025-09-09 03:09:35.176344 | debian-bookworm | # Node Information 2025-09-09 03:09:35.176372 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-09 03:09:35.176430 | debian-bookworm | Hostname: debian 2025-09-09 03:09:35.176451 | debian-bookworm | Username: zuul 2025-09-09 03:09:35.176468 | debian-bookworm | Distro: Debian 12.12 2025-09-09 03:09:35.176487 | debian-bookworm | Provider: regiocloud-a 2025-09-09 03:09:35.176505 | debian-bookworm | Region: 2025-09-09 03:09:35.176522 | debian-bookworm | Label: debian-bookworm 2025-09-09 03:09:35.176538 | debian-bookworm | Product Name: OpenStack Nova 2025-09-09 03:09:35.176554 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe60:e5ca 2025-09-09 03:09:35.205211 | 2025-09-09 03:09:35.205346 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-09 03:09:36.632505 | debian-bookworm -> localhost | changed 2025-09-09 03:09:36.638272 | 2025-09-09 03:09:36.638363 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-09 03:09:38.793567 | debian-bookworm -> localhost | changed 2025-09-09 03:09:38.802133 | 2025-09-09 03:09:38.802219 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-09 03:09:39.151080 | debian-bookworm -> localhost | ok 2025-09-09 03:09:39.156435 | 2025-09-09 03:09:39.156523 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-09 03:09:39.214200 | debian-bookworm | ok 2025-09-09 03:09:39.255088 | debian-bookworm | included: /var/lib/zuul/builds/1c1965a6186a451893a38ae13a0e1ed3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-09 03:09:39.275093 | 2025-09-09 03:09:39.275183 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-09 03:09:41.671834 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-09 03:09:41.672059 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1c1965a6186a451893a38ae13a0e1ed3/work/1c1965a6186a451893a38ae13a0e1ed3_id_rsa 2025-09-09 03:09:41.672129 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1c1965a6186a451893a38ae13a0e1ed3/work/1c1965a6186a451893a38ae13a0e1ed3_id_rsa.pub 2025-09-09 03:09:41.672157 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-09 03:09:41.672182 | debian-bookworm -> localhost | SHA256:0S0FGWNRfta+4a9JEd2WaGekgbtJzAqX9ruzHPmIgAQ zuul-build-sshkey 2025-09-09 03:09:41.672204 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-09 03:09:41.672236 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-09 03:09:41.672258 | debian-bookworm -> localhost | | **+. . | 2025-09-09 03:09:41.672280 | debian-bookworm -> localhost | | o.* =oo| 2025-09-09 03:09:41.672300 | debian-bookworm -> localhost | | E . * ++++=| 2025-09-09 03:09:41.672320 | debian-bookworm -> localhost | | . . = *.oo+ | 2025-09-09 03:09:41.672339 | debian-bookworm -> localhost | | . S + o ...| 2025-09-09 03:09:41.672368 | debian-bookworm -> localhost | | . . . +. ..o| 2025-09-09 03:09:41.672389 | debian-bookworm -> localhost | | . . o. .o | 2025-09-09 03:09:41.672409 | debian-bookworm -> localhost | | . oo+. ..| 2025-09-09 03:09:41.672430 | debian-bookworm -> localhost | | . =+.o..| 2025-09-09 03:09:41.672451 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-09 03:09:41.672519 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.518901 2025-09-09 03:09:41.680080 | 2025-09-09 03:09:41.680189 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-09 03:09:41.740553 | debian-bookworm | ok 2025-09-09 03:09:41.757543 | debian-bookworm | included: /var/lib/zuul/builds/1c1965a6186a451893a38ae13a0e1ed3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-09 03:09:41.795569 | 2025-09-09 03:09:41.795705 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-09 03:09:41.825784 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:09:41.832334 | 2025-09-09 03:09:41.832438 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-09 03:09:42.442703 | debian-bookworm | changed 2025-09-09 03:09:42.448705 | 2025-09-09 03:09:42.448801 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-09 03:09:42.708054 | debian-bookworm | ok 2025-09-09 03:09:42.716093 | 2025-09-09 03:09:42.716195 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-09 03:09:43.335617 | debian-bookworm | changed 2025-09-09 03:09:43.343124 | 2025-09-09 03:09:43.343218 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-09 03:09:43.977125 | debian-bookworm | changed 2025-09-09 03:09:43.990692 | 2025-09-09 03:09:43.990887 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-09 03:09:44.023184 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:09:44.028227 | 2025-09-09 03:09:44.028313 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-09 03:09:44.845344 | debian-bookworm -> localhost | changed 2025-09-09 03:09:44.856813 | 2025-09-09 03:09:44.856907 | TASK [add-build-sshkey : Add back temp key] 2025-09-09 03:09:45.653501 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1c1965a6186a451893a38ae13a0e1ed3/work/1c1965a6186a451893a38ae13a0e1ed3_id_rsa (zuul-build-sshkey) 2025-09-09 03:09:45.653720 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017634 2025-09-09 03:09:45.663261 | 2025-09-09 03:09:45.663352 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-09 03:09:46.078940 | debian-bookworm | ok 2025-09-09 03:09:46.083286 | 2025-09-09 03:09:46.083359 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-09 03:09:46.109564 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:09:46.118224 | 2025-09-09 03:09:46.118305 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-09 03:09:46.661752 | debian-bookworm | ok 2025-09-09 03:09:46.678607 | 2025-09-09 03:09:46.678704 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-09 03:09:46.760598 | debian-bookworm | ok 2025-09-09 03:09:46.782265 | 2025-09-09 03:09:46.782363 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-09 03:09:47.696154 | debian-bookworm -> localhost | ok 2025-09-09 03:09:47.701476 | 2025-09-09 03:09:47.701555 | TASK [validate-host : Collect information about the host] 2025-09-09 03:09:49.105785 | debian-bookworm | ok 2025-09-09 03:09:49.117050 | 2025-09-09 03:09:49.117189 | TASK [validate-host : Sanitize hostname] 2025-09-09 03:09:49.338693 | debian-bookworm | ok 2025-09-09 03:09:49.421341 | 2025-09-09 03:09:49.421550 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-09 03:09:50.925684 | debian-bookworm -> localhost | changed 2025-09-09 03:09:50.932743 | 2025-09-09 03:09:50.932845 | TASK [validate-host : Collect information about zuul worker] 2025-09-09 03:09:51.465333 | debian-bookworm | ok 2025-09-09 03:09:51.470045 | 2025-09-09 03:09:51.470146 | TASK [validate-host : Write out all zuul information for each host] 2025-09-09 03:09:52.299686 | debian-bookworm -> localhost | changed 2025-09-09 03:09:52.311296 | 2025-09-09 03:09:52.311387 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-09 03:09:52.602553 | debian-bookworm | ok 2025-09-09 03:09:52.608169 | 2025-09-09 03:09:52.608259 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-09 03:09:54.404103 | debian-bookworm | changed: 2025-09-09 03:09:54.404284 | debian-bookworm | cd+++++++++ src/ 2025-09-09 03:09:54.404326 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-09 03:09:54.404348 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-09 03:09:54.404367 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-09 03:09:54.404384 | debian-bookworm | =6.1 2025-09-09 03:10:16.993716 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-09 03:10:17.026865 | debian-bookworm | Collecting chardet>=5.2 2025-09-09 03:10:17.035343 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-09 03:10:17.059593 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 10.5 MB/s eta 0:00:00 2025-09-09 03:10:17.111430 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-09 03:10:17.120993 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-09 03:10:17.173566 | debian-bookworm | Collecting filelock>=3.18 2025-09-09 03:10:17.181783 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-09 03:10:17.227408 | debian-bookworm | Collecting packaging>=25 2025-09-09 03:10:17.235539 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-09 03:10:17.246358 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.3 MB/s eta 0:00:00 2025-09-09 03:10:17.303085 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-09 03:10:17.311247 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-09 03:10:17.347488 | debian-bookworm | Collecting pluggy>=1.6 2025-09-09 03:10:17.355101 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-09 03:10:17.491331 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-09 03:10:17.499549 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-09 03:10:17.638985 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-09 03:10:17.647119 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-09 03:10:17.893743 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 24.7 MB/s eta 0:00:00 2025-09-09 03:10:17.990015 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-09 03:10:17.998053 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-09 03:10:18.021235 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.9 MB/s eta 0:00:00 2025-09-09 03:10:18.166050 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-09 03:10:19.131665 | debian-bookworm | Successfully installed cachetools-6.2.0 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.19.1 packaging-25.0 platformdirs-4.4.0 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.30.2 virtualenv-20.34.0 2025-09-09 03:10:19.823783 | debian-bookworm | ok: Runtime: 0:00:03.100304 2025-09-09 03:10:19.829595 | 2025-09-09 03:10:19.829691 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-09 03:10:19.856633 | debian-bookworm | ok 2025-09-09 03:10:19.861499 | 2025-09-09 03:10:19.861589 | TASK [ensure-tox : Output tox version] 2025-09-09 03:10:19.941148 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-09 03:10:19.959563 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-09 03:10:20.891259 | debian-bookworm | ok: Runtime: 0:00:00.294197 2025-09-09 03:10:20.903801 | 2025-09-09 03:10:20.903981 | TASK [ensure-tox : Make global symlink] 2025-09-09 03:10:20.928994 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:20.945769 | 2025-09-09 03:10:20.945890 | PLAY RECAP 2025-09-09 03:10:20.945974 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-09 03:10:20.946015 | 2025-09-09 03:10:21.059391 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-09 03:10:21.060782 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-09 03:10:22.054670 | 2025-09-09 03:10:22.054797 | PLAY [all] 2025-09-09 03:10:22.072304 | 2025-09-09 03:10:22.072412 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-09 03:10:22.067729 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-09 03:10:22.601759 | debian-bookworm | ok: Runtime: 0:00:00.027935 2025-09-09 03:10:22.613765 | 2025-09-09 03:10:22.614058 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-09 03:10:23.155598 | debian-bookworm | ok: Runtime: 0:00:00.006944 2025-09-09 03:10:23.163907 | 2025-09-09 03:10:23.164006 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-09 03:10:23.155152 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-09 03:10:23.156973 | debian-bookworm | sudo: a password is required 2025-09-09 03:10:23.734325 | debian-bookworm | ok: Runtime: 0:00:00.021362 2025-09-09 03:10:23.743782 | 2025-09-09 03:10:23.743880 | TASK [tox : Check to see if the constraints file exists] 2025-09-09 03:10:23.796801 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:23.802679 | 2025-09-09 03:10:23.802775 | TASK [tox : Fail if constraints file is missing] 2025-09-09 03:10:23.846623 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:23.854092 | 2025-09-09 03:10:23.854199 | TASK [tox : Record file location] 2025-09-09 03:10:23.918199 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:23.930153 | 2025-09-09 03:10:23.930303 | TASK [tox : Install tox siblings] 2025-09-09 03:10:23.983572 | debian-bookworm | ok 2025-09-09 03:10:24.037121 | debian-bookworm | included: /var/lib/zuul/builds/1c1965a6186a451893a38ae13a0e1ed3/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-09 03:10:24.051199 | 2025-09-09 03:10:24.051342 | TASK [tox : Run tox without tests] 2025-09-09 03:10:24.941086 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-09 03:10:36.976000 | debian-bookworm | test: OK (12.17 seconds) 2025-09-09 03:10:36.976104 | debian-bookworm | congratulations :) (12.20 seconds) 2025-09-09 03:10:37.120235 | debian-bookworm | ok: Runtime: 0:00:12.881533 2025-09-09 03:10:37.125795 | 2025-09-09 03:10:37.125886 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-09 03:10:37.876519 | debian-bookworm | changed 2025-09-09 03:10:37.881601 | 2025-09-09 03:10:37.881692 | TASK [tox : Get tox envlist config] 2025-09-09 03:10:38.941784 | debian-bookworm | ok: Runtime: 0:00:00.283330 2025-09-09 03:10:38.947154 | 2025-09-09 03:10:38.947325 | TASK [tox : Install any sibling python packages] 2025-09-09 03:10:39.507878 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-09 03:10:39.512347 | 2025-09-09 03:10:39.512421 | TASK [tox : Remove tempfile] 2025-09-09 03:10:39.936715 | debian-bookworm | changed 2025-09-09 03:10:39.941978 | 2025-09-09 03:10:39.942070 | TASK [tox : Emit tox command] 2025-09-09 03:10:39.989688 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-09 03:10:40.002723 | 2025-09-09 03:10:40.002816 | TASK [tox : Run tox] 2025-09-09 03:10:40.635073 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-09 03:10:41.252652 | debian-bookworm | ............... 2025-09-09 03:10:41.252697 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-09 03:10:41.252711 | debian-bookworm | Ran 15 tests in 0.174s 2025-09-09 03:10:41.252717 | debian-bookworm | 2025-09-09 03:10:41.253235 | debian-bookworm | OK 2025-09-09 03:10:41.342006 | debian-bookworm | test: OK (0.75=setup[0.04]+cmd[0.71] seconds) 2025-09-09 03:10:41.342073 | debian-bookworm | congratulations :) (0.78 seconds) 2025-09-09 03:10:41.564825 | debian-bookworm | ok: Runtime: 0:00:01.069924 2025-09-09 03:10:41.569279 | 2025-09-09 03:10:41.569350 | TASK [tox : Look for output] 2025-09-09 03:10:42.043113 | debian-bookworm | ok 2025-09-09 03:10:42.059020 | 2025-09-09 03:10:42.063387 | TASK [tox : Return file comments to Zuul] 2025-09-09 03:10:42.126248 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:42.135023 | 2025-09-09 03:10:42.135098 | PLAY RECAP 2025-09-09 03:10:42.135201 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-09 03:10:42.135226 | 2025-09-09 03:10:42.268721 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-09 03:10:42.270056 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-09 03:10:43.147903 | 2025-09-09 03:10:43.148043 | PLAY [all] 2025-09-09 03:10:43.173179 | 2025-09-09 03:10:43.173301 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-09 03:10:43.267963 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:43.273643 | 2025-09-09 03:10:43.273765 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-09 03:10:43.312854 | debian-bookworm | ok 2025-09-09 03:10:43.317693 | 2025-09-09 03:10:43.317779 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-09 03:10:43.886458 | debian-bookworm -> localhost | changed 2025-09-09 03:10:43.894380 | 2025-09-09 03:10:43.894476 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-09 03:10:43.938530 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:43.951546 | 2025-09-09 03:10:43.951656 | TASK [fetch-tox-output : Set envlist fact] 2025-09-09 03:10:44.016179 | debian-bookworm | ok 2025-09-09 03:10:44.022429 | 2025-09-09 03:10:44.022523 | TASK [fetch-tox-output : Get tox version] 2025-09-09 03:10:44.701513 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-09 03:10:44.719927 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-09 03:10:45.056852 | debian-bookworm | ok: Runtime: 0:00:00.284663 2025-09-09 03:10:45.062397 | 2025-09-09 03:10:45.062497 | TASK [fetch-tox-output : Find all default environments] 2025-09-09 03:10:45.590557 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:45.607612 | 2025-09-09 03:10:45.607827 | TASK [fetch-tox-output : Set envlist fact] 2025-09-09 03:10:45.642341 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:45.651900 | 2025-09-09 03:10:45.652050 | TASK [fetch-tox-output : Find all default environments] 2025-09-09 03:10:46.182140 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:46.187897 | 2025-09-09 03:10:46.187997 | TASK [fetch-tox-output : Set envlist fact] 2025-09-09 03:10:46.221874 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:46.227953 | 2025-09-09 03:10:46.228056 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-09 03:10:46.266669 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:46.283633 | 2025-09-09 03:10:46.283766 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-09 03:10:47.038149 | debian-bookworm | changed: 2025-09-09 03:10:47.038370 | debian-bookworm | created directory /var/lib/zuul/builds/1c1965a6186a451893a38ae13a0e1ed3/work/logs/tox/test 2025-09-09 03:10:47.038417 | debian-bookworm | cd+++++++++ ./ 2025-09-09 03:10:47.038448 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-09 03:10:47.038483 | 2025-09-09 03:10:47.046081 | 2025-09-09 03:10:47.046154 | PLAY RECAP 2025-09-09 03:10:47.046202 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-09 03:10:47.046225 | 2025-09-09 03:10:47.157374 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-09 03:10:47.158387 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-09 03:10:47.863802 | 2025-09-09 03:10:47.863940 | PLAY [all] 2025-09-09 03:10:47.878202 | 2025-09-09 03:10:47.878318 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-09 03:10:48.113598 | debian-bookworm | changed: non-zero return code 2025-09-09 03:10:48.119491 | 2025-09-09 03:10:48.119626 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-09 03:10:48.145510 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:48.152341 | 2025-09-09 03:10:48.152463 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-09 03:10:48.184586 | 2025-09-09 03:10:48.185067 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-09 03:10:48.228075 | 2025-09-09 03:10:48.228404 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-09 03:10:48.253921 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:48.265123 | 2025-09-09 03:10:48.265300 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-09 03:10:48.297255 | 2025-09-09 03:10:48.297464 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-09 03:10:48.321046 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:48.326615 | 2025-09-09 03:10:48.326728 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-09 03:10:48.350142 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:48.356217 | 2025-09-09 03:10:48.356332 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-09 03:10:48.380742 | debian-bookworm | skipping: Conditional result was False 2025-09-09 03:10:48.387805 | 2025-09-09 03:10:48.387887 | PLAY RECAP 2025-09-09 03:10:48.387943 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-09 03:10:48.387966 | 2025-09-09 03:10:48.521600 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-09 03:10:48.524497 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-09 03:10:49.313964 | 2025-09-09 03:10:49.314201 | PLAY [Base post] 2025-09-09 03:10:49.328969 | 2025-09-09 03:10:49.329152 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-09 03:10:49.960015 | debian-bookworm | changed 2025-09-09 03:10:49.978887 | 2025-09-09 03:10:49.979066 | PLAY RECAP 2025-09-09 03:10:49.979140 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-09 03:10:49.979204 | 2025-09-09 03:10:50.216106 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-09 03:10:50.217335 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-09 03:10:51.023077 | 2025-09-09 03:10:51.023261 | PLAY [Base post-logs] 2025-09-09 03:10:51.047614 | 2025-09-09 03:10:51.047888 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-09 03:10:51.917505 | localhost | changed 2025-09-09 03:10:51.928929 | 2025-09-09 03:10:51.929223 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-09 03:10:51.958082 | localhost | ok 2025-09-09 03:10:51.962412 | 2025-09-09 03:10:51.962554 | TASK [Set zuul-log-path fact] 2025-09-09 03:10:51.980964 | localhost | ok 2025-09-09 03:10:51.989931 | 2025-09-09 03:10:51.990076 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-09 03:10:52.016463 | localhost | ok 2025-09-09 03:10:52.019890 | 2025-09-09 03:10:52.020004 | TASK [upload-logs : Create log directories] 2025-09-09 03:10:52.648716 | localhost | changed 2025-09-09 03:10:52.651786 | 2025-09-09 03:10:52.651905 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-09 03:10:53.782569 | localhost -> localhost | ok: Runtime: 0:00:00.022298 2025-09-09 03:10:53.787592 | 2025-09-09 03:10:53.787723 | TASK [upload-logs : Upload logs to log server] 2025-09-09 03:10:54.588874 | localhost | Output suppressed because no_log was given 2025-09-09 03:10:54.590268 | 2025-09-09 03:10:54.590343 | LOOP [upload-logs : Compress console log and json output] 2025-09-09 03:10:54.654000 | localhost | skipping: Conditional result was False 2025-09-09 03:10:54.670082 | localhost | skipping: Conditional result was False 2025-09-09 03:10:54.676652 | 2025-09-09 03:10:54.676743 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-09 03:10:54.721863 | localhost | skipping: Conditional result was False 2025-09-09 03:10:54.722115 | 2025-09-09 03:10:54.725976 | localhost | skipping: Conditional result was False 2025-09-09 03:10:54.731673 | 2025-09-09 03:10:54.731752 | LOOP [upload-logs : Upload console log and json output]