2025-09-14 00:17:19.011418 | Job console starting 2025-09-14 00:17:19.021205 | Updating git repos 2025-09-14 00:17:19.051232 | Cloning repos into workspace 2025-09-14 00:17:19.153839 | Restoring repo states 2025-09-14 00:17:19.179346 | Merging changes 2025-09-14 00:17:19.855318 | Checking out repos 2025-09-14 00:17:19.892359 | Preparing playbooks 2025-09-14 00:17:20.956212 | Running Ansible setup 2025-09-14 00:17:27.019869 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-14 00:17:28.653301 | 2025-09-14 00:17:28.653418 | PLAY [Base pre] 2025-09-14 00:17:28.684075 | 2025-09-14 00:17:28.684195 | TASK [Setup log path fact] 2025-09-14 00:17:28.715643 | debian-bookworm | ok 2025-09-14 00:17:28.741613 | 2025-09-14 00:17:28.741737 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-14 00:17:28.799564 | debian-bookworm | ok 2025-09-14 00:17:28.809613 | 2025-09-14 00:17:28.809702 | TASK [emit-job-header : Print job information] 2025-09-14 00:17:28.872574 | # Job Information 2025-09-14 00:17:28.872708 | Ansible Version: 2.16.14 2025-09-14 00:17:28.872737 | Job: tox 2025-09-14 00:17:28.872763 | Pipeline: check 2025-09-14 00:17:28.872782 | Executor: 521e9411259a 2025-09-14 00:17:28.872799 | Triggered by: https://github.com/osism/openstack-image-manager/pull/917 2025-09-14 00:17:28.872818 | Event ID: 28866810-9100-11f0-8985-09c78d0bd972 2025-09-14 00:17:28.876731 | 2025-09-14 00:17:28.876810 | LOOP [emit-job-header : Print node information] 2025-09-14 00:17:29.079400 | debian-bookworm | ok: 2025-09-14 00:17:29.079536 | debian-bookworm | # Node Information 2025-09-14 00:17:29.079564 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-14 00:17:29.079584 | debian-bookworm | Hostname: debian 2025-09-14 00:17:29.079602 | debian-bookworm | Username: zuul 2025-09-14 00:17:29.079620 | debian-bookworm | Distro: Debian 12.12 2025-09-14 00:17:29.079639 | debian-bookworm | Provider: regiocloud-a 2025-09-14 00:17:29.079657 | debian-bookworm | Region: 2025-09-14 00:17:29.079675 | debian-bookworm | Label: debian-bookworm 2025-09-14 00:17:29.079691 | debian-bookworm | Product Name: OpenStack Nova 2025-09-14 00:17:29.079706 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe24:12f2 2025-09-14 00:17:29.092721 | 2025-09-14 00:17:29.092819 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-14 00:17:29.639089 | debian-bookworm -> localhost | changed 2025-09-14 00:17:29.650111 | 2025-09-14 00:17:29.650216 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-14 00:17:30.985850 | debian-bookworm -> localhost | changed 2025-09-14 00:17:30.994251 | 2025-09-14 00:17:30.994334 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-14 00:17:31.545165 | debian-bookworm -> localhost | ok 2025-09-14 00:17:31.551085 | 2025-09-14 00:17:31.551195 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-14 00:17:31.568499 | debian-bookworm | ok 2025-09-14 00:17:31.600495 | debian-bookworm | included: /var/lib/zuul/builds/9cb241c10808499ea50c3ae5e1d0e4da/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-14 00:17:31.606412 | 2025-09-14 00:17:31.606497 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-14 00:17:33.380404 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-14 00:17:33.380631 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9cb241c10808499ea50c3ae5e1d0e4da/work/9cb241c10808499ea50c3ae5e1d0e4da_id_rsa 2025-09-14 00:17:33.380675 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9cb241c10808499ea50c3ae5e1d0e4da/work/9cb241c10808499ea50c3ae5e1d0e4da_id_rsa.pub 2025-09-14 00:17:33.380697 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-14 00:17:33.380717 | debian-bookworm -> localhost | SHA256:Qr71InVX6KnbEcxhRcXDPfQ2Y2pC07JJ9frOoKN8w1Y zuul-build-sshkey 2025-09-14 00:17:33.380735 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-14 00:17:33.380761 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-14 00:17:33.380780 | debian-bookworm -> localhost | | o=+o| 2025-09-14 00:17:33.380798 | debian-bookworm -> localhost | | oo.++| 2025-09-14 00:17:33.380814 | debian-bookworm -> localhost | | . =+..==| 2025-09-14 00:17:33.380832 | debian-bookworm -> localhost | | o o==++.o| 2025-09-14 00:17:33.380862 | debian-bookworm -> localhost | | o S .+B+ | 2025-09-14 00:17:33.380902 | debian-bookworm -> localhost | | = o ooE. | 2025-09-14 00:17:33.380927 | debian-bookworm -> localhost | | o . + o. . | 2025-09-14 00:17:33.380945 | debian-bookworm -> localhost | | ... O..+ | 2025-09-14 00:17:33.380962 | debian-bookworm -> localhost | | o=.+ o | 2025-09-14 00:17:33.380990 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-14 00:17:33.381125 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.082977 2025-09-14 00:17:33.386925 | 2025-09-14 00:17:33.387000 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-14 00:17:33.406267 | debian-bookworm | ok 2025-09-14 00:17:33.418321 | debian-bookworm | included: /var/lib/zuul/builds/9cb241c10808499ea50c3ae5e1d0e4da/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-14 00:17:33.426772 | 2025-09-14 00:17:33.426905 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-14 00:17:33.449746 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:17:33.456585 | 2025-09-14 00:17:33.456665 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-14 00:17:34.108713 | debian-bookworm | changed 2025-09-14 00:17:34.117993 | 2025-09-14 00:17:34.118162 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-14 00:17:34.369121 | debian-bookworm | ok 2025-09-14 00:17:34.374629 | 2025-09-14 00:17:34.374721 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-14 00:17:35.065863 | debian-bookworm | changed 2025-09-14 00:17:35.083518 | 2025-09-14 00:17:35.083613 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-14 00:17:35.758683 | debian-bookworm | changed 2025-09-14 00:17:35.765523 | 2025-09-14 00:17:35.765609 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-14 00:17:35.789183 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:17:35.794994 | 2025-09-14 00:17:35.795084 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-14 00:17:36.322004 | debian-bookworm -> localhost | changed 2025-09-14 00:17:36.333305 | 2025-09-14 00:17:36.333409 | TASK [add-build-sshkey : Add back temp key] 2025-09-14 00:17:36.678539 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9cb241c10808499ea50c3ae5e1d0e4da/work/9cb241c10808499ea50c3ae5e1d0e4da_id_rsa (zuul-build-sshkey) 2025-09-14 00:17:36.678762 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009284 2025-09-14 00:17:36.686509 | 2025-09-14 00:17:36.686596 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-14 00:17:37.088390 | debian-bookworm | ok 2025-09-14 00:17:37.097588 | 2025-09-14 00:17:37.097838 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-14 00:17:37.121542 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:17:37.134417 | 2025-09-14 00:17:37.134516 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-14 00:17:37.544259 | debian-bookworm | ok 2025-09-14 00:17:37.569102 | 2025-09-14 00:17:37.569249 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-14 00:17:37.611463 | debian-bookworm | ok 2025-09-14 00:17:37.619690 | 2025-09-14 00:17:37.619787 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-14 00:17:38.097432 | debian-bookworm -> localhost | ok 2025-09-14 00:17:38.102494 | 2025-09-14 00:17:38.102576 | TASK [validate-host : Collect information about the host] 2025-09-14 00:17:39.345296 | debian-bookworm | ok 2025-09-14 00:17:39.375636 | 2025-09-14 00:17:39.375749 | TASK [validate-host : Sanitize hostname] 2025-09-14 00:17:39.586639 | debian-bookworm | ok 2025-09-14 00:17:39.620457 | 2025-09-14 00:17:39.620553 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-14 00:17:40.266780 | debian-bookworm -> localhost | changed 2025-09-14 00:17:40.272448 | 2025-09-14 00:17:40.272543 | TASK [validate-host : Collect information about zuul worker] 2025-09-14 00:17:40.712293 | debian-bookworm | ok 2025-09-14 00:17:40.727800 | 2025-09-14 00:17:40.727908 | TASK [validate-host : Write out all zuul information for each host] 2025-09-14 00:17:41.448365 | debian-bookworm -> localhost | changed 2025-09-14 00:17:41.460367 | 2025-09-14 00:17:41.460489 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-14 00:17:41.764579 | debian-bookworm | ok 2025-09-14 00:17:41.785433 | 2025-09-14 00:17:41.786361 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-14 00:17:43.104241 | debian-bookworm | changed: 2025-09-14 00:17:43.104449 | debian-bookworm | cd+++++++++ src/ 2025-09-14 00:17:43.104484 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-14 00:17:43.104508 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-14 00:17:43.104530 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-14 00:17:43.104551 | debian-bookworm | =6.1 2025-09-14 00:18:08.167115 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-14 00:18:08.198690 | debian-bookworm | Collecting chardet>=5.2 2025-09-14 00:18:08.209947 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-14 00:18:08.232071 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 15.0 MB/s eta 0:00:00 2025-09-14 00:18:08.281928 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-14 00:18:08.290564 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-14 00:18:08.343905 | debian-bookworm | Collecting filelock>=3.18 2025-09-14 00:18:08.351945 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-14 00:18:08.399958 | debian-bookworm | Collecting packaging>=25 2025-09-14 00:18:08.408343 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-14 00:18:08.416889 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.4 MB/s eta 0:00:00 2025-09-14 00:18:08.459674 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-14 00:18:08.468160 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-14 00:18:08.505230 | debian-bookworm | Collecting pluggy>=1.6 2025-09-14 00:18:08.513256 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-14 00:18:08.607173 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-14 00:18:08.619305 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-14 00:18:08.757129 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-14 00:18:08.765548 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-14 00:18:09.028275 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 23.3 MB/s eta 0:00:00 2025-09-14 00:18:09.134705 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-14 00:18:09.142980 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-14 00:18:09.167139 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 22.3 MB/s eta 0:00:00 2025-09-14 00:18:09.327760 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-14 00:18:10.293820 | 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-14 00:18:10.475233 | debian-bookworm | ok: Runtime: 0:00:03.077883 2025-09-14 00:18:10.488493 | 2025-09-14 00:18:10.488626 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-14 00:18:10.549363 | debian-bookworm | ok 2025-09-14 00:18:10.554491 | 2025-09-14 00:18:10.554616 | TASK [ensure-tox : Output tox version] 2025-09-14 00:18:11.225588 | 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-14 00:18:11.245211 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-14 00:18:11.615979 | debian-bookworm | ok: Runtime: 0:00:00.320528 2025-09-14 00:18:11.622155 | 2025-09-14 00:18:11.622265 | TASK [ensure-tox : Make global symlink] 2025-09-14 00:18:11.676535 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:11.685346 | 2025-09-14 00:18:11.685441 | PLAY RECAP 2025-09-14 00:18:11.685495 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-14 00:18:11.685519 | 2025-09-14 00:18:11.905448 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-14 00:18:11.906994 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-14 00:18:13.093253 | 2025-09-14 00:18:13.093412 | PLAY [all] 2025-09-14 00:18:13.114318 | 2025-09-14 00:18:13.114449 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-14 00:18:13.708740 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-14 00:18:14.160147 | debian-bookworm | ok: Runtime: 0:00:00.031191 2025-09-14 00:18:14.165912 | 2025-09-14 00:18:14.166020 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-14 00:18:14.732478 | debian-bookworm | ok: Runtime: 0:00:00.007125 2025-09-14 00:18:14.737151 | 2025-09-14 00:18:14.737751 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-14 00:18:15.118992 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-14 00:18:15.123405 | debian-bookworm | sudo: a password is required 2025-09-14 00:18:15.272877 | debian-bookworm | ok: Runtime: 0:00:00.026200 2025-09-14 00:18:15.280914 | 2025-09-14 00:18:15.281003 | TASK [tox : Check to see if the constraints file exists] 2025-09-14 00:18:15.324242 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:15.328935 | 2025-09-14 00:18:15.329021 | TASK [tox : Fail if constraints file is missing] 2025-09-14 00:18:15.362124 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:15.366892 | 2025-09-14 00:18:15.366976 | TASK [tox : Record file location] 2025-09-14 00:18:15.409697 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:15.414453 | 2025-09-14 00:18:15.414536 | TASK [tox : Install tox siblings] 2025-09-14 00:18:15.443763 | debian-bookworm | ok 2025-09-14 00:18:15.457949 | debian-bookworm | included: /var/lib/zuul/builds/9cb241c10808499ea50c3ae5e1d0e4da/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-14 00:18:15.462963 | 2025-09-14 00:18:15.463075 | TASK [tox : Run tox without tests] 2025-09-14 00:18:16.561633 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-14 00:18:29.514618 | debian-bookworm | test: OK (13.48 seconds) 2025-09-14 00:18:29.514715 | debian-bookworm | congratulations :) (13.51 seconds) 2025-09-14 00:18:30.015486 | debian-bookworm | ok: Runtime: 0:00:13.804492 2025-09-14 00:18:30.020481 | 2025-09-14 00:18:30.020560 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-14 00:18:30.804900 | debian-bookworm | changed 2025-09-14 00:18:30.809839 | 2025-09-14 00:18:30.809919 | TASK [tox : Get tox envlist config] 2025-09-14 00:18:31.854684 | debian-bookworm | ok: Runtime: 0:00:00.357693 2025-09-14 00:18:31.860586 | 2025-09-14 00:18:31.860670 | TASK [tox : Install any sibling python packages] 2025-09-14 00:18:32.428201 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-14 00:18:32.432688 | 2025-09-14 00:18:32.432784 | TASK [tox : Remove tempfile] 2025-09-14 00:18:33.184605 | debian-bookworm | changed 2025-09-14 00:18:33.190427 | 2025-09-14 00:18:33.190512 | TASK [tox : Emit tox command] 2025-09-14 00:18:33.292292 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-14 00:18:33.299671 | 2025-09-14 00:18:33.299755 | TASK [tox : Run tox] 2025-09-14 00:18:33.985293 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-14 00:18:34.672999 | debian-bookworm | ............... 2025-09-14 00:18:34.673053 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-14 00:18:34.673069 | debian-bookworm | Ran 15 tests in 0.184s 2025-09-14 00:18:34.673074 | debian-bookworm | 2025-09-14 00:18:34.673082 | debian-bookworm | OK 2025-09-14 00:18:34.767493 | debian-bookworm | test: OK (0.82=setup[0.04]+cmd[0.78] seconds) 2025-09-14 00:18:34.767577 | debian-bookworm | congratulations :) (0.85 seconds) 2025-09-14 00:18:34.904475 | debian-bookworm | ok: Runtime: 0:00:01.132349 2025-09-14 00:18:34.909927 | 2025-09-14 00:18:34.910000 | TASK [tox : Look for output] 2025-09-14 00:18:35.443743 | debian-bookworm | ok 2025-09-14 00:18:35.469834 | 2025-09-14 00:18:35.469941 | TASK [tox : Return file comments to Zuul] 2025-09-14 00:18:35.515382 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:35.523102 | 2025-09-14 00:18:35.523171 | PLAY RECAP 2025-09-14 00:18:35.523213 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-14 00:18:35.523234 | 2025-09-14 00:18:35.779257 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-14 00:18:35.780119 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-14 00:18:36.921254 | 2025-09-14 00:18:36.921371 | PLAY [all] 2025-09-14 00:18:36.957377 | 2025-09-14 00:18:36.957585 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-14 00:18:37.004798 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:37.009515 | 2025-09-14 00:18:37.009619 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-14 00:18:37.071443 | debian-bookworm | ok 2025-09-14 00:18:37.075791 | 2025-09-14 00:18:37.075878 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-14 00:18:37.936185 | debian-bookworm -> localhost | changed 2025-09-14 00:18:37.942264 | 2025-09-14 00:18:37.942352 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-14 00:18:38.015606 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:38.020072 | 2025-09-14 00:18:38.020161 | TASK [fetch-tox-output : Set envlist fact] 2025-09-14 00:18:38.069797 | debian-bookworm | ok 2025-09-14 00:18:38.074919 | 2025-09-14 00:18:38.075001 | TASK [fetch-tox-output : Get tox version] 2025-09-14 00:18:39.023627 | 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-14 00:18:39.041952 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-14 00:18:39.129493 | debian-bookworm | ok: Runtime: 0:00:00.297034 2025-09-14 00:18:39.133741 | 2025-09-14 00:18:39.133822 | TASK [fetch-tox-output : Find all default environments] 2025-09-14 00:18:39.680636 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:39.687136 | 2025-09-14 00:18:39.687242 | TASK [fetch-tox-output : Set envlist fact] 2025-09-14 00:18:39.710173 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:39.717052 | 2025-09-14 00:18:39.717162 | TASK [fetch-tox-output : Find all default environments] 2025-09-14 00:18:40.265415 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:40.270056 | 2025-09-14 00:18:40.270146 | TASK [fetch-tox-output : Set envlist fact] 2025-09-14 00:18:40.292714 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:40.297514 | 2025-09-14 00:18:40.297589 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-14 00:18:40.356903 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:40.365168 | 2025-09-14 00:18:40.365250 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-14 00:18:41.166016 | debian-bookworm | changed: 2025-09-14 00:18:41.166221 | debian-bookworm | created directory /var/lib/zuul/builds/9cb241c10808499ea50c3ae5e1d0e4da/work/logs/tox/test 2025-09-14 00:18:41.166290 | debian-bookworm | cd+++++++++ ./ 2025-09-14 00:18:41.166320 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-14 00:18:41.166348 | 2025-09-14 00:18:41.183173 | 2025-09-14 00:18:41.183257 | PLAY RECAP 2025-09-14 00:18:41.183305 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-14 00:18:41.183330 | 2025-09-14 00:18:41.393970 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-14 00:18:41.394759 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-14 00:18:42.370530 | 2025-09-14 00:18:42.370850 | PLAY [all] 2025-09-14 00:18:42.392695 | 2025-09-14 00:18:42.392807 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-14 00:18:42.713410 | debian-bookworm | changed: non-zero return code 2025-09-14 00:18:42.718152 | 2025-09-14 00:18:42.718242 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-14 00:18:42.786323 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:42.791054 | 2025-09-14 00:18:42.791161 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-14 00:18:42.859913 | 2025-09-14 00:18:42.860149 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-14 00:18:42.920011 | 2025-09-14 00:18:42.920163 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-14 00:18:42.983547 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:42.989151 | 2025-09-14 00:18:42.989323 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-14 00:18:43.038584 | 2025-09-14 00:18:43.038760 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-14 00:18:43.081860 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:43.087775 | 2025-09-14 00:18:43.087897 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-14 00:18:43.130972 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:43.136526 | 2025-09-14 00:18:43.136607 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-14 00:18:43.169294 | debian-bookworm | skipping: Conditional result was False 2025-09-14 00:18:43.176193 | 2025-09-14 00:18:43.176258 | PLAY RECAP 2025-09-14 00:18:43.176300 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-14 00:18:43.176320 | 2025-09-14 00:18:43.388726 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-14 00:18:43.389900 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-14 00:18:45.174489 | 2025-09-14 00:18:45.174603 | PLAY [Base post] 2025-09-14 00:18:45.230332 | 2025-09-14 00:18:45.230452 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-14 00:18:45.921544 | debian-bookworm | changed 2025-09-14 00:18:45.926863 | 2025-09-14 00:18:45.926956 | PLAY RECAP 2025-09-14 00:18:45.927004 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-14 00:18:45.927054 | 2025-09-14 00:18:46.185277 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-14 00:18:46.186088 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-14 00:18:47.346573 | 2025-09-14 00:18:47.346718 | PLAY [Base post-logs] 2025-09-14 00:18:47.354653 | 2025-09-14 00:18:47.354761 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-14 00:18:48.292749 | localhost | changed 2025-09-14 00:18:48.300411 | 2025-09-14 00:18:48.300536 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-14 00:18:48.341586 | localhost | ok 2025-09-14 00:18:48.344299 | 2025-09-14 00:18:48.344383 | TASK [Set zuul-log-path fact] 2025-09-14 00:18:48.370191 | localhost | ok 2025-09-14 00:18:48.376285 | 2025-09-14 00:18:48.376361 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-14 00:18:48.422018 | localhost | ok 2025-09-14 00:18:48.424395 | 2025-09-14 00:18:48.424472 | TASK [upload-logs : Create log directories] 2025-09-14 00:18:49.112685 | localhost | changed 2025-09-14 00:18:49.115302 | 2025-09-14 00:18:49.115383 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-14 00:18:49.555739 | localhost -> localhost | ok: Runtime: 0:00:00.023167 2025-09-14 00:18:49.558690 | 2025-09-14 00:18:49.558769 | TASK [upload-logs : Upload logs to log server] 2025-09-14 00:18:50.159519 | localhost | Output suppressed because no_log was given 2025-09-14 00:18:50.160876 | 2025-09-14 00:18:50.160950 | LOOP [upload-logs : Compress console log and json output] 2025-09-14 00:18:50.260150 | localhost | skipping: Conditional result was False 2025-09-14 00:18:50.279108 | localhost | skipping: Conditional result was False 2025-09-14 00:18:50.287223 | 2025-09-14 00:18:50.287307 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-14 00:18:50.357443 | localhost | skipping: Conditional result was False 2025-09-14 00:18:50.357634 | 2025-09-14 00:18:50.367471 | localhost | skipping: Conditional result was False 2025-09-14 00:18:50.373202 | 2025-09-14 00:18:50.373296 | LOOP [upload-logs : Upload console log and json output]